Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.01.2022 19:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16432368 kB
MemAvailable 31271740 kB
Buffers 0 kB
Cached 15210444 kB
SwapCached 1220 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.9
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-01-07 19:38:06
DTS_END                         2022-01-07 19:38:06.2250
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/58058
TRN_ID                          245321
ATT_ID                          708
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 9333.14 21215 1119977
customer order: creation 178.19 347 21383
customer order: refuse 15.23 322 1827
order to supplier: creation 47.96 1234 5755
order to supplier: removal 3.40 100533 408
invoice (draft): creation 47.98 3386 5757
invoice (draft): removal 52.20 825 6264
invoice accept: apply 43.87 17724 5264
invoice accept: cancel 46.51 762 5581
customer reserve: creation 4001.82 177 480218
customer reserve: removal 4606.43 57 552771
realization accept: apply 86.21 169 10345
realization accept: cancel 71.59 109 8591
payment from customer: creation 59.40 108 7128
payment from customer: removal 5.78 90 693
payment to supplier: creation 50.18 143 6021
payment to supplier: removal 3.65 55 438
service: total inventory turnovers 5.03 924 604
service: total monetary turnovers 6.74 28 809
service: aggregate perf. data 0.97 18567 116
service: refresh index statistics 0.03 278739 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-01-2022 19:39:00 to 07-01-2022 19:39:02.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4294
TEST_TIME 2 5174
TEST_TIME 3 6215
TEST_TIME 4 6387
TEST_TIME 5 6507
TEST_TIME 6 6183
TEST_TIME 7 6408
TEST_TIME 8 6684
TEST_TIME 9 6591
TEST_TIME 10 7133
TEST_TIME 11 7372
TEST_TIME 12 7301
TEST_TIME 13 7489
TEST_TIME 14 7637
TEST_TIME 15 7612
TEST_TIME 16 7597
TEST_TIME 17 7721
TEST_TIME 18 7995
TEST_TIME 19 8222
TEST_TIME 20 8186
TEST_TIME 21 8247
TEST_TIME 22 8425
TEST_TIME 23 8539
TEST_TIME 24 8531
TEST_TIME 25 8985
TEST_TIME 26 9534
TEST_TIME 27 9803
TEST_TIME 28 10232
TEST_TIME 29 10148
TEST_TIME 30 10408
TEST_TIME 31 10370
TEST_TIME 32 10229
TEST_TIME 33 10287
TEST_TIME 34 10229
TEST_TIME 35 10134
TEST_TIME 36 10203
TEST_TIME 37 10110
TEST_TIME 38 9936
TEST_TIME 39 10011
TEST_TIME 40 10060
TEST_TIME 41 10042
TEST_TIME 42 9986
TEST_TIME 43 10014
TEST_TIME 44 10121
TEST_TIME 45 10099
TEST_TIME 46 9998
TEST_TIME 47 9804
TEST_TIME 48 9784
TEST_TIME 49 9808
TEST_TIME 50 9652
TEST_TIME 51 9656
TEST_TIME 52 9691
TEST_TIME 53 9628
TEST_TIME 54 9583
TEST_TIME 55 9503
TEST_TIME 56 9440
TEST_TIME 57 9493
TEST_TIME 58 9429
TEST_TIME 59 9514
TEST_TIME 60 9495
TEST_TIME 61 9445
TEST_TIME 62 9546
TEST_TIME 63 9536
TEST_TIME 64 9573
TEST_TIME 65 9695
TEST_TIME 66 9623
TEST_TIME 67 9557
TEST_TIME 68 9680
TEST_TIME 69 9676
TEST_TIME 70 9673
TEST_TIME 71 9762
TEST_TIME 72 9747
TEST_TIME 73 9709
TEST_TIME 74 9781
TEST_TIME 75 9726
TEST_TIME 76 9627
TEST_TIME 77 9624
TEST_TIME 78 9557
TEST_TIME 79 9556
TEST_TIME 80 9516
TEST_TIME 81 9460
TEST_TIME 82 9409
TEST_TIME 83 9444
TEST_TIME 84 9400
TEST_TIME 85 9372
TEST_TIME 86 9305
TEST_TIME 87 9288
TEST_TIME 88 9281
TEST_TIME 89 9335
TEST_TIME 90 9357
TEST_TIME 91 9341
TEST_TIME 92 9386
TEST_TIME 93 9383
TEST_TIME 94 9400
TEST_TIME 95 9445
TEST_TIME 96 9449
TEST_TIME 97 9456
TEST_TIME 98 9476
TEST_TIME 99 9457
TEST_TIME 100 9437
TEST_TIME 101 9490
TEST_TIME 102 9511
TEST_TIME 103 9506
TEST_TIME 104 9453
TEST_TIME 105 9446
TEST_TIME 106 9430
TEST_TIME 107 9394
TEST_TIME 108 9338
TEST_TIME 109 9361
TEST_TIME 110 9304
TEST_TIME 111 9272
TEST_TIME 112 9263
TEST_TIME 113 9304
TEST_TIME 114 9328
TEST_TIME 115 9361
TEST_TIME 116 9343
TEST_TIME 117 9418
TEST_TIME 118 9389
TEST_TIME 119 9402
TEST_TIME 120 9343

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 07-01-2022 19:39:03 to 07-01-2022 19:39:07.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1563974 1563974 0 0.00 0 23027 6 0 0
doc_list_biud 1563974 1563974 0 0.00 0 54198 26 0 0
sp_add_invoice_to_stock 5402 5264 138 2.55 12 163316 17724 0 138
sp_cancel_adding_invoice 5581 5581 0 0.00 5 57884 763 0 0
sp_cancel_client_order 1827 1827 0 0.00 4 7603 322 0 0
sp_cancel_customer_reserve 552771 552771 0 0.00 1 54199 58 0 0
sp_cancel_pay_from_customer 693 693 0 0.00 2 9757 91 0 0
sp_cancel_pay_to_supplier 438 438 0 0.00 2 1111 56 0 0
sp_cancel_supplier_invoice 6273 6264 9 0.14 2 14616 826 0 9
sp_cancel_supplier_order 408 408 0 0.00 5 1062605 100534 0 0
sp_cancel_write_off 8591 8591 0 0.00 1 9422 110 0 0
sp_client_order 21383 21383 0 0.00 3 25952 347 0 0
sp_customer_reserve 480218 480218 0 0.00 0 53045 178 0 0
sp_fill_shopping_cart 32890 32890 0 0.00 1 21015 37 0 0
sp_fill_shopping_cart_clo_res 480210 480210 0 0.00 0 17210 12 0 0
sp_get_clo_for_invoice 5253 5253 0 0.00 0 19936 279 0 0
sp_kill_cost_storno 15303 15303 0 0.00 0 8604 55 0 0
sp_kill_qstorno_ret_qs2qd 1120805 1120805 0 0.00 0 54196 32 0 0
sp_kill_qty_storno 591038 591038 0 0.00 0 54197 68 0 0
sp_lock_selected_doc 21539 21539 0 0.00 0 6440 6 0 0
sp_make_cost_storno 28745 28745 0 0.00 0 11644 53 0 0
sp_multiply_rows_for_pdistr 28745 28745 0 0.00 0 12423 33 0 0
sp_multiply_rows_for_qdistr 21383 21383 0 0.00 0 17945 156 0 0
sp_pay_from_customer 7128 7128 0 0.00 4 13805 108 0 0
sp_pay_to_supplier 6021 6021 0 0.00 4 13279 143 0 0
sp_payment_common 13149 13149 0 0.00 1 8686 29 0 0
sp_qd_handle_on_cancel_clo 1827 1827 0 0.00 0 7596 298 0 0
sp_qd_handle_on_invoice_upd_sts 10832 10832 0 0.00 0 25573 308 0 0
sp_qd_handle_on_reserve_upd_sts 10345 10345 0 0.00 0 17695 51 0 0
sp_reserve_write_off 10345 10345 0 0.00 6 17742 169 0 0
sp_supplier_invoice 5757 5757 0 0.00 7 88356 3387 0 0
sp_supplier_order 5755 5755 0 0.00 6 26626 1234 0 0
srv_aggregate_perf_data 116 116 0 0.00 2208 425041 18568 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 993 161751 47661 0 0
srv_find_qd_qs_mism 1070370 1070370 0 0.00 0 23026 6 0 0
srv_make_invnt_saldo 604 604 0 0.00 31 58990 925 0 0
srv_make_money_saldo 809 809 0 0.00 0 4700 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 180877 415567 278739 0 0
t$perf-abend:sp_add_invoice_to_stock 138 138 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5264 5264 0 0.00 0 17553 185 0 0
t$perf-norm:sp_cancel_adding_invoice 416 416 0 0.00 0 19700 134 0 0
t$perf-norm:sp_cancel_client_order 1827 1827 0 0.00 0 11355 22 0 0
t$perf-norm:sp_cancel_customer_reserve 696 696 0 0.00 0 4472 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 693 693 0 0.00 0 2356 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 438 438 0 0.00 0 5367 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 411 411 0 0.00 0 2861 15 0 0
t$perf-norm:sp_cancel_supplier_order 408 408 0 0.00 0 27646 1787 0 0
t$perf-norm:sp_cancel_write_off 1054 1054 0 0.00 0 2900 7 0 0
t$perf-norm:sp_client_order 21383 21383 0 0.00 0 12364 21 0 0
t$perf-norm:sp_customer_reserve 1007 1007 0 0.00 0 1993 9 0 0
t$perf-norm:sp_pay_from_customer 7128 7128 0 0.00 0 11043 10 0 0
t$perf-norm:sp_pay_to_supplier 6021 6021 0 0.00 0 9004 15 0 0
t$perf-norm:sp_reserve_write_off 10345 10345 0 0.00 0 9012 11 0 0
t$perf-norm:sp_supplier_invoice 5757 5757 0 0.00 0 11765 24 0 0
t$perf-norm:sp_supplier_order 5755 5755 0 0.00 0 8457 17 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 72 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 4509 74 0 0
t$perf-norm:srv_make_invnt_saldo 604 604 0 0.00 0 3246 27 0 0
t$perf-norm:srv_make_money_saldo 809 809 0 0.00 0 805 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10778 10502 276 2.56 0 4993 16 0 276
v_all_customers 37826 37826 0 0.00 0 16 1 0 0
v_all_suppliers 31432 31432 0 0.00 0 18 1 0 0
v_all_wares 42766 42766 0 0.00 0 36 1 0 0
v_cancel_adding_invoice 832 832 0 0.00 0 1927 12 0 0
v_cancel_client_order 3654 3654 0 0.00 0 1130 3 0 0
v_cancel_customer_prepayment 1386 1386 0 0.00 0 1532 5 0 0
v_cancel_customer_reserve 1392 1392 0 0.00 0 591 3 0 0
v_cancel_payment_to_supplier 876 876 0 0.00 0 359 3 0 0
v_cancel_supplier_invoice 840 822 18 2.14 0 2193 15 0 18
v_cancel_supplier_order 816 816 0 0.00 0 360 3 0 0
v_cancel_write_off 2108 2108 0 0.00 0 4153 12 0 0
v_max_id_clo_ord 5755 5755 0 0.00 0 2410 2 0 0
v_max_id_clo_res 1007 1007 0 0.00 0 2924 12 0 0
v_max_id_ord_sup 5752 5752 0 0.00 0 8412 6 0 0
v_max_non_paid_invoice 1812 1812 0 0.00 0 6204 8 0 0
v_max_non_paid_realizn 4212 4212 0 0.00 0 1045 3 0 0
v_min_id_clo_ord 5755 5755 0 0.00 0 2258 4 0 0
v_min_id_clo_res 1007 1007 0 0.00 0 5314 19 0 0
v_min_id_ord_sup 5752 5752 0 0.00 0 4921 5 0 0
v_min_non_paid_invoice 6021 6021 0 0.00 0 8039 25 0 0
v_min_non_paid_realizn 7128 7128 0 0.00 0 6667 18 0 0
v_our_firm 10772 10772 0 0.00 0 19 1 0 0
v_reserve_write_off 20690 20690 0 0.00 0 5115 4 0 0
x_lock_depdocs_on_canc_invoice 11434 11434 0 0.00 0 30644 131 0 0
x_lock_depdocs_on_canc_sup_ord 408 408 0 0.00 0 15859 419 0 0
x_make_qstorno_1000_1200 5755 5755 0 0.00 2 26614 1159 0 0
x_make_qstorno_1200_2000 5757 5757 0 0.00 2 85527 3246 0 0
x_make_qstorno_2100_3300 480218 480218 0 0.00 0 53044 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-01-2022 19:39:08 to 07-01-2022 19:39:12.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
17:08:10 13 8192000000 8810324016 8825446400 14638720 0.178 4 3 4 0 14824080 78240 107120
17:09:10 15940 8192000000 9909632784 10222383104 992100944 10.802 100 87 174 0 1021043200 5955312 22986944
17:10:37 21055 8192000000 9954495584 10278342656 1038333856 11.249 100 88 177 0 1069381888 8705584 22342448
17:12:14 14688 8192000000 9982437536 10320265216 1071021968 11.562 100 82 164 0 1101393440 9745040 20626432
17:13:40 13519 8192000000 10040526784 10390212608 1122241424 12.048 100 77 154 2 1151427536 11148688 18037424
17:14:58 8812 8192000000 10093208000 10456322048 1177939872 12.571 100 70 142 0 1205326928 11303808 16083248
17:16:09 6349 8192000000 10135936496 10504171520 1221659856 12.977 100 84 169 0 1254121808 13008192 19453760
17:17:18 3232 8192000000 10162309456 10538659840 1247054624 13.211 100 78 154 3 1277322528 13258288 17009616
17:18:24 1656 8192000000 10199171312 10579058688 1283620224 13.546 100 93 187 1 1318261408 13915088 20726096
17:19:27 18218 8192000000 10204840096 10594807808 1299317088 13.689 100 65 130 1 1323303312 9680832 14305392
17:20:50 10168 8192000000 10195843920 10606686208 1304156528 13.733 100 29 58 1 1321631120 10713600 6760992
17:22:04 7180 8192000000 10200760080 10612867072 1313621968 13.819 100 27 54 0 1326093216 6808464 5662784
17:23:13 46309 8192000000 10222656960 10632216576 1315758176 13.838 100 41 82 1 1332559824 7421152 9380496
17:25:03 6743 8192000000 10214642752 10636730368 1315200016 13.833 99 25 50 0 1326677824 5551200 5926608
17:26:11 10971 8192000000 10229348976 10647736320 1333161008 13.996 100 26 53 1 1345846048 6551472 6133568
17:27:24 7210 8192000000 10217586784 10637025280 1316307024 13.843 99 24 48 1 1327312144 5470448 5534672
17:28:31 22449 8192000000 10200226336 10610008064 1291031104 13.614 99 53 105 2 1310312592 5382336 13899152
17:29:59 6151 8192000000 10177628352 10583580672 1285126896 13.560 100 20 40 0 1294659056 5226624 4305536
17:31:11 13337 8192000000 10128201200 10526928896 1173191200 12.527 94 19 38 1 1182887088 5118368 4577520
17:32:28 10291 8192000000 10151767360 10550075392 1223829248 12.997 97 26 52 1 1239979776 9725856 6424672
17:33:38 23564 8192000000 10158233712 10563751936 1254327264 13.278 99 32 64 1 1267985648 6593744 7064640
17:35:03 7937 8192000000 10169092752 10582065152 1281861616 13.530 100 20 40 0 1292992944 6194736 4936592
17:36:11 9077 8192000000 10189854496 10600730624 1292555552 13.628 100 35 68 1 1306844960 5038304 9251104
17:37:21 70247 8192000000 10213480864 10621009920 1309225648 13.779 100 28 56 1 1321463072 5864512 6372912
17:39:54 9524 8192000000 10244743104 10653261824 1336814384 14.029 100 39 76 0 1353485888 8670864 8000640
17:41:10 16444 8192000000 10267296384 10688294912 1360046416 14.238 100 44 88 0 1381036304 10671248 10318640
17:42:30 5550 8192000000 10291388448 10707492864 1376881296 14.389 100 55 108 0 1401538992 12035936 12621760
17:43:42 17700 8192000000 10300833040 10722988032 1388973536 14.497 100 52 104 2 1414077168 12873616 12230016
17:45:12 12995 8192000000 10305002192 10731216896 1392836080 14.531 100 43 86 2 1420307072 16996976 10474016
17:46:35 12113 8192000000 10301736448 10721517568 1378934592 14.407 99 46 92 1 1406438784 16704432 10799760
17:47:53 9374 8192000000 10316228368 10736467968 1370987056 14.336 98 62 122 1 1410379792 24105488 15287248
17:49:14 4186 8192000000 10338576864 10757926912 1402797104 14.620 100 63 126 1 1441089216 23371888 14920224
17:50:21 5856 8192000000 10351454496 10776809472 1413336096 14.714 100 69 138 2 1464953248 34064752 17552400
17:51:33 2981 8192000000 10356068304 10772451328 1410231952 14.686 100 73 146 2 1462049424 34432960 17384512
17:52:40 8089 8192000000 10334902672 10758021120 1396928592 14.568 100 50 98 2 1440117424 30837616 12351216
17:53:55 6074 8192000000 10348740704 10767491072 1401764800 14.611 100 71 136 1 1453314512 33411056 18138656
17:55:08 3919 8192000000 10336737184 10741764096 1368667472 14.315 98 86 172 4 1430880192 40401936 21810784
17:56:18 2329 8192000000 10346126608 10744037376 1373865536 14.362 100 93 186 0 1444861184 47960688 23034960
17:57:28 5101 8192000000 10334303280 10753945600 1391552032 14.520 100 51 102 1 1441299296 36500208 13247056
17:58:43 991 8192000000 10352141360 10763304960 1395473936 14.555 100 80 154 1 1457521200 41800272 20246992
17:59:50 2430 8192000000 10349374208 10758918144 1391015984 14.515 100 68 136 1 1449701744 41429264 17256496
18:00:58 9477 8192000000 10342606208 10766241792 1380901344 14.425 99 40 80 2 1438692064 46777152 11013568
18:02:16 12531 8192000000 10325445152 10749202432 1380294800 14.419 100 34 68 1 1431701648 42008256 9398592
18:03:38 30729 8192000000 10300944112 10731978752 1345021392 14.103 99 27 54 0 1407639520 54280080 8338048
18:05:11 10095 8192000000 10302650480 10729795584 1335578784 14.018 98 38 77 0 1385231216 39557392 10095040
18:06:23 79550 8192000000 10300012752 10740682752 1377761440 14.397 100 40 76 4 1404441744 17320704 9359600
18:08:44 42937 8192000000 10274277296 10727104512 1350420960 14.151 99 15 30 0 1369124000 15406944 3296096
18:10:27 89651 8192000000 10292645488 10741280768 1368122768 14.310 99 27 53 2 1385168464 11539248 5506448
18:12:58 161750 8192000000 10278588624 10722570240 1342807920 14.083 97 7 14 1 1356781872 12770000 1203952
18:16:41 44657 8192000000 10307012576 10741821440 1384095936 14.453 100 39 76 2 1401989984 9201808 8692240
18:18:42 115683 8192000000 10298757104 10739326976 1356036432 14.202 98 12 24 0 1370610688 12026528 2547728
18:21:38 143192 8192000000 10295144128 10738462720 1329347088 13.961 96 7 14 0 1346464640 15896544 1221008
18:25:29 83325 8192000000 10303308672 10744524800 1379800736 14.415 99 36 72 0 1396454704 8271072 8382896
18:28:05 61964 8192000000 10312422448 10753953792 1396938816 14.568 100 47 90 2 1416149920 8875728 10335376
18:30:08 12818 8192000000 10307513552 10754945024 1388396720 14.492 99 47 91 2 1406956208 7608000 10951488
18:31:28 51724 8192000000 10310275872 10759995392 1388362096 14.491 99 19 37 2 1403677376 10615520 4699760
18:33:25 95206 8192000000 10299073184 10757423104 1378822192 14.406 98 13 25 1 1395166368 12651968 3692208
18:36:08 61218 8192000000 10260341744 10709942272 1312123456 13.805 95 20 40 1 1330904304 13709760 5071088
18:38:16 41869 8192000000 10261899888 10725576704 1359624160 14.234 100 11 22 1 1377735680 15083712 3027808
18:40:04 48963 8192000000 10273302304 10734047232 1354415904 14.187 99 9 18 0 1369240064 12998032 1826128
18:41:53 57898 8192000000 10276890624 10734481408 1348223744 14.131 98 17 34 0 1365446688 13694624 3528320
18:43:54 52734 8192000000 10286456224 10732548096 1332036768 13.986 96 17 34 1 1347522512 11962960 3522784
18:45:51 85497 8192000000 10285999248 10756259840 1364788656 14.280 99 26 52 0 1392373536 21530880 6054000
18:48:32 44588 8192000000 10285535472 10736590848 1351321744 14.159 98 28 56 3 1372755664 15570016 5863904
18:50:18 56071 8192000000 10260011264 10703130624 1297283968 13.671 95 20 40 1 1312102896 10480656 4338272
18:52:18 72139 8192000000 10268172832 10710781952 1333347456 13.997 98 23 45 1 1350720576 12430704 4942416
18:54:34 78843 8192000000 10263438192 10720325632 1338329136 14.042 98 16 32 0 1352874160 10658128 3886896
18:56:59 54633 8192000000 10266048928 10718007296 1364515296 14.278 100 26 52 1 1377999280 7290528 6193456
18:59:26 55265 8192000000 10260608224 10712887296 1351625008 14.162 99 12 24 0 1361864144 7549888 2689248
19:01:21 67594 8192000000 10251656912 10712719360 1353889072 14.182 99 7 14 0 1365438976 9539440 2010464
19:03:39 15143 8192000000 10248215248 10708557824 1344727504 14.100 99 14 28 2 1356256832 7836816 3692512
19:04:55 93395 8192000000 10266310032 10740744192 1373485104 14.358 100 15 30 0 1391461568 14615232 3361232
19:07:30 28238 8192000000 10265761536 10720788480 1341993488 14.075 98 31 58 1 1360242752 11024736 7224528
19:09:48 75215 8192000000 10271791472 10735128576 1366945984 14.300 99 19 38 0 1384018992 12726992 4346016
19:12:04 56401 8192000000 10297496240 10755387392 1366966160 14.300 98 17 34 0 1381802912 11419824 3416928
19:14:04 71834 8192000000 10308468032 10754961408 1383174240 14.445 99 32 64 2 1399970704 9222448 7574016
19:16:20 53238 8192000000 10295679952 10746290176 1374898512 14.371 99 17 34 0 1386606672 8010080 3698080
19:18:18 12270 8192000000 10298281104 10740146176 1369410288 14.322 99 36 72 2 1385736000 8937936 7387776
19:19:34 65639 8192000000 10296978480 10749902848 1377519232 14.394 99 12 24 0 1388468336 8704272 2244832
19:23:14 88776 8192000000 10263803104 10733002752 1347953152 14.129 98 16 32 1 1366300192 14933392 3413648
19:25:43 56963 8192000000 10258406192 10717339648 1341473248 14.071 98 12 24 0 1356887520 13028448 2385824
19:28:04 95496 8192000000 10282943120 10739810304 1329601088 13.964 96 16 32 0 1346961344 13587296 3772960
19:30:42 68399 8192000000 10262529216 10723561472 1304266928 13.734 95 21 43 2 1324215632 14991456 4957248
19:32:52 100997 8192000000 10287728240 10752552960 1362669024 14.261 99 15 30 0 1382381136 16144880 3567232
19:35:34 75812 8192000000 10284127632 10723926016 1328763696 13.956 97 19 38 0 1351166112 18201728 4200688
19:37:51 26288 8192000000 9083059744 9312727040 235797728 2.797 18 4 8 0 240913168 4278912 836528

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 07-01-2022 19:39:14 to 07-01-2022 19:39:28.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 138 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 07-01-2022 19:39:29 to 07-01-2022 19:39:31.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          245618
MON$OLDEST_ACTIVE               245619
MON$OLDEST_SNAPSHOT             245619
MON$NEXT_TRANSACTION            245619
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-03 16:41:55.3470
MON$PAGES                       2036448
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33548 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33548 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33548 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33548 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:603: 
:604: mscs-test-161.budzdorov.ru    Fri Jan  7 19:38:26 2022
:605:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:606: 
:607: 
:608: mscs-test-161.budzdorov.ru    Fri Jan  7 19:38:26 2022
:609:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:610: 
:611: 
:612: mscs-test-161.budzdorov.ru    Fri Jan  7 19:38:26 2022
:613:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:614: 
--- end of diff output ---

Final processing ISQL logs in /var/tmp/logs-oltp30 according to config parameter 'remove_isql_logs'

07.01.22 19:39:32. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-01-07 19:39:35 No severe PSQL-related problems occured
07.01.22 19:39:36. End of report.