Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.03.2022 22: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 16767520 kB
MemAvailable 31638120 kB
Buffers 0 kB
Cached 14978928 kB
SwapCached 2184 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.10
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-03-29 22:38:11
DTS_END                         2022-03-29 22:38:11.8090
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/50424
TRN_ID                          238104
ATT_ID                          654
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: 9327.14 23327 1119257
customer order: creation 165.53 379 19863
customer order: refuse 14.48 358 1738
order to supplier: creation 44.10 1233 5292
order to supplier: removal 3.38 103674 405
invoice (draft): creation 44.81 3469 5377
invoice (draft): removal 54.11 741 6493
invoice accept: apply 40.55 20021 4866
invoice accept: cancel 48.69 880 5843
customer reserve: creation 3814.72 196 457766
customer reserve: removal 4809.64 56 577157
realization accept: apply 82.08 189 9850
realization accept: cancel 80.56 123 9667
payment from customer: creation 56.78 125 6814
payment from customer: removal 5.37 121 644
payment to supplier: creation 47.03 146 5644
payment to supplier: removal 3.21 74 385
service: total inventory turnovers 4.70 1104 564
service: total monetary turnovers 6.68 25 801
service: aggregate perf. data 0.70 24159 84
service: refresh index statistics 0.03 309476 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 29-03-2022 22:40:09 to 29-03-2022 22:40:11.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5893
TEST_TIME 2 5801
TEST_TIME 3 4240
TEST_TIME 4 4530
TEST_TIME 5 4656
TEST_TIME 6 4653
TEST_TIME 7 5246
TEST_TIME 8 5531
TEST_TIME 9 5773
TEST_TIME 10 6154
TEST_TIME 11 6448
TEST_TIME 12 6672
TEST_TIME 13 6974
TEST_TIME 14 6898
TEST_TIME 15 6863
TEST_TIME 16 6903
TEST_TIME 17 7136
TEST_TIME 18 7712
TEST_TIME 19 7978
TEST_TIME 20 8012
TEST_TIME 21 8878
TEST_TIME 22 9118
TEST_TIME 23 9360
TEST_TIME 24 9603
TEST_TIME 25 9793
TEST_TIME 26 9950
TEST_TIME 27 10360
TEST_TIME 28 10572
TEST_TIME 29 10486
TEST_TIME 30 10330
TEST_TIME 31 10169
TEST_TIME 32 10137
TEST_TIME 33 10094
TEST_TIME 34 9994
TEST_TIME 35 10031
TEST_TIME 36 9830
TEST_TIME 37 9835
TEST_TIME 38 9782
TEST_TIME 39 9849
TEST_TIME 40 9776
TEST_TIME 41 9625
TEST_TIME 42 9699
TEST_TIME 43 9578
TEST_TIME 44 9512
TEST_TIME 45 9438
TEST_TIME 46 9386
TEST_TIME 47 9431
TEST_TIME 48 9359
TEST_TIME 49 9562
TEST_TIME 50 9490
TEST_TIME 51 9398
TEST_TIME 52 9253
TEST_TIME 53 9211
TEST_TIME 54 9211
TEST_TIME 55 9246
TEST_TIME 56 9306
TEST_TIME 57 9306
TEST_TIME 58 9259
TEST_TIME 59 9257
TEST_TIME 60 9458
TEST_TIME 61 9689
TEST_TIME 62 9724
TEST_TIME 63 9835
TEST_TIME 64 9784
TEST_TIME 65 9759
TEST_TIME 66 9816
TEST_TIME 67 9872
TEST_TIME 68 9819
TEST_TIME 69 9738
TEST_TIME 70 9644
TEST_TIME 71 9677
TEST_TIME 72 9643
TEST_TIME 73 9706
TEST_TIME 74 9641
TEST_TIME 75 9654
TEST_TIME 76 9643
TEST_TIME 77 9642
TEST_TIME 78 9581
TEST_TIME 79 9494
TEST_TIME 80 9531
TEST_TIME 81 9490
TEST_TIME 82 9446
TEST_TIME 83 9393
TEST_TIME 84 9321
TEST_TIME 85 9360
TEST_TIME 86 9385
TEST_TIME 87 9360
TEST_TIME 88 9360
TEST_TIME 89 9457
TEST_TIME 90 9473
TEST_TIME 91 9530
TEST_TIME 92 9548
TEST_TIME 93 9573
TEST_TIME 94 9551
TEST_TIME 95 9595
TEST_TIME 96 9614
TEST_TIME 97 9654
TEST_TIME 98 9652
TEST_TIME 99 9600
TEST_TIME 100 9542
TEST_TIME 101 9540
TEST_TIME 102 9499
TEST_TIME 103 9450
TEST_TIME 104 9417
TEST_TIME 105 9403
TEST_TIME 106 9353
TEST_TIME 107 9373
TEST_TIME 108 9397
TEST_TIME 109 9422
TEST_TIME 110 9394
TEST_TIME 111 9419
TEST_TIME 112 9411
TEST_TIME 113 9409
TEST_TIME 114 9421
TEST_TIME 115 9384
TEST_TIME 116 9448
TEST_TIME 117 9463
TEST_TIME 118 9439
TEST_TIME 119 9375
TEST_TIME 120 9329

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 29-03-2022 22:40:12 to 29-03-2022 22:40:16.

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 1542813 1542813 0 0.00 0 20037 7 0 0
doc_list_biud 1542812 1542812 0 0.00 0 67572 27 0 0
sp_add_invoice_to_stock 5002 4866 136 2.71 41 278494 20021 0 136
sp_cancel_adding_invoice 5843 5843 0 0.00 3 97499 881 0 0
sp_cancel_client_order 1738 1738 0 0.00 4 13585 358 0 0
sp_cancel_customer_reserve 577157 577157 0 0.00 1 37767 57 0 0
sp_cancel_pay_from_customer 644 644 0 0.00 2 9665 122 0 0
sp_cancel_pay_to_supplier 385 385 0 0.00 2 2547 75 0 0
sp_cancel_supplier_invoice 6506 6493 13 0.19 1 67593 742 0 13
sp_cancel_supplier_order 405 405 0 0.00 10 970922 103675 0 0
sp_cancel_write_off 9667 9667 0 0.00 1 19471 123 0 0
sp_client_order 19863 19863 0 0.00 3 25824 379 0 0
sp_customer_reserve 457766 457766 0 0.00 0 77706 196 0 0
sp_fill_shopping_cart 30518 30518 0 0.00 1 31037 39 0 0
sp_fill_shopping_cart_clo_res 457764 457764 0 0.00 0 24968 14 0 0
sp_get_clo_for_invoice 4863 4863 0 0.00 0 14111 270 0 0
sp_kill_cost_storno 16539 16539 0 0.00 0 14242 60 0 0
sp_kill_qstorno_ret_qs2qd 1170879 1170879 0 0.00 0 67569 30 0 0
sp_kill_qty_storno 616016 616016 0 0.00 0 67571 67 0 0
sp_lock_selected_doc 20227 20227 0 0.00 0 8481 5 0 0
sp_make_cost_storno 27171 27171 0 0.00 0 13959 57 0 0
sp_multiply_rows_for_pdistr 27171 27171 0 0.00 0 15245 36 0 0
sp_multiply_rows_for_qdistr 19863 19863 0 0.00 0 25814 183 0 0
sp_pay_from_customer 6814 6814 0 0.00 4 19261 125 0 0
sp_pay_to_supplier 5644 5644 0 0.00 5 21128 146 0 0
sp_payment_common 12458 12458 0 0.00 1 14609 35 0 0
sp_qd_handle_on_cancel_clo 1738 1738 0 0.00 0 13580 339 0 0
sp_qd_handle_on_invoice_upd_sts 10706 10706 0 0.00 0 27631 364 0 0
sp_qd_handle_on_reserve_upd_sts 9850 9850 0 0.00 0 10454 55 0 0
sp_reserve_write_off 9850 9850 0 0.00 6 19510 190 0 0
sp_supplier_invoice 5377 5377 0 0.00 6 125427 3470 0 0
sp_supplier_order 5292 5292 0 0.00 5 33456 1234 0 0
srv_aggregate_perf_data 84 84 0 0.00 2457 298759 24159 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1092 117540 44973 0 0
srv_find_qd_qs_mism 1072677 1072677 0 0.00 0 20036 6 0 0
srv_make_invnt_saldo 564 564 0 0.00 38 61961 1105 0 0
srv_make_money_saldo 801 801 0 0.00 1 684 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 159759 502946 309476 0 0
t$perf-abend:sp_add_invoice_to_stock 136 136 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4866 4866 0 0.00 0 13778 187 0 0
t$perf-norm:sp_cancel_adding_invoice 384 384 0 0.00 0 5837 120 0 0
t$perf-norm:sp_cancel_client_order 1738 1738 0 0.00 0 1817 11 0 0
t$perf-norm:sp_cancel_customer_reserve 643 643 0 0.00 0 1153 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 644 644 0 0.00 0 5119 27 0 0
t$perf-norm:sp_cancel_pay_to_supplier 385 385 0 0.00 0 3759 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 392 392 0 0.00 0 720 17 0 0
t$perf-norm:sp_cancel_supplier_order 405 405 0 0.00 0 21398 1718 0 0
t$perf-norm:sp_cancel_write_off 923 923 0 0.00 0 5894 16 0 0
t$perf-norm:sp_client_order 19863 19863 0 0.00 0 6243 20 0 0
t$perf-norm:sp_customer_reserve 1003 1003 0 0.00 0 5154 13 0 0
t$perf-norm:sp_pay_from_customer 6814 6814 0 0.00 0 5221 11 0 0
t$perf-norm:sp_pay_to_supplier 5644 5644 0 0.00 0 8229 15 0 0
t$perf-norm:sp_reserve_write_off 9850 9850 0 0.00 0 18429 13 0 0
t$perf-norm:sp_supplier_invoice 5377 5377 0 0.00 0 13554 24 0 0
t$perf-norm:sp_supplier_order 5292 5292 0 0.00 0 7015 18 0 0
t$perf-norm:srv_aggregate_perf_data 84 84 0 0.00 0 225 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 574 11 0 0
t$perf-norm:srv_make_invnt_saldo 564 564 0 0.00 0 2589 30 0 0
t$perf-norm:srv_make_money_saldo 801 801 0 0.00 0 1513 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 2 2 0 0
v_add_invoice_to_stock 9998 9726 272 2.72 0 8713 19 0 272
v_all_customers 35758 35758 0 0.00 0 16 1 0 0
v_all_suppliers 29754 29754 0 0.00 0 93 1 0 0
v_all_wares 39726 39726 0 0.00 0 207 1 0 0
v_cancel_adding_invoice 768 768 0 0.00 0 2332 8 0 0
v_cancel_client_order 3476 3476 0 0.00 0 1792 4 0 0
v_cancel_customer_prepayment 1288 1288 0 0.00 0 3532 14 0 0
v_cancel_customer_reserve 1286 1286 0 0.00 0 1421 6 0 0
v_cancel_payment_to_supplier 770 770 0 0.00 0 2538 10 0 0
v_cancel_supplier_invoice 810 784 26 3.20 0 2460 21 0 26
v_cancel_supplier_order 810 810 0 0.00 0 2406 7 0 0
v_cancel_write_off 1846 1846 0 0.00 0 1860 13 0 0
v_max_id_clo_ord 5289 5289 0 0.00 0 628 1 0 0
v_max_id_clo_res 1003 1003 0 0.00 0 17506 26 0 0
v_max_id_ord_sup 5366 5366 0 0.00 0 3763 4 0 0
v_max_non_paid_invoice 1422 1422 0 0.00 0 1578 4 0 0
v_max_non_paid_realizn 3702 3702 0 0.00 0 6913 9 0 0
v_min_id_clo_ord 5289 5289 0 0.00 0 6777 13 0 0
v_min_id_clo_res 1003 1003 0 0.00 0 5663 12 0 0
v_min_id_ord_sup 5366 5366 0 0.00 0 7268 7 0 0
v_min_non_paid_invoice 5644 5644 0 0.00 0 7793 16 0 0
v_min_non_paid_realizn 6814 6814 0 0.00 0 14566 34 0 0
v_our_firm 10192 10192 0 0.00 0 19 1 0 0
v_reserve_write_off 19700 19700 0 0.00 0 3858 7 0 0
x_lock_depdocs_on_canc_invoice 11944 11944 0 0.00 0 20715 118 0 0
x_lock_depdocs_on_canc_sup_ord 405 405 0 0.00 0 4973 324 0 0
x_make_qstorno_1000_1200 5292 5292 0 0.00 1 33440 1140 0 0
x_make_qstorno_1200_2000 5377 5377 0 0.00 3 118151 3338 0 0
x_make_qstorno_2100_3300 457766 457766 0 0.00 0 77704 179 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-03-2022 22:40:17 to 29-03-2022 22:40:21.

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
20:08:15 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:15 32146 8192000000 9895940704 10204221440 975548304 10.641 100 86 172 0 1004301200 5939120 22813776
20:10:54 25869 8192000000 9948707632 10270089216 1026863840 11.138 100 86 172 0 1056894016 7930080 22100096
20:12:21 22869 8192000000 9982761664 10315440128 1065960560 11.513 100 85 170 0 1096821328 9642800 21217968
20:13:48 11574 8192000000 10042710224 10391408640 1123512944 12.060 100 79 158 0 1153050912 10616896 18921072
20:15:10 6500 8192000000 10082422384 10442129408 1174272384 12.537 100 78 156 0 1204247392 12088224 17886784
20:16:18 5022 8192000000 10130625264 10501242880 1217620048 12.940 100 74 147 1 1246074560 12224352 16230160
20:17:24 3300 8192000000 10168474368 10541572096 1246732144 13.208 100 85 168 0 1279164384 14349408 18082832
20:18:29 1606 8192000000 10192904416 10572816384 1277806240 13.493 100 93 186 1 1313564928 15267472 20491216
20:19:32 1280 8192000000 10208426832 10593345536 1293937568 13.640 100 95 187 1 1329617840 13076544 22603728
20:20:35 15021 8192000000 10213257968 10607140864 1305195888 13.742 100 66 133 0 1330259440 10248272 14815280
20:21:50 4447 8192000000 10203170464 10613215232 1314056080 13.823 100 34 68 0 1328246128 6520864 7669184
20:22:56 7306 8192000000 10211941072 10630438912 1321318448 13.889 100 31 63 2 1338620896 10575248 6727200
20:24:04 10142 8192000000 10208100864 10625851392 1325630400 13.928 100 18 35 0 1335108080 5278784 4198896
20:25:14 41876 8192000000 10225422768 10642296832 1330811792 13.974 100 48 98 1 1348916480 6431296 11673392
20:26:58 4440 8192000000 10232967920 10645983232 1334417520 14.007 100 38 74 5 1349049904 6335232 8297152
20:28:05 7295 8192000000 10190908384 10607071232 1283877344 13.548 98 20 39 2 1293633232 5745376 4010512
20:29:15 7672 8192000000 10151212416 10548989952 1218332224 12.946 95 26 50 1 1229584896 5025968 6226704
20:30:24 5688 8192000000 10149098864 10549215232 1216478512 12.929 96 52 106 1 1235137072 5797088 12861472
20:31:35 4022 8192000000 10150816544 10548617216 1253119296 13.267 100 29 58 1 1266464608 6474928 6870384
20:32:41 5943 8192000000 10158030416 10559733760 1263970016 13.366 100 21 41 1 1274685728 5338320 5377392
20:33:48 11085 8192000000 10173123328 10587770880 1287273968 13.579 100 15 30 0 1296294288 5752208 3268112
20:35:06 20592 8192000000 10178769664 10595905536 1287705456 13.583 100 18 36 0 1300824848 9465760 3653632
20:36:27 9136 8192000000 10192821424 10605240320 1303692512 13.729 100 16 32 0 1312749232 5712432 3344288
20:37:40 8654 8192000000 10203195072 10617360384 1309740752 13.784 100 24 48 0 1321744352 6445344 5558256
20:38:55 24581 8192000000 10252333584 10661355520 1343662912 14.090 100 42 84 3 1361780864 8412512 9705440
20:40:24 25582 8192000000 10257096496 10681831424 1360839152 14.245 100 36 70 0 1377206544 8272320 8095072
20:41:51 58697 8192000000 10267841808 10694201344 1363598816 14.270 100 20 40 0 1378379408 9910256 4870336
20:44:03 9538 8192000000 10311347568 10724491264 1385451664 14.465 100 62 124 0 1414273024 14509344 14312016
20:45:19 5323 8192000000 10313170624 10733199360 1387979888 14.488 100 74 142 2 1425939024 19956576 18002560
20:46:28 3284 8192000000 10337710576 10747920384 1403676112 14.628 100 68 136 1 1440131728 20531136 15924480
20:47:35 8647 8192000000 10331924320 10749493248 1399735504 14.593 100 58 116 0 1437730992 24352384 13643104
20:48:47 7716 8192000000 10317501344 10742931456 1393428112 14.536 100 51 102 1 1427809488 22294848 12086528
20:50:10 8517 8192000000 10328835312 10748170240 1398809248 14.584 100 64 127 0 1438385888 24186368 15390272
20:51:22 7058 8192000000 10332040704 10750758912 1397269760 14.571 100 60 119 0 1443434464 31730384 14434320
20:52:36 3210 8192000000 10343406528 10752303104 1398436032 14.581 100 74 145 2 1446788496 30578400 17774064
20:53:43 2983 8192000000 10326813568 10732089344 1362912832 14.264 98 75 149 2 1414783936 33497760 18373344
20:54:52 5466 8192000000 10325192256 10731290624 1372668128 14.351 100 68 136 2 1428192592 37698640 17825824
20:56:04 1829 8192000000 10358941920 10767253504 1397797792 14.575 100 75 148 3 1462951184 46126976 19026416
20:57:13 1091 8192000000 10334693712 10732552192 1362035056 14.256 100 92 185 3 1434781328 49311536 23434736
20:58:19 9160 8192000000 10328360144 10752221184 1378898384 14.407 100 39 78 0 1444695168 54407728 11389056
20:59:35 19934 8192000000 10315737200 10750545920 1371468688 14.340 100 35 70 2 1431348512 49691248 10188576
21:01:01 8806 8192000000 10315786176 10746380288 1378548368 14.404 100 37 72 0 1422099776 34314848 9236560
21:02:15 14801 8192000000 10307364256 10744459264 1368279200 14.312 100 27 54 1 1416064256 40839328 6945728
21:03:39 19122 8192000000 10312095520 10756861952 1377095664 14.391 100 20 40 2 1422730176 40050960 5583552
21:05:07 34962 8192000000 10284193424 10727944192 1353320640 14.177 99 16 32 1 1392449024 34857504 4270880
21:07:44 58399 8192000000 10269477776 10710351872 1306321872 13.753 95 10 20 2 1318064288 9451280 2291136
21:09:44 17570 8192000000 10314507760 10742939648 1382780304 14.441 100 38 76 1 1406236400 14525840 8930256
21:11:17 48266 8192000000 10317388096 10754465792 1391087056 14.516 100 18 35 1 1414186032 19216960 3882016
21:13:15 104558 8192000000 10286057376 10729906176 1350611824 14.153 97 32 64 1 1367619728 9632448 7375456
21:16:16 19581 8192000000 10322474848 10763755520 1405742592 14.646 100 44 88 1 1426322096 10365200 10214304
21:17:38 47140 8192000000 10295131184 10755731456 1387370128 14.482 99 17 34 0 1403872256 12025536 4476592
21:20:07 61008 8192000000 10290918672 10750939136 1381129904 14.427 99 12 24 0 1392878640 8530240 3218496
21:22:09 36411 8192000000 10316470272 10779566080 1404016320 14.631 100 19 38 1 1428385312 19691632 4677360
21:23:48 74898 8192000000 10316762480 10783920128 1406312288 14.651 100 18 36 0 1438415088 27407936 4694864
21:26:13 47013 8192000000 10311810320 10775113728 1372852208 14.353 98 15 30 1 1409645376 32319680 4473488
21:28:01 32710 8192000000 10296672624 10757992448 1395562016 14.555 100 21 41 0 1411886896 12120656 4204224
21:29:37 70131 8192000000 10296456976 10762674176 1402831344 14.620 100 18 34 0 1414644032 8077488 3735200
21:31:48 60216 8192000000 10302571888 10780262400 1387164768 14.481 99 11 22 0 1402039728 12760192 2114768
21:33:53 53310 8192000000 10285899008 10749779968 1363420000 14.268 97 19 38 0 1383454256 15239392 4794864
21:35:47 55989 8192000000 10337006864 10790617088 1414595696 14.725 100 21 42 2 1439912000 20195424 5120880
21:37:44 77316 8192000000 10307267200 10778427392 1372884064 14.353 97 17 34 0 1405460224 28235824 4340336
21:40:01 91005 8192000000 10282858944 10733699072 1284031440 13.550 93 15 30 0 1304736640 17500992 3204208
21:42:35 28442 8192000000 10256425216 10696192000 1283578336 13.546 94 15 30 0 1294027168 6878320 3570512
21:44:05 27791 8192000000 10287938032 10722406400 1353914752 14.183 99 50 100 1 1376085984 11149216 11022016
21:45:34 68577 8192000000 10282788256 10724028416 1316804288 13.848 96 21 42 1 1337180832 15695968 4680576
21:47:53 101180 8192000000 10273488256 10710974464 1312148944 13.806 97 39 78 1 1337132528 15315264 9668320
21:50:36 26369 8192000000 10258293392 10711236608 1336939408 14.030 99 30 61 1 1363775600 19733632 7102560
21:52:05 86487 8192000000 10242914896 10698424320 1315608256 13.837 98 19 38 1 1335656032 15449216 4598560
21:54:32 43364 8192000000 10244520592 10699739136 1344047008 14.094 100 22 42 1 1363113456 13766560 5299888
21:56:16 53874 8192000000 10237656848 10685206528 1279152784 13.505 95 8 16 0 1294416432 13606208 1657440
21:58:18 69150 8192000000 10238784400 10678259712 1314882800 13.830 98 34 64 2 1331973920 8905248 8185872
22:00:29 81505 8192000000 10239148944 10687729664 1300957792 13.704 96 19 38 1 1312959504 7421312 4580400
22:02:51 91328 8192000000 10253640032 10709364736 1339918832 14.057 100 8 16 0 1353816544 12159072 1738640
22:05:22 41868 8192000000 10269754304 10714673152 1349230816 14.141 99 19 38 1 1367216912 13306784 4679312
22:07:04 63530 8192000000 10283330432 10745880576 1370090224 14.328 100 20 40 0 1395620384 21069616 4460544
22:09:11 101938 8192000000 10292552832 10742620160 1331543920 13.981 96 27 54 1 1348192256 11268864 5379472
22:11:58 40708 8192000000 10295742976 10733240320 1349030896 14.139 97 32 62 0 1363594368 7929632 6633840
22:13:39 57596 8192000000 10296412368 10755817472 1391130560 14.516 100 17 34 1 1406972960 11927680 3914720
22:15:47 63914 8192000000 10324629232 10774581248 1386351696 14.473 99 16 32 1 1408001248 18159440 3490112
22:17:52 70403 8192000000 10307521632 10768474112 1390236032 14.508 99 31 62 1 1408037776 11231040 6570704
22:20:13 50885 8192000000 10292545680 10761187328 1406072240 14.649 100 13 27 1 1417575392 8413808 3089344
22:22:04 87950 8192000000 10289872608 10762461184 1373447344 14.358 98 12 24 1 1388536736 12692304 2397088
22:24:33 46086 8192000000 10304208192 10766594048 1403612336 14.627 100 23 45 0 1421613568 11946064 6055168
22:26:37 72193 8192000000 10292552000 10752667648 1353839520 14.182 97 22 44 0 1369106112 10071440 5195152
22:29:00 45823 8192000000 10262997856 10710761472 1326781456 13.938 96 25 50 0 1340822560 8115232 5925872
22:30:56 34988 8192000000 10296742208 10735996928 1364373616 14.277 99 47 95 2 1385617344 10865664 10378064
22:32:35 68884 8192000000 10292427072 10725638144 1298876176 13.685 94 33 66 2 1321257840 14783248 7598416
22:35:26 117539 8192000000 10255759952 10710200320 1292031088 13.623 94 13 25 1 1305231856 10101584 3099184

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 29-03-2022 22:40:23 to 29-03-2022 22:40:38.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 29-03-2022 22:40:39 to 29-03-2022 22: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          237620
MON$OLDEST_ACTIVE               238508
MON$OLDEST_SNAPSHOT             238508
MON$NEXT_TRANSACTION            238508
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                       2026016
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33574 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33574 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33574 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33574 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 ---
:700: 
:701: mscs-test-161.budzdorov.ru    Tue Mar 29 22:38:15 2022
:702:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:703: 
:704: 
:705: mscs-test-161.budzdorov.ru    Tue Mar 29 22:38:16 2022
:706:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:707: 
--- end of diff output ---

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

29.03.22 22: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-03-29 22:40:44 No severe PSQL-related problems occured
29.03.22 22:40:45. End of report.