Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.06.2022 10: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 16128856 kB
MemAvailable 31223044 kB
Buffers 0 kB
Cached 15542460 kB
SwapCached 2448 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-06-16 10:37:17
DTS_END                         2022-06-16 10:37:17.9600
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/37468
TRN_ID                          222338
ATT_ID                          610
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: 8985.73 29539 1078288
customer order: creation 169.08 458 20290
customer order: refuse 15.22 397 1826
order to supplier: creation 45.07 1314 5408
order to supplier: removal 3.24 161722 389
invoice (draft): creation 45.75 3058 5490
invoice (draft): removal 50.58 855 6069
invoice accept: apply 41.63 27808 4996
invoice accept: cancel 45.38 1105 5446
customer reserve: creation 3881.98 266 465838
customer reserve: removal 4389.27 94 526713
realization accept: apply 82.21 264 9865
realization accept: cancel 86.13 174 10335
payment from customer: creation 58.37 180 7004
payment from customer: removal 5.83 135 699
payment to supplier: creation 50.53 179 6064
payment to supplier: removal 3.40 140 408
service: total inventory turnovers 3.70 4039 444
service: total monetary turnovers 7.51 343 901
service: aggregate perf. data 0.82 17236 98
service: refresh index statistics 0.04 371012 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-06-2022 10:38:27 to 16-06-2022 10:38:29.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7321
TEST_TIME 2 5444
TEST_TIME 3 4728
TEST_TIME 4 5452
TEST_TIME 5 5003
TEST_TIME 6 5200
TEST_TIME 7 5335
TEST_TIME 8 5736
TEST_TIME 9 6029
TEST_TIME 10 6403
TEST_TIME 11 6796
TEST_TIME 12 7128
TEST_TIME 13 7079
TEST_TIME 14 7332
TEST_TIME 15 7349
TEST_TIME 16 7384
TEST_TIME 17 7454
TEST_TIME 18 7429
TEST_TIME 19 7710
TEST_TIME 20 7977
TEST_TIME 21 8139
TEST_TIME 22 8592
TEST_TIME 23 8989
TEST_TIME 24 8880
TEST_TIME 25 8768
TEST_TIME 26 8991
TEST_TIME 27 9350
TEST_TIME 28 9655
TEST_TIME 29 9737
TEST_TIME 30 9693
TEST_TIME 31 10250
TEST_TIME 32 10745
TEST_TIME 33 11012
TEST_TIME 34 10889
TEST_TIME 35 10741
TEST_TIME 36 10723
TEST_TIME 37 10678
TEST_TIME 38 10623
TEST_TIME 39 10485
TEST_TIME 40 10303
TEST_TIME 41 10191
TEST_TIME 42 10040
TEST_TIME 43 9994
TEST_TIME 44 9990
TEST_TIME 45 9894
TEST_TIME 46 9897
TEST_TIME 47 9869
TEST_TIME 48 9820
TEST_TIME 49 9697
TEST_TIME 50 9741
TEST_TIME 51 9648
TEST_TIME 52 9795
TEST_TIME 53 9737
TEST_TIME 54 9616
TEST_TIME 55 9699
TEST_TIME 56 9708
TEST_TIME 57 9871
TEST_TIME 58 9849
TEST_TIME 59 9819
TEST_TIME 60 9799
TEST_TIME 61 9881
TEST_TIME 62 9819
TEST_TIME 63 9716
TEST_TIME 64 9713
TEST_TIME 65 9619
TEST_TIME 66 9534
TEST_TIME 67 9516
TEST_TIME 68 9453
TEST_TIME 69 9406
TEST_TIME 70 9330
TEST_TIME 71 9402
TEST_TIME 72 9337
TEST_TIME 73 9433
TEST_TIME 74 9404
TEST_TIME 75 9436
TEST_TIME 76 9404
TEST_TIME 77 9451
TEST_TIME 78 9498
TEST_TIME 79 9535
TEST_TIME 80 9511
TEST_TIME 81 9557
TEST_TIME 82 9552
TEST_TIME 83 9543
TEST_TIME 84 9526
TEST_TIME 85 9458
TEST_TIME 86 9475
TEST_TIME 87 9435
TEST_TIME 88 9409
TEST_TIME 89 9335
TEST_TIME 90 9270
TEST_TIME 91 9233
TEST_TIME 92 9249
TEST_TIME 93 9209
TEST_TIME 94 9190
TEST_TIME 95 9186
TEST_TIME 96 9166
TEST_TIME 97 9128
TEST_TIME 98 9185
TEST_TIME 99 9144
TEST_TIME 100 9149
TEST_TIME 101 9198
TEST_TIME 102 9215
TEST_TIME 103 9195
TEST_TIME 104 9364
TEST_TIME 105 9306
TEST_TIME 106 9242
TEST_TIME 107 9235
TEST_TIME 108 9207
TEST_TIME 109 9169
TEST_TIME 110 9142
TEST_TIME 111 9108
TEST_TIME 112 9049
TEST_TIME 113 9056
TEST_TIME 114 9064
TEST_TIME 115 9028
TEST_TIME 116 9011
TEST_TIME 117 9045
TEST_TIME 118 9044
TEST_TIME 119 9063
TEST_TIME 120 9009

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 16-06-2022 10:38:31 to 16-06-2022 10:38:34.

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 1506814 1506814 0 0.00 0 23243 14 0 0
doc_list_biud 1506814 1506814 0 0.00 0 27235 38 0 0
sp_add_invoice_to_stock 5166 4996 170 3.29 38 218200 27808 0 170
sp_cancel_adding_invoice 5446 5446 0 0.00 7 85367 1105 0 0
sp_cancel_client_order 1826 1826 0 0.00 6 16551 398 0 0
sp_cancel_customer_reserve 526713 526713 0 0.00 1 17050 95 0 0
sp_cancel_pay_from_customer 699 699 0 0.00 4 9453 135 0 0
sp_cancel_pay_to_supplier 408 408 0 0.00 3 4696 140 0 0
sp_cancel_supplier_invoice 6089 6069 20 0.32 4 21692 855 0 20
sp_cancel_supplier_order 389 389 0 0.00 14 1172310 161723 0 0
sp_cancel_write_off 10335 10335 0 0.00 2 23248 175 0 0
sp_client_order 20290 20290 0 0.00 5 16431 458 0 0
sp_customer_reserve 465838 465838 0 0.00 1 73602 266 0 0
sp_fill_shopping_cart 31181 31181 0 0.00 2 9357 77 0 0
sp_fill_shopping_cart_clo_res 465804 465804 0 0.00 0 14999 18 0 0
sp_get_clo_for_invoice 4947 4947 0 0.00 0 26122 245 0 0
sp_kill_cost_storno 16888 16888 0 0.00 0 23233 71 0 0
sp_kill_qstorno_ret_qs2qd 1070219 1070219 0 0.00 0 21646 41 0 0
sp_kill_qty_storno 565585 565585 0 0.00 0 27235 94 0 0
sp_lock_selected_doc 20579 20579 0 0.00 0 9606 7 0 0
sp_make_cost_storno 27875 27875 0 0.00 0 12689 75 0 0
sp_multiply_rows_for_pdistr 27875 27875 0 0.00 0 22649 39 0 0
sp_multiply_rows_for_qdistr 20290 20290 0 0.00 0 16099 168 0 0
sp_pay_from_customer 7004 7004 0 0.00 5 22673 180 0 0
sp_pay_to_supplier 6064 6064 0 0.00 7 8090 179 0 0
sp_payment_common 13068 13068 0 0.00 2 8550 41 0 0
sp_qd_handle_on_cancel_clo 1826 1826 0 0.00 1 16544 322 0 0
sp_qd_handle_on_invoice_upd_sts 10388 10388 0 0.00 0 10367 450 0 0
sp_qd_handle_on_reserve_upd_sts 9865 9865 0 0.00 0 27234 69 0 0
sp_reserve_write_off 9865 9865 0 0.00 8 27301 264 0 0
sp_supplier_invoice 5490 5490 0 0.00 10 102267 3058 0 0
sp_supplier_order 5408 5408 0 0.00 7 53010 1314 0 0
srv_aggregate_perf_data 98 98 0 0.00 3124 163006 17236 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 745 142649 34247 0 0
srv_find_qd_qs_mism 1029132 1029132 0 0.00 0 12183 12 0 0
srv_make_invnt_saldo 444 444 0 0.00 61 90718 4040 0 0
srv_make_money_saldo 901 901 0 0.00 1 8038 343 0 0
srv_recalc_idx_stat 5 5 0 0.00 317823 426716 371012 0 0
t$perf-abend:sp_add_invoice_to_stock 170 170 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 20 20 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4996 4996 0 0.00 1 12072 859 0 0
t$perf-norm:sp_cancel_adding_invoice 379 379 0 0.00 1 6458 436 0 0
t$perf-norm:sp_cancel_client_order 1826 1826 0 0.00 0 1500 25 0 0
t$perf-norm:sp_cancel_customer_reserve 664 664 0 0.00 0 1276 25 0 0
t$perf-norm:sp_cancel_pay_from_customer 699 699 0 0.00 0 2917 22 0 0
t$perf-norm:sp_cancel_pay_to_supplier 408 408 0 0.00 0 2165 22 0 0
t$perf-norm:sp_cancel_supplier_invoice 422 422 0 0.00 0 4451 44 0 0
t$perf-norm:sp_cancel_supplier_order 389 389 0 0.00 1 93840 6999 0 0
t$perf-norm:sp_cancel_write_off 985 985 0 0.00 0 2130 19 0 0
t$perf-norm:sp_client_order 20290 20290 0 0.00 0 4953 31 0 0
t$perf-norm:sp_customer_reserve 1002 1002 0 0.00 0 1570 14 0 0
t$perf-norm:sp_pay_from_customer 7004 7004 0 0.00 0 4009 19 0 0
t$perf-norm:sp_pay_to_supplier 6064 6064 0 0.00 0 2032 16 0 0
t$perf-norm:sp_reserve_write_off 9865 9865 0 0.00 0 3514 21 0 0
t$perf-norm:sp_supplier_invoice 5490 5490 0 0.00 0 1632 26 0 0
t$perf-norm:sp_supplier_order 5408 5408 0 0.00 0 5254 31 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 2383 40 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 221 9 0 0
t$perf-norm:srv_make_invnt_saldo 444 444 0 0.00 0 2597 27 0 0
t$perf-norm:srv_make_money_saldo 901 901 0 0.00 0 3333 9 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 222 71 0 0
v_add_invoice_to_stock 10224 9884 340 3.32 0 5841 26 0 340
v_all_customers 37148 37148 0 0.00 0 89 3 0 0
v_all_suppliers 30974 30974 0 0.00 0 84 4 0 0
v_all_wares 40580 40580 0 0.00 0 80 3 0 0
v_cancel_adding_invoice 758 758 0 0.00 0 4329 18 0 0
v_cancel_client_order 3652 3652 0 0.00 0 3711 11 0 0
v_cancel_customer_prepayment 1398 1398 0 0.00 0 2704 17 0 0
v_cancel_customer_reserve 1328 1328 0 0.00 0 3553 16 0 0
v_cancel_payment_to_supplier 816 816 0 0.00 0 4679 21 0 0
v_cancel_supplier_invoice 884 844 40 4.52 0 4330 29 0 40
v_cancel_supplier_order 778 778 0 0.00 0 3370 21 0 0
v_cancel_write_off 1970 1970 0 0.00 0 3494 23 0 0
v_max_id_clo_ord 5408 5408 0 0.00 0 56 4 0 0
v_max_id_clo_res 1002 1002 0 0.00 0 1099 11 0 0
v_max_id_ord_sup 5483 5483 0 0.00 0 2905 6 0 0
v_max_non_paid_invoice 1468 1468 0 0.00 0 111 6 0 0
v_max_non_paid_realizn 3659 3659 0 0.00 0 1507 7 0 0
v_min_id_clo_ord 5408 5408 0 0.00 0 5414 9 0 0
v_min_id_clo_res 1002 1002 0 0.00 0 5755 24 0 0
v_min_id_ord_sup 5483 5483 0 0.00 0 4967 9 0 0
v_min_non_paid_invoice 6064 6064 0 0.00 0 5452 13 0 0
v_min_non_paid_realizn 7004 7004 0 0.00 0 6107 14 0 0
v_our_firm 10122 10122 0 0.00 0 89 4 0 0
v_reserve_write_off 19730 19730 0 0.00 0 5053 11 0 0
x_lock_depdocs_on_canc_invoice 11093 11093 0 0.00 0 31771 129 0 0
x_lock_depdocs_on_canc_sup_ord 389 389 0 0.00 0 6145 357 0 0
x_make_qstorno_1000_1200 5408 5408 0 0.00 3 52988 1154 0 0
x_make_qstorno_1200_2000 5490 5490 0 0.00 4 96490 2849 0 0
x_make_qstorno_2100_3300 465838 465838 0 0.00 0 73585 237 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-06-2022 10:38:35 to 16-06-2022 10:38:39.

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
08:07:21 20 8192000000 8801795936 8814387200 5756896 0.070 3 1 2 0 5858064 65392 35776
08:08:22 26254 8192000000 9710856496 10005520384 789073648 8.785 100 93 186 1 820567616 7819216 23674752
08:09:54 25659 8192000000 9750641376 10055905280 826783968 9.167 100 91 182 0 861528848 12856016 21888864
08:11:26 23605 8192000000 9805049040 10122141696 862335712 9.524 100 85 171 0 908419008 26504608 19578688
08:12:55 13465 8192000000 9885062096 10223349760 924272512 10.138 100 74 148 1 987790192 47493392 16024288
08:14:12 3145 8192000000 9928436208 10274746368 971359520 10.600 100 84 163 1 1043189136 54838496 16991120
08:15:18 1953 8192000000 9958258032 10313363456 1005566608 10.932 100 88 174 2 1079718080 56465824 17685648
08:16:22 3231 8192000000 10005078448 10373148672 1024801328 11.118 100 79 150 2 1120220896 79896560 15523008
08:17:28 1242 8192000000 10022334112 10395590656 1052321376 11.383 100 95 183 1 1141172224 70442016 18408832
08:18:31 896 8192000000 10033046672 10407198720 1063691296 11.492 100 94 187 0 1150626048 68506784 18427968
08:19:34 1041 8192000000 10021945184 10396971008 1073473456 11.585 100 92 179 2 1147108064 55352848 18281760
08:20:38 2559 8192000000 10038490528 10419601408 1083452736 11.680 100 80 148 1 1165648608 68320640 13875232
08:21:41 1189 8192000000 10058576288 10441416704 1088378288 11.727 100 98 186 1 1180754944 73250416 19126240
08:22:43 1114 8192000000 10058732112 10443665408 1095886880 11.799 100 92 174 1 1179484496 67107744 16489872
08:23:46 1129 8192000000 10059682576 10447695872 1097909280 11.818 100 89 174 0 1182447904 66358432 18180192
08:24:48 1213 8192000000 10071465952 10461982720 1095327472 11.793 100 93 177 2 1194074960 80393504 18353984
08:25:50 1421 8192000000 10088970656 10475569152 1099558768 11.833 100 97 190 0 1210723472 91390592 19774112
08:26:52 1095 8192000000 10092759568 10482790400 1107442080 11.908 100 91 181 0 1210867184 84317760 19107344
08:27:55 2007 8192000000 10053853392 10450182144 1106090240 11.895 100 66 119 1 1179006640 60124512 12791888
08:28:58 3029 8192000000 10048247872 10445066240 1105814048 11.893 100 73 142 0 1169364656 48859664 14690944
08:30:03 3155 8192000000 10056565904 10449604608 1091424800 11.756 99 73 140 0 1171425888 65224048 14777040
08:31:07 1224 8192000000 10045475328 10427179008 1072311152 11.574 100 97 187 0 1167574480 76442032 18821296
08:32:09 1146 8192000000 10025069168 10405371904 1068386496 11.537 100 96 185 0 1146497408 59481360 18629552
08:33:11 4538 8192000000 10011388496 10392113152 1056628752 11.424 100 73 135 1 1137678800 68352848 12697200
08:34:16 6387 8192000000 9978871424 10355339264 1030682160 11.175 99 66 122 0 1099575872 57072544 11821168
08:35:25 915 8192000000 10018189424 10396078080 1044863360 11.311 100 91 172 0 1137886448 75150672 17872416
08:36:28 1030 8192000000 10006486976 10382540800 1047815936 11.340 100 93 176 0 1131246048 64970192 18459920
08:37:33 850 8192000000 10002699408 10378092544 1052559264 11.385 100 90 167 2 1125061424 56704272 15797888
08:38:46 2911 8192000000 10039983760 10423226368 1053241920 11.392 100 70 137 0 1161590336 93802176 14546240
08:40:15 27377 8192000000 10034795440 10427445248 1071750496 11.569 100 73 135 0 1149312016 62345072 15216448
08:41:48 4817 8192000000 10040747792 10437271552 1088097584 11.725 100 48 92 0 1160426992 62792176 9537232
08:43:08 1497 8192000000 10136554400 10521706496 1103316272 11.869 100 77 144 1 1250963120 132806032 14840816
08:44:20 1680 8192000000 10156144944 10542092288 1109183440 11.925 100 91 175 1 1264047248 137241952 17621856
08:45:26 1180 8192000000 10208356192 10606092288 1118893680 12.017 100 91 172 1 1326630992 188133456 19603856
08:46:31 5802 8192000000 10218670288 10607673344 1129296928 12.115 100 82 161 2 1327929680 181520960 17111792
08:47:40 2273 8192000000 10252730704 10651512832 1145935248 12.271 100 77 149 0 1365805216 204796208 15073760
08:48:46 838 8192000000 10278834608 10686623744 1158396448 12.388 100 94 182 4 1390630624 210421888 21812288
08:49:51 1754 8192000000 10285275664 10698436608 1158652016 12.391 100 85 168 1 1397021392 219979440 18389936
08:50:58 1149 8192000000 10315401184 10724470784 1167444624 12.473 100 97 186 3 1423722528 235290608 20987296
08:52:02 1690 8192000000 10334721168 10739822592 1178382800 12.575 100 90 178 1 1443711904 245189328 20139776
08:53:07 2447 8192000000 10355142000 10768482304 1180744736 12.597 100 88 175 0 1466674080 265760912 20168432
08:54:13 3795 8192000000 10368373840 10792501248 1179783136 12.588 100 70 137 1 1482137888 285658912 16695840
08:55:21 991 8192000000 10425102064 10843156480 1191327248 12.696 100 97 191 0 1535262880 319768528 24167104
08:56:26 809 8192000000 10428558512 10865856512 1198192912 12.760 100 90 178 0 1534217616 314170192 21854512
08:57:29 2304 8192000000 10423079008 10860900352 1186493120 12.651 99 68 130 0 1533055008 330011360 16550528
08:58:40 1044 8192000000 10447762384 10891956224 1196362800 12.743 100 87 168 1 1552688976 335629584 20696592
08:59:46 1028 8192000000 10439962304 10893860864 1201310400 12.788 100 86 163 2 1542103856 321346352 19447104
09:00:50 20220 8192000000 10410203184 10870284288 1178741712 12.578 99 37 72 0 1498217760 309811296 9664752
09:02:15 20130 8192000000 10449819296 10897461248 1171241024 12.508 99 37 74 1 1554662256 373985200 9436032
09:03:49 16403 8192000000 10385335824 10857177088 1140856912 12.224 98 41 81 0 1483814064 332770128 10187024
09:05:14 22354 8192000000 10359249648 10821742592 1164125920 12.442 100 41 80 0 1459634800 286274880 9234000
09:06:37 30325 8192000000 10330177600 10793082880 1156146736 12.367 100 24 48 1 1445630000 283462944 6020320
09:08:26 16648 8192000000 10254648896 10719318016 1151764464 12.326 100 27 54 0 1370760528 211911536 7084528
09:09:52 71651 8192000000 10067617568 10518671360 1127476608 12.098 99 10 20 0 1218060512 88291024 2292880
09:12:04 29722 8192000000 10091596384 10541195264 1130758400 12.129 99 31 53 1 1174664816 38324768 5581648
09:13:35 36460 8192000000 10093398848 10532327424 1147658512 12.288 100 37 73 0 1195941552 39846704 8436336
09:15:20 114706 8192000000 10071132656 10531528704 1124624592 12.071 98 19 36 0 1159953248 30643664 4684992
09:18:48 16047 8192000000 10079966128 10525937664 1146029472 12.272 100 28 50 3 1173006480 22120576 4856432
09:20:09 9159 8192000000 10095960224 10519879680 1142581168 12.240 100 70 118 0 1188258560 34570832 11106560
09:21:20 35219 8192000000 10106817632 10563596288 1150033248 12.310 100 32 64 0 1224433360 67318800 7081312
09:22:58 59546 8192000000 10126064224 10579726336 1142914576 12.243 100 32 64 0 1246359216 96375232 7069408
09:25:02 45165 8192000000 10094532592 10567983104 1138163392 12.198 100 11 22 1 1205156944 64319152 2674400
09:26:48 78993 8192000000 10085446256 10552684544 1125417952 12.078 99 12 24 0 1203196640 74322672 3456016
09:29:23 37492 8192000000 10026580960 10463698944 1095208144 11.792 98 24 48 1 1158708752 56844128 6656480
09:31:09 32526 8192000000 10098926512 10517770240 1112175552 11.953 100 61 120 1 1184150320 58733568 13241200
09:32:47 50252 8192000000 10102719264 10568417280 1120847824 12.035 99 22 44 1 1207591808 82375952 4368032
09:34:40 65269 8192000000 10079011408 10525102080 1099416624 11.832 98 16 30 0 1158407920 54877776 4113520
09:36:57 19258 8192000000 10083115488 10510639104 1108804912 11.921 99 49 96 0 1178385792 60309456 9271424
09:38:19 81925 8192000000 10091418704 10561630208 1105371216 11.889 98 24 48 2 1168420608 58268160 4781232
09:40:57 80415 8192000000 10033328224 10482622464 1092730048 11.769 97 32 62 0 1139096016 38630256 7735712
09:43:20 736 8192000000 10062267584 10483814400 1123500176 12.060 100 88 170 5 1167039888 23403264 20136448
09:44:28 63931 8192000000 10100417648 10565267456 1118599184 12.014 99 10 20 0 1207346992 86412480 2335328
09:46:32 64492 8192000000 10126341760 10616582144 1134773424 12.166 100 11 22 0 1228466048 91568032 2124592
09:49:18 73010 8192000000 10055529280 10544893952 1102591280 11.862 97 13 26 0 1189789056 84123136 3074640
09:51:33 22872 8192000000 10110327664 10541502464 1112700560 11.958 98 55 109 1 1191628032 68165856 10761616
09:53:08 20680 8192000000 10133305056 10590232576 1113342768 11.964 98 40 79 0 1200210336 78975632 7891936
09:54:43 26512 8192000000 10119785440 10574757888 1110320432 11.935 98 43 86 0 1202153920 82958864 8874624
09:56:16 49909 8192000000 10084885200 10510573568 1115416112 11.984 99 38 73 1 1167909200 45472896 7020192
09:58:09 18117 8192000000 10107420064 10558124032 1124682016 12.071 100 37 74 0 1217139072 84864032 7593024
09:59:29 12215 8192000000 10073908848 10506641408 1114726448 11.977 99 37 71 1 1162785984 41142496 6917040
10:00:43 18398 8192000000 10112683056 10556055552 1126281120 12.086 100 57 114 1 1220093008 82149488 11662400
10:02:04 142648 8192000000 10108306000 10593980416 1134428416 12.163 100 41 82 0 1254895472 111646256 8820800
10:05:27 63966 8192000000 10058291216 10538610688 1118288928 12.011 99 11 22 1 1162467888 41483584 2695376
10:07:37 69399 8192000000 10081591744 10550067200 1124720320 12.072 100 17 34 0 1190174752 61500848 3953584
10:09:47 37277 8192000000 10054982608 10509959168 1121857232 12.045 99 13 26 1 1156188448 31763984 2567232
10:11:25 39774 8192000000 10112764128 10554175488 1129869296 12.120 99 34 68 1 1187056240 49829072 7357872
10:13:10 55286 8192000000 10166795600 10626945024 1139989296 12.215 99 35 70 0 1240823040 93157088 7676656
10:15:06 51274 8192000000 10141921440 10600255488 1115496400 11.984 97 23 46 0 1240004768 119163232 5345136
10:16:58 36729 8192000000 10234113984 10686410752 1159445472 12.398 100 44 85 3 1321071584 152298848 9327264
10:18:41 24770 8192000000 10224045344 10657624064 1146233760 12.274 99 41 82 1 1300422912 145728592 8460560
10:20:07 35799 8192000000 10214400544 10660823040 1152544384 12.333 100 28 56 0 1312668112 154232160 5891568
10:21:45 103367 8192000000 10078239136 10511994880 1103170352 11.868 97 31 61 1 1143080208 33399776 6510080
10:24:34 60606 8192000000 10087318112 10572783616 1134919344 12.168 100 16 32 0 1194638688 56575616 3143728
10:26:42 85098 8192000000 10047971792 10526801920 1069090464 11.543 94 7 14 0 1094672016 24519264 1062288
10:29:07 35859 8192000000 10099760944 10530312192 1126486640 12.088 100 39 69 2 1187593248 53967408 7139200
10:30:52 65984 8192000000 10111201664 10576457728 1125889296 12.083 99 22 44 0 1206561552 76032576 4639680
10:33:06 48883 8192000000 10194229104 10655801344 1116107920 11.990 98 38 76 1 1245842016 121381664 8352432
10:34:56 57620 8192000000 10191228768 10688159744 1142941488 12.243 100 28 56 0 1283635472 134398336 6295648
10:37:04 24236 8192000000 9087227488 9336184832 200208144 2.385 17 13 24 2 276598912 73424256 2966512

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 16-06-2022 10:38:41 to 16-06-2022 10:38:57.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 170 v_add_invoice_to_stock 335544517
except 20 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-06-2022 10:38:58 to 16-06-2022 10:39:00.

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          222671
MON$OLDEST_ACTIVE               222672
MON$OLDEST_SNAPSHOT             222672
MON$NEXT_TRANSACTION            222672
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                       2046560
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             776
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2780 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2780 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2780 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2780 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 ---
:75: 
:76: mscs-test-161.budzdorov.ru    Thu Jun 16 10:37:53 2022
:77:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:78: 
--- end of diff output ---

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

16.06.22 10:39:01. 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-06-16 10:39:03 No severe PSQL-related problems occured
16.06.22 10:39:04. End of report.