Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.07.2022 19:40
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 16050540 kB
MemAvailable 31160676 kB
Buffers 0 kB
Cached 15688264 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-08 19:38:25
DTS_END                         2022-07-08 19:38:25.2270
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/53448
TRN_ID                          236987
ATT_ID                          704
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: 9370.83 21601 1124500
customer order: creation 169.77 387 20372
customer order: refuse 13.47 351 1616
order to supplier: creation 45.77 1256 5492
order to supplier: removal 3.43 96138 411
invoice (draft): creation 46.97 3466 5636
invoice (draft): removal 54.56 779 6547
invoice accept: apply 42.37 16727 5084
invoice accept: cancel 48.75 789 5850
customer reserve: creation 3846.03 167 461524
customer reserve: removal 4810.52 53 577262
realization accept: apply 81.93 212 9831
realization accept: cancel 80.38 111 9645
payment from customer: creation 57.94 135 6953
payment from customer: removal 5.70 93 684
payment to supplier: creation 48.10 134 5772
payment to supplier: removal 3.43 103 412
service: total inventory turnovers 4.47 1041 536
service: total monetary turnovers 6.38 24 766
service: aggregate perf. data 0.86 16553 103
service: refresh index statistics 0.03 293501 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-07-2022 19:41:13 to 08-07-2022 19:41:15.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2365
TEST_TIME 2 2605
TEST_TIME 3 2081
TEST_TIME 4 4070
TEST_TIME 5 3898
TEST_TIME 6 3752
TEST_TIME 7 4816
TEST_TIME 8 5878
TEST_TIME 9 6015
TEST_TIME 10 6512
TEST_TIME 11 6361
TEST_TIME 12 6869
TEST_TIME 13 7103
TEST_TIME 14 7140
TEST_TIME 15 7474
TEST_TIME 16 7573
TEST_TIME 17 8010
TEST_TIME 18 8792
TEST_TIME 19 8701
TEST_TIME 20 8730
TEST_TIME 21 8616
TEST_TIME 22 8841
TEST_TIME 23 8761
TEST_TIME 24 9063
TEST_TIME 25 9029
TEST_TIME 26 9231
TEST_TIME 27 9252
TEST_TIME 28 9570
TEST_TIME 29 10096
TEST_TIME 30 10849
TEST_TIME 31 11029
TEST_TIME 32 10825
TEST_TIME 33 10617
TEST_TIME 34 10467
TEST_TIME 35 10478
TEST_TIME 36 10388
TEST_TIME 37 10414
TEST_TIME 38 10257
TEST_TIME 39 10258
TEST_TIME 40 10222
TEST_TIME 41 10100
TEST_TIME 42 10183
TEST_TIME 43 10149
TEST_TIME 44 10079
TEST_TIME 45 9990
TEST_TIME 46 9898
TEST_TIME 47 9733
TEST_TIME 48 9837
TEST_TIME 49 9834
TEST_TIME 50 9738
TEST_TIME 51 9711
TEST_TIME 52 9652
TEST_TIME 53 9712
TEST_TIME 54 9723
TEST_TIME 55 9668
TEST_TIME 56 9778
TEST_TIME 57 9791
TEST_TIME 58 9789
TEST_TIME 59 9829
TEST_TIME 60 9816
TEST_TIME 61 9832
TEST_TIME 62 9878
TEST_TIME 63 9907
TEST_TIME 64 9938
TEST_TIME 65 9907
TEST_TIME 66 9927
TEST_TIME 67 9819
TEST_TIME 68 9737
TEST_TIME 69 9791
TEST_TIME 70 9712
TEST_TIME 71 9685
TEST_TIME 72 9691
TEST_TIME 73 9688
TEST_TIME 74 9632
TEST_TIME 75 9589
TEST_TIME 76 9541
TEST_TIME 77 9581
TEST_TIME 78 9617
TEST_TIME 79 9591
TEST_TIME 80 9634
TEST_TIME 81 9611
TEST_TIME 82 9575
TEST_TIME 83 9643
TEST_TIME 84 9645
TEST_TIME 85 9682
TEST_TIME 86 9797
TEST_TIME 87 9745
TEST_TIME 88 9786
TEST_TIME 89 9921
TEST_TIME 90 9880
TEST_TIME 91 9788
TEST_TIME 92 9758
TEST_TIME 93 9705
TEST_TIME 94 9709
TEST_TIME 95 9643
TEST_TIME 96 9605
TEST_TIME 97 9615
TEST_TIME 98 9646
TEST_TIME 99 9619
TEST_TIME 100 9561
TEST_TIME 101 9566
TEST_TIME 102 9606
TEST_TIME 103 9683
TEST_TIME 104 9664
TEST_TIME 105 9667
TEST_TIME 106 9684
TEST_TIME 107 9678
TEST_TIME 108 9727
TEST_TIME 109 9732
TEST_TIME 110 9675
TEST_TIME 111 9604
TEST_TIME 112 9593
TEST_TIME 113 9556
TEST_TIME 114 9554
TEST_TIME 115 9509
TEST_TIME 116 9492
TEST_TIME 117 9462
TEST_TIME 118 9450
TEST_TIME 119 9422
TEST_TIME 120 9372

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 08-07-2022 19:41:17 to 08-07-2022 19:41:20.

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 1553667 1553667 0 0.00 0 26131 6 0 0
doc_list_biud 1553667 1553667 0 0.00 0 29399 26 0 0
sp_add_invoice_to_stock 5196 5084 112 2.15 20 171454 16727 0 112
sp_cancel_adding_invoice 5850 5850 0 0.00 4 81395 789 0 0
sp_cancel_client_order 1616 1616 0 0.00 4 12179 351 0 0
sp_cancel_customer_reserve 577262 577262 0 0.00 0 26388 53 0 0
sp_cancel_pay_from_customer 684 684 0 0.00 2 5870 93 0 0
sp_cancel_pay_to_supplier 412 412 0 0.00 3 7531 104 0 0
sp_cancel_supplier_invoice 6556 6547 9 0.13 1 13352 780 0 9
sp_cancel_supplier_order 411 411 0 0.00 9 943873 96139 0 0
sp_cancel_write_off 9645 9645 0 0.00 1 9257 111 0 0
sp_client_order 20372 20372 0 0.00 3 63587 388 0 0
sp_customer_reserve 461524 461524 0 0.00 0 51591 167 0 0
sp_fill_shopping_cart 31488 31488 0 0.00 1 14479 38 0 0
sp_fill_shopping_cart_clo_res 461522 461522 0 0.00 0 27258 14 0 0
sp_get_clo_for_invoice 5082 5082 0 0.00 0 26034 303 0 0
sp_kill_cost_storno 16591 16591 0 0.00 0 9252 54 0 0
sp_kill_qstorno_ret_qs2qd 1171127 1171127 0 0.00 0 26385 29 0 0
sp_kill_qty_storno 616243 616243 0 0.00 0 29398 64 0 0
sp_lock_selected_doc 20523 20523 0 0.00 0 8179 8 0 0
sp_make_cost_storno 27637 27637 0 0.00 0 18645 65 0 0
sp_multiply_rows_for_pdistr 27637 27637 0 0.00 0 8868 29 0 0
sp_multiply_rows_for_qdistr 20372 20372 0 0.00 0 63580 179 0 0
sp_pay_from_customer 6953 6953 0 0.00 4 20784 136 0 0
sp_pay_to_supplier 5772 5772 0 0.00 5 13419 134 0 0
sp_payment_common 12725 12725 0 0.00 1 10874 31 0 0
sp_qd_handle_on_cancel_clo 1616 1616 0 0.00 0 12166 311 0 0
sp_qd_handle_on_invoice_upd_sts 10931 10931 0 0.00 0 29397 333 0 0
sp_qd_handle_on_reserve_upd_sts 9831 9831 0 0.00 0 17476 67 0 0
sp_reserve_write_off 9831 9831 0 0.00 6 19781 212 0 0
sp_supplier_invoice 5636 5636 0 0.00 7 100409 3467 0 0
sp_supplier_order 5492 5492 0 0.00 5 41216 1256 0 0
srv_aggregate_perf_data 103 103 0 0.00 2491 157439 16554 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1032 109955 43473 0 0
srv_find_qd_qs_mism 1078051 1078051 0 0.00 0 26130 5 0 0
srv_make_invnt_saldo 536 536 0 0.00 33 37983 1041 0 0
srv_make_money_saldo 766 766 0 0.00 1 743 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 158665 470365 293502 0 0
t$perf-abend:sp_add_invoice_to_stock 112 112 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5084 5084 0 0.00 0 14893 187 0 0
t$perf-norm:sp_cancel_adding_invoice 407 407 0 0.00 0 1999 73 0 0
t$perf-norm:sp_cancel_client_order 1616 1616 0 0.00 0 4291 12 0 0
t$perf-norm:sp_cancel_customer_reserve 672 672 0 0.00 0 2198 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 684 684 0 0.00 0 1674 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 412 412 0 0.00 0 3133 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 393 393 0 0.00 0 812 12 0 0
t$perf-norm:sp_cancel_supplier_order 411 411 0 0.00 0 22920 1738 0 0
t$perf-norm:sp_cancel_write_off 1016 1016 0 0.00 0 1986 7 0 0
t$perf-norm:sp_client_order 20372 20372 0 0.00 0 11343 18 0 0
t$perf-norm:sp_customer_reserve 977 977 0 0.00 0 12621 27 0 0
t$perf-norm:sp_pay_from_customer 6953 6953 0 0.00 0 9118 14 0 0
t$perf-norm:sp_pay_to_supplier 5772 5772 0 0.00 0 12610 15 0 0
t$perf-norm:sp_reserve_write_off 9831 9831 0 0.00 0 8951 14 0 0
t$perf-norm:sp_supplier_invoice 5636 5636 0 0.00 0 8149 20 0 0
t$perf-norm:sp_supplier_order 5492 5492 0 0.00 0 14266 27 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 261 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 2658 48 0 0
t$perf-norm:srv_make_invnt_saldo 536 536 0 0.00 0 3751 25 0 0
t$perf-norm:srv_make_money_saldo 766 766 0 0.00 0 1569 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 2 2 0 0
v_add_invoice_to_stock 10386 10162 224 2.15 0 7667 19 0 224
v_all_customers 37250 37250 0 0.00 0 195 1 0 0
v_all_suppliers 30374 30374 0 0.00 0 132 1 0 0
v_all_wares 40744 40744 0 0.00 0 29 1 0 0
v_cancel_adding_invoice 814 814 0 0.00 0 5816 21 0 0
v_cancel_client_order 3232 3232 0 0.00 0 3994 10 0 0
v_cancel_customer_prepayment 1368 1368 0 0.00 0 4613 11 0 0
v_cancel_customer_reserve 1344 1344 0 0.00 0 2660 7 0 0
v_cancel_payment_to_supplier 824 824 0 0.00 0 4107 13 0 0
v_cancel_supplier_invoice 804 786 18 2.23 0 1700 17 0 18
v_cancel_supplier_order 822 822 0 0.00 0 5207 20 0 0
v_cancel_write_off 2032 2032 0 0.00 0 2262 14 0 0
v_max_id_clo_ord 5491 5491 0 0.00 0 4568 2 0 0
v_max_id_clo_res 977 977 0 0.00 0 4527 18 0 0
v_max_id_ord_sup 5625 5625 0 0.00 0 3250 3 0 0
v_max_non_paid_invoice 1701 1701 0 0.00 0 4083 9 0 0
v_max_non_paid_realizn 3637 3637 0 0.00 0 1995 4 0 0
v_min_id_clo_ord 5491 5491 0 0.00 0 13212 10 0 0
v_min_id_clo_res 977 977 0 0.00 0 7153 20 0 0
v_min_id_ord_sup 5625 5625 0 0.00 0 5298 6 0 0
v_min_non_paid_invoice 5772 5772 0 0.00 0 10867 19 0 0
v_min_non_paid_realizn 6953 6953 0 0.00 0 7962 24 0 0
v_our_firm 10126 10126 0 0.00 0 20 1 0 0
v_reserve_write_off 19662 19662 0 0.00 0 7484 9 0 0
x_lock_depdocs_on_canc_invoice 12004 12004 0 0.00 0 39693 116 0 0
x_lock_depdocs_on_canc_sup_ord 411 411 0 0.00 0 17724 355 0 0
x_make_qstorno_1000_1200 5492 5492 0 0.00 1 41207 1174 0 0
x_make_qstorno_1200_2000 5636 5636 0 0.00 3 100315 3336 0 0
x_make_qstorno_2100_3300 461524 461524 0 0.00 0 43389 151 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-07-2022 19:41:21 to 08-07-2022 19:41:25.

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
17:08:29 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:29 29231 8192000000 9906986208 10217914368 987523504 10.757 100 88 176 0 1017105296 6473424 23108368
17:11:03 20183 8192000000 9950228448 10275606528 1039169776 11.257 100 90 178 0 1070612240 8621872 22820592
17:12:32 17407 8192000000 9983307920 10315325440 1065776352 11.512 100 85 170 0 1096405888 9562576 21066960
17:13:58 7234 8192000000 10025247360 10376130560 1117901584 12.007 100 76 153 0 1146516944 10433504 18181856
17:15:09 4891 8192000000 10090852736 10449530880 1176155184 12.554 100 83 165 1 1206525552 11662432 18707936
17:16:22 3537 8192000000 10128791424 10498318336 1215018416 12.916 100 81 162 1 1246775120 14074064 17682640
17:17:28 1730 8192000000 10159910976 10536054784 1242695616 13.171 100 77 151 1 1271643920 12899328 16048976
17:18:32 1957 8192000000 10189109856 10570477568 1272533872 13.445 100 84 167 1 1304279456 13847120 17898464
17:19:35 1375 8192000000 10204158240 10589650944 1291648768 13.619 100 87 169 2 1323390016 12774656 18966592
17:20:40 4408 8192000000 10192256576 10592874496 1298759664 13.684 100 55 107 3 1321951440 10728160 12463616
17:21:50 4200 8192000000 10202181696 10609303552 1307232656 13.761 100 34 70 0 1320856400 5855296 7768448
17:22:56 9859 8192000000 10196708896 10612641792 1314043984 13.823 100 21 43 0 1324992944 6451488 4497472
17:24:09 9192 8192000000 10207951216 10628542464 1326028880 13.931 100 20 37 0 1335112016 5194976 3888160
17:25:21 64774 8192000000 10232908448 10648006656 1333044736 13.995 100 39 77 2 1347899648 5372448 9482464
17:27:26 5072 8192000000 10225320928 10647183360 1334500912 14.008 100 34 68 0 1349572272 6248736 8822624
17:28:36 8534 8192000000 10208653600 10625781760 1308088864 13.769 99 29 58 0 1321457984 6058640 7310480
17:29:48 8990 8192000000 10162919056 10578726912 1261546304 13.344 98 16 31 0 1270347296 5265776 3535216
17:30:57 35096 8192000000 10134341312 10542235648 1135827856 12.176 88 22 43 0 1145821584 4773488 5220240
17:32:33 7474 8192000000 10136263504 10537308160 1227452416 13.031 97 18 35 0 1236749520 4995504 4301600
17:33:41 6190 8192000000 10165411184 10573111296 1273321072 13.452 100 34 69 0 1287150992 6172896 7657024
17:34:50 63030 8192000000 10170637744 10587009024 1276037104 13.477 99 29 58 1 1288473200 6140112 6295984
17:36:53 7050 8192000000 10187674160 10602319872 1297063280 13.669 100 36 68 0 1312113280 6279040 8770960
17:38:01 14269 8192000000 10207409936 10621255680 1309596992 13.782 100 15 31 0 1317768800 4848768 3323040
17:39:24 11630 8192000000 10242344736 10652950528 1335577664 14.018 100 36 72 0 1354559216 11023600 7957952
17:40:59 74775 8192000000 10256416240 10677321728 1353183024 14.176 100 41 82 1 1373870976 10769856 9918096
17:43:24 63344 8192000000 10273358128 10696994816 1361667200 14.252 100 28 56 0 1379998656 11672864 6658592
17:45:29 16438 8192000000 10287971360 10715684864 1375178480 14.373 100 40 80 0 1402591264 17642720 9770064
17:46:47 6005 8192000000 10298754224 10725150720 1380311088 14.419 100 49 96 0 1409642624 17457920 11873616
17:47:56 3645 8192000000 10320660848 10738864128 1401122928 14.605 100 61 121 1 1429986784 15646512 13217344
17:49:02 1922 8192000000 10316818256 10741862400 1388318144 14.491 99 66 130 1 1418003344 13928848 15756352
17:50:10 2427 8192000000 10340107856 10763091968 1410394400 14.687 100 59 118 1 1448206448 23702016 14110032
17:51:16 1031 8192000000 10325402192 10739818496 1394796464 14.549 100 86 168 0 1436191520 21268528 20126528
17:52:22 1208 8192000000 10334810000 10748678144 1393588000 14.538 99 70 138 2 1432840288 22477808 16774480
17:53:29 3492 8192000000 10325220144 10731495424 1376471040 14.385 100 80 157 0 1428890880 32343936 20075904
17:54:40 4025 8192000000 10340929280 10754535424 1391646080 14.521 100 67 134 0 1441363376 33228752 16488544
17:55:48 2886 8192000000 10333186928 10748997632 1374852336 14.370 99 70 140 0 1429591072 37270400 17468336
17:56:56 5061 8192000000 10324574048 10752135168 1390811504 14.513 100 35 70 0 1437304464 37202672 9290288
17:58:16 13725 8192000000 10326279904 10752516096 1388102720 14.489 100 39 78 1 1432216032 33961952 10151360
17:59:34 6047 8192000000 10338528528 10761682944 1392087312 14.524 100 44 88 1 1444247536 40827680 11332544
18:00:43 13326 8192000000 10316882480 10746540032 1368855792 14.317 99 33 66 0 1416896224 38871120 9169312
18:01:58 14357 8192000000 10319371232 10748616704 1361717312 14.253 99 32 64 0 1419818320 49591664 8509344
18:03:20 23738 8192000000 10309314144 10739638272 1333205472 13.996 98 26 52 0 1382710144 42681648 6823024
18:04:46 18493 8192000000 10287706496 10726592512 1325840688 13.930 98 29 58 0 1387295952 52395280 9059984
18:06:17 28487 8192000000 10295126848 10737905664 1340034224 14.058 100 22 44 1 1406245040 59578752 6632064
18:07:47 80008 8192000000 10291167744 10715795456 1341434176 14.070 99 24 47 1 1379106000 32489264 5182560
18:10:09 56217 8192000000 10260344944 10693804032 1335305664 14.015 98 23 47 1 1347461232 7432688 4722880
18:12:07 27549 8192000000 10295153088 10717413376 1369044864 14.318 100 59 111 0 1388508112 6497488 12965760
18:13:42 59017 8192000000 10284709104 10729603072 1366934976 14.300 99 22 43 0 1379796064 8140720 4720368
18:15:41 26807 8192000000 10293883184 10731143168 1357323344 14.213 98 38 74 1 1375303168 9998528 7981296
18:17:09 19469 8192000000 10291529696 10733694976 1381030336 14.426 100 30 58 0 1396759120 9110864 6617920
18:18:29 24370 8192000000 10292266816 10742775808 1377499216 14.394 99 10 18 0 1386247120 6947840 1800064
18:20:01 37039 8192000000 10292133952 10744291328 1377606512 14.395 99 36 70 1 1398757248 12171568 8979168
18:21:49 50419 8192000000 10285916016 10743980032 1385040960 14.462 100 12 24 0 1395629952 7703744 2885248
18:23:43 109843 8192000000 10305532224 10754281472 1392480400 14.528 100 26 51 0 1406425424 8317184 5627840
18:26:51 54232 8192000000 10298262176 10773979136 1401150240 14.605 100 27 53 0 1420860336 13145536 6564560
18:28:52 76800 8192000000 10293330416 10766786560 1376389856 14.384 98 10 16 0 1387972688 9040400 2542432
18:31:09 29971 8192000000 10299587952 10764095488 1391215760 14.517 99 35 69 0 1408153152 9107488 7829904
18:32:40 30686 8192000000 10292187056 10746662912 1356804960 14.209 97 25 50 2 1371073248 8011760 6256528
18:34:17 54648 8192000000 10294897088 10764308480 1381379680 14.429 99 15 30 0 1395052240 10206576 3465984
18:36:33 69648 8192000000 10301617584 10766077952 1379262864 14.410 99 24 48 0 1396354304 11904704 5186736
18:38:43 69116 8192000000 10303380528 10762190848 1371566032 14.341 98 25 50 0 1389176992 12098400 5512560
18:40:54 64800 8192000000 10276289104 10721583104 1323773696 13.911 95 14 28 0 1336385216 9836560 2774960
18:43:03 42957 8192000000 10273060432 10709975040 1309774976 13.784 96 29 58 0 1327031808 10712560 6544272
18:44:52 18556 8192000000 10246989504 10662805504 1298282368 13.680 96 54 108 2 1324051424 12523888 13245168
18:46:13 82639 8192000000 10239224384 10690056192 1301515760 13.709 97 33 66 0 1322583040 13426320 7640960
18:48:38 9536 8192000000 10241864864 10685808640 1338327920 14.042 100 21 42 0 1352609616 9556928 4724768
18:49:49 73196 8192000000 10230306272 10682048512 1321604848 13.891 98 22 44 0 1335190976 8530720 5055408
18:52:04 56993 8192000000 10249200304 10695999488 1316100384 13.841 97 15 30 0 1329372192 10066496 3205312
18:54:01 75252 8192000000 10254866368 10705731584 1322694704 13.901 98 11 22 0 1338132128 12791088 2646336
18:56:29 93983 8192000000 10251929872 10700193792 1335677840 14.018 99 6 12 0 1350761504 13670832 1412832
18:59:17 60616 8192000000 10268726672 10709729280 1333178384 13.996 98 17 34 0 1346145104 8681536 4285184
19:01:24 44305 8192000000 10274542528 10723336192 1362714416 14.262 100 28 56 0 1381469264 12634560 6120288
19:03:15 74625 8192000000 10290594528 10747416576 1375674608 14.378 100 16 32 0 1395237040 15335904 4226528
19:06:05 20348 8192000000 10290521456 10735247360 1366435488 14.295 100 34 68 0 1389294544 15553136 7305920
19:07:27 90277 8192000000 10292869936 10736582656 1359759792 14.235 98 17 33 1 1377976848 14921728 3295328
19:10:02 86094 8192000000 10295901024 10751463424 1374391664 14.366 99 35 69 0 1391162224 8372672 8397888
19:12:29 77877 8192000000 10294836656 10760290304 1391311600 14.518 100 14 28 0 1410631712 16178384 3141728
19:14:49 57627 8192000000 10276097040 10731274240 1331158560 13.978 95 16 32 0 1351332384 16065264 4108560
19:16:51 86796 8192000000 10302270496 10758823936 1359075808 14.229 97 17 34 0 1373790784 10376000 4338976
19:19:25 70134 8192000000 10306735120 10769432576 1388820160 14.495 99 9 18 0 1413311056 22039808 2451088
19:21:45 46346 8192000000 10302790304 10745827328 1379767216 14.414 99 31 62 1 1396565920 8937904 7860800
19:23:31 43661 8192000000 10313752416 10766577664 1391876944 14.523 100 23 46 2 1407892736 10894048 5121744
19:25:22 45578 8192000000 10326504896 10778198016 1403013536 14.622 100 27 54 0 1422567728 13731888 5822304
19:27:12 109955 8192000000 10288516768 10755571712 1342144720 14.077 96 18 36 0 1365501616 19448992 3907904
19:30:25 42232 8192000000 10298381568 10771255296 1383130144 14.445 100 13 26 0 1409926368 23119824 3676400
19:32:11 39210 8192000000 10284277600 10763730944 1372234080 14.347 99 12 22 0 1397146336 22000256 2912000
19:35:06 51783 8192000000 10264904192 10722701312 1359164848 14.230 99 14 28 1 1372917008 10895520 2856640
19:36:59 68383 8192000000 10265574848 10720772096 1325750768 13.929 96 11 22 0 1342245280 14170720 2323792

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 08-07-2022 19:41:27 to 08-07-2022 19:41:42.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 112 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-07-2022 19:41:43 to 08-07-2022 19:41:45.

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          235859
MON$OLDEST_ACTIVE               238082
MON$OLDEST_SNAPSHOT             238082
MON$NEXT_TRANSACTION            238082
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                       2045216
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 ---
:137: 
:138: mscs-test-161.budzdorov.ru    Fri Jul  8 19:39:15 2022
:139:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:140: 
--- end of diff output ---

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

08.07.22 19:41:46. 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-08 19:41:48 No severe PSQL-related problems occured
08.07.22 19:41:49. End of report.