Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.07.2022 01:39
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 16073456 kB
MemAvailable 31141400 kB
Buffers 0 kB
Cached 15671292 kB
SwapCached 2528 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-07-13 01:38:28
DTS_END                         2022-07-13 01:38:28.6680
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(57:17)
IP                              ::1/59238
TRN_ID                          245929
ATT_ID                          661
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: 9516.45 22561 1141974
customer order: creation 177.82 342 21338
customer order: refuse 15.58 329 1870
order to supplier: creation 46.88 1162 5626
order to supplier: removal 3.58 96854 429
invoice (draft): creation 47.73 3321 5728
invoice (draft): removal 53.88 795 6465
invoice accept: apply 43.02 16384 5162
invoice accept: cancel 48.83 740 5860
customer reserve: creation 3981.71 161 477805
customer reserve: removal 4788.63 56 574636
realization accept: apply 87.41 186 10489
realization accept: cancel 85.13 112 10216
payment from customer: creation 62.17 108 7460
payment from customer: removal 6.07 80 728
payment to supplier: creation 52.08 122 6250
payment to supplier: removal 3.29 58 395
service: total inventory turnovers 4.78 1106 573
service: total monetary turnovers 6.99 27 839
service: aggregate perf. data 0.84 22352 101
service: refresh index statistics 0.03 306927 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-07-2022 01:40:08 to 13-07-2022 01:40:10.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5849
TEST_TIME 2 3328
TEST_TIME 3 3440
TEST_TIME 4 3785
TEST_TIME 5 4083
TEST_TIME 6 4136
TEST_TIME 7 4243
TEST_TIME 8 4144
TEST_TIME 9 4693
TEST_TIME 10 5108
TEST_TIME 11 5650
TEST_TIME 12 6169
TEST_TIME 13 6222
TEST_TIME 14 6379
TEST_TIME 15 6696
TEST_TIME 16 6809
TEST_TIME 17 7106
TEST_TIME 18 7646
TEST_TIME 19 7979
TEST_TIME 20 8514
TEST_TIME 21 8430
TEST_TIME 22 8506
TEST_TIME 23 8413
TEST_TIME 24 8794
TEST_TIME 25 9153
TEST_TIME 26 9466
TEST_TIME 27 9821
TEST_TIME 28 10331
TEST_TIME 29 10835
TEST_TIME 30 11013
TEST_TIME 31 10857
TEST_TIME 32 10768
TEST_TIME 33 10854
TEST_TIME 34 10840
TEST_TIME 35 10646
TEST_TIME 36 10505
TEST_TIME 37 10482
TEST_TIME 38 10362
TEST_TIME 39 10469
TEST_TIME 40 10520
TEST_TIME 41 10343
TEST_TIME 42 10288
TEST_TIME 43 10198
TEST_TIME 44 10156
TEST_TIME 45 10193
TEST_TIME 46 10188
TEST_TIME 47 10052
TEST_TIME 48 10027
TEST_TIME 49 9975
TEST_TIME 50 9840
TEST_TIME 51 9768
TEST_TIME 52 9791
TEST_TIME 53 9766
TEST_TIME 54 9709
TEST_TIME 55 9694
TEST_TIME 56 9690
TEST_TIME 57 9631
TEST_TIME 58 9606
TEST_TIME 59 9673
TEST_TIME 60 9738
TEST_TIME 61 9819
TEST_TIME 62 9768
TEST_TIME 63 9781
TEST_TIME 64 9805
TEST_TIME 65 9972
TEST_TIME 66 9903
TEST_TIME 67 10041
TEST_TIME 68 10008
TEST_TIME 69 10100
TEST_TIME 70 10076
TEST_TIME 71 9947
TEST_TIME 72 9870
TEST_TIME 73 9803
TEST_TIME 74 9815
TEST_TIME 75 9774
TEST_TIME 76 9731
TEST_TIME 77 9778
TEST_TIME 78 9675
TEST_TIME 79 9684
TEST_TIME 80 9709
TEST_TIME 81 9675
TEST_TIME 82 9675
TEST_TIME 83 9667
TEST_TIME 84 9699
TEST_TIME 85 9704
TEST_TIME 86 9746
TEST_TIME 87 9733
TEST_TIME 88 9715
TEST_TIME 89 9703
TEST_TIME 90 9758
TEST_TIME 91 9831
TEST_TIME 92 9753
TEST_TIME 93 9695
TEST_TIME 94 9662
TEST_TIME 95 9685
TEST_TIME 96 9636
TEST_TIME 97 9606
TEST_TIME 98 9611
TEST_TIME 99 9604
TEST_TIME 100 9672
TEST_TIME 101 9634
TEST_TIME 102 9641
TEST_TIME 103 9641
TEST_TIME 104 9741
TEST_TIME 105 9710
TEST_TIME 106 9699
TEST_TIME 107 9734
TEST_TIME 108 9738
TEST_TIME 109 9708
TEST_TIME 110 9741
TEST_TIME 111 9686
TEST_TIME 112 9673
TEST_TIME 113 9665
TEST_TIME 114 9652
TEST_TIME 115 9610
TEST_TIME 116 9581
TEST_TIME 117 9533
TEST_TIME 118 9563
TEST_TIME 119 9571
TEST_TIME 120 9518

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 13-07-2022 01:40:12 to 13-07-2022 01:40:15.

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 1583861 1583861 0 0.00 0 15831 5 0 0
doc_list_biud 1583860 1583860 0 0.00 0 81097 26 0 0
sp_add_invoice_to_stock 5278 5162 116 2.19 41 160720 16384 0 116
sp_cancel_adding_invoice 5860 5860 0 0.00 4 58961 740 0 0
sp_cancel_client_order 1870 1870 0 0.00 4 7638 329 0 0
sp_cancel_customer_reserve 574636 574636 0 0.00 0 25566 57 0 0
sp_cancel_pay_from_customer 728 728 0 0.00 3 7948 80 0 0
sp_cancel_pay_to_supplier 395 395 0 0.00 3 1759 58 0 0
sp_cancel_supplier_invoice 6471 6465 6 0.09 1 81102 795 0 6
sp_cancel_supplier_order 429 429 0 0.00 5 949798 96854 0 0
sp_cancel_write_off 10216 10216 0 0.00 1 23376 113 0 0
sp_client_order 21338 21338 0 0.00 3 28956 342 0 0
sp_customer_reserve 477805 477805 0 0.00 0 53606 162 0 0
sp_fill_shopping_cart 32688 32688 0 0.00 1 12965 37 0 0
sp_fill_shopping_cart_clo_res 477804 477804 0 0.00 0 15285 13 0 0
sp_get_clo_for_invoice 5161 5161 0 0.00 0 25088 296 0 0
sp_kill_cost_storno 17199 17199 0 0.00 0 9130 54 0 0
sp_kill_qstorno_ret_qs2qd 1166382 1166382 0 0.00 0 81096 31 0 0
sp_kill_qty_storno 615126 615126 0 0.00 0 81097 67 0 0
sp_lock_selected_doc 21791 21791 0 0.00 0 9623 6 0 0
sp_make_cost_storno 29360 29360 0 0.00 0 15825 51 0 0
sp_multiply_rows_for_pdistr 29360 29360 0 0.00 0 13724 29 0 0
sp_multiply_rows_for_qdistr 21338 21338 0 0.00 0 17957 149 0 0
sp_pay_from_customer 7460 7460 0 0.00 4 15744 108 0 0
sp_pay_to_supplier 6250 6250 0 0.00 4 14763 123 0 0
sp_payment_common 13710 13710 0 0.00 1 14622 26 0 0
sp_qd_handle_on_cancel_clo 1870 1870 0 0.00 0 7632 303 0 0
sp_qd_handle_on_invoice_upd_sts 11021 11021 0 0.00 0 32604 315 0 0
sp_qd_handle_on_reserve_upd_sts 10489 10489 0 0.00 0 23393 66 0 0
sp_reserve_write_off 10489 10489 0 0.00 6 23406 186 0 0
sp_supplier_invoice 5728 5728 0 0.00 5 120912 3322 0 0
sp_supplier_order 5626 5626 0 0.00 4 35842 1162 0 0
srv_aggregate_perf_data 101 101 0 0.00 2502 374053 22352 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1769 119956 43837 0 0
srv_find_qd_qs_mism 1092079 1092079 0 0.00 0 9831 4 0 0
srv_make_invnt_saldo 573 573 0 0.00 30 69514 1107 0 0
srv_make_money_saldo 839 839 0 0.00 0 3196 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 233531 474951 306928 0 0
t$perf-abend:sp_add_invoice_to_stock 116 116 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 5162 5162 0 0.00 0 14788 149 0 0
t$perf-norm:sp_cancel_adding_invoice 441 441 0 0.00 1 13375 105 0 0
t$perf-norm:sp_cancel_client_order 1870 1870 0 0.00 0 7561 17 0 0
t$perf-norm:sp_cancel_customer_reserve 763 763 0 0.00 0 3295 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 728 728 0 0.00 0 5566 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 395 395 0 0.00 0 3027 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 431 431 0 0.00 0 754 13 0 0
t$perf-norm:sp_cancel_supplier_order 429 429 0 0.00 0 24201 1639 0 0
t$perf-norm:sp_cancel_write_off 1084 1084 0 0.00 0 1726 4 0 0
t$perf-norm:sp_client_order 21338 21338 0 0.00 0 13852 17 0 0
t$perf-norm:sp_customer_reserve 1042 1042 0 0.00 0 1983 9 0 0
t$perf-norm:sp_pay_from_customer 7460 7460 0 0.00 0 27509 17 0 0
t$perf-norm:sp_pay_to_supplier 6250 6250 0 0.00 0 25276 20 0 0
t$perf-norm:sp_reserve_write_off 10489 10489 0 0.00 0 7995 11 0 0
t$perf-norm:sp_supplier_invoice 5728 5728 0 0.00 0 4040 14 0 0
t$perf-norm:sp_supplier_order 5626 5626 0 0.00 0 3025 13 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 367 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 166 3 0 0
t$perf-norm:srv_make_invnt_saldo 573 573 0 0.00 0 1104 18 0 0
t$perf-norm:srv_make_money_saldo 839 839 0 0.00 0 1510 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 14 5 0 0
v_add_invoice_to_stock 10554 10322 232 2.19 0 5580 18 0 232
v_all_customers 39142 39142 0 0.00 0 48 1 0 0
v_all_suppliers 32010 32010 0 0.00 0 20 1 0 0
v_all_wares 42676 42676 0 0.00 0 59 1 0 0
v_cancel_adding_invoice 882 882 0 0.00 0 2119 10 0 0
v_cancel_client_order 3740 3740 0 0.00 0 3788 6 0 0
v_cancel_customer_prepayment 1456 1456 0 0.00 0 4296 9 0 0
v_cancel_customer_reserve 1526 1526 0 0.00 0 2495 6 0 0
v_cancel_payment_to_supplier 790 790 0 0.00 0 1344 5 0 0
v_cancel_supplier_invoice 874 862 12 1.37 0 3071 18 0 12
v_cancel_supplier_order 858 858 0 0.00 0 5171 11 0 0
v_cancel_write_off 2168 2168 0 0.00 0 3619 12 0 0
v_max_id_clo_ord 5626 5626 0 0.00 0 742 1 0 0
v_max_id_clo_res 1042 1042 0 0.00 0 2340 14 0 0
v_max_id_ord_sup 5724 5724 0 0.00 0 5288 7 0 0
v_max_non_paid_invoice 1595 1595 0 0.00 0 2725 5 0 0
v_max_non_paid_realizn 3813 3813 0 0.00 0 6488 5 0 0
v_min_id_clo_ord 5626 5626 0 0.00 0 2074 4 0 0
v_min_id_clo_res 1042 1042 0 0.00 0 3656 15 0 0
v_min_id_ord_sup 5724 5724 0 0.00 0 5595 7 0 0
v_min_non_paid_invoice 6250 6250 0 0.00 0 11587 16 0 0
v_min_non_paid_realizn 7460 7460 0 0.00 0 14616 21 0 0
v_our_firm 10828 10828 0 0.00 0 24 1 0 0
v_reserve_write_off 20978 20978 0 0.00 0 7514 6 0 0
x_lock_depdocs_on_canc_invoice 11894 11894 0 0.00 0 14576 110 0 0
x_lock_depdocs_on_canc_sup_ord 429 429 0 0.00 0 15216 315 0 0
x_make_qstorno_1000_1200 5626 5626 0 0.00 1 35834 1091 0 0
x_make_qstorno_1200_2000 5728 5728 0 0.00 2 116701 3179 0 0
x_make_qstorno_2100_3300 477804 477804 0 0.00 0 53604 146 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-07-2022 01:40:16 to 13-07-2022 01:40:20.

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
23:08:32 13 8192000000 8806533408 8820334592 11746112 0.143 3 3 4 0 11930016 76800 107104
23:09:33 24349 8192000000 9909488176 10220236800 990037024 10.782 100 86 172 0 1018538816 6110624 22391168
23:11:05 17935 8192000000 9950087296 10272141312 1032495968 11.192 100 89 178 0 1064256304 8526352 23233984
23:12:34 15201 8192000000 9978284512 10306310144 1054756976 11.406 100 87 174 0 1086746352 9877888 22111488
23:13:55 11682 8192000000 10023771760 10365575168 1105137840 11.886 100 75 150 1 1133590896 10409488 18043568
23:15:14 7779 8192000000 10073265120 10429779968 1153859424 12.346 100 75 149 1 1183200528 12468048 16873056
23:16:24 4205 8192000000 10127860240 10490171392 1204604448 12.819 100 85 165 1 1236245232 13241296 18399488
23:17:29 2066 8192000000 10146393360 10517147648 1239728944 13.144 100 89 178 0 1273447088 13502064 20216080
23:18:32 2070 8192000000 10184471888 10562109440 1268471648 13.408 100 78 153 0 1298494784 13254656 16768480
23:19:36 1832 8192000000 10197314000 10588192768 1290912512 13.613 100 73 147 0 1322925840 15394912 16618416
23:20:41 2284 8192000000 10209754464 10606653440 1306650992 13.756 100 73 147 0 1336376368 14100672 15624704
23:21:51 3881 8192000000 10197510464 10607562752 1311432160 13.799 100 39 78 1 1329479760 9605808 8441792
23:22:57 15733 8192000000 10203374768 10617106432 1318208336 13.860 100 19 38 0 1332823040 10469408 4145296
23:24:19 26930 8192000000 10213447712 10634973184 1324825088 13.920 100 15 31 0 1333809040 5504928 3479024
23:25:46 5474 8192000000 10224758304 10643812352 1330791296 13.974 100 50 100 0 1355138736 13222288 11125152
23:26:55 5745 8192000000 10238102720 10654359552 1334355760 14.006 100 29 58 0 1346901312 5730640 6814912
23:28:02 13515 8192000000 10213806096 10635841536 1308068512 13.769 98 15 30 1 1320608928 9540992 2999424
23:29:18 9587 8192000000 10185654144 10593878016 1252004736 13.257 96 10 18 0 1259025424 5347296 1673392
23:30:33 35760 8192000000 10182029184 10579750912 1242944928 13.173 96 53 106 1 1262919072 8117696 11856448
23:32:09 6907 8192000000 10152603776 10553933824 1246565920 13.207 99 24 49 0 1256989584 4941968 5481696
23:33:18 8369 8192000000 10151084912 10562318336 1262751424 13.355 100 19 38 0 1272425872 5481024 4193424
23:34:27 51984 8192000000 10170403056 10585563136 1282469888 13.536 100 45 87 2 1298674256 5618736 10585632
23:36:20 11314 8192000000 10181758144 10597634048 1295392512 13.653 100 22 40 0 1305596256 5051008 5152736
23:37:32 8926 8192000000 10188149200 10604990464 1299867440 13.694 100 13 27 0 1307336784 4781024 2688320
23:38:44 12445 8192000000 10238487664 10643959808 1329989264 13.967 100 37 74 0 1344866896 6691120 8186512
23:40:06 96443 8192000000 10264604352 10684387328 1350111904 14.148 100 58 114 1 1372631696 9532784 12987008
23:43:03 9132 8192000000 10276758656 10697687040 1365043440 14.283 100 40 80 0 1391025776 16776272 9206064
23:44:23 25862 8192000000 10307618896 10728034304 1384223568 14.454 100 39 78 0 1416232976 22032800 9976608
23:45:53 32336 8192000000 10315148384 10743898112 1394923408 14.550 100 37 74 0 1429244880 25079392 9242080
23:47:41 4447 8192000000 10330942432 10756300800 1399357056 14.589 100 49 96 1 1443421328 31583936 12480336
23:48:54 1765 8192000000 10351017712 10774274048 1411282720 14.695 100 66 131 0 1459191904 31371984 16537200
23:50:00 4623 8192000000 10350228048 10778558464 1409508416 14.680 100 42 82 1 1453312080 32713376 11090288
23:51:12 1843 8192000000 10369947520 10782691328 1415877968 14.736 100 85 165 3 1475704416 40433120 19393328
23:52:18 3853 8192000000 10371723808 10791636992 1414137360 14.721 100 63 124 1 1476358016 46606640 15614016
23:53:28 2634 8192000000 10353636080 10765369344 1387202672 14.481 99 66 131 1 1451679856 48170464 16306720
23:54:38 4839 8192000000 10348750720 10764742656 1392922784 14.532 100 63 123 0 1458033088 48912880 16197424
23:55:51 3240 8192000000 10336991984 10738339840 1356905104 14.210 99 83 166 0 1430050048 51682720 21462224
23:56:58 5024 8192000000 10328240672 10737074176 1370191712 14.329 100 58 109 0 1433520832 47685536 15643584
23:58:08 15945 8192000000 10330101968 10753851392 1377592784 14.395 100 41 80 0 1441386288 52303840 11489664
23:59:41 4738 8192000000 10338985360 10761486336 1385243952 14.463 100 51 102 2 1443926336 45882064 12800320
00:00:54 13177 8192000000 10321751120 10747109376 1364773184 14.280 99 29 58 0 1418446976 45868304 7805488
00:02:18 6015 8192000000 10323333808 10756042752 1368725920 14.316 100 34 68 1 1429371664 50770176 9875568
00:03:26 18867 8192000000 10298445568 10735468544 1352417568 14.169 100 30 60 0 1407402496 46622080 8362848
00:04:55 27179 8192000000 10281031552 10711240704 1332032688 13.986 99 26 51 0 1380671936 41722368 6916880
00:06:25 44612 8192000000 10276604992 10721075200 1341437584 14.070 99 18 37 1 1379040720 32528736 5074400
00:08:11 74812 8192000000 10272542128 10719416320 1324102080 13.914 97 6 12 1 1343902256 18716496 1083680
00:10:26 14790 8192000000 10285751808 10721210368 1375624192 14.377 100 30 61 1 1389103408 6486304 6992912
00:11:43 79452 8192000000 10290123024 10741657600 1355370368 14.196 98 9 18 0 1371931568 14742608 1818592
00:14:07 66561 8192000000 10296810736 10738638848 1368416112 14.313 99 36 69 0 1394397488 18599680 7381696
00:16:21 101499 8192000000 10294553264 10751594496 1376749216 14.387 100 29 58 0 1404067312 20029824 7288272
00:19:03 36501 8192000000 10281058048 10733625344 1365484128 14.287 99 28 54 0 1380513040 8600752 6428160
00:20:42 39225 8192000000 10285799536 10737606656 1371978224 14.345 99 26 50 0 1385848640 8027584 5842832
00:22:22 13312 8192000000 10301969008 10746384384 1387365984 14.482 100 47 94 0 1405827616 7533968 10927664
00:23:37 52639 8192000000 10299967488 10760232960 1381011168 14.426 99 25 50 0 1397039168 10390080 5637920
00:25:34 56263 8192000000 10296935984 10763497472 1386965664 14.479 99 13 26 0 1402737392 12342496 3429232
00:27:31 40920 8192000000 10309548000 10771005440 1402372928 14.616 100 10 20 0 1416816752 12304208 2139616
00:29:13 66259 8192000000 10311528208 10777575424 1403480832 14.626 100 18 35 1 1423206864 15371200 4354832
00:31:23 31269 8192000000 10294017952 10762371072 1391925280 14.523 99 43 88 0 1412907952 10948080 10034592
00:33:01 67951 8192000000 10298459600 10763075584 1390178032 14.507 99 8 16 0 1398404848 6774320 1452496
00:35:09 25370 8192000000 10307889184 10760572928 1379093376 14.408 98 23 47 0 1390263520 6223312 4946832
00:36:41 64942 8192000000 10299308976 10757791744 1348522512 14.134 96 12 23 0 1362485808 11153680 2809616
00:38:48 49657 8192000000 10303833616 10759876608 1392144576 14.525 100 14 28 0 1409859072 14599296 3115200
00:40:39 41587 8192000000 10294997328 10743681024 1338047696 14.040 96 22 44 0 1356405872 13359920 4998256
00:42:21 72324 8192000000 10257094032 10714284032 1300036576 13.696 95 11 22 0 1321332496 18603872 2692048
00:44:34 19822 8192000000 10297742448 10732953600 1344240240 14.096 98 53 105 0 1371394368 16161312 10992816
00:46:00 51628 8192000000 10250545744 10689777664 1263685888 13.364 93 14 28 0 1278261632 11245328 3330416
00:47:52 119956 8192000000 10233746928 10675273728 1314042528 13.823 97 12 24 0 1329422672 12587664 2792480
00:50:57 30847 8192000000 10258117968 10688253952 1329300768 13.961 99 52 102 0 1350009376 9051472 11657136
00:52:28 56847 8192000000 10251888960 10714734592 1331593504 13.982 98 13 26 0 1352469920 17897760 2978656
00:54:26 97845 8192000000 10248804016 10712170496 1320890352 13.885 97 8 16 0 1334351184 11527312 1933520
00:58:26 49945 8192000000 10266820656 10704982016 1341584304 14.072 99 15 30 0 1359490768 14243216 3663248
01:00:19 40768 8192000000 10233571632 10678894592 1308035696 13.768 97 20 40 0 1321296160 8748528 4511936
01:02:01 29785 8192000000 10281747840 10720145408 1365237168 14.284 100 43 86 0 1385740672 11409968 9093536
01:03:31 71082 8192000000 10278338800 10728198144 1348982032 14.138 98 21 42 0 1365841152 12013808 4845312
01:05:42 114084 8192000000 10288575088 10750398464 1348432624 14.133 97 12 24 0 1364434208 13323584 2678000
01:08:37 83302 8192000000 10299361840 10769362944 1366919920 14.299 98 10 20 1 1382208512 13278480 2010112
01:11:26 77393 8192000000 10308483760 10763649024 1389884848 14.505 99 28 57 0 1406159312 9901344 6373120
01:13:44 70666 8192000000 10298007840 10769842176 1361450176 14.250 97 5 10 0 1375835968 13645376 740416
01:16:07 29710 8192000000 10307223840 10764615680 1401248592 14.606 100 44 86 0 1423651296 12118768 10283936
01:17:37 51236 8192000000 10301846304 10763968512 1365684112 14.288 97 17 34 0 1381631808 11946000 4001696
01:19:29 26639 8192000000 10328538768 10775138304 1399363936 14.589 99 53 104 0 1421940640 10879632 11697072
01:21:01 105873 8192000000 10314573808 10788249600 1379357120 14.411 98 10 20 0 1400831776 19207328 2267328
01:23:50 15073 8192000000 10300882080 10751164416 1389980560 14.506 100 36 68 1 1404852848 6779152 8093136
01:25:06 67671 8192000000 10297973808 10763579392 1339958560 14.057 96 13 26 0 1357442960 14883072 2601328
01:27:14 17284 8192000000 10298765008 10736914432 1358405600 14.223 98 30 60 0 1370793264 5552896 6834768
01:28:32 113272 8192000000 10260574752 10715242496 1321187264 13.887 96 14 28 0 1339784000 15409408 3187328
01:31:26 56251 8192000000 10266517008 10722873344 1342954784 14.084 99 18 38 0 1362396464 15318560 4123120
01:33:23 45074 8192000000 10262067200 10722476032 1352374768 14.169 99 11 22 0 1365581888 10676192 2530928
01:35:26 72916 8192000000 10247690800 10714165248 1334117536 14.004 98 12 24 0 1352189648 15102688 2969424

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 13-07-2022 01:40:22 to 13-07-2022 01:40:37.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 116 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 13-07-2022 01:40:38 to 13-07-2022 01:40:40.

Content of mon$database and FB version

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


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

Database statistics

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

Database validation

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

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

--- start of diff output ---
:993: 
:994: mscs-test-161.budzdorov.ru    Wed Jul 13 01:38:36 2022
:995:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:996: 
:997: 
:998: mscs-test-161.budzdorov.ru    Wed Jul 13 01:38:37 2022
:999:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1000: 
:1001: 
:1002: mscs-test-161.budzdorov.ru    Wed Jul 13 01:38:43 2022
:1003:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1004: 
:1005: 
:1006: mscs-test-161.budzdorov.ru    Wed Jul 13 01:38:43 2022
:1007:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1008: 
:1009: 
:1010: mscs-test-161.budzdorov.ru    Wed Jul 13 01:38:43 2022
:1011:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1012: 
:1013: 
:1014: mscs-test-161.budzdorov.ru    Wed Jul 13 01:38:43 2022
:1015:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1016: 
:1017: 
:1018: mscs-test-161.budzdorov.ru    Wed Jul 13 01:38:43 2022
:1019:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1020: 
:1021: 
:1022: mscs-test-161.budzdorov.ru    Wed Jul 13 01:38:43 2022
:1023:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1024: 
--- end of diff output ---

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

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