Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.06.2022 04: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 15871404 kB
MemAvailable 31192640 kB
Buffers 20 kB
Cached 15799180 kB
SwapCached 2500 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-26 04:37:17
DTS_END                         2022-06-26 04:37:17.5860
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/46226
TRN_ID                          216271
ATT_ID                          644
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: 8630.99 29094 1035719
customer order: creation 165.39 511 19847
customer order: refuse 13.94 513 1673
order to supplier: creation 44.40 1390 5328
order to supplier: removal 3.24 190649 389
invoice (draft): creation 44.88 3281 5385
invoice (draft): removal 48.28 1087 5793
invoice accept: apply 40.73 31278 4888
invoice accept: cancel 43.11 1402 5173
customer reserve: creation 3754.75 300 450570
customer reserve: removal 4190.50 116 502860
realization accept: apply 80.48 303 9658
realization accept: cancel 75.18 194 9022
payment from customer: creation 57.74 188 6929
payment from customer: removal 5.53 127 664
payment to supplier: creation 47.03 195 5644
payment to supplier: removal 3.28 148 394
service: total inventory turnovers 3.91 4481 469
service: total monetary turnovers 7.60 459 912
service: aggregate perf. data 0.97 14513 116
service: refresh index statistics 0.04 330738 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 26-06-2022 04:38:46 to 26-06-2022 04:38:49.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 10983
TEST_TIME 2 7952
TEST_TIME 3 6988
TEST_TIME 4 7731
TEST_TIME 5 7143
TEST_TIME 6 7010
TEST_TIME 7 6970
TEST_TIME 8 6963
TEST_TIME 9 6937
TEST_TIME 10 7377
TEST_TIME 11 7187
TEST_TIME 12 7320
TEST_TIME 13 7606
TEST_TIME 14 7595
TEST_TIME 15 7563
TEST_TIME 16 7673
TEST_TIME 17 7654
TEST_TIME 18 7635
TEST_TIME 19 7613
TEST_TIME 20 7605
TEST_TIME 21 7768
TEST_TIME 22 7763
TEST_TIME 23 7795
TEST_TIME 24 7761
TEST_TIME 25 7826
TEST_TIME 26 7791
TEST_TIME 27 7827
TEST_TIME 28 7859
TEST_TIME 29 8182
TEST_TIME 30 8151
TEST_TIME 31 8231
TEST_TIME 32 8374
TEST_TIME 33 8855
TEST_TIME 34 8990
TEST_TIME 35 9093
TEST_TIME 36 9397
TEST_TIME 37 9699
TEST_TIME 38 10080
TEST_TIME 39 10348
TEST_TIME 40 10388
TEST_TIME 41 10294
TEST_TIME 42 10270
TEST_TIME 43 10041
TEST_TIME 44 9839
TEST_TIME 45 9741
TEST_TIME 46 9705
TEST_TIME 47 9584
TEST_TIME 48 9754
TEST_TIME 49 9669
TEST_TIME 50 9568
TEST_TIME 51 9521
TEST_TIME 52 9433
TEST_TIME 53 9400
TEST_TIME 54 9340
TEST_TIME 55 9284
TEST_TIME 56 9223
TEST_TIME 57 9156
TEST_TIME 58 9111
TEST_TIME 59 8984
TEST_TIME 60 8917
TEST_TIME 61 8956
TEST_TIME 62 9079
TEST_TIME 63 9075
TEST_TIME 64 9139
TEST_TIME 65 9093
TEST_TIME 66 9145
TEST_TIME 67 9131
TEST_TIME 68 9243
TEST_TIME 69 9205
TEST_TIME 70 9215
TEST_TIME 71 9184
TEST_TIME 72 9151
TEST_TIME 73 9051
TEST_TIME 74 8966
TEST_TIME 75 8969
TEST_TIME 76 8921
TEST_TIME 77 8895
TEST_TIME 78 8830
TEST_TIME 79 8868
TEST_TIME 80 8829
TEST_TIME 81 8815
TEST_TIME 82 8779
TEST_TIME 83 8804
TEST_TIME 84 8827
TEST_TIME 85 8894
TEST_TIME 86 8904
TEST_TIME 87 8980
TEST_TIME 88 8962
TEST_TIME 89 8968
TEST_TIME 90 8936
TEST_TIME 91 9046
TEST_TIME 92 9046
TEST_TIME 93 9029
TEST_TIME 94 8968
TEST_TIME 95 8933
TEST_TIME 96 8887
TEST_TIME 97 8867
TEST_TIME 98 8828
TEST_TIME 99 8787
TEST_TIME 100 8768
TEST_TIME 101 8760
TEST_TIME 102 8729
TEST_TIME 103 8790
TEST_TIME 104 8780
TEST_TIME 105 8767
TEST_TIME 106 8737
TEST_TIME 107 8819
TEST_TIME 108 8858
TEST_TIME 109 8885
TEST_TIME 110 8854
TEST_TIME 111 8881
TEST_TIME 112 8853
TEST_TIME 113 8806
TEST_TIME 114 8782
TEST_TIME 115 8762
TEST_TIME 116 8790
TEST_TIME 117 8760
TEST_TIME 118 8714
TEST_TIME 119 8716
TEST_TIME 120 8657

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 26-06-2022 04:38:50 to 26-06-2022 04:38:53.

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 1450866 1450866 0 0.00 0 36083 16 0 0
doc_list_biud 1450849 1450849 0 0.00 0 45299 47 0 0
sp_add_invoice_to_stock 4993 4888 105 2.10 31 341400 31278 0 105
sp_cancel_adding_invoice 5173 5173 0 0.00 7 64248 1403 0 0
sp_cancel_client_order 1673 1673 0 0.00 5 10343 513 0 0
sp_cancel_customer_reserve 502860 502860 0 0.00 1 45324 117 0 0
sp_cancel_pay_from_customer 664 664 0 0.00 3 3406 127 0 0
sp_cancel_pay_to_supplier 394 394 0 0.00 3 1658 148 0 0
sp_cancel_supplier_invoice 5807 5793 14 0.24 3 28054 1087 0 14
sp_cancel_supplier_order 389 389 0 0.00 12 1271114 190650 0 0
sp_cancel_write_off 9022 9022 0 0.00 2 15371 195 0 0
sp_client_order 19847 19847 0 0.00 6 24589 511 0 0
sp_customer_reserve 450570 450570 0 0.00 1 62819 301 0 0
sp_fill_shopping_cart 30555 30555 0 0.00 2 21352 84 0 0
sp_fill_shopping_cart_clo_res 450526 450526 0 0.00 0 17791 21 0 0
sp_get_clo_for_invoice 4829 4829 0 0.00 0 20085 265 0 0
sp_kill_cost_storno 15253 15253 0 0.00 0 36072 87 0 0
sp_kill_qstorno_ret_qs2qd 1020924 1020924 0 0.00 0 45243 51 0 0
sp_kill_qty_storno 539395 539395 0 0.00 0 45289 116 0 0
sp_lock_selected_doc 20015 20015 0 0.00 0 7610 8 0 0
sp_make_cost_storno 27060 27060 0 0.00 0 18486 80 0 0
sp_multiply_rows_for_pdistr 27056 27056 0 0.00 0 11886 41 0 0
sp_multiply_rows_for_qdistr 19847 19847 0 0.00 0 9569 196 0 0
sp_pay_from_customer 6929 6929 0 0.00 5 11894 188 0 0
sp_pay_to_supplier 5644 5644 0 0.00 6 10709 195 0 0
sp_payment_common 12571 12571 0 0.00 2 8316 43 0 0
sp_qd_handle_on_cancel_clo 1673 1673 0 0.00 0 10329 429 0 0
sp_qd_handle_on_invoice_upd_sts 10001 10001 0 0.00 0 26687 583 0 0
sp_qd_handle_on_reserve_upd_sts 9657 9657 0 0.00 0 11744 69 0 0
sp_reserve_write_off 9658 9658 0 0.00 8 18513 303 0 0
sp_supplier_invoice 5385 5385 0 0.00 11 113425 3281 0 0
sp_supplier_order 5328 5328 0 0.00 7 81524 1390 0 0
srv_aggregate_perf_data 116 116 0 0.00 2540 110517 14513 0 0
srv_fill_mon_cache_memory 75 75 0 0.00 704 147866 30394 0 0
srv_find_qd_qs_mism 988328 988328 0 0.00 0 27012 13 0 0
srv_make_invnt_saldo 469 469 0 0.00 63 120001 4481 0 0
srv_make_money_saldo 912 912 0 0.00 1 21549 460 0 0
srv_recalc_idx_stat 5 5 0 0.00 216014 457997 330738 0 0
t$perf-abend:sp_add_invoice_to_stock 105 105 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4889 4889 0 0.00 0 11879 1136 0 0
t$perf-norm:sp_cancel_adding_invoice 399 399 0 0.00 2 5954 553 0 0
t$perf-norm:sp_cancel_client_order 1673 1673 0 0.00 0 5940 39 0 0
t$perf-norm:sp_cancel_customer_reserve 661 661 0 0.00 0 759 32 0 0
t$perf-norm:sp_cancel_pay_from_customer 664 664 0 0.00 0 4104 24 0 0
t$perf-norm:sp_cancel_pay_to_supplier 394 394 0 0.00 0 1683 22 0 0
t$perf-norm:sp_cancel_supplier_invoice 377 377 0 0.00 0 1180 46 0 0
t$perf-norm:sp_cancel_supplier_order 389 389 0 0.00 0 96960 7463 0 0
t$perf-norm:sp_cancel_write_off 975 975 0 0.00 0 4777 39 0 0
t$perf-norm:sp_client_order 19847 19847 0 0.00 0 7522 42 0 0
t$perf-norm:sp_customer_reserve 946 946 0 0.00 0 9688 36 0 0
t$perf-norm:sp_pay_from_customer 6929 6929 0 0.00 0 6397 20 0 0
t$perf-norm:sp_pay_to_supplier 5644 5644 0 0.00 0 5915 24 0 0
t$perf-norm:sp_reserve_write_off 9658 9658 0 0.00 0 15706 31 0 0
t$perf-norm:sp_supplier_invoice 5386 5386 0 0.00 0 3752 38 0 0
t$perf-norm:sp_supplier_order 5328 5328 0 0.00 0 5757 42 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 1041 29 0 0
t$perf-norm:srv_fill_mon_cache_memory 75 75 0 0.00 0 90 5 0 0
t$perf-norm:srv_make_invnt_saldo 469 469 0 0.00 0 2037 26 0 0
t$perf-norm:srv_make_money_saldo 912 912 0 0.00 0 5655 11 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 167 77 0 0
v_add_invoice_to_stock 9862 9652 210 2.12 0 5242 26 0 210
v_all_customers 36507 36507 0 0.00 0 86 4 0 0
v_all_suppliers 29523 29523 0 0.00 0 78 5 0 0
v_all_wares 39690 39690 0 0.00 0 114 4 0 0
v_cancel_adding_invoice 798 798 0 0.00 0 882 17 0 0
v_cancel_client_order 3346 3346 0 0.00 0 3264 12 0 0
v_cancel_customer_prepayment 1328 1328 0 0.00 0 980 12 0 0
v_cancel_customer_reserve 1322 1322 0 0.00 0 1743 12 0 0
v_cancel_payment_to_supplier 788 788 0 0.00 0 1265 16 0 0
v_cancel_supplier_invoice 782 754 28 3.58 0 1859 27 0 28
v_cancel_supplier_order 778 778 0 0.00 0 1852 18 0 0
v_cancel_write_off 1950 1950 0 0.00 0 1495 25 0 0
v_max_id_clo_ord 5326 5326 0 0.00 0 139 5 0 0
v_max_id_clo_res 946 946 0 0.00 0 6516 19 0 0
v_max_id_ord_sup 5383 5383 0 0.00 0 5425 9 0 0
v_max_non_paid_invoice 1588 1588 0 0.00 0 290 9 0 0
v_max_non_paid_realizn 3571 3571 0 0.00 0 5872 11 0 0
v_min_id_clo_ord 5326 5326 0 0.00 0 399 7 0 0
v_min_id_clo_res 946 946 0 0.00 0 5236 19 0 0
v_min_id_ord_sup 5383 5383 0 0.00 0 3263 8 0 0
v_min_non_paid_invoice 5642 5642 0 0.00 0 5745 13 0 0
v_min_non_paid_realizn 6928 6928 0 0.00 0 4918 13 0 0
v_our_firm 9896 9896 0 0.00 0 92 5 0 0
v_reserve_write_off 19312 19312 0 0.00 0 5088 14 0 0
x_lock_depdocs_on_canc_invoice 10589 10589 0 0.00 0 12054 143 0 0
x_lock_depdocs_on_canc_sup_ord 389 389 0 0.00 0 8158 410 0 0
x_make_qstorno_1000_1200 5328 5328 0 0.00 3 80838 1207 0 0
x_make_qstorno_1200_2000 5385 5385 0 0.00 5 113377 3060 0 0
x_make_qstorno_2100_3300 450570 450570 0 0.00 0 62816 265 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-06-2022 04:38:54 to 26-06-2022 04:38:58.

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
02:07:21 1 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:22 29545 8192000000 9719853776 10019160064 795907520 8.855 100 94 188 0 826973920 7833856 23232544
02:09:56 13601 8192000000 9753479040 10060869632 831031904 9.210 100 83 166 0 862312144 11288112 19992128
02:11:14 15175 8192000000 9786979152 10100740096 853843216 9.439 100 81 162 0 890231776 17283472 19105088
02:12:32 10854 8192000000 9846131888 10177462272 895414688 9.853 100 79 158 0 960025328 47117424 17493216
02:13:48 4288 8192000000 9891500816 10232500224 944190832 10.334 100 79 158 1 1007751328 46573328 16987168
02:14:54 2801 8192000000 9949321952 10299486208 982865200 10.712 100 87 174 1 1063691408 63342784 17483424
02:16:01 2457 8192000000 10003899728 10361495552 1009817584 10.974 100 91 179 1 1119967920 92158448 17991888
02:17:07 1131 8192000000 10005958736 10373963776 1035017248 11.217 100 95 184 2 1131719104 77300672 19401184
02:18:09 1736 8192000000 10025507600 10398646272 1054888064 11.408 100 95 186 0 1149109984 76034272 18187648
02:19:12 1413 8192000000 10028655712 10407002112 1065942208 11.513 100 90 176 0 1146512448 63873840 16696400
02:20:17 973 8192000000 10056782960 10436730880 1076047568 11.610 100 96 180 1 1179448672 84965760 18435344
02:21:21 1134 8192000000 10053759856 10435891200 1085648688 11.701 100 96 182 1 1175951696 71819360 18483648
02:22:25 1031 8192000000 10062863616 10446991360 1089973840 11.742 100 96 188 1 1177716032 69055888 18686304
02:23:26 4769 8192000000 10050280000 10435551232 1089222512 11.735 100 83 155 2 1170161424 64625232 16313680
02:24:33 4283 8192000000 10030948256 10427457536 1093207904 11.773 100 58 107 2 1161034096 57492816 10333376
02:25:38 878 8192000000 10063662224 10454695936 1096899536 11.808 100 91 171 4 1187081632 72389136 17792960
02:26:40 1113 8192000000 10041777616 10433736704 1100993008 11.847 100 93 176 2 1165342256 45252912 19096336
02:27:41 1273 8192000000 10043155088 10439639040 1100869600 11.846 100 80 159 0 1161912752 44604352 16438800
02:28:45 6448 8192000000 10043515936 10436816896 1091465040 11.757 100 64 128 1 1161271488 57357888 12448560
02:29:53 9279 8192000000 10023877264 10423533568 1087826160 11.722 100 48 96 1 1159742656 62174144 9742352
02:31:03 1139 8192000000 10045368688 10434064384 1089295872 11.736 100 97 182 0 1167192208 60178176 17718160
02:32:06 910 8192000000 10005399984 10384187392 1061594208 11.472 99 86 160 2 1127628752 49844448 16190096
02:33:07 1565 8192000000 9969910656 10342973440 1035687824 11.223 99 73 136 0 1089540288 41443984 12408480
02:34:10 1240 8192000000 9985464768 10355589120 1019822496 11.070 99 95 176 0 1108048736 70864624 17361616
02:35:12 2003 8192000000 10000074096 10373337088 1039588464 11.261 100 79 142 2 1115635024 62329136 13717424
02:36:16 3477 8192000000 9985533488 10359824384 1017402720 11.047 99 79 142 4 1101376720 69834192 14139808
02:37:21 1229 8192000000 9995316832 10363674624 1036671152 11.233 100 95 182 1 1112044032 57108896 18263984
02:38:29 1302 8192000000 10028691840 10411483136 1058230944 11.440 100 90 171 3 1154697168 77965568 18500656
02:39:36 6168 8192000000 10005353936 10394161152 1053747792 11.397 99 65 124 0 1126955360 60218144 12989424
02:40:52 3583 8192000000 10043798768 10440699904 1077910560 11.628 100 63 124 1 1169861632 79411552 12539520
02:41:59 1060 8192000000 10057829536 10440699904 1081646992 11.663 100 89 173 2 1178061872 78434240 17980640
02:43:08 2009 8192000000 10105807904 10492252160 1098289344 11.821 100 81 156 0 1224053136 109534496 16229296
02:44:13 1681 8192000000 10134663296 10520170496 1100630544 11.844 100 87 164 0 1253082128 134729792 17721792
02:45:19 3390 8192000000 10136453136 10533142528 1109646512 11.929 100 74 143 1 1256429712 132088576 14694624
02:46:30 1378 8192000000 10193715072 10592837632 1125667152 12.080 100 95 178 0 1304745072 160261376 18816544
02:47:35 4952 8192000000 10175162080 10588041216 1139159088 12.208 100 82 148 1 1286065472 131088208 15818176
02:48:44 1066 8192000000 10203829280 10600198144 1151315008 12.322 100 81 158 1 1315125696 147388384 16422304
02:49:49 1994 8192000000 10226912224 10641567744 1158690224 12.391 100 83 161 1 1336168720 160117696 17360800
02:50:56 854 8192000000 10229591232 10639785984 1173135056 12.526 100 94 186 0 1339195872 145401856 20658960
02:52:01 1439 8192000000 10264537616 10670051328 1184078960 12.628 100 82 161 0 1373537008 171786096 17671952
02:53:09 1751 8192000000 10312213296 10715979776 1196642928 12.745 100 87 164 1 1421576592 206910096 18023568
02:54:13 1715 8192000000 10343785600 10751696896 1205091712 12.824 100 95 185 1 1455258096 229033744 21132640
02:55:18 1409 8192000000 10380812048 10786930688 1195925584 12.738 100 94 181 2 1484898672 268592496 20380592
02:56:22 954 8192000000 10418272096 10835714048 1193910640 12.720 100 98 190 3 1530467504 314211248 22345616
02:57:27 690 8192000000 10393868672 10826960896 1176044096 12.553 100 94 183 1 1501554176 302747792 22762288
02:58:33 1365 8192000000 10451203280 10877255680 1189984016 12.683 100 97 189 0 1560160688 347608752 22567920
02:59:39 1238 8192000000 10433100176 10875965440 1182870096 12.617 100 96 185 1 1546640032 340594016 23175920
03:00:46 755 8192000000 10458554352 10862755840 1186999072 12.655 99 95 183 1 1561873776 352796768 22077936
03:01:51 729 8192000000 10465174224 10884096000 1191768144 12.700 100 99 199 0 1567643632 351856592 24018896
03:02:58 1188 8192000000 10473043072 10905829376 1188703408 12.671 100 79 155 0 1581123328 372794032 19625888
03:04:05 772 8192000000 10523649216 10941681664 1192688912 12.708 100 96 189 0 1628632896 411030752 24913232
03:05:10 1757 8192000000 10547142432 10991087616 1197253408 12.751 100 95 184 0 1650216320 430249968 22712944
03:06:15 955 8192000000 10590358208 11045855232 1211221296 12.880 100 97 193 1 1688684880 453566560 23897024
03:07:17 4547 8192000000 10565664192 11018022912 1203209760 12.806 100 61 119 0 1669492144 450702208 15580176
03:08:28 4185 8192000000 10618850672 11064840192 1200745776 12.783 100 66 132 0 1718908784 501232896 16930112
03:09:41 4158 8192000000 10522498576 11010031616 1184710496 12.634 100 55 110 0 1624859264 425699744 14449024
03:10:50 8262 8192000000 10473718496 10946973696 1184249120 12.630 100 48 94 0 1582778160 386516400 12012640
03:12:06 17826 8192000000 10462254272 10956451840 1179963920 12.590 100 31 62 0 1566024880 377898064 8162896
03:13:31 13430 8192000000 10372515680 10857357312 1149840816 12.308 99 23 46 0 1497934928 342043088 6051024
03:14:53 41105 8192000000 10235398592 10749743104 1127486992 12.098 99 24 48 1 1401245232 267872176 5886064
03:16:35 56938 8192000000 10076584432 10567176192 1072171248 11.573 95 12 24 1 1165725968 90810368 2744352
03:18:39 29684 8192000000 10104572512 10517872640 1113854912 11.969 99 71 139 0 1185407936 56527248 15025776
03:20:09 147866 8192000000 10115852208 10570330112 1116148944 11.991 99 28 56 2 1189346064 66968464 6228656
03:23:46 40781 8192000000 10106839968 10538450944 1083881088 11.684 96 48 94 0 1172734432 78887152 9966192
03:25:28 13264 8192000000 10071324784 10573082624 1108572688 11.919 99 28 52 2 1186297440 72348848 5375904
03:26:47 36226 8192000000 10064607296 10509955072 1130478528 12.126 100 18 33 0 1165011584 30510752 4022304
03:28:28 36692 8192000000 10079035744 10546991104 1125994192 12.084 99 9 18 0 1190455248 62644560 1816496
03:30:05 56166 8192000000 10083577888 10558824448 1126305248 12.087 99 34 69 0 1213405216 78903280 8196688
03:32:17 70666 8192000000 10076597936 10528473088 1131432576 12.135 100 25 50 0 1159473616 22675184 5365856
03:34:48 15956 8192000000 10058781280 10508308480 1138790320 12.204 100 15 27 1 1174427328 32901136 2735872
03:36:05 90269 8192000000 10105629120 10578898944 1108771184 11.921 97 17 34 0 1189550992 76465200 4314608
03:38:37 70877 8192000000 10080183120 10605260800 1120405600 12.031 99 9 18 0 1189064336 66669952 1988784
03:40:50 74272 8192000000 10086915920 10569490432 1094686480 11.787 96 26 51 2 1159383024 59112160 5584384
03:43:09 45202 8192000000 10148600464 10634149888 1119548480 12.023 98 37 74 0 1246846448 119406432 7891536
03:45:32 48112 8192000000 10091805904 10533195776 1103801920 11.874 97 31 62 0 1137416768 27634880 5979968
03:47:26 23147 8192000000 10100153888 10548957184 1122256496 12.048 99 39 78 0 1186677296 57143552 7277248
03:48:56 6875 8192000000 10095098400 10528911360 1140887200 12.224 100 42 84 0 1194914208 45758752 8268256
03:50:03 110502 8192000000 10122094720 10567901184 1135032096 12.169 100 44 88 0 1223004368 78817648 9154624
03:53:05 51659 8192000000 10099172576 10558558208 1135636608 12.174 99 33 63 1 1195233968 53534272 6063088
03:54:59 31712 8192000000 10092408400 10587193344 1137175600 12.189 100 10 21 0 1211776080 72123824 2476656
03:56:32 73279 8192000000 10089730064 10545618944 1137582144 12.193 99 12 23 1 1184791072 45364624 1844304
03:58:51 48010 8192000000 10153840656 10590367744 1115982432 11.989 96 24 48 0 1207054944 85728272 5344240
04:00:41 35170 8192000000 10135398912 10597478400 1149295808 12.303 100 19 37 0 1242283664 89108880 3878976
04:02:17 69896 8192000000 10117247296 10633428992 1123038768 12.056 98 14 28 0 1197508912 71552144 2918000
04:04:29 77041 8192000000 10088902000 10538250240 1120276448 12.030 98 38 75 1 1204770480 76839056 7654976
04:06:48 49330 8192000000 10164503488 10608365568 1139641568 12.212 100 38 76 0 1251591696 103679056 8271072
04:08:37 62736 8192000000 10090022160 10541645824 1131675712 12.137 99 9 18 0 1192247552 58908336 1663504
04:10:45 85182 8192000000 10092659040 10562961408 1106313584 11.898 98 48 96 1 1210525680 93947376 10264720
04:13:20 40171 8192000000 10092411088 10544427008 1113404368 11.965 98 36 72 0 1166432784 45581344 7447072
04:15:11 48340 8192000000 10078477360 10557239296 1136678800 12.184 100 15 30 0 1195791024 56146544 2965680
04:17:24 63766 8192000000 10136883840 10609209344 1122305744 12.049 97 13 24 0 1192520368 67313728 2900896
04:19:36 36144 8192000000 10123484240 10579390464 1122465504 12.050 99 31 61 0 1241038368 111449440 7123424
04:21:39 32163 8192000000 10132278320 10575089664 1124379120 12.068 99 35 68 0 1216520832 84604016 7537696
04:23:11 28065 8192000000 10162086224 10597449728 1136645808 12.184 99 30 60 0 1254045888 111111232 6288848
04:24:39 79078 8192000000 10080470000 10556870656 1099206304 11.830 97 19 38 0 1205930608 102694752 4029552
04:27:13 46614 8192000000 10089943168 10516758528 1109462032 11.927 98 50 99 0 1178201040 58830304 9908704
04:29:06 15103 8192000000 10088045104 10514878464 1121207968 12.038 99 45 90 0 1181474576 51477584 8789024
04:30:22 57412 8192000000 10116617264 10557632512 1129714432 12.119 100 47 94 0 1200916976 61796480 9406064
04:32:40 80056 8192000000 10059585600 10580742144 1124943824 12.074 99 7 14 0 1179768560 53372496 1452240
04:35:21 24301 8192000000 10094091936 10528882688 1137014896 12.187 100 45 84 0 1187956928 42316048 8625984
04:36:46 29601 8192000000 10168425952 10613751808 1152141984 12.330 100 38 76 0 1251379152 90710080 8527088

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 26-06-2022 04:39:00 to 26-06-2022 04:39:16.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 105 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 26-06-2022 04:39:17 to 26-06-2022 04:39:19.

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          214297
MON$OLDEST_ACTIVE               217548
MON$OLDEST_SNAPSHOT             217548
MON$NEXT_TRANSACTION            217548
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                       2073760
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             770
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2789 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2789 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2789 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2789 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 ---
:65: 
:66: mscs-test-161.budzdorov.ru    Sun Jun 26 04:37:34 2022
:67:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:68: 
--- end of diff output ---

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

26.06.22 04:39:20. 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-26 04:39:22 No severe PSQL-related problems occured
26.06.22 04:39:24. End of report.