Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.01.2022 01: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 16520576 kB
MemAvailable 31352624 kB
Buffers 0 kB
Cached 15186012 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-05 01:38:04
DTS_END                         2022-01-05 01:38:04.0960
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/44490
TRN_ID                          242004
ATT_ID                          709
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: 9220.00 23573 1106400
customer order: creation 172.88 372 20745
customer order: refuse 14.85 391 1782
order to supplier: creation 44.68 1341 5362
order to supplier: removal 3.25 111815 390
invoice (draft): creation 45.53 3498 5463
invoice (draft): removal 50.53 851 6064
invoice accept: apply 42.74 18227 5129
invoice accept: cancel 46.34 815 5561
customer reserve: creation 3939.21 180 472705
customer reserve: removal 4570.48 62 548458
realization accept: apply 84.31 213 10117
realization accept: cancel 76.72 125 9206
payment from customer: creation 59.68 124 7162
payment from customer: removal 5.91 76 709
payment to supplier: creation 47.87 154 5744
payment to supplier: removal 3.32 92 398
service: total inventory turnovers 4.66 1069 559
service: total monetary turnovers 6.26 32 751
service: aggregate perf. data 0.76 26008 91
service: refresh index statistics 0.03 306019 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 05-01-2022 01:40:26 to 05-01-2022 01:40:28.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3706
TEST_TIME 2 3271
TEST_TIME 3 3669
TEST_TIME 4 4758
TEST_TIME 5 4781
TEST_TIME 6 6069
TEST_TIME 7 5816
TEST_TIME 8 6239
TEST_TIME 9 6401
TEST_TIME 10 6487
TEST_TIME 11 6672
TEST_TIME 12 6908
TEST_TIME 13 6968
TEST_TIME 14 6868
TEST_TIME 15 7188
TEST_TIME 16 8071
TEST_TIME 17 7992
TEST_TIME 18 8405
TEST_TIME 19 8490
TEST_TIME 20 8365
TEST_TIME 21 8511
TEST_TIME 22 8733
TEST_TIME 23 8936
TEST_TIME 24 8849
TEST_TIME 25 8863
TEST_TIME 26 9222
TEST_TIME 27 10142
TEST_TIME 28 10630
TEST_TIME 29 10743
TEST_TIME 30 10937
TEST_TIME 31 10708
TEST_TIME 32 10536
TEST_TIME 33 10325
TEST_TIME 34 10406
TEST_TIME 35 10216
TEST_TIME 36 10148
TEST_TIME 37 10071
TEST_TIME 38 9947
TEST_TIME 39 9854
TEST_TIME 40 9731
TEST_TIME 41 9924
TEST_TIME 42 9859
TEST_TIME 43 9813
TEST_TIME 44 9652
TEST_TIME 45 9539
TEST_TIME 46 9680
TEST_TIME 47 9560
TEST_TIME 48 9592
TEST_TIME 49 9555
TEST_TIME 50 9500
TEST_TIME 51 9442
TEST_TIME 52 9358
TEST_TIME 53 9256
TEST_TIME 54 9135
TEST_TIME 55 9230
TEST_TIME 56 9216
TEST_TIME 57 9226
TEST_TIME 58 9326
TEST_TIME 59 9271
TEST_TIME 60 9446
TEST_TIME 61 9449
TEST_TIME 62 9405
TEST_TIME 63 9440
TEST_TIME 64 9490
TEST_TIME 65 9588
TEST_TIME 66 9575
TEST_TIME 67 9657
TEST_TIME 68 9683
TEST_TIME 69 9634
TEST_TIME 70 9636
TEST_TIME 71 9529
TEST_TIME 72 9494
TEST_TIME 73 9440
TEST_TIME 74 9427
TEST_TIME 75 9360
TEST_TIME 76 9384
TEST_TIME 77 9336
TEST_TIME 78 9257
TEST_TIME 79 9253
TEST_TIME 80 9293
TEST_TIME 81 9317
TEST_TIME 82 9276
TEST_TIME 83 9324
TEST_TIME 84 9304
TEST_TIME 85 9398
TEST_TIME 86 9405
TEST_TIME 87 9418
TEST_TIME 88 9398
TEST_TIME 89 9494
TEST_TIME 90 9462
TEST_TIME 91 9511
TEST_TIME 92 9484
TEST_TIME 93 9398
TEST_TIME 94 9366
TEST_TIME 95 9377
TEST_TIME 96 9324
TEST_TIME 97 9300
TEST_TIME 98 9270
TEST_TIME 99 9286
TEST_TIME 100 9268
TEST_TIME 101 9305
TEST_TIME 102 9270
TEST_TIME 103 9286
TEST_TIME 104 9266
TEST_TIME 105 9373
TEST_TIME 106 9392
TEST_TIME 107 9374
TEST_TIME 108 9397
TEST_TIME 109 9452
TEST_TIME 110 9460
TEST_TIME 111 9433
TEST_TIME 112 9369
TEST_TIME 113 9376
TEST_TIME 114 9352
TEST_TIME 115 9318
TEST_TIME 116 9349
TEST_TIME 117 9319
TEST_TIME 118 9311
TEST_TIME 119 9300
TEST_TIME 120 9225

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 05-01-2022 01:40:29 to 05-01-2022 01:40:33.

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 1543261 1543261 0 0.00 0 43682 6 0 0
doc_list_biud 1543260 1543260 0 0.00 0 31136 28 0 0
sp_add_invoice_to_stock 5247 5129 118 2.24 42 183315 18227 0 118
sp_cancel_adding_invoice 5561 5561 0 0.00 5 72383 815 0 0
sp_cancel_client_order 1782 1782 0 0.00 4 14731 391 0 0
sp_cancel_customer_reserve 548458 548458 0 0.00 1 21931 62 0 0
sp_cancel_pay_from_customer 709 709 0 0.00 2 4706 76 0 0
sp_cancel_pay_to_supplier 398 398 0 0.00 3 5413 93 0 0
sp_cancel_supplier_invoice 6073 6064 9 0.14 2 21649 851 0 9
sp_cancel_supplier_order 390 390 0 0.00 10 1005023 111816 0 0
sp_cancel_write_off 9206 9206 0 0.00 1 20073 126 0 0
sp_client_order 20745 20745 0 0.00 3 29742 373 0 0
sp_customer_reserve 472705 472705 0 0.00 0 77949 180 0 0
sp_fill_shopping_cart 31567 31567 0 0.00 1 19545 42 0 0
sp_fill_shopping_cart_clo_res 472704 472704 0 0.00 0 16729 15 0 0
sp_get_clo_for_invoice 5112 5112 0 0.00 0 26286 304 0 0
sp_kill_cost_storno 15874 15874 0 0.00 0 8429 61 0 0
sp_kill_qstorno_ret_qs2qd 1112576 1112576 0 0.00 0 21929 34 0 0
sp_kill_qty_storno 586690 586690 0 0.00 0 31130 73 0 0
sp_lock_selected_doc 21041 21041 0 0.00 0 10163 8 0 0
sp_make_cost_storno 28135 28135 0 0.00 0 43257 66 0 0
sp_multiply_rows_for_pdistr 28135 28135 0 0.00 0 41710 42 0 0
sp_multiply_rows_for_qdistr 20745 20745 0 0.00 0 13645 151 0 0
sp_pay_from_customer 7162 7162 0 0.00 4 43083 125 0 0
sp_pay_to_supplier 5744 5744 0 0.00 4 43268 154 0 0
sp_payment_common 12906 12906 0 0.00 1 41397 33 0 0
sp_qd_handle_on_cancel_clo 1782 1782 0 0.00 0 14717 343 0 0
sp_qd_handle_on_invoice_upd_sts 10673 10673 0 0.00 0 31123 364 0 0
sp_qd_handle_on_reserve_upd_sts 10117 10117 0 0.00 0 13919 65 0 0
sp_reserve_write_off 10117 10117 0 0.00 6 44255 214 0 0
sp_supplier_invoice 5463 5463 0 0.00 6 91769 3498 0 0
sp_supplier_order 5362 5362 0 0.00 5 35898 1341 0 0
srv_aggregate_perf_data 91 91 0 0.00 2835 707068 26008 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1758 130667 46207 0 0
srv_find_qd_qs_mism 1057970 1057970 0 0.00 0 10024 5 0 0
srv_make_invnt_saldo 559 559 0 0.00 37 40548 1069 0 0
srv_make_money_saldo 751 751 0 0.00 1 3409 32 0 0
srv_recalc_idx_stat 4 4 0 0.00 126569 445686 306019 0 0
t$perf-abend:sp_add_invoice_to_stock 118 118 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 5129 5129 0 0.00 0 10732 167 0 0
t$perf-norm:sp_cancel_adding_invoice 427 427 0 0.00 0 8556 107 0 0
t$perf-norm:sp_cancel_client_order 1782 1782 0 0.00 0 2897 13 0 0
t$perf-norm:sp_cancel_customer_reserve 655 655 0 0.00 0 2840 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 709 709 0 0.00 0 7285 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 398 398 0 0.00 0 3236 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 405 405 0 0.00 0 405 14 0 0
t$perf-norm:sp_cancel_supplier_order 390 390 0 0.00 0 23344 1704 0 0
t$perf-norm:sp_cancel_write_off 1046 1046 0 0.00 0 947 4 0 0
t$perf-norm:sp_client_order 20745 20745 0 0.00 0 10953 17 0 0
t$perf-norm:sp_customer_reserve 1001 1001 0 0.00 0 4184 7 0 0
t$perf-norm:sp_pay_from_customer 7162 7162 0 0.00 0 6231 8 0 0
t$perf-norm:sp_pay_to_supplier 5744 5744 0 0.00 0 5280 9 0 0
t$perf-norm:sp_reserve_write_off 10117 10117 0 0.00 0 8012 12 0 0
t$perf-norm:sp_supplier_invoice 5463 5463 0 0.00 0 4832 15 0 0
t$perf-norm:sp_supplier_order 5362 5362 0 0.00 0 11036 13 0 0
t$perf-norm:srv_aggregate_perf_data 91 91 0 0.00 0 1122 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 1853 38 0 0
t$perf-norm:srv_make_invnt_saldo 559 559 0 0.00 0 2522 24 0 0
t$perf-norm:srv_make_money_saldo 751 751 0 0.00 0 1183 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10460 10224 236 2.25 0 9466 21 0 236
v_all_customers 37494 37494 0 0.00 0 19 1 0 0
v_all_suppliers 29736 29736 0 0.00 0 30 1 0 0
v_all_wares 41490 41490 0 0.00 0 78 1 0 0
v_cancel_adding_invoice 854 854 0 0.00 0 102 5 0 0
v_cancel_client_order 3564 3564 0 0.00 0 3936 6 0 0
v_cancel_customer_prepayment 1418 1418 0 0.00 0 1148 4 0 0
v_cancel_customer_reserve 1310 1310 0 0.00 0 675 3 0 0
v_cancel_payment_to_supplier 796 796 0 0.00 0 373 4 0 0
v_cancel_supplier_invoice 828 810 18 2.17 0 5054 25 0 18
v_cancel_supplier_order 780 780 0 0.00 0 5021 17 0 0
v_cancel_write_off 2092 2092 0 0.00 0 2212 13 0 0
v_max_id_clo_ord 5362 5362 0 0.00 0 775 2 0 0
v_max_id_clo_res 1001 1001 0 0.00 0 3166 14 0 0
v_max_id_ord_sup 5460 5460 0 0.00 0 5385 4 0 0
v_max_non_paid_invoice 1698 1698 0 0.00 0 1854 4 0 0
v_max_non_paid_realizn 3868 3868 0 0.00 0 2222 4 0 0
v_min_id_clo_ord 5362 5362 0 0.00 0 3305 9 0 0
v_min_id_clo_res 1001 1001 0 0.00 0 1387 6 0 0
v_min_id_ord_sup 5460 5460 0 0.00 0 6234 12 0 0
v_min_non_paid_invoice 5744 5744 0 0.00 0 39410 23 0 0
v_min_non_paid_realizn 7162 7162 0 0.00 0 30014 21 0 0
v_our_firm 10584 10584 0 0.00 0 19 1 0 0
v_reserve_write_off 20230 20230 0 0.00 0 6021 6 0 0
x_lock_depdocs_on_canc_invoice 11220 11220 0 0.00 0 15452 128 0 0
x_lock_depdocs_on_canc_sup_ord 390 390 0 0.00 0 5300 380 0 0
x_make_qstorno_1000_1200 5362 5362 0 0.00 2 35888 1263 0 0
x_make_qstorno_1200_2000 5463 5463 0 0.00 2 91443 3340 0 0
x_make_qstorno_2100_3300 472705 472705 0 0.00 0 77948 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-01-2022 01:40:34 to 05-01-2022 01:40:38.

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:07 3 8192000000 8801711424 8813842432 7737296 0.094 2 2 3 0 7878384 66144 74944
23:09:08 28709 8192000000 9892042288 10200530944 972448304 10.611 100 93 186 0 1003494192 5913408 25132480
23:10:39 18664 8192000000 9936473728 10259025920 1021197296 11.084 100 88 176 0 1051946304 7756384 22992624
23:12:00 27128 8192000000 9979354064 10312491008 1057163264 11.429 100 89 178 0 1089334784 9633072 22538448
23:13:36 12925 8192000000 10033364768 10383278080 1120506592 12.032 100 74 148 0 1148309072 9882768 17919712
23:14:52 10032 8192000000 10082109200 10440990720 1170163488 12.498 100 79 158 2 1199504464 11363776 17977200
23:16:08 6571 8192000000 10138396592 10508767232 1223159552 12.991 100 80 161 0 1253087136 12140208 17787376
23:17:16 3445 8192000000 10178342240 10555949056 1264502272 13.371 100 89 178 0 1296385088 12499008 19383808
23:18:21 2041 8192000000 10208966128 10595414016 1289839408 13.603 100 96 192 2 1325190144 13829136 21521600
23:19:24 40585 8192000000 10208374288 10613583872 1306774368 13.757 100 52 105 2 1327483696 7710512 12998816
23:21:06 6382 8192000000 10208341312 10617827328 1313164352 13.815 100 26 50 2 1323808208 5802080 4841776
23:22:14 15250 8192000000 10207410576 10628403200 1320276304 13.879 100 10 20 2 1328041904 5610928 2154672
23:23:34 68410 8192000000 10227281152 10644692992 1329323632 13.961 100 44 88 2 1345969984 5468352 11178000
23:25:43 3959 8192000000 10230611120 10653220864 1337994096 14.039 100 36 72 3 1353352256 7666928 7691232
23:26:50 6675 8192000000 10232553072 10652368896 1325486448 13.926 99 21 43 2 1335744784 5913248 4345088
23:28:03 28162 8192000000 10195354016 10615123968 1259779024 13.328 95 30 61 2 1273054144 5782624 7492496
23:29:59 5839 8192000000 10157836208 10553475072 1240836720 13.154 98 35 70 0 1255575680 6208384 8530576
23:31:06 7776 8192000000 10131149888 10528587776 1224940544 13.007 99 17 34 0 1234241280 5229904 4070832
23:32:14 15686 8192000000 10145311424 10543120384 1226124560 13.018 98 10 20 2 1233207264 4982736 2099968
23:33:32 58135 8192000000 10162803312 10578243584 1274924144 13.467 100 26 52 0 1290505792 9708560 5873088
23:35:30 5810 8192000000 10172878128 10589384704 1287728720 13.584 100 26 52 1 1300593536 6781696 6083120
23:36:36 10013 8192000000 10196239680 10609545216 1302004560 13.713 100 13 25 0 1309654832 4845600 2804672
23:37:47 9303 8192000000 10202971872 10622484480 1312660752 13.810 100 15 30 2 1320860864 5302576 2897536
23:39:04 18002 8192000000 10250669792 10655109120 1340593040 14.063 100 48 96 0 1359558688 7925360 11040288
23:40:25 40865 8192000000 10258293248 10676555776 1354449504 14.187 100 40 80 0 1373084416 9365936 9268976
23:42:16 24666 8192000000 10272828448 10693767168 1366462160 14.295 100 44 88 3 1391091536 14364048 10265328
23:44:01 15219 8192000000 10301916448 10723966976 1385026144 14.461 100 56 112 1 1416857104 17928320 13902640
23:45:23 9938 8192000000 10315178672 10736504832 1396281536 14.562 100 56 113 1 1424822880 15242832 13298512
23:46:41 10315 8192000000 10324906816 10746904576 1394544864 14.546 99 72 144 1 1428193824 17096352 16552608
23:48:00 14067 8192000000 10349582512 10775535616 1421652208 14.787 100 54 108 1 1453310000 18363680 13294112
23:49:29 9632 8192000000 10350845968 10768683008 1415791584 14.735 100 62 124 1 1454985280 23733856 15459840
23:50:43 2569 8192000000 10346403632 10764402688 1403439312 14.626 99 72 144 0 1445988512 25230912 17318288
23:51:52 1756 8192000000 10348241440 10759462912 1403597376 14.627 100 87 174 2 1456065936 31394560 21074000
23:52:58 2651 8192000000 10369650336 10778558464 1415432320 14.732 100 91 180 3 1477617648 39117248 23068080
23:54:03 9151 8192000000 10352027152 10768752640 1402512176 14.617 100 71 142 1 1460599616 40335664 17751776
23:55:18 7977 8192000000 10340109984 10745147392 1369920528 14.326 99 81 161 1 1430691680 40447296 20323856
23:56:33 3155 8192000000 10333508352 10742468608 1370985312 14.336 99 75 151 2 1428857312 39113056 18758944
23:57:40 5464 8192000000 10350036112 10768011264 1391708000 14.521 99 61 122 0 1447329808 40327600 15294208
23:59:06 4039 8192000000 10365034672 10780561408 1404982592 14.639 100 68 135 1 1462574256 40034496 17557168
00:00:15 10337 8192000000 10361405296 10791313408 1412250352 14.704 100 41 83 2 1468302816 45097120 10955344
00:01:30 11990 8192000000 10348644080 10783092736 1403398192 14.625 100 27 54 1 1455033152 43839136 7795824
00:02:54 14457 8192000000 10355560176 10786136064 1398819456 14.584 100 38 76 0 1457122688 48079968 10223264
00:04:11 4636 8192000000 10346750704 10780340224 1386539488 14.475 100 44 88 1 1453401488 54846672 12015328
00:05:20 17301 8192000000 10313475616 10762539008 1357896688 14.218 99 21 42 0 1412244960 48015648 6332624
00:06:41 31384 8192000000 10289401600 10731982848 1349563008 14.144 99 15 30 1 1379471008 25861296 4046704
00:08:15 13977 8192000000 10301082704 10740883456 1377018160 14.390 100 43 86 3 1402886912 15822112 10046640
00:09:35 80435 8192000000 10287709024 10735312896 1353210784 14.176 98 28 56 1 1374212832 14877648 6124400
00:11:58 47567 8192000000 10280623488 10733350912 1365061808 14.283 99 26 52 1 1384061744 13000672 5999264
00:14:00 61772 8192000000 10283905296 10736939008 1366027408 14.291 99 18 36 0 1386654016 16547872 4078736
00:16:17 83556 8192000000 10271775152 10723352576 1335100368 14.013 97 16 32 0 1351324192 12785056 3438768
00:18:41 58318 8192000000 10276922512 10731560960 1353617168 14.180 98 21 40 0 1378823344 20784672 4421504
00:20:39 95798 8192000000 10284267520 10733637632 1365176864 14.284 99 19 38 0 1380516336 10996672 4342800
00:23:30 87054 8192000000 10288996896 10760962048 1385662160 14.467 100 20 40 0 1407301904 16117328 5522416
00:26:03 50259 8192000000 10293240992 10759888896 1395440736 14.554 100 13 26 0 1408697936 10677072 2580128
00:27:58 43903 8192000000 10286770784 10749038592 1393604384 14.538 100 11 22 0 1403036352 7085104 2346864
00:29:58 42278 8192000000 10286552992 10751016960 1391086048 14.516 100 9 17 0 1400506464 7773152 1647264
00:31:40 60363 8192000000 10309292608 10767581184 1399888256 14.594 100 21 42 0 1414010704 9384016 4738432
00:33:55 48393 8192000000 10290565472 10756337664 1377484912 14.394 99 11 22 1 1395061424 14958096 2618416
00:35:43 57777 8192000000 10286471712 10742030336 1353602720 14.180 97 20 40 1 1368991504 10957312 4431472
00:37:44 90050 8192000000 10289402128 10765107200 1353088896 14.175 97 10 20 1 1372875664 17909040 1877728
00:40:44 14678 8192000000 10290273216 10727772160 1354076896 14.184 98 54 108 1 1379060528 13146976 11836656
00:42:02 65944 8192000000 10281878608 10734735360 1323165168 13.905 95 19 38 0 1347863312 20105280 4592864
00:44:08 40799 8192000000 10285322768 10727710720 1365289232 14.285 100 26 50 1 1381840544 11401840 5149472
00:45:55 54463 8192000000 10283679792 10728849408 1329357968 13.961 96 28 56 0 1348004272 12564784 6081520
00:48:03 130666 8192000000 10268724224 10714824704 1325232880 13.924 96 26 52 0 1340807392 9208896 6365616
00:51:14 32161 8192000000 10273967840 10722406400 1360513360 14.242 99 23 46 0 1374537072 8522816 5500896
00:52:47 86633 8192000000 10263185728 10716962816 1351220000 14.158 98 11 22 0 1364138016 10635296 2282720
00:55:18 70236 8192000000 10269399152 10724003840 1327758384 13.947 96 14 28 0 1340882128 10192496 2931248
00:57:32 78755 8192000000 10267800208 10727677952 1311820240 13.803 95 11 22 0 1323108992 8742208 2546544
00:59:59 26728 8192000000 10271641776 10716581888 1347099536 14.121 98 20 40 0 1357749968 6126976 4523456
01:01:30 91761 8192000000 10287330576 10739253248 1365024192 14.282 99 17 34 2 1384891520 16074320 3793008
01:04:10 86190 8192000000 10291277712 10747334656 1369069040 14.319 99 16 32 0 1388419040 15774576 3575424
01:06:37 41688 8192000000 10288901552 10735685632 1373951248 14.362 99 36 72 2 1395588832 13555344 8082240
01:08:26 80474 8192000000 10283561040 10734387200 1311501424 13.800 94 15 29 1 1325891296 11556080 2833792
01:10:50 101427 8192000000 10303104144 10755096576 1394093920 14.542 100 27 53 0 1412156896 12569408 5493568
01:13:39 50068 8192000000 10301961520 10770587648 1398249664 14.579 100 10 20 0 1416054864 15900368 1904832
01:15:29 47904 8192000000 10309176928 10765189120 1365555872 14.287 97 13 25 0 1380150000 11586112 3008016
01:17:21 68522 8192000000 10297081792 10741149696 1372705632 14.351 98 19 38 0 1390371920 13266864 4399424
01:19:40 48242 8192000000 10313550960 10761703424 1381522304 14.430 98 43 86 3 1403016720 11065328 10429088
01:21:33 101155 8192000000 10307083008 10765643776 1360683184 14.243 97 5 10 0 1377056768 15436240 937344
01:24:19 61199 8192000000 10308591568 10756550656 1381408048 14.429 99 15 30 1 1399281552 14186368 3687136
01:26:24 67145 8192000000 10304961424 10762936320 1358377248 14.223 97 16 31 1 1375976240 13518304 4080688
01:28:38 51269 8192000000 10281318064 10725462016 1338041952 14.040 97 12 25 0 1349342816 8396800 2904064
01:30:30 120596 8192000000 10283718224 10736074752 1348775424 14.136 98 36 72 1 1367177520 10277536 8124560
01:33:32 30011 8192000000 10285157968 10730704896 1358165264 14.221 99 18 36 0 1374972288 12180688 4626336
01:35:10 86609 8192000000 10253276672 10703937536 1334560208 14.008 97 13 26 1 1349945360 12313040 3072112
01:37:48 30739 8192000000 9357896672 9658298368 517713584 5.944 38 6 9 1 523688896 4455280 1520032

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 05-01-2022 01:40:40 to 05-01-2022 01:40:55.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 118 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 05-01-2022 01:40:55 to 05-01-2022 01:40:57.

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          242293
MON$OLDEST_ACTIVE               242294
MON$OLDEST_SNAPSHOT             242294
MON$NEXT_TRANSACTION            242294
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                       2031648
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 ---
:449: 
:450: mscs-test-161.budzdorov.ru    Wed Jan  5 01:38:24 2022
:451:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:452: 
:453: 
:454: mscs-test-161.budzdorov.ru    Wed Jan  5 01:38:27 2022
:455:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:456: 
:457: 
:458: mscs-test-161.budzdorov.ru    Wed Jan  5 01:38:27 2022
:459:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:460: 
:461: 
:462: mscs-test-161.budzdorov.ru    Wed Jan  5 01:38:27 2022
:463:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:464: 
:465: 
:466: mscs-test-161.budzdorov.ru    Wed Jan  5 01:38:28 2022
:467:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:468: 
--- end of diff output ---

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

05.01.22 01:40:58. 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-05 01:41:01 No severe PSQL-related problems occured
05.01.22 01:41:02. End of report.