Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.01.2022 01:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16285064 kB
MemAvailable 31286568 kB
Buffers 0 kB
Cached 15357020 kB
SwapCached 1200 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.9
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-01-06 01:38:00
DTS_END                         2022-01-06 01:38:00.9640
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/59712
TRN_ID                          235937
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: 9636.41 20285 1156369
customer order: creation 165.13 391 19815
customer order: refuse 14.37 315 1724
order to supplier: creation 44.58 1337 5349
order to supplier: removal 3.78 104756 453
invoice (draft): creation 44.54 3117 5345
invoice (draft): removal 58.84 771 7061
invoice accept: apply 40.13 17745 4816
invoice accept: cancel 52.58 698 6310
customer reserve: creation 3765.39 173 451847
customer reserve: removal 5140.39 60 616847
realization accept: apply 81.28 183 9754
realization accept: cancel 101.38 111 12166
payment from customer: creation 57.13 102 6855
payment from customer: removal 5.12 79 614
payment to supplier: creation 46.63 145 5596
payment to supplier: removal 3.69 124 443
service: total inventory turnovers 4.78 1020 574
service: total monetary turnovers 5.92 33 710
service: aggregate perf. data 0.72 26045 86
service: refresh index statistics 0.03 248489 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-01-2022 01:38:57 to 06-01-2022 01:38:59.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9990
TEST_TIME 2 7185
TEST_TIME 3 5645
TEST_TIME 4 5668
TEST_TIME 5 5457
TEST_TIME 6 5563
TEST_TIME 7 5760
TEST_TIME 8 5799
TEST_TIME 9 5992
TEST_TIME 10 6039
TEST_TIME 11 7023
TEST_TIME 12 7173
TEST_TIME 13 7417
TEST_TIME 14 7574
TEST_TIME 15 7584
TEST_TIME 16 7858
TEST_TIME 17 8172
TEST_TIME 18 8420
TEST_TIME 19 9152
TEST_TIME 20 9205
TEST_TIME 21 8922
TEST_TIME 22 9152
TEST_TIME 23 9496
TEST_TIME 24 9511
TEST_TIME 25 9809
TEST_TIME 26 10423
TEST_TIME 27 10237
TEST_TIME 28 10596
TEST_TIME 29 11498
TEST_TIME 30 11388
TEST_TIME 31 11161
TEST_TIME 32 10981
TEST_TIME 33 11301
TEST_TIME 34 11190
TEST_TIME 35 11124
TEST_TIME 36 11068
TEST_TIME 37 11048
TEST_TIME 38 10898
TEST_TIME 39 10867
TEST_TIME 40 11000
TEST_TIME 41 10934
TEST_TIME 42 10699
TEST_TIME 43 10622
TEST_TIME 44 10589
TEST_TIME 45 10472
TEST_TIME 46 10478
TEST_TIME 47 10475
TEST_TIME 48 10354
TEST_TIME 49 10296
TEST_TIME 50 10356
TEST_TIME 51 10410
TEST_TIME 52 10302
TEST_TIME 53 10268
TEST_TIME 54 10234
TEST_TIME 55 10151
TEST_TIME 56 10092
TEST_TIME 57 10111
TEST_TIME 58 10045
TEST_TIME 59 9996
TEST_TIME 60 9893
TEST_TIME 61 9786
TEST_TIME 62 9788
TEST_TIME 63 9793
TEST_TIME 64 9782
TEST_TIME 65 9833
TEST_TIME 66 9861
TEST_TIME 67 9853
TEST_TIME 68 9900
TEST_TIME 69 9927
TEST_TIME 70 9889
TEST_TIME 71 9991
TEST_TIME 72 9956
TEST_TIME 73 10023
TEST_TIME 74 10026
TEST_TIME 75 10010
TEST_TIME 76 10072
TEST_TIME 77 10095
TEST_TIME 78 9991
TEST_TIME 79 9976
TEST_TIME 80 9887
TEST_TIME 81 9874
TEST_TIME 82 9849
TEST_TIME 83 9805
TEST_TIME 84 9738
TEST_TIME 85 9728
TEST_TIME 86 9699
TEST_TIME 87 9671
TEST_TIME 88 9790
TEST_TIME 89 9784
TEST_TIME 90 9764
TEST_TIME 91 9803
TEST_TIME 92 9785
TEST_TIME 93 9776
TEST_TIME 94 9737
TEST_TIME 95 9959
TEST_TIME 96 9945
TEST_TIME 97 10008
TEST_TIME 98 9994
TEST_TIME 99 9963
TEST_TIME 100 10027
TEST_TIME 101 9974
TEST_TIME 102 9921
TEST_TIME 103 9870
TEST_TIME 104 9817
TEST_TIME 105 9825
TEST_TIME 106 9821
TEST_TIME 107 9798
TEST_TIME 108 9776
TEST_TIME 109 9732
TEST_TIME 110 9703
TEST_TIME 111 9701
TEST_TIME 112 9697
TEST_TIME 113 9657
TEST_TIME 114 9666
TEST_TIME 115 9702
TEST_TIME 116 9699
TEST_TIME 117 9698
TEST_TIME 118 9731
TEST_TIME 119 9721
TEST_TIME 120 9640

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 06-01-2022 01:39:01 to 06-01-2022 01:39:04.

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 1576273 1576273 0 0.00 0 22269 6 0 0
doc_list_biud 1576271 1576271 0 0.00 0 71575 29 0 0
sp_add_invoice_to_stock 4930 4816 114 2.31 32 154875 17745 0 114
sp_cancel_adding_invoice 6310 6310 0 0.00 5 57722 699 0 0
sp_cancel_client_order 1724 1724 0 0.00 3 8316 315 0 0
sp_cancel_customer_reserve 616847 616847 0 0.00 1 71578 60 0 0
sp_cancel_pay_from_customer 614 614 0 0.00 3 4285 80 0 0
sp_cancel_pay_to_supplier 443 443 0 0.00 2 5766 124 0 0
sp_cancel_supplier_invoice 7073 7061 12 0.16 1 23205 772 0 12
sp_cancel_supplier_order 453 453 0 0.00 7 998586 104757 0 0
sp_cancel_write_off 12166 12166 0 0.00 1 22438 112 0 0
sp_client_order 19815 19815 0 0.00 3 19640 391 0 0
sp_customer_reserve 451847 451847 0 0.00 0 60980 174 0 0
sp_fill_shopping_cart 30497 30497 0 0.00 1 9589 35 0 0
sp_fill_shopping_cart_clo_res 451836 451836 0 0.00 0 20441 13 0 0
sp_get_clo_for_invoice 4805 4805 0 0.00 0 26461 283 0 0
sp_kill_cost_storno 19533 19533 0 0.00 0 9449 54 0 0
sp_kill_qstorno_ret_qs2qd 1253374 1253374 0 0.00 0 71574 33 0 0
sp_kill_qty_storno 659120 659120 0 0.00 0 71575 70 0 0
sp_lock_selected_doc 20097 20097 0 0.00 0 6489 5 0 0
sp_make_cost_storno 27010 27010 0 0.00 0 21924 56 0 0
sp_multiply_rows_for_pdistr 27010 27010 0 0.00 0 19919 34 0 0
sp_multiply_rows_for_qdistr 19815 19815 0 0.00 0 12684 156 0 0
sp_pay_from_customer 6855 6855 0 0.00 4 13160 103 0 0
sp_pay_to_supplier 5596 5596 0 0.00 4 21104 145 0 0
sp_payment_common 12451 12451 0 0.00 1 16535 28 0 0
sp_qd_handle_on_cancel_clo 1724 1724 0 0.00 0 8303 295 0 0
sp_qd_handle_on_invoice_upd_sts 11115 11115 0 0.00 0 27655 326 0 0
sp_qd_handle_on_reserve_upd_sts 9754 9754 0 0.00 0 12116 59 0 0
sp_reserve_write_off 9754 9754 0 0.00 7 22020 183 0 0
sp_supplier_invoice 5345 5345 0 0.00 5 83813 3118 0 0
sp_supplier_order 5349 5349 0 0.00 5 34200 1338 0 0
srv_aggregate_perf_data 86 86 0 0.00 3417 617371 26046 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2152 98245 43786 0 0
srv_find_qd_qs_mism 1110949 1110949 0 0.00 0 22268 5 0 0
srv_make_invnt_saldo 574 574 0 0.00 42 52966 1021 0 0
srv_make_money_saldo 710 710 0 0.00 1 5290 33 0 0
srv_recalc_idx_stat 4 4 0 0.00 105784 360046 248489 0 0
t$perf-abend:sp_add_invoice_to_stock 114 114 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4816 4816 0 0.00 0 15782 164 0 0
t$perf-norm:sp_cancel_adding_invoice 380 380 0 0.00 0 1907 69 0 0
t$perf-norm:sp_cancel_client_order 1724 1724 0 0.00 0 1957 13 0 0
t$perf-norm:sp_cancel_customer_reserve 608 608 0 0.00 0 4626 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 614 614 0 0.00 0 4777 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 443 443 0 0.00 0 1426 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 381 381 0 0.00 0 429 9 0 0
t$perf-norm:sp_cancel_supplier_order 453 453 0 0.00 0 24967 1551 0 0
t$perf-norm:sp_cancel_write_off 935 935 0 0.00 0 1297 5 0 0
t$perf-norm:sp_client_order 19815 19815 0 0.00 0 15992 16 0 0
t$perf-norm:sp_customer_reserve 983 983 0 0.00 0 471 3 0 0
t$perf-norm:sp_pay_from_customer 6855 6855 0 0.00 0 8977 11 0 0
t$perf-norm:sp_pay_to_supplier 5596 5596 0 0.00 0 5115 10 0 0
t$perf-norm:sp_reserve_write_off 9754 9754 0 0.00 0 8110 15 0 0
t$perf-norm:sp_supplier_invoice 5345 5345 0 0.00 0 5536 19 0 0
t$perf-norm:sp_supplier_order 5349 5349 0 0.00 0 5414 15 0 0
t$perf-norm:srv_aggregate_perf_data 86 86 0 0.00 0 32 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 17 1 0 0
t$perf-norm:srv_make_invnt_saldo 574 574 0 0.00 0 703 20 0 0
t$perf-norm:srv_make_money_saldo 710 710 0 0.00 0 1505 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 3 0 0
v_add_invoice_to_stock 9836 9608 228 2.31 0 8759 20 0 228
v_all_customers 36414 36414 0 0.00 0 78 0 0 0
v_all_suppliers 29810 29810 0 0.00 0 15 1 0 0
v_all_wares 39628 39628 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 760 760 0 0.00 0 724 7 0 0
v_cancel_client_order 3448 3448 0 0.00 0 2836 4 0 0
v_cancel_customer_prepayment 1228 1228 0 0.00 0 2404 7 0 0
v_cancel_customer_reserve 1216 1216 0 0.00 0 6402 12 0 0
v_cancel_payment_to_supplier 886 886 0 0.00 0 4386 10 0 0
v_cancel_supplier_invoice 786 762 24 3.05 0 280 13 0 24
v_cancel_supplier_order 906 906 0 0.00 0 173 3 0 0
v_cancel_write_off 1870 1870 0 0.00 0 6362 17 0 0
v_max_id_clo_ord 5346 5346 0 0.00 0 1560 1 0 0
v_max_id_clo_res 983 983 0 0.00 0 1730 11 0 0
v_max_id_ord_sup 5337 5337 0 0.00 0 4169 4 0 0
v_max_non_paid_invoice 1374 1374 0 0.00 0 1052 3 0 0
v_max_non_paid_realizn 3496 3496 0 0.00 0 1267 3 0 0
v_min_id_clo_ord 5346 5346 0 0.00 0 7728 11 0 0
v_min_id_clo_res 983 983 0 0.00 0 2328 5 0 0
v_min_id_ord_sup 5337 5337 0 0.00 0 4257 5 0 0
v_min_non_paid_invoice 5596 5596 0 0.00 0 16526 23 0 0
v_min_non_paid_realizn 6855 6855 0 0.00 0 12873 22 0 0
v_our_firm 9932 9932 0 0.00 0 17 1 0 0
v_reserve_write_off 19508 19508 0 0.00 0 6404 6 0 0
x_lock_depdocs_on_canc_invoice 12990 12990 0 0.00 0 15786 110 0 0
x_lock_depdocs_on_canc_sup_ord 453 453 0 0.00 0 3829 306 0 0
x_make_qstorno_1000_1200 5349 5349 0 0.00 1 34195 1263 0 0
x_make_qstorno_1200_2000 5345 5345 0 0.00 2 82880 2993 0 0
x_make_qstorno_2100_3300 451847 451847 0 0.00 0 60979 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-01-2022 01:39:05 to 06-01-2022 01:39:09.

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:04 9 8192000000 8801913504 8814170112 6674320 0.081 3 1 2 0 6775248 58144 42784
23:09:05 41624 8192000000 9913643488 10224803840 990022992 10.782 100 88 176 0 1019152288 5948992 23180304
23:10:52 28016 8192000000 9950163136 10271883264 1031493104 11.183 100 89 178 1 1063106480 8808672 22804704
23:12:23 10552 8192000000 9983787312 10320785408 1070878752 11.560 100 79 158 0 1100329152 9550880 19899520
23:13:37 15122 8192000000 10046658752 10399547392 1131303328 12.134 100 75 150 0 1160221312 11318528 17599456
23:14:54 6547 8192000000 10097932752 10465894400 1187920784 12.664 100 69 138 1 1215568352 11811024 15836544
23:16:02 4465 8192000000 10132199680 10504835072 1226415104 13.021 100 85 170 2 1259205776 13694544 19096128
23:17:08 3264 8192000000 10179511936 10557198336 1260574752 13.335 100 84 168 0 1291849840 12906944 18368144
23:18:14 2114 8192000000 10206686640 10589110272 1289364016 13.598 100 95 189 1 1323331664 13537984 20429664
23:19:18 1566 8192000000 10206288512 10597834752 1302286864 13.716 100 73 146 1 1330381360 11576208 16518288
23:20:20 4329 8192000000 10199719024 10608844800 1312536512 13.809 100 35 70 1 1328916784 7805616 8574656
23:21:25 12446 8192000000 10225328288 10626818048 1315368784 13.835 100 63 126 1 1336186864 5120032 15698048
23:22:38 5183 8192000000 10216084160 10633240576 1325977920 13.931 100 32 64 0 1340147616 6580976 7588720
23:23:45 6931 8192000000 10221167184 10639732736 1332517024 13.990 100 26 52 0 1344893088 6395392 5980672
23:24:53 10320 8192000000 10232944096 10647584768 1336245632 14.024 100 27 53 3 1348276784 5293120 6738032
23:26:04 24701 8192000000 10230433744 10655686656 1343265920 14.087 100 17 34 1 1352515728 5472224 3777584
23:27:32 6840 8192000000 10209471216 10625974272 1296874608 13.667 98 30 60 1 1309465616 5709984 6881024
23:28:39 7751 8192000000 10163295088 10572230656 1266417824 13.389 99 24 49 1 1277951152 6161472 5371856
23:29:48 34184 8192000000 10127601712 10511937536 1167831248 12.477 93 23 45 1 1178011488 4879920 5300320
23:31:25 6794 8192000000 10128814288 10525831168 1217409968 12.938 98 27 54 0 1229927760 6230480 6287312
23:32:35 5636 8192000000 10144861328 10546982912 1243137152 13.175 98 25 50 3 1254055344 4934800 5983392
23:33:42 9261 8192000000 10173825696 10584948736 1286574816 13.573 100 21 42 0 1296826608 5150080 5101712
23:34:52 55896 8192000000 10201635008 10612105216 1303356160 13.726 100 29 55 1 1315402000 4983840 7062000
23:36:52 7868 8192000000 10203727696 10620682240 1310687024 13.792 100 26 52 2 1322789088 6251456 5850608
23:38:03 11420 8192000000 10225919136 10636058624 1323206576 13.906 100 51 102 3 1340736256 5920304 11609376
23:39:21 18565 8192000000 10268790000 10677731328 1353661776 14.180 100 57 114 2 1382245664 15780752 12803136
23:40:43 89545 8192000000 10292048752 10720165888 1378042384 14.399 100 45 90 2 1410762976 21764928 10955664
23:43:37 31858 8192000000 10302077152 10738835456 1387347584 14.482 100 37 71 2 1422537008 26488576 8700848
23:45:14 13533 8192000000 10324929392 10752192512 1404974192 14.639 100 49 98 2 1441095040 24558704 11562144
23:46:32 6838 8192000000 10335458112 10757513216 1412821584 14.709 100 64 126 2 1453376608 24988256 15566768
23:47:45 7782 8192000000 10343233312 10772484096 1415885104 14.736 100 52 103 2 1458719120 30303664 12530352
23:48:58 11461 8192000000 10338314944 10763325440 1405307136 14.642 100 65 127 1 1455228080 34256944 15664000
23:50:19 3488 8192000000 10349858032 10763644928 1400405168 14.599 100 87 173 0 1453818480 32724416 20688896
23:51:30 3030 8192000000 10368661904 10776866816 1408631968 14.672 100 84 168 1 1466615216 37600880 20382368
23:52:38 2151 8192000000 10365012560 10778734592 1417183008 14.748 100 83 165 2 1468670608 31048080 20439520
23:53:48 2664 8192000000 10370903808 10781683712 1414215792 14.721 100 88 174 2 1472013744 35782304 22015648
23:54:54 22433 8192000000 10335365344 10769027072 1386889296 14.478 99 39 78 2 1443926848 46689792 10347760
23:56:23 2568 8192000000 10366053536 10778365952 1399978544 14.595 100 77 152 1 1471557984 51321888 20257552
23:57:31 2967 8192000000 10356603072 10771501056 1376909712 14.389 99 71 142 1 1449911424 53743120 19258592
23:58:39 23058 8192000000 10329270576 10756902912 1375684528 14.378 99 36 72 1 1432284912 46989744 9610640
00:00:12 10574 8192000000 10342069264 10765639680 1371252528 14.338 99 48 95 1 1439212576 55209488 12750560
00:01:25 26950 8192000000 10337921136 10767831040 1381420992 14.429 100 33 66 1 1447497264 56530832 9545440
00:02:55 5669 8192000000 10312520416 10746511360 1349207936 14.140 99 32 64 2 1414814672 56188624 9418112
00:04:02 17321 8192000000 10334195776 10768912384 1372251296 14.347 100 27 54 0 1440808896 60317264 8240336
00:05:59 23332 8192000000 10305528432 10742407168 1360029376 14.238 100 30 60 0 1411915120 43742880 8142864
00:07:34 57925 8192000000 10243273136 10686148608 1292462528 13.627 97 8 15 1 1316453120 22258224 1732368
00:09:34 44794 8192000000 10285959568 10713309184 1330665184 13.973 97 37 74 2 1353374304 14212768 8496352
00:11:19 69491 8192000000 10268389392 10711040000 1317940832 13.858 97 10 19 0 1336180560 16189184 2050544
00:13:31 59909 8192000000 10290834160 10742034432 1371592688 14.341 100 5 10 1 1382801648 10315488 893472
00:15:35 48443 8192000000 10293955024 10733821952 1361907728 14.254 99 38 76 1 1377195456 6469536 8818192
00:17:31 22693 8192000000 10303396736 10749128704 1387017344 14.479 100 35 70 1 1407845904 13059680 7768880
00:18:53 82800 8192000000 10303359952 10754732032 1382642320 14.440 99 14 28 0 1404993648 18964880 3386448
00:22:33 79865 8192000000 10323236768 10771607552 1397954560 14.577 100 29 56 0 1420059232 16163344 5941328
00:25:31 49715 8192000000 10323981664 10786607104 1405390176 14.643 100 18 36 1 1424936144 15514768 4031200
00:27:38 41821 8192000000 10349197984 10795200512 1416490192 14.742 100 45 88 0 1442628512 15330800 10807520
00:29:27 24797 8192000000 10318118592 10788069376 1407392496 14.661 100 10 20 0 1424622592 14520688 2709408
00:31:40 58922 8192000000 10326224224 10791657472 1413385936 14.714 100 13 26 0 1432850720 16172752 3292032
00:35:28 59378 8192000000 10314228016 10786054144 1408855760 14.674 99 11 22 0 1419359680 8173088 2330832
00:37:28 59843 8192000000 10310460768 10784735232 1406505744 14.653 99 16 32 0 1418154800 8422416 3226640
00:39:41 93495 8192000000 10317384720 10799104000 1417810992 14.753 100 8 16 0 1429553600 10066272 1676336
00:42:15 20479 8192000000 10328660768 10791972864 1419753648 14.771 100 28 55 1 1436142704 10633264 5755792
00:43:44 28591 8192000000 10297231488 10767560704 1389316384 14.500 99 13 24 0 1399555456 7666912 2572160
00:45:15 10224 8192000000 10325655344 10781212672 1408423984 14.670 100 52 104 0 1433577024 13506656 11646384
00:46:30 60370 8192000000 10295961952 10762682368 1360653776 14.243 97 16 32 0 1377510176 13172960 3683440
00:49:13 44127 8192000000 10261364016 10724282368 1300785216 13.702 94 12 24 0 1316382960 12839344 2758400
00:51:00 49567 8192000000 10251131968 10712072192 1296205936 13.661 95 19 38 1 1314954128 14557936 4190256
00:53:02 26661 8192000000 10273575712 10714828800 1342262672 14.078 99 31 62 0 1361992144 12492752 7236720
00:54:30 87083 8192000000 10253356848 10697355264 1288603024 13.591 95 10 20 1 1307026512 16340336 2083152
00:56:57 70256 8192000000 10239500576 10687782912 1302092848 13.714 96 27 54 0 1316091136 8474976 5523312
00:59:14 41007 8192000000 10233575136 10682900480 1320411488 13.880 98 46 90 1 1345100320 14197888 10490944
01:01:07 54852 8192000000 10241083200 10696843264 1303331392 13.726 96 12 22 1 1316149824 10191264 2627168
01:03:02 98245 8192000000 10247610352 10696560640 1313456352 13.817 98 21 42 1 1332123248 13383920 5282976
01:05:48 82070 8192000000 10239891952 10690088960 1317492960 13.854 98 13 27 0 1342278528 21562624 3222944
01:08:10 68541 8192000000 10283375760 10730299392 1341602208 14.072 98 24 48 0 1355011696 8342176 5067312
01:10:19 70578 8192000000 10292454688 10740637696 1355961088 14.201 98 23 46 3 1374733456 13164112 5608256
01:12:33 61566 8192000000 10293655840 10763177984 1361363520 14.250 98 18 35 0 1388275728 22812624 4099584
01:14:36 90065 8192000000 10303825504 10774122496 1372570992 14.350 99 15 30 0 1392478064 16888768 3018304
01:17:08 40948 8192000000 10308878992 10758393856 1395606064 14.556 100 28 56 1 1412471952 10943328 5922560
01:19:00 92488 8192000000 10299681296 10754064384 1391154016 14.516 100 23 46 1 1404306784 8214768 4938000
01:21:40 87068 8192000000 10311239040 10760617984 1377398144 14.393 98 30 60 1 1396680368 11274688 8007536
01:24:59 37351 8192000000 10275211776 10732650496 1354904528 14.192 98 24 48 0 1372033872 11171952 5957392
01:26:43 72836 8192000000 10297870368 10757214208 1389513760 14.502 100 19 38 1 1402855568 8820512 4521296
01:29:04 47605 8192000000 10296961200 10765926400 1390842304 14.513 100 26 50 0 1408310976 11839920 5628752
01:30:53 53774 8192000000 10275979536 10739257344 1343175936 14.086 97 4 8 0 1354576656 10568864 831856
01:32:47 84309 8192000000 10279359552 10742804480 1326912176 13.939 96 14 28 0 1341544080 11566960 3064944
01:35:12 58724 8192000000 10270137312 10736783360 1336994976 14.030 97 15 30 0 1351661184 11367072 3299136
01:37:29 27965 8192000000 10269051360 10713288704 1327002416 13.940 97 44 86 0 1346079696 9662208 9415072

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 114 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-01-2022 01:39:27 to 06-01-2022 01:39:29.

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          233107
MON$OLDEST_ACTIVE               236062
MON$OLDEST_SNAPSHOT             236062
MON$NEXT_TRANSACTION            236062
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                       2048544
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33548 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33548 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33548 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33548 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 ---
:509: 
:510: mscs-test-161.budzdorov.ru    Thu Jan  6 01:38:01 2022
:511:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:512: 
:513: 
:514: mscs-test-161.budzdorov.ru    Thu Jan  6 01:38:01 2022
:515:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:516: 
:517: 
:518: mscs-test-161.budzdorov.ru    Thu Jan  6 01:38:02 2022
:519:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:520: 
:521: 
:522: mscs-test-161.budzdorov.ru    Thu Jan  6 01:38:02 2022
:523:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:524: 
:525: 
:526: mscs-test-161.budzdorov.ru    Thu Jan  6 01:38:03 2022
:527:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:528: 
:529: 
:530: mscs-test-161.budzdorov.ru    Thu Jan  6 01:38:07 2022
:531:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:532: 
:533: 
:534: mscs-test-161.budzdorov.ru    Thu Jan  6 01:38:07 2022
:535:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:536: 
--- end of diff output ---

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

06.01.22 01:39:30. 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-01-06 01:39:32 No severe PSQL-related problems occured
06.01.22 01:39:33. End of report.