Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 01.01.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 16475656 kB
MemAvailable 31325876 kB
Buffers 0 kB
Cached 15184996 kB
SwapCached 1652 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.1
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-01-01 10:37:12
DTS_END                         2022-01-01 10:37:12.3850
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/58730
TRN_ID                          226980
ATT_ID                          614
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: 9163.02 22908 1099562
customer order: creation 158.32 479 18999
customer order: refuse 13.92 319 1670
order to supplier: creation 42.83 1338 5139
order to supplier: removal 3.48 119597 418
invoice (draft): creation 43.21 3210 5185
invoice (draft): removal 52.98 846 6358
invoice accept: apply 39.64 19781 4757
invoice accept: cancel 47.87 815 5744
customer reserve: creation 3728.36 191 447403
customer reserve: removal 4755.86 70 570703
realization accept: apply 78.40 195 9408
realization accept: cancel 78.47 118 9417
payment from customer: creation 55.58 126 6670
payment from customer: removal 5.38 87 646
payment to supplier: creation 45.54 137 5465
payment to supplier: removal 3.01 98 361
service: total inventory turnovers 3.82 3249 458
service: total monetary turnovers 5.43 531 652
service: aggregate perf. data 0.88 17299 105
service: refresh index statistics 0.03 289678 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 01-01-2022 10:38:25 to 01-01-2022 10:38:27.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5591
TEST_TIME 2 3677
TEST_TIME 3 3772
TEST_TIME 4 5031
TEST_TIME 5 5226
TEST_TIME 6 5147
TEST_TIME 7 4932
TEST_TIME 8 4794
TEST_TIME 9 4992
TEST_TIME 10 5171
TEST_TIME 11 5264
TEST_TIME 12 5740
TEST_TIME 13 5946
TEST_TIME 14 6264
TEST_TIME 15 6383
TEST_TIME 16 6411
TEST_TIME 17 6475
TEST_TIME 18 6752
TEST_TIME 19 7390
TEST_TIME 20 8375
TEST_TIME 21 8326
TEST_TIME 22 9053
TEST_TIME 23 9450
TEST_TIME 24 9968
TEST_TIME 25 11354
TEST_TIME 26 11284
TEST_TIME 27 11246
TEST_TIME 28 11093
TEST_TIME 29 10987
TEST_TIME 30 10796
TEST_TIME 31 10627
TEST_TIME 32 10552
TEST_TIME 33 10529
TEST_TIME 34 10443
TEST_TIME 35 10326
TEST_TIME 36 10248
TEST_TIME 37 10171
TEST_TIME 38 10164
TEST_TIME 39 10114
TEST_TIME 40 10061
TEST_TIME 41 10028
TEST_TIME 42 9914
TEST_TIME 43 9875
TEST_TIME 44 9983
TEST_TIME 45 9932
TEST_TIME 46 9921
TEST_TIME 47 10050
TEST_TIME 48 10069
TEST_TIME 49 10083
TEST_TIME 50 10062
TEST_TIME 51 9985
TEST_TIME 52 10087
TEST_TIME 53 10113
TEST_TIME 54 10153
TEST_TIME 55 10132
TEST_TIME 56 10001
TEST_TIME 57 9962
TEST_TIME 58 9881
TEST_TIME 59 9852
TEST_TIME 60 9820
TEST_TIME 61 9706
TEST_TIME 62 9630
TEST_TIME 63 9735
TEST_TIME 64 9745
TEST_TIME 65 9711
TEST_TIME 66 9766
TEST_TIME 67 9693
TEST_TIME 68 9708
TEST_TIME 69 9825
TEST_TIME 70 9982
TEST_TIME 71 9952
TEST_TIME 72 9914
TEST_TIME 73 9928
TEST_TIME 74 9891
TEST_TIME 75 9834
TEST_TIME 76 9734
TEST_TIME 77 9785
TEST_TIME 78 9700
TEST_TIME 79 9713
TEST_TIME 80 9628
TEST_TIME 81 9603
TEST_TIME 82 9609
TEST_TIME 83 9551
TEST_TIME 84 9618
TEST_TIME 85 9569
TEST_TIME 86 9523
TEST_TIME 87 9672
TEST_TIME 88 9696
TEST_TIME 89 9678
TEST_TIME 90 9618
TEST_TIME 91 9736
TEST_TIME 92 9697
TEST_TIME 93 9601
TEST_TIME 94 9567
TEST_TIME 95 9514
TEST_TIME 96 9487
TEST_TIME 97 9423
TEST_TIME 98 9402
TEST_TIME 99 9417
TEST_TIME 100 9417
TEST_TIME 101 9411
TEST_TIME 102 9376
TEST_TIME 103 9352
TEST_TIME 104 9439
TEST_TIME 105 9490
TEST_TIME 106 9492
TEST_TIME 107 9449
TEST_TIME 108 9435
TEST_TIME 109 9469
TEST_TIME 110 9386
TEST_TIME 111 9345
TEST_TIME 112 9335
TEST_TIME 113 9321
TEST_TIME 114 9300
TEST_TIME 115 9264
TEST_TIME 116 9259
TEST_TIME 117 9245
TEST_TIME 118 9266
TEST_TIME 119 9239
TEST_TIME 120 9169

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 01-01-2022 10:38:29 to 01-01-2022 10:38:32.

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 1513667 1513667 0 0.00 0 29855 7 0 0
doc_list_biud 1513656 1513656 0 0.00 0 28935 32 0 0
sp_add_invoice_to_stock 4858 4757 101 2.07 54 281525 19782 0 101
sp_cancel_adding_invoice 5744 5744 0 0.00 5 77194 816 0 0
sp_cancel_client_order 1670 1670 0 0.00 4 9894 319 0 0
sp_cancel_customer_reserve 570703 570703 0 0.00 1 30142 71 0 0
sp_cancel_pay_from_customer 646 646 0 0.00 3 5104 88 0 0
sp_cancel_pay_to_supplier 361 361 0 0.00 3 7278 99 0 0
sp_cancel_supplier_invoice 6364 6358 6 0.09 2 23325 847 0 6
sp_cancel_supplier_order 418 418 0 0.00 8 1157476 119598 0 0
sp_cancel_write_off 9417 9417 0 0.00 1 12554 118 0 0
sp_client_order 18999 18999 0 0.00 4 28313 480 0 0
sp_customer_reserve 447403 447403 0 0.00 0 104375 192 0 0
sp_fill_shopping_cart 29321 29321 0 0.00 1 33634 50 0 0
sp_fill_shopping_cart_clo_res 447400 447400 0 0.00 0 16893 19 0 0
sp_get_clo_for_invoice 4745 4745 0 0.00 0 26702 450 0 0
sp_kill_cost_storno 16168 16168 0 0.00 0 11001 58 0 0
sp_kill_qstorno_ret_qs2qd 1157599 1157599 0 0.00 0 28885 37 0 0
sp_kill_qty_storno 608457 608457 0 0.00 0 28934 80 0 0
sp_lock_selected_doc 19599 19599 0 0.00 0 9424 7 0 0
sp_make_cost_storno 26288 26288 0 0.00 0 23743 69 0 0
sp_multiply_rows_for_pdistr 26283 26283 0 0.00 0 14534 33 0 0
sp_multiply_rows_for_qdistr 18999 18999 0 0.00 0 26054 271 0 0
sp_pay_from_customer 6670 6670 0 0.00 4 21697 127 0 0
sp_pay_to_supplier 5465 5465 0 0.00 5 16542 138 0 0
sp_payment_common 12133 12133 0 0.00 1 13624 23 0 0
sp_qd_handle_on_cancel_clo 1670 1670 0 0.00 0 9886 288 0 0
sp_qd_handle_on_invoice_upd_sts 10486 10486 0 0.00 0 27263 354 0 0
sp_qd_handle_on_reserve_upd_sts 9405 9405 0 0.00 0 10135 58 0 0
sp_reserve_write_off 9408 9408 0 0.00 6 21955 196 0 0
sp_supplier_invoice 5185 5185 0 0.00 7 84789 3211 0 0
sp_supplier_order 5139 5139 0 0.00 6 42119 1339 0 0
srv_aggregate_perf_data 105 105 0 0.00 2946 275537 17299 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1676 124974 49857 0 0
srv_find_qd_qs_mism 1054490 1054490 0 0.00 0 22466 6 0 0
srv_make_invnt_saldo 458 458 0 0.00 71 104697 3249 0 0
srv_make_money_saldo 652 652 0 0.00 1 14254 531 0 0
srv_recalc_idx_stat 4 4 0 0.00 247113 342712 289679 0 0
t$perf-abend:sp_add_invoice_to_stock 101 101 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4757 4757 0 0.00 0 12805 193 0 0
t$perf-norm:sp_cancel_adding_invoice 376 376 0 0.00 0 3476 96 0 0
t$perf-norm:sp_cancel_client_order 1670 1670 0 0.00 0 2719 10 0 0
t$perf-norm:sp_cancel_customer_reserve 670 670 0 0.00 0 548 6 0 0
t$perf-norm:sp_cancel_pay_from_customer 646 646 0 0.00 0 9351 19 0 0
t$perf-norm:sp_cancel_pay_to_supplier 361 361 0 0.00 0 3265 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 389 389 0 0.00 0 1911 25 0 0
t$perf-norm:sp_cancel_supplier_order 418 418 0 0.00 0 23100 1483 0 0
t$perf-norm:sp_cancel_write_off 922 922 0 0.00 0 976 6 0 0
t$perf-norm:sp_client_order 18999 18999 0 0.00 0 6302 17 0 0
t$perf-norm:sp_customer_reserve 904 904 0 0.00 0 6257 11 0 0
t$perf-norm:sp_pay_from_customer 6670 6670 0 0.00 0 12546 9 0 0
t$perf-norm:sp_pay_to_supplier 5465 5465 0 0.00 0 10544 13 0 0
t$perf-norm:sp_reserve_write_off 9408 9408 0 0.00 0 4481 11 0 0
t$perf-norm:sp_supplier_invoice 5185 5185 0 0.00 0 6310 17 0 0
t$perf-norm:sp_supplier_order 5139 5139 0 0.00 0 10766 19 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 787 23 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 233 5 0 0
t$perf-norm:srv_make_invnt_saldo 458 458 0 0.00 0 453 5 0 0
t$perf-norm:srv_make_money_saldo 652 652 0 0.00 0 343 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 75 22 0 0
v_add_invoice_to_stock 9686 9484 202 2.08 0 5557 17 0 202
v_all_customers 34824 34824 0 0.00 0 25 1 0 0
v_all_suppliers 28674 28674 0 0.00 0 46 1 0 0
v_all_wares 37998 37998 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 752 752 0 0.00 0 718 7 0 0
v_cancel_client_order 3340 3340 0 0.00 0 1444 4 0 0
v_cancel_customer_prepayment 1292 1292 0 0.00 0 4696 11 0 0
v_cancel_customer_reserve 1340 1340 0 0.00 0 3238 6 0 0
v_cancel_payment_to_supplier 722 722 0 0.00 0 5335 15 0 0
v_cancel_supplier_invoice 790 778 12 1.51 0 2157 16 0 12
v_cancel_supplier_order 836 836 0 0.00 0 1627 7 0 0
v_cancel_write_off 1844 1844 0 0.00 0 2896 13 0 0
v_max_id_clo_ord 5139 5139 0 0.00 0 470 1 0 0
v_max_id_clo_res 904 904 0 0.00 0 4340 21 0 0
v_max_id_ord_sup 5183 5183 0 0.00 0 5799 4 0 0
v_max_non_paid_invoice 1447 1447 0 0.00 0 457 2 0 0
v_max_non_paid_realizn 3505 3505 0 0.00 0 958 4 0 0
v_min_id_clo_ord 5139 5139 0 0.00 0 24153 36 0 0
v_min_id_clo_res 904 904 0 0.00 0 9348 22 0 0
v_min_id_ord_sup 5182 5182 0 0.00 0 6008 7 0 0
v_min_non_paid_invoice 5463 5463 0 0.00 0 8923 15 0 0
v_min_non_paid_realizn 6669 6669 0 0.00 0 13621 17 0 0
v_our_firm 9502 9502 0 0.00 0 20 1 0 0
v_reserve_write_off 18806 18806 0 0.00 0 5637 6 0 0
x_lock_depdocs_on_canc_invoice 11713 11713 0 0.00 0 18351 111 0 0
x_lock_depdocs_on_canc_sup_ord 418 418 0 0.00 0 8652 310 0 0
x_make_qstorno_1000_1200 5139 5139 0 0.00 2 36767 1235 0 0
x_make_qstorno_1200_2000 5185 5185 0 0.00 3 74777 3038 0 0
x_make_qstorno_2100_3300 447403 447403 0 0.00 0 104372 170 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 01-01-2022 10:38:33 to 01-01-2022 10:38:37.

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:16 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:17 20768 8192000000 9709846048 10003427328 789855504 8.793 100 90 180 0 820194448 7743008 22595936
08:09:44 15417 8192000000 9755463520 10058522624 829088928 9.190 100 93 186 0 863477328 11825408 22562992
08:11:05 10927 8192000000 9784163680 10093137920 852986224 9.430 100 89 178 0 893152432 18949472 21216736
08:12:18 15179 8192000000 9846144192 10176835584 910232960 10.000 100 76 152 0 952236720 25495216 16508544
08:13:37 7469 8192000000 9929166272 10269663232 954865488 10.439 100 87 172 1 1038026672 65433712 17727472
08:14:46 7077 8192000000 9957717856 10317602816 990428880 10.786 100 69 139 0 1065260416 60693568 14137968
08:15:56 7928 8192000000 9999225312 10363113472 1020332624 11.075 100 83 166 0 1109828656 73035008 16461024
08:17:07 2454 8192000000 10023042688 10387402752 1044657200 11.309 100 90 180 0 1128323824 64929920 18736704
08:18:11 1331 8192000000 10010746976 10382270464 1062964288 11.485 100 88 174 1 1124985376 43744080 18277008
08:19:15 6431 8192000000 10008117376 10400899072 1066450000 11.518 100 65 131 1 1131570288 50421280 14699008
08:20:24 3187 8192000000 9985227104 10388701184 1072610000 11.577 100 30 60 1 1113540688 34555904 6374784
08:21:28 7658 8192000000 9981799248 10384109568 1077118560 11.620 100 24 49 1 1106066912 23806624 5141728
08:22:36 56820 8192000000 10013270656 10400247808 1089964848 11.742 100 53 104 1 1115301184 14410224 10926112
08:24:33 6250 8192000000 10014484672 10430009344 1094524544 11.786 100 40 80 1 1132480528 29267152 8688832
08:25:40 10271 8192000000 10008313328 10421264384 1102956096 11.866 100 31 60 1 1127462688 17705680 6800912
08:26:54 8751 8192000000 10001077424 10408931328 1075765424 11.607 98 23 47 0 1092020128 11496848 4757856
08:28:03 32252 8192000000 10013544432 10434195456 1087229744 11.716 100 44 87 3 1130399680 33005184 10164752
08:29:36 6178 8192000000 9989426960 10383908864 1050529280 11.366 99 46 91 1 1086982032 26841744 9611008
08:30:46 10275 8192000000 9926000240 10320998400 962182048 10.510 94 20 40 0 999234128 32685792 4366288
08:31:57 8096 8192000000 9949091152 10329468928 1022457680 11.096 100 45 88 0 1053728448 21077776 10192992
08:33:09 30738 8192000000 9958650272 10363174912 1028026048 11.149 99 36 72 0 1065538976 30019760 7493168
08:34:40 6908 8192000000 9978754672 10362310656 1059507360 11.452 100 50 101 2 1092300816 21948992 10844464
08:35:48 6985 8192000000 9989647696 10391076864 1070491840 11.557 100 45 88 2 1103069312 23347616 9229856
08:36:57 6363 8192000000 9984061536 10378686464 1074791760 11.598 100 27 52 1 1104551136 23743168 6016208
08:38:08 98588 8192000000 10059161408 10460442624 1102674704 11.863 100 46 92 0 1145567904 33368496 9524704
08:41:00 14969 8192000000 10121129504 10523492352 1126298784 12.086 100 48 94 3 1232354560 95720176 10335600
08:42:21 25509 8192000000 10171406752 10571698176 1145260496 12.265 100 56 112 0 1280029984 123433824 11335664
08:43:53 10074 8192000000 10188484224 10595540992 1154509280 12.352 100 41 78 2 1294016304 129961616 9545408
08:45:08 14399 8192000000 10260848736 10670071808 1163604208 12.437 100 54 108 0 1356080288 181385216 11090864
08:46:30 36423 8192000000 10278697920 10706952192 1176901056 12.561 100 36 72 0 1396964912 211561184 8502672
08:48:13 2744 8192000000 10340117776 10753425408 1186955104 12.655 100 71 139 2 1442054480 239317344 15782032
08:49:21 3546 8192000000 10409678960 10819334144 1197581136 12.754 100 82 162 1 1504658528 288243120 18834272
08:50:28 4538 8192000000 10427041696 10843762688 1194608432 12.726 100 85 169 0 1535202512 320700960 19893120
08:51:38 1762 8192000000 10433020912 10849361920 1195559056 12.735 100 71 143 1 1536319088 324458272 16301760
08:52:45 1673 8192000000 10473477088 10884435968 1197598192 12.754 100 78 156 0 1569363952 353056304 18709456
08:53:52 3320 8192000000 10490717184 10895691776 1169501184 12.492 99 74 148 1 1589348496 402167840 17679472
08:55:00 2551 8192000000 10561411168 10982797312 1192094400 12.703 100 90 180 1 1658653712 445255248 21304064
08:56:09 6632 8192000000 10543004784 10987835392 1179965696 12.590 99 71 142 2 1650163152 452607408 17590048
08:57:22 16738 8192000000 10484526656 11035660288 1188325760 12.668 100 37 74 1 1586446144 388467504 9652880
08:58:41 8334 8192000000 10538963152 10980249600 1182017328 12.609 100 43 86 0 1642885280 449593392 11274560
08:59:50 18992 8192000000 10376389232 10981175296 1136597216 12.184 98 29 58 0 1536835488 392564672 7673600
09:01:11 36732 8192000000 10322109904 10801278976 1073832048 11.589 95 19 38 1 1399567376 320685792 5049536
09:02:50 73265 8192000000 10061742592 10496225280 1114091360 11.971 98 15 31 2 1161157808 44075344 2991104
09:05:07 31040 8192000000 10026258944 10450796544 1057469584 11.432 94 20 39 0 1085641808 24659056 3513168
09:06:41 43797 8192000000 10088096512 10500288512 1103353536 11.869 98 52 103 0 1150286208 35763472 11169200
09:08:25 112614 8192000000 10075985712 10539335680 1121011776 12.037 99 31 62 1 1186589696 59353760 6224160
09:11:27 79030 8192000000 10104294080 10546896896 1106282608 11.897 97 32 63 2 1129310512 15971024 7056880
09:14:15 70238 8192000000 10133083888 10581479424 1123596496 12.061 99 23 46 3 1224740864 96218240 4926128
09:16:25 24709 8192000000 10071420816 10521280512 1078842432 11.636 96 18 35 0 1138358928 55989680 3526816
09:17:56 48538 8192000000 10057024528 10490986496 1112078800 11.952 100 17 34 0 1197503712 81313664 4111248
09:19:49 87621 8192000000 10095711392 10578759680 1105970320 11.894 98 9 18 1 1192658832 84899248 1789264
09:22:16 30591 8192000000 10127042928 10546610176 1130493072 12.126 100 56 110 1 1198100480 56116464 11490944
09:23:48 96254 8192000000 10066444224 10548006912 1138393824 12.200 100 19 37 1 1229824192 86940256 4490112
09:26:25 64050 8192000000 10087765488 10528075776 1128398816 12.106 99 9 18 0 1161371600 31360352 1612432
09:28:32 31174 8192000000 10131983744 10564513792 1147756624 12.288 100 54 107 1 1215778736 57343200 10678912
09:30:05 94191 8192000000 10127782400 10634485760 1129598000 12.118 98 19 38 0 1186096272 52839440 3658832
09:32:39 109353 8192000000 10080147520 10521325568 1132713536 12.147 99 15 30 0 1168446816 32795328 2937952
09:35:29 25294 8192000000 10090950736 10543865856 1144399936 12.257 99 30 59 1 1191980848 41758032 5822880
09:36:54 43383 8192000000 10065307264 10528112640 1118701408 12.015 98 18 36 0 1162864928 40724864 3438656
09:38:38 121554 8192000000 10089580640 10631368704 1119921696 12.026 97 11 22 0 1215249152 92842432 2485024
09:41:42 20792 8192000000 10070477904 10506280960 1106292192 11.897 97 24 48 1 1134414464 23634928 4487344
09:43:14 63572 8192000000 10149750736 10621943808 1145608240 12.268 100 19 38 0 1240412432 90350320 4453872
09:45:18 106254 8192000000 10169121200 10698219520 1126246800 12.086 97 24 48 1 1278533360 146517808 5768752
09:48:06 51901 8192000000 10078733776 10509168640 1073856112 11.589 95 24 48 0 1120086816 41927824 4302880
09:49:58 37996 8192000000 10080934032 10529767424 1092119744 11.763 97 37 74 1 1153739824 54308208 7311872
09:51:39 23520 8192000000 10089523152 10496155648 1113485952 11.965 100 56 112 1 1173520640 48396192 11638496
09:53:04 60430 8192000000 10128242656 10565808128 1100406784 11.841 98 38 75 2 1209068704 100762400 7899520
09:55:55 90143 8192000000 10055470064 10550235136 1110264096 11.935 99 10 20 0 1160284384 47672480 2347808
09:58:27 124968 8192000000 10094035952 10566983680 1094593360 11.786 97 16 31 1 1173617536 75365504 3658672
10:01:33 41052 8192000000 10074185712 10511052800 1113150096 11.962 98 27 54 2 1143549552 24785392 5614064
10:03:28 73478 8192000000 10134841920 10634174464 1104552576 11.881 98 15 30 1 1239098272 131135744 3409952
10:05:42 75305 8192000000 10064876560 10505228288 1110369408 11.936 99 19 37 2 1146836896 32966784 3500704
10:08:01 42365 8192000000 10104774432 10588766208 1102807600 11.864 98 30 60 1 1189807152 81041808 5957744
10:09:49 119050 8192000000 10036719984 10489360384 1101608752 11.853 98 33 66 0 1149711456 41214608 6888096
10:12:54 47405 8192000000 10090084048 10547240960 1112359312 11.955 98 9 18 0 1167000688 52914432 1726944
10:15:09 76104 8192000000 10111426464 10566868992 1139261968 12.209 100 16 32 1 1182912992 40178208 3472816
10:17:25 96290 8192000000 10104836608 10570498048 1125190352 12.076 98 16 32 1 1166629680 38120224 3319104
10:20:02 64441 8192000000 10155851648 10623967232 1111919520 11.951 97 23 46 1 1207486080 90115376 5451184
10:22:07 51539 8192000000 10095559936 10606276608 1131940848 12.140 99 11 21 0 1198815216 64742848 2131520
10:24:31 21730 8192000000 10097427120 10531131392 1144497392 12.258 100 40 78 2 1172965440 20292816 8175232
10:25:56 69671 8192000000 10078674816 10587607040 1136297840 12.181 99 16 32 2 1219122800 79643568 3181392
10:28:05 61300 8192000000 10132965632 10588794880 1155070864 12.357 100 33 66 0 1209715808 48347920 6297024
10:30:08 26710 8192000000 10126529088 10615316480 1159397024 12.398 100 8 16 0 1238579888 77134320 2048544
10:31:39 54558 8192000000 10095114400 10584506368 1136280432 12.181 98 6 12 0 1193780368 56204208 1295728
10:33:35 50418 8192000000 10090066336 10539945984 1102652224 11.863 95 16 33 0 1145750704 39600656 3497824
10:35:44 45849 8192000000 10097137072 10536402944 1117475632 12.003 97 23 47 1 1163377296 41395888 4505776

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 01-01-2022 10:38:39 to 01-01-2022 10:38:54.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 101 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 01-01-2022 10:38:55 to 01-01-2022 10:38:57.

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          224617
MON$OLDEST_ACTIVE               227191
MON$OLDEST_SNAPSHOT             227191
MON$NEXT_TRANSACTION            227191
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                       2038432
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             780
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2699 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2699 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2699 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2699 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 ---
:11: 
:12: mscs-test-161.budzdorov.ru    Sat Jan  1 10:37:19 2022
:13:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:14: 
:15: 
:16: mscs-test-161.budzdorov.ru    Sat Jan  1 10:37:22 2022
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:18: 
:19: 
:20: mscs-test-161.budzdorov.ru    Sat Jan  1 10:37:23 2022
:21:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:22: 
--- end of diff output ---

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

01.01.22 10:38:58. 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-01-01 10:39:00 No severe PSQL-related problems occured
01.01.22 10:39:02. End of report.