Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.05.2022 13: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 16024048 kB
MemAvailable 31398564 kB
Buffers 0 kB
Cached 15709656 kB
SwapCached 2440 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 4.0.2
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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-05-30 13:37:15
DTS_END                         2022-05-30 13:37:15.0970
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_CLIENT_ORDER(32:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/60128
TRN_ID                          216059
ATT_ID                          582
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: 9077.70 24063 1089324
customer order: creation 173.21 486 20785
customer order: refuse 14.24 492 1709
order to supplier: creation 45.69 1376 5483
order to supplier: removal 3.52 182050 422
invoice (draft): creation 46.53 3478 5584
invoice (draft): removal 51.03 1127 6123
invoice accept: apply 41.57 26496 4988
invoice accept: cancel 46.49 1425 5579
customer reserve: creation 3826.33 250 459159
customer reserve: removal 4531.16 110 543739
realization accept: apply 82.87 330 9944
realization accept: cancel 84.58 159 10149
payment from customer: creation 59.59 197 7151
payment from customer: removal 5.48 175 658
payment to supplier: creation 48.53 239 5824
payment to supplier: removal 3.71 132 445
service: total inventory turnovers 4.03 4539 483
service: total monetary turnovers 8.18 430 982
service: aggregate perf. data 0.94 17610 113
service: refresh index statistics 0.03 240167 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 30-05-2022 13:39:06 to 30-05-2022 13:39:08.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8293
TEST_TIME 2 6829
TEST_TIME 3 7084
TEST_TIME 4 6884
TEST_TIME 5 6652
TEST_TIME 6 6329
TEST_TIME 7 6208
TEST_TIME 8 6372
TEST_TIME 9 6528
TEST_TIME 10 6697
TEST_TIME 11 6921
TEST_TIME 12 7067
TEST_TIME 13 7381
TEST_TIME 14 7459
TEST_TIME 15 7325
TEST_TIME 16 7296
TEST_TIME 17 7289
TEST_TIME 18 7466
TEST_TIME 19 7546
TEST_TIME 20 7732
TEST_TIME 21 7723
TEST_TIME 22 7701
TEST_TIME 23 7862
TEST_TIME 24 7856
TEST_TIME 25 7949
TEST_TIME 26 8116
TEST_TIME 27 8412
TEST_TIME 28 8442
TEST_TIME 29 8538
TEST_TIME 30 8770
TEST_TIME 31 8899
TEST_TIME 32 9369
TEST_TIME 33 9591
TEST_TIME 34 9758
TEST_TIME 35 10077
TEST_TIME 36 10088
TEST_TIME 37 10452
TEST_TIME 38 10433
TEST_TIME 39 10756
TEST_TIME 40 10719
TEST_TIME 41 10702
TEST_TIME 42 10603
TEST_TIME 43 10510
TEST_TIME 44 10430
TEST_TIME 45 10336
TEST_TIME 46 10141
TEST_TIME 47 10060
TEST_TIME 48 10055
TEST_TIME 49 10109
TEST_TIME 50 9967
TEST_TIME 51 9911
TEST_TIME 52 9837
TEST_TIME 53 9842
TEST_TIME 54 9894
TEST_TIME 55 9862
TEST_TIME 56 9774
TEST_TIME 57 9709
TEST_TIME 58 9668
TEST_TIME 59 9616
TEST_TIME 60 9618
TEST_TIME 61 9632
TEST_TIME 62 9598
TEST_TIME 63 9660
TEST_TIME 64 9647
TEST_TIME 65 9723
TEST_TIME 66 9712
TEST_TIME 67 9660
TEST_TIME 68 9650
TEST_TIME 69 9706
TEST_TIME 70 9726
TEST_TIME 71 9682
TEST_TIME 72 9688
TEST_TIME 73 9730
TEST_TIME 74 9650
TEST_TIME 75 9562
TEST_TIME 76 9539
TEST_TIME 77 9489
TEST_TIME 78 9477
TEST_TIME 79 9435
TEST_TIME 80 9369
TEST_TIME 81 9393
TEST_TIME 82 9349
TEST_TIME 83 9271
TEST_TIME 84 9271
TEST_TIME 85 9222
TEST_TIME 86 9216
TEST_TIME 87 9231
TEST_TIME 88 9237
TEST_TIME 89 9237
TEST_TIME 90 9276
TEST_TIME 91 9321
TEST_TIME 92 9323
TEST_TIME 93 9350
TEST_TIME 94 9423
TEST_TIME 95 9369
TEST_TIME 96 9421
TEST_TIME 97 9458
TEST_TIME 98 9399
TEST_TIME 99 9342
TEST_TIME 100 9372
TEST_TIME 101 9374
TEST_TIME 102 9386
TEST_TIME 103 9347
TEST_TIME 104 9352
TEST_TIME 105 9335
TEST_TIME 106 9355
TEST_TIME 107 9375
TEST_TIME 108 9372
TEST_TIME 109 9351
TEST_TIME 110 9350
TEST_TIME 111 9373
TEST_TIME 112 9318
TEST_TIME 113 9258
TEST_TIME 114 9224
TEST_TIME 115 9218
TEST_TIME 116 9189
TEST_TIME 117 9177
TEST_TIME 118 9143
TEST_TIME 119 9172
TEST_TIME 120 9107

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 30-05-2022 13:39:10 to 30-05-2022 13:39:13.

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 1512596 1512596 0 0.00 0 15069 14 0 0
doc_list_biud 1512587 1512587 0 0.00 0 41849 47 0 0
sp_add_invoice_to_stock 5139 4988 151 2.93 72 183235 26496 0 151
sp_cancel_adding_invoice 5579 5579 0 0.00 8 88092 1426 0 0
sp_cancel_client_order 1709 1709 0 0.00 6 28100 493 0 0
sp_cancel_customer_reserve 543739 543739 0 0.00 1 41769 111 0 0
sp_cancel_pay_from_customer 658 658 0 0.00 4 7125 176 0 0
sp_cancel_pay_to_supplier 445 445 0 0.00 4 4268 132 0 0
sp_cancel_supplier_invoice 6136 6123 13 0.21 3 41855 1127 0 13
sp_cancel_supplier_order 422 422 0 0.00 20 1606377 182051 0 0
sp_cancel_write_off 10149 10149 0 0.00 2 11670 160 0 0
sp_client_order 20785 20785 0 0.00 6 39061 486 0 0
sp_customer_reserve 459159 459159 0 0.00 1 102222 251 0 0
sp_fill_shopping_cart 31836 31836 0 0.00 1 9883 87 0 0
sp_fill_shopping_cart_clo_res 459122 459122 0 0.00 0 21193 18 0 0
sp_get_clo_for_invoice 4942 4942 0 0.00 0 25826 239 0 0
sp_kill_cost_storno 16831 16831 0 0.00 0 8312 80 0 0
sp_kill_qstorno_ret_qs2qd 1104172 1104172 0 0.00 0 41848 48 0 0
sp_kill_qty_storno 582606 582606 0 0.00 0 41849 112 0 0
sp_lock_selected_doc 20602 20602 0 0.00 0 11027 9 0 0
sp_make_cost_storno 27860 27860 0 0.00 0 14170 95 0 0
sp_multiply_rows_for_pdistr 27859 27859 0 0.00 0 8494 44 0 0
sp_multiply_rows_for_qdistr 20785 20785 0 0.00 0 9290 172 0 0
sp_pay_from_customer 7151 7151 0 0.00 6 22303 197 0 0
sp_pay_to_supplier 5824 5824 0 0.00 7 22484 240 0 0
sp_payment_common 12974 12974 0 0.00 2 8199 51 0 0
sp_qd_handle_on_cancel_clo 1709 1709 0 0.00 1 28089 412 0 0
sp_qd_handle_on_invoice_upd_sts 10521 10521 0 0.00 0 25024 626 0 0
sp_qd_handle_on_reserve_upd_sts 9943 9943 0 0.00 0 34391 70 0 0
sp_reserve_write_off 9944 9944 0 0.00 9 34499 330 0 0
sp_supplier_invoice 5584 5584 0 0.00 9 99671 3479 0 0
sp_supplier_order 5483 5483 0 0.00 7 44661 1376 0 0
srv_aggregate_perf_data 113 113 0 0.00 2541 212785 17610 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 844 211112 34633 0 0
srv_find_qd_qs_mism 1040576 1040576 0 0.00 0 15066 10 0 0
srv_make_invnt_saldo 483 483 0 0.00 63 107226 4540 0 0
srv_make_money_saldo 982 982 0 0.00 2 25571 430 0 0
srv_recalc_idx_stat 4 4 0 0.00 78515 387157 240168 0 0
t$perf-abend:sp_add_invoice_to_stock 151 151 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4992 4992 0 0.00 1 32493 1172 0 0
t$perf-norm:sp_cancel_adding_invoice 426 426 0 0.00 1 6317 514 0 0
t$perf-norm:sp_cancel_client_order 1709 1709 0 0.00 0 2413 30 0 0
t$perf-norm:sp_cancel_customer_reserve 676 676 0 0.00 0 3716 38 0 0
t$perf-norm:sp_cancel_pay_from_customer 658 658 0 0.00 0 587 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 445 445 0 0.00 0 385 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 369 369 0 0.00 0 852 35 0 0
t$perf-norm:sp_cancel_supplier_order 422 422 0 0.00 0 105429 8641 0 0
t$perf-norm:sp_cancel_write_off 1015 1015 0 0.00 0 3834 25 0 0
t$perf-norm:sp_client_order 20785 20785 0 0.00 0 6072 41 0 0
t$perf-norm:sp_customer_reserve 1014 1014 0 0.00 0 1072 18 0 0
t$perf-norm:sp_pay_from_customer 7151 7151 0 0.00 0 4849 20 0 0
t$perf-norm:sp_pay_to_supplier 5824 5824 0 0.00 0 8179 25 0 0
t$perf-norm:sp_reserve_write_off 9944 9944 0 0.00 0 12191 27 0 0
t$perf-norm:sp_supplier_invoice 5584 5584 0 0.00 0 2462 33 0 0
t$perf-norm:sp_supplier_order 5483 5483 0 0.00 0 7787 41 0 0
t$perf-norm:srv_aggregate_perf_data 113 113 0 0.00 0 3228 71 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 1340 40 0 0
t$perf-norm:srv_make_invnt_saldo 483 483 0 0.00 0 6982 31 0 0
t$perf-norm:srv_make_money_saldo 982 982 0 0.00 0 3556 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 2876 775 0 0
v_add_invoice_to_stock 10180 9878 302 2.96 0 16698 30 0 302
v_all_customers 38258 38258 0 0.00 0 67 4 0 0
v_all_suppliers 30744 30744 0 0.00 0 151 5 0 0
v_all_wares 41559 41559 0 0.00 0 62 4 0 0
v_cancel_adding_invoice 852 852 0 0.00 0 2549 22 0 0
v_cancel_client_order 3418 3418 0 0.00 0 2657 10 0 0
v_cancel_customer_prepayment 1316 1316 0 0.00 0 2319 17 0 0
v_cancel_customer_reserve 1352 1352 0 0.00 1 17744 37 0 0
v_cancel_payment_to_supplier 890 890 0 0.00 0 4212 16 0 0
v_cancel_supplier_invoice 764 738 26 3.40 0 687 24 0 26
v_cancel_supplier_order 844 844 0 0.00 0 1257 12 0 0
v_cancel_write_off 2030 2030 0 0.00 0 5046 30 0 0
v_max_id_clo_ord 5474 5474 0 0.00 0 276 5 0 0
v_max_id_clo_res 1014 1014 0 0.00 0 4318 24 0 0
v_max_id_ord_sup 5576 5576 0 0.00 0 2792 6 0 0
v_max_non_paid_invoice 1500 1500 0 0.00 0 54 8 0 0
v_max_non_paid_realizn 3581 3581 0 0.00 0 2430 9 0 0
v_min_id_clo_ord 5474 5474 0 0.00 0 1551 8 0 0
v_min_id_clo_res 1014 1014 0 0.00 0 3206 14 0 0
v_min_id_ord_sup 5576 5576 0 0.00 0 5515 9 0 0
v_min_non_paid_invoice 5823 5823 0 0.00 0 8193 18 0 0
v_min_non_paid_realizn 7151 7151 0 0.00 0 6484 20 0 0
v_our_firm 10438 10438 0 0.00 0 61 5 0 0
v_reserve_write_off 19886 19886 0 0.00 0 10106 15 0 0
x_lock_depdocs_on_canc_invoice 11333 11333 0 0.00 0 25539 126 0 0
x_lock_depdocs_on_canc_sup_ord 422 422 0 0.00 0 13097 392 0 0
x_make_qstorno_1000_1200 5483 5483 0 0.00 2 44647 1176 0 0
x_make_qstorno_1200_2000 5584 5584 0 0.00 4 99656 3264 0 0
x_make_qstorno_2100_3300 459159 459159 0 0.00 0 102215 218 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-05-2022 13:39:14 to 30-05-2022 13:39:18.

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
11:07:18 23 8192000000 8805581376 8819630080 9664160 0.117 4 2 2 0 9765648 65712 35776
11:08:20 23490 8192000000 9740590176 10042773504 819618128 9.095 100 85 170 0 847512736 7678144 20216464
11:09:50 16274 8192000000 9763913120 10072530944 842703312 9.327 100 93 186 0 877502592 12932608 21866672
11:11:12 16366 8192000000 9799460592 10115428352 869119552 9.591 100 91 182 0 909595968 19725568 20750848
11:12:34 15853 8192000000 9853317584 10189549568 905110752 9.949 100 81 162 0 967215376 43441696 18662928
11:13:52 4220 8192000000 9920577488 10259329024 949195984 10.383 100 86 168 1 1034654304 67649920 17808400
11:15:00 3752 8192000000 9951654768 10302685184 981982896 10.703 100 83 164 1 1064363024 65379424 17000704
11:16:08 5024 8192000000 9988980528 10353238016 1011984560 10.995 100 79 154 0 1104602176 77318064 15299552
11:17:17 1269 8192000000 9999919520 10369359872 1031982320 11.188 100 91 179 0 1125342496 75118704 18241472
11:18:21 885 8192000000 10014597792 10388512768 1052209056 11.382 100 94 176 0 1134927600 65312336 17406208
11:19:26 9472 8192000000 10029417856 10399866880 1063087200 11.486 100 94 181 2 1145485952 64267808 18130944
11:20:38 1502 8192000000 10068014608 10444697600 1070247680 11.554 100 95 183 0 1189661216 100231808 19181728
11:21:44 1408 8192000000 10046848512 10422992896 1072300176 11.574 100 94 179 1 1170199264 77690304 20208784
11:22:49 1110 8192000000 10077165536 10462691328 1081306704 11.660 100 93 183 1 1197120096 96860736 18952656
11:23:52 1140 8192000000 10044869264 10433024000 1095774192 11.798 100 97 176 2 1169955328 55079152 19101984
11:24:56 1761 8192000000 10079590176 10465464320 1099934832 11.837 100 98 192 1 1207923760 87642512 20346416
11:26:02 1404 8192000000 10063841664 10452860928 1102638576 11.863 100 93 175 1 1183243136 63337408 17267152
11:27:07 1412 8192000000 10073167904 10460016640 1103917184 11.875 100 99 191 0 1195339264 72114112 19307968
11:28:11 1163 8192000000 10071370160 10463694848 1101690080 11.854 100 100 193 1 1192707840 71180336 19837424
11:29:14 1454 8192000000 10075185024 10463969280 1103792016 11.874 100 98 185 1 1195721504 73009168 18920320
11:30:17 3479 8192000000 10072160032 10464567296 1095574128 11.796 100 95 187 3 1198002912 83168608 19260176
11:31:22 1224 8192000000 10059640848 10445385728 1083295840 11.679 100 94 187 0 1180584400 78725632 18562928
11:32:26 2350 8192000000 10048543168 10439176192 1086052048 11.705 100 78 150 2 1175088624 73995040 15041536
11:33:33 985 8192000000 10025195456 10403229696 1059011680 11.447 100 92 180 1 1143122448 65879488 18231280
11:34:35 1076 8192000000 10007331440 10379272192 1053018704 11.390 100 88 173 2 1128668928 58278752 17371472
11:35:38 1205 8192000000 9994183616 10366230528 1030340816 11.172 99 95 181 2 1113282176 64771920 18169440
11:36:47 993 8192000000 10019442400 10391683072 1037566544 11.241 99 91 173 0 1139444096 83171584 18705968
11:37:56 2434 8192000000 9986225408 10356019200 1036457952 11.231 99 95 180 2 1104243920 48755920 19030048
11:39:06 2153 8192000000 10027533200 10406252544 1065785808 11.512 100 90 179 1 1146947216 63403968 17757440
11:40:15 1238 8192000000 10043607744 10425098240 1073300288 11.584 100 88 166 2 1170858544 77415072 20143184
11:41:21 4774 8192000000 10078338480 10465767424 1095350256 11.794 100 72 142 0 1197816208 87083888 15382064
11:42:29 8969 8192000000 10090026592 10471641088 1090415376 11.747 99 74 135 2 1206821152 102710272 13695504
11:43:48 5665 8192000000 10137994272 10536685568 1110839008 11.940 100 73 134 0 1252536448 127106752 14590688
11:45:02 2694 8192000000 10173523248 10569416704 1118352048 12.011 100 72 144 0 1290564784 156780176 15432560
11:46:07 3645 8192000000 10216692928 10620321792 1135073696 12.169 100 83 162 0 1335697296 183142640 17480960
11:47:16 1198 8192000000 10272677904 10665979904 1144042848 12.254 100 95 190 0 1385265104 220400000 20822256
11:48:20 1765 8192000000 10242380784 10653741056 1147769600 12.289 100 93 171 0 1359084784 190573824 20741360
11:49:26 1227 8192000000 10305019936 10701082624 1150089472 12.310 100 96 192 1 1413934240 242487024 21357744
11:50:31 1754 8192000000 10290429280 10730287104 1159499440 12.399 100 86 167 0 1407380560 227985136 19895984
11:51:37 2439 8192000000 10301026272 10706436096 1162546816 12.427 100 80 152 0 1410595776 231144896 16904064
11:52:48 1462 8192000000 10350885504 10755014656 1171408368 12.510 100 99 191 1 1463323904 268377280 23538256
11:53:54 1054 8192000000 10374116672 10795384832 1172937168 12.524 100 96 190 1 1484771568 289749712 22084688
11:54:58 945 8192000000 10407459632 10830876672 1177918144 12.571 100 99 188 0 1516789808 315203968 23667696
11:56:02 1044 8192000000 10426528528 10843836416 1183235648 12.620 100 93 180 1 1536613232 331717904 21659680
11:57:07 3892 8192000000 10426976256 10859048960 1186895424 12.654 100 70 136 1 1545141792 341183568 17062800
11:58:20 3135 8192000000 10446063376 10876125184 1191164736 12.694 100 77 148 0 1556706160 347969200 17572224
11:59:28 2599 8192000000 10468801232 10904784896 1174694432 12.541 100 88 176 0 1577138816 382045856 20398528
12:00:33 3068 8192000000 10487193648 10908131328 1175185056 12.545 100 96 189 2 1592471968 394733600 22553312
12:01:42 1443 8192000000 10509904000 10953265152 1188604640 12.670 100 93 184 0 1616623888 406010080 22009168
12:02:47 843 8192000000 10521895936 10968014848 1185788128 12.644 100 90 178 0 1625759984 418690016 21281840
12:03:52 1026 8192000000 10505642288 10949144576 1179690016 12.587 100 90 170 2 1609463152 409684064 20089072
12:04:57 1300 8192000000 10525893072 10960875520 1190622336 12.689 100 80 161 0 1633766576 423047296 20096944
12:06:05 876 8192000000 10485476640 10933956608 1164753552 12.448 100 96 189 0 1591464672 404645920 22065200
12:07:08 2912 8192000000 10528158128 10985693184 1177283328 12.565 100 67 134 1 1631601808 437678320 16640160
12:08:46 2796 8192000000 10442364448 10939887616 1189910288 12.683 100 67 128 0 1566196128 360846560 15439280
12:09:52 3407 8192000000 10405083616 10885447680 1174363680 12.538 100 59 118 0 1510418704 321865168 14189856
12:11:01 18092 8192000000 10361660544 10826764288 1152161328 12.330 100 29 56 1 1460458816 299954656 8342832
12:12:21 13975 8192000000 10268433104 10716934144 1132919776 12.149 98 38 76 0 1350878496 208165312 9793408
12:13:43 34373 8192000000 10200830352 10700451840 1142598976 12.240 99 29 58 1 1361907296 211654176 7654144
12:15:29 103052 8192000000 10107473472 10578259968 1122779552 12.053 99 4 8 0 1204841120 81065136 996432
12:18:21 78557 8192000000 10063288304 10519728128 1107278112 11.907 97 28 54 0 1144781056 32779680 4723264
12:20:41 40315 8192000000 10123591824 10568380416 1144835744 12.261 100 21 39 0 1222618976 74020304 3762928
12:22:30 211110 8192000000 10120568992 10631335936 1077342272 11.622 94 18 36 0 1209956144 128573648 4040224
12:27:06 42264 8192000000 10092791888 10527338496 1125839920 12.082 99 42 78 1 1183655168 48265376 9549872
12:28:50 13089 8192000000 10142759040 10591191040 1142081632 12.235 100 31 60 0 1228242864 79683920 6477312
12:30:09 46751 8192000000 10153628064 10626514944 1128499472 12.107 98 49 96 1 1231785104 92292784 10992848
12:32:07 21034 8192000000 10069344384 10552430592 1134364576 12.162 100 13 26 0 1198538064 60814768 3358720
12:33:29 159141 8192000000 10106592352 10602102784 1141152672 12.226 100 10 19 0 1231427600 88265088 2009840
12:37:09 51631 8192000000 10111277728 10559283200 1144098592 12.254 99 11 19 2 1201948768 55612048 2238128
12:39:03 20014 8192000000 10108766848 10559549440 1134140320 12.160 98 24 48 0 1196426336 57612752 4673264
12:40:23 42219 8192000000 10117040720 10578100224 1116195312 11.991 97 18 36 2 1175979072 56184240 3599520
12:42:06 28079 8192000000 10076569344 10537066496 1135778864 12.176 99 29 56 1 1188133376 46064320 6290192
12:43:34 18076 8192000000 10100540032 10551005184 1129898384 12.120 99 46 92 0 1198359888 59410032 9051472
12:44:53 10865 8192000000 10121252016 10551496704 1135778368 12.176 100 53 102 0 1210720128 64593536 10348224
12:46:06 55127 8192000000 10113218464 10581680128 1113170640 11.962 98 44 85 2 1191101664 69395664 8535360
12:48:07 70588 8192000000 10142078512 10589851648 1137794336 12.195 100 36 72 1 1197168720 52114336 7260048
12:50:49 141602 8192000000 10112898176 10575159296 1127546960 12.098 99 15 30 0 1228347888 97278784 3522144
12:54:13 60027 8192000000 10082994880 10582257664 1118985584 12.017 98 15 30 0 1212695920 90383616 3326720
12:56:21 65160 8192000000 10058482480 10514518016 1085674784 11.702 96 16 31 0 1145549824 55949504 3925536
12:58:32 55572 8192000000 10059662080 10515501056 1098482864 11.823 98 14 28 0 1145130800 43629488 3018448
13:00:28 21294 8192000000 10118940720 10539909120 1091232144 11.754 96 60 114 2 1177091296 72016720 13842432
13:01:53 26153 8192000000 10145620608 10579451904 1125964256 12.083 100 39 75 0 1249503616 114573632 8965728
13:03:23 39753 8192000000 10201874688 10650451968 1130662448 12.128 99 44 86 1 1297919360 157340832 9916080
13:05:07 27476 8192000000 10176451728 10628337664 1121324368 12.040 98 34 68 0 1282637232 152970144 8342720
13:06:36 16224 8192000000 10239534576 10682490880 1133539424 12.155 98 45 90 0 1319244672 175273520 10431728
13:07:54 46687 8192000000 10212905968 10676133888 1150176064 12.311 99 31 61 2 1309983152 152501232 7305856
13:10:07 91722 8192000000 10206246304 10682503168 1137318592 12.190 98 24 48 1 1336731872 193397056 6016224
13:12:42 101883 8192000000 10069248128 10609598464 1119301680 12.020 98 26 50 1 1225466640 100701824 5463136
13:15:26 66477 8192000000 10113856384 10564972544 1092880976 11.770 95 16 31 0 1159356224 63503056 2972192
13:17:40 43907 8192000000 10120751920 10550927360 1120595120 12.033 98 37 75 0 1177763152 49273152 7894880
13:19:40 34514 8192000000 10099910192 10547339264 1135119104 12.170 99 37 72 1 1180812960 37433216 8260640
13:21:26 28098 8192000000 10107414704 10565795840 1145286480 12.265 100 14 28 0 1197010320 48495488 3228352
13:23:01 125520 8192000000 10069778624 10550870016 1083126912 11.677 94 22 44 0 1149525808 61668224 4730672
13:26:09 54969 8192000000 10086057216 10531635200 1121094720 12.037 97 21 40 1 1154287696 29267440 3925536
13:28:07 53362 8192000000 10088376112 10540789760 1145922384 12.271 100 28 45 2 1172551344 22473424 4155536
13:30:12 120860 8192000000 10063076128 10548531200 1071620256 11.568 93 22 44 0 1149501696 72693280 5188160
13:33:16 31359 8192000000 10111457808 10549329920 1142716288 12.241 100 52 104 1 1204441328 51309296 10415744
13:34:52 62309 8192000000 10114914800 10571821056 1126519360 12.089 98 25 50 1 1193714096 61711504 5483232
13:36:55 28265 8192000000 9488725664 9866285056 615416720 6.987 54 5 10 1 636611360 20528848 665792

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 30-05-2022 13:39:20 to 30-05-2022 13:39:36.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 151 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 30-05-2022 13:39:37 to 30-05-2022 13:39:39.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          216261
MON$OLDEST_ACTIVE               216262
MON$OLDEST_SNAPSHOT             216262
MON$NEXT_TRANSACTION            216262
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-30 07:30:08.6030 GMT
MON$PAGES                       2082784
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             764
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2770 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2770 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2770 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2770 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.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 ---
:228: 
:229: mscs-test-161.budzdorov.ru    Mon May 30 13:37:36 2022
:230:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:231: 
--- end of diff output ---

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

30.05.22 13:39:40. There are 3 logs in /var/tmp/logs-oltp40 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-05-30 13:39:43 No severe PSQL-related problems occured
30.05.22 13:39:44. End of report.