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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16209708 kB
MemAvailable 31100092 kB
Buffers 0 kB
Cached 15483196 kB
SwapCached 2520 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-10 01:38:16
DTS_END                         2022-07-10 01:38:16.7850
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/44602
TRN_ID                          241420
ATT_ID                          720
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: 9243.24 19786 1109189
customer order: creation 175.52 354 21062
customer order: refuse 15.32 398 1838
order to supplier: creation 46.30 1271 5556
order to supplier: removal 3.27 105896 392
invoice (draft): creation 45.81 3554 5497
invoice (draft): removal 50.88 820 6105
invoice accept: apply 42.32 17852 5078
invoice accept: cancel 46.18 823 5542
customer reserve: creation 3948.03 175 473763
customer reserve: removal 4565.00 59 547800
realization accept: apply 85.38 177 10246
realization accept: cancel 87.25 107 10470
payment from customer: creation 59.63 117 7156
payment from customer: removal 5.79 66 695
payment to supplier: creation 51.27 144 6152
payment to supplier: removal 3.56 56 427
service: total inventory turnovers 4.64 1002 557
service: total monetary turnovers 6.29 24 755
service: aggregate perf. data 0.78 27920 94
service: refresh index statistics 0.03 234905 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 10-07-2022 01:39:19 to 10-07-2022 01:39:22.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 10516
TEST_TIME 2 6541
TEST_TIME 3 5754
TEST_TIME 4 5216
TEST_TIME 5 6206
TEST_TIME 6 5771
TEST_TIME 7 5502
TEST_TIME 8 5778
TEST_TIME 9 5980
TEST_TIME 10 5953
TEST_TIME 11 5927
TEST_TIME 12 6085
TEST_TIME 13 6270
TEST_TIME 14 6408
TEST_TIME 15 6602
TEST_TIME 16 6529
TEST_TIME 17 6853
TEST_TIME 18 7422
TEST_TIME 19 8005
TEST_TIME 20 8459
TEST_TIME 21 8381
TEST_TIME 22 8691
TEST_TIME 23 8678
TEST_TIME 24 8626
TEST_TIME 25 8611
TEST_TIME 26 9089
TEST_TIME 27 9583
TEST_TIME 28 9736
TEST_TIME 29 10701
TEST_TIME 30 10622
TEST_TIME 31 10754
TEST_TIME 32 10459
TEST_TIME 33 10335
TEST_TIME 34 10289
TEST_TIME 35 10233
TEST_TIME 36 10094
TEST_TIME 37 10176
TEST_TIME 38 10081
TEST_TIME 39 9914
TEST_TIME 40 9800
TEST_TIME 41 9759
TEST_TIME 42 9712
TEST_TIME 43 9750
TEST_TIME 44 9557
TEST_TIME 45 9587
TEST_TIME 46 9484
TEST_TIME 47 9450
TEST_TIME 48 9353
TEST_TIME 49 9310
TEST_TIME 50 9310
TEST_TIME 51 9298
TEST_TIME 52 9203
TEST_TIME 53 9292
TEST_TIME 54 9171
TEST_TIME 55 9136
TEST_TIME 56 9126
TEST_TIME 57 9142
TEST_TIME 58 9104
TEST_TIME 59 9144
TEST_TIME 60 9213
TEST_TIME 61 9182
TEST_TIME 62 9361
TEST_TIME 63 9299
TEST_TIME 64 9404
TEST_TIME 65 9345
TEST_TIME 66 9341
TEST_TIME 67 9521
TEST_TIME 68 9546
TEST_TIME 69 9582
TEST_TIME 70 9615
TEST_TIME 71 9524
TEST_TIME 72 9433
TEST_TIME 73 9488
TEST_TIME 74 9421
TEST_TIME 75 9366
TEST_TIME 76 9382
TEST_TIME 77 9364
TEST_TIME 78 9309
TEST_TIME 79 9326
TEST_TIME 80 9248
TEST_TIME 81 9207
TEST_TIME 82 9204
TEST_TIME 83 9210
TEST_TIME 84 9276
TEST_TIME 85 9286
TEST_TIME 86 9285
TEST_TIME 87 9271
TEST_TIME 88 9343
TEST_TIME 89 9312
TEST_TIME 90 9350
TEST_TIME 91 9350
TEST_TIME 92 9393
TEST_TIME 93 9456
TEST_TIME 94 9461
TEST_TIME 95 9431
TEST_TIME 96 9455
TEST_TIME 97 9551
TEST_TIME 98 9494
TEST_TIME 99 9458
TEST_TIME 100 9421
TEST_TIME 101 9366
TEST_TIME 102 9360
TEST_TIME 103 9318
TEST_TIME 104 9319
TEST_TIME 105 9319
TEST_TIME 106 9262
TEST_TIME 107 9212
TEST_TIME 108 9204
TEST_TIME 109 9206
TEST_TIME 110 9236
TEST_TIME 111 9246
TEST_TIME 112 9244
TEST_TIME 113 9242
TEST_TIME 114 9281
TEST_TIME 115 9258
TEST_TIME 116 9264
TEST_TIME 117 9308
TEST_TIME 118 9283
TEST_TIME 119 9305
TEST_TIME 120 9250

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 10-07-2022 01:39:23 to 10-07-2022 01:39:26.

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 1546404 1546404 0 0.00 0 22117 6 0 0
doc_list_biud 1546392 1546392 0 0.00 0 50643 27 0 0
sp_add_invoice_to_stock 5176 5078 98 1.89 31 152308 17852 0 98
sp_cancel_adding_invoice 5542 5542 0 0.00 5 57628 824 0 0
sp_cancel_client_order 1838 1838 0 0.00 4 13807 398 0 0
sp_cancel_customer_reserve 547800 547800 0 0.00 0 50644 60 0 0
sp_cancel_pay_from_customer 695 695 0 0.00 3 4992 67 0 0
sp_cancel_pay_to_supplier 427 427 0 0.00 3 2389 56 0 0
sp_cancel_supplier_invoice 6114 6105 9 0.14 1 20562 820 0 9
sp_cancel_supplier_order 392 392 0 0.00 5 990879 105897 0 0
sp_cancel_write_off 10470 10470 0 0.00 0 11469 107 0 0
sp_client_order 21062 21062 0 0.00 3 49143 354 0 0
sp_customer_reserve 473763 473763 0 0.00 0 70171 175 0 0
sp_fill_shopping_cart 32099 32099 0 0.00 1 14556 40 0 0
sp_fill_shopping_cart_clo_res 473752 473752 0 0.00 0 20973 14 0 0
sp_get_clo_for_invoice 5067 5067 0 0.00 0 21531 323 0 0
sp_kill_cost_storno 17134 17134 0 0.00 0 8299 55 0 0
sp_kill_qstorno_ret_qs2qd 1112567 1112567 0 0.00 0 50640 32 0 0
sp_kill_qty_storno 587459 587459 0 0.00 0 50642 70 0 0
sp_lock_selected_doc 21222 21222 0 0.00 0 8139 7 0 0
sp_make_cost_storno 28620 28620 0 0.00 0 16167 54 0 0
sp_multiply_rows_for_pdistr 28620 28620 0 0.00 0 9170 26 0 0
sp_multiply_rows_for_qdistr 21062 21062 0 0.00 0 38006 144 0 0
sp_pay_from_customer 7156 7156 0 0.00 4 14572 118 0 0
sp_pay_to_supplier 6152 6152 0 0.00 4 23269 145 0 0
sp_payment_common 13307 13307 0 0.00 1 13129 29 0 0
sp_qd_handle_on_cancel_clo 1838 1838 0 0.00 0 12155 359 0 0
sp_qd_handle_on_invoice_upd_sts 10608 10608 0 0.00 0 14838 327 0 0
sp_qd_handle_on_reserve_upd_sts 10246 10246 0 0.00 0 12816 56 0 0
sp_reserve_write_off 10246 10246 0 0.00 6 12920 178 0 0
sp_supplier_invoice 5497 5497 0 0.00 6 69197 3555 0 0
sp_supplier_order 5556 5556 0 0.00 4 51429 1272 0 0
srv_aggregate_perf_data 94 94 0 0.00 2192 736451 27921 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2675 152534 42807 0 0
srv_find_qd_qs_mism 1059724 1059724 0 0.00 0 22116 5 0 0
srv_make_invnt_saldo 557 557 0 0.00 39 66236 1003 0 0
srv_make_money_saldo 755 755 0 0.00 0 680 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 114555 379370 234906 0 0
t$perf-abend:sp_add_invoice_to_stock 98 98 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 5078 5078 0 0.00 0 12001 151 0 0
t$perf-norm:sp_cancel_adding_invoice 424 424 0 0.00 0 3390 86 0 0
t$perf-norm:sp_cancel_client_order 1838 1838 0 0.00 0 3810 14 0 0
t$perf-norm:sp_cancel_customer_reserve 713 713 0 0.00 0 2486 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 695 695 0 0.00 0 690 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 427 427 0 0.00 0 1577 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 402 402 0 0.00 0 4260 26 0 0
t$perf-norm:sp_cancel_supplier_order 392 392 0 0.00 0 19651 1647 0 0
t$perf-norm:sp_cancel_write_off 1019 1019 0 0.00 0 1289 6 0 0
t$perf-norm:sp_client_order 21062 21062 0 0.00 0 9273 15 0 0
t$perf-norm:sp_customer_reserve 1015 1015 0 0.00 0 5446 13 0 0
t$perf-norm:sp_pay_from_customer 7156 7156 0 0.00 0 7370 9 0 0
t$perf-norm:sp_pay_to_supplier 6152 6152 0 0.00 0 14210 12 0 0
t$perf-norm:sp_reserve_write_off 10246 10246 0 0.00 0 6981 15 0 0
t$perf-norm:sp_supplier_invoice 5497 5497 0 0.00 0 5527 16 0 0
t$perf-norm:sp_supplier_order 5556 5556 0 0.00 0 5343 17 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 2116 24 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 6 0 0 0
t$perf-norm:srv_make_invnt_saldo 557 557 0 0.00 0 2051 26 0 0
t$perf-norm:srv_make_money_saldo 755 755 0 0.00 0 11457 33 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 3 0 0
v_add_invoice_to_stock 10328 10132 196 1.89 0 7892 15 0 196
v_all_customers 38312 38312 0 0.00 0 22 0 0 0
v_all_suppliers 30878 30878 0 0.00 0 23 1 0 0
v_all_wares 42122 42122 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 848 848 0 0.00 0 3127 9 0 0
v_cancel_client_order 3676 3676 0 0.00 0 5551 7 0 0
v_cancel_customer_prepayment 1390 1390 0 0.00 0 4928 6 0 0
v_cancel_customer_reserve 1426 1426 0 0.00 0 500 3 0 0
v_cancel_payment_to_supplier 854 854 0 0.00 0 541 4 0 0
v_cancel_supplier_invoice 822 804 18 2.18 0 823 13 0 18
v_cancel_supplier_order 784 784 0 0.00 0 2988 10 0 0
v_cancel_write_off 2038 2038 0 0.00 0 9667 23 0 0
v_max_id_clo_ord 5555 5555 0 0.00 0 1582 1 0 0
v_max_id_clo_res 1015 1015 0 0.00 0 4389 14 0 0
v_max_id_ord_sup 5483 5483 0 0.00 0 7490 6 0 0
v_max_non_paid_invoice 1751 1751 0 0.00 0 310 2 0 0
v_max_non_paid_realizn 3771 3771 0 0.00 0 4981 5 0 0
v_min_id_clo_ord 5555 5555 0 0.00 0 4767 11 0 0
v_min_id_clo_res 1015 1015 0 0.00 0 3775 13 0 0
v_min_id_ord_sup 5483 5483 0 0.00 0 3551 8 0 0
v_min_non_paid_invoice 6152 6152 0 0.00 0 10631 18 0 0
v_min_non_paid_realizn 7155 7155 0 0.00 0 13114 25 0 0
v_our_firm 10578 10578 0 0.00 0 26 1 0 0
v_reserve_write_off 20491 20491 0 0.00 0 9135 8 0 0
x_lock_depdocs_on_canc_invoice 11245 11245 0 0.00 0 18565 125 0 0
x_lock_depdocs_on_canc_sup_ord 392 392 0 0.00 0 10888 385 0 0
x_make_qstorno_1000_1200 5556 5556 0 0.00 1 51419 1189 0 0
x_make_qstorno_1200_2000 5497 5497 0 0.00 3 69171 3403 0 0
x_make_qstorno_2100_3300 473763 473763 0 0.00 0 70171 159 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-07-2022 01:39:27 to 10-07-2022 01:39:31.

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:20 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:22 21047 8192000000 9890501568 10199109632 975578160 10.641 100 89 178 0 1005524064 5951936 23993968
23:10:48 20333 8192000000 9937574944 10259656704 1023854992 11.109 100 91 182 0 1055168256 8082512 23230752
23:12:12 20829 8192000000 9981575104 10314641408 1057943920 11.437 100 87 174 0 1089368528 9781264 21643344
23:13:41 13079 8192000000 10041766432 10388246528 1117621232 12.005 100 74 147 0 1145540544 10731968 17187344
23:15:02 4729 8192000000 10080154080 10437935104 1168524656 12.483 100 80 160 0 1198662576 11458032 18679888
23:16:09 5945 8192000000 10124401472 10491559936 1214595680 12.912 100 78 155 0 1244701872 12965152 17141040
23:17:19 1898 8192000000 10160323856 10536501248 1249790912 13.236 100 86 170 0 1282699632 13644336 19264384
23:18:22 1262 8192000000 10184367776 10564947968 1271297136 13.433 100 87 174 1 1304684784 14407856 18979792
23:19:25 1193 8192000000 10207352032 10591940608 1294020208 13.641 100 82 162 1 1324253744 12776528 17457008
23:20:28 19250 8192000000 10191580592 10600005632 1298918240 13.685 100 35 70 0 1318454816 11725312 7811264
23:21:49 3542 8192000000 10202946624 10613727232 1312123840 13.805 100 31 60 1 1325815792 7142704 6549248
23:22:54 3227 8192000000 10204909760 10618896384 1318469104 13.863 100 30 59 0 1332853440 7465408 6918928
23:24:03 7390 8192000000 10210971744 10627084288 1324507264 13.917 100 22 38 1 1334608656 5753408 4347984
23:25:14 62474 8192000000 10221559632 10641506304 1328918000 13.957 100 21 42 0 1339422784 6223552 4281232
23:27:19 5420 8192000000 10226602416 10645159936 1332167296 13.987 100 31 62 0 1346089088 6563760 7358032
23:28:31 10258 8192000000 10202371840 10615189504 1290073168 13.605 98 14 28 1 1298243824 4578928 3591728
23:29:42 50880 8192000000 10189474000 10605379584 1291820640 13.621 99 28 56 1 1304308192 5997776 6489776
23:31:36 3783 8192000000 10130309296 10523811840 1222399696 12.984 98 28 56 1 1234671440 5683984 6587760
23:32:42 4900 8192000000 10149230736 10544832512 1242703424 13.171 99 30 58 1 1256059072 6373424 6982224
23:33:48 10004 8192000000 10154473248 10561474560 1249187824 13.231 98 20 40 0 1259880384 5872944 4819616
23:35:02 34049 8192000000 10171642880 10586853376 1273890720 13.457 99 19 38 1 1283469968 4983184 4596064
23:36:37 8319 8192000000 10180604880 10595790848 1295025376 13.650 100 20 40 0 1305524448 5823744 4675328
23:37:47 4920 8192000000 10198723056 10612731904 1306482144 13.754 100 30 60 0 1319915536 6275056 7158336
23:39:02 5312 8192000000 10240595088 10646667264 1333968192 14.003 100 47 91 2 1351521616 7375472 10177952
23:40:16 76925 8192000000 10259976160 10678571008 1350557168 14.152 100 43 85 0 1373465904 13300480 9608256
23:42:45 17285 8192000000 10282200032 10698141696 1364828624 14.281 100 49 96 0 1390887696 15041312 11017760
23:44:07 30529 8192000000 10290401024 10717048832 1382907616 14.443 100 42 82 0 1406598576 13293632 10397328
23:45:56 10925 8192000000 10312716384 10739421184 1400320224 14.598 100 52 104 1 1431172080 17893856 12958000
23:47:27 8510 8192000000 10330856544 10758737920 1412191728 14.703 100 52 104 0 1441851424 17149856 12509840
23:48:43 4494 8192000000 10344826448 10771025920 1419440704 14.768 100 52 104 0 1454268528 22327488 12500336
23:49:51 9127 8192000000 10356542592 10780049408 1424225040 14.810 100 63 126 1 1467804144 28342176 15236928
23:51:06 4204 8192000000 10363652672 10786131968 1420317344 14.776 100 66 132 1 1467487488 30470080 16700064
23:52:15 9213 8192000000 10372715440 10797592576 1426884528 14.834 100 66 131 0 1478220480 35645536 15690416
23:53:33 6697 8192000000 10367856624 10791104512 1422271312 14.793 100 59 118 1 1474156080 36738864 15145904
23:54:44 3141 8192000000 10342813792 10747965440 1385840864 14.469 99 74 144 0 1447244848 42847392 18556592
23:55:54 3453 8192000000 10356223488 10765733888 1385424768 14.465 99 74 145 0 1453037120 49002624 18609728
23:57:04 7021 8192000000 10328989872 10762522624 1368590096 14.314 98 36 72 0 1424554384 46444384 9519904
23:58:17 2674 8192000000 10360077344 10771480576 1386082768 14.471 100 82 163 2 1460230768 53350032 20797968
23:59:23 30287 8192000000 10329890768 10758000640 1372680608 14.351 99 39 78 0 1430589792 47347920 10561264
00:01:04 6695 8192000000 10336232208 10766389248 1389983936 14.506 100 27 54 0 1440382256 43221216 7177104
00:02:14 13277 8192000000 10346755856 10779283456 1388020160 14.488 99 33 66 1 1445054272 48225328 8808784
00:03:36 11389 8192000000 10349190272 10776834048 1391092640 14.516 100 32 64 0 1454482160 54568432 8821088
00:04:49 23688 8192000000 10339812384 10787368960 1391604448 14.520 100 23 46 0 1447641040 49269216 6767376
00:06:19 34176 8192000000 10295509024 10725789696 1306048336 13.750 96 24 49 0 1364943216 51086752 7808128
00:07:56 53023 8192000000 10271866608 10707337216 1319832368 13.875 97 14 28 0 1345063936 21735552 3496016
00:10:06 44320 8192000000 10276764512 10695667712 1349602320 14.144 99 47 94 1 1366756976 6855872 10298784
00:11:51 20761 8192000000 10283523472 10723897344 1366748944 14.298 100 42 83 0 1391030896 14866464 9415488
00:13:20 57871 8192000000 10263011664 10704322560 1335177344 14.014 98 11 20 0 1350463984 13171504 2115136
00:15:18 96329 8192000000 10264645408 10710933504 1336997568 14.030 98 29 56 1 1354715728 10732208 6985952
00:18:05 113525 8192000000 10282415152 10733088768 1378509152 14.403 100 23 46 0 1398170032 14386416 5274464
00:21:00 40875 8192000000 10280146304 10737315840 1373734656 14.360 99 11 20 2 1387358416 11319168 2304592
00:22:45 45532 8192000000 10297260448 10740985856 1375050080 14.372 99 40 80 0 1392130656 7760944 9319632
00:24:37 25874 8192000000 10280692064 10733019136 1381013104 14.426 99 46 86 0 1397925600 7484912 9427584
00:26:05 28708 8192000000 10301016096 10762145792 1399350320 14.589 100 12 24 0 1417891248 15525984 3014944
00:28:32 38962 8192000000 10303420336 10759634944 1402533488 14.618 100 26 48 0 1420621088 11716608 6370992
00:30:22 47925 8192000000 10292628032 10757091328 1388598560 14.493 99 8 17 0 1405230592 14603568 2028464
00:32:19 37538 8192000000 10296882080 10750070784 1396882400 14.567 100 20 42 0 1408730944 7199360 4649184
00:34:05 55372 8192000000 10285949088 10751385600 1375827760 14.379 98 10 19 0 1384873696 6965232 2080704
00:36:01 57844 8192000000 10309727072 10767720448 1375338080 14.375 98 9 18 0 1385180752 8024736 1817936
00:38:02 83251 8192000000 10292798864 10750996480 1359059632 14.229 97 18 36 0 1378573344 15529792 3983920
00:40:25 60834 8192000000 10293262000 10740310016 1311542864 13.800 94 17 34 0 1327084208 12210560 3330784
00:42:39 37005 8192000000 10278492944 10718097408 1338650672 14.045 97 31 62 0 1359197888 13251360 7295856
00:44:16 83290 8192000000 10285643904 10750529536 1368483392 14.313 99 13 26 0 1389383744 17688848 3211504
00:46:41 152534 8192000000 10264842544 10712080384 1268182176 13.405 92 14 28 0 1283336080 11920816 3233088
00:50:21 92173 8192000000 10263636064 10718674944 1359983376 14.237 99 36 70 1 1381428352 12456832 8988144
00:53:14 11137 8192000000 10275258464 10716102656 1364297008 14.276 100 32 64 3 1380920880 9386976 7236896
00:54:26 42332 8192000000 10244082016 10697785344 1342966304 14.084 99 15 30 0 1355395536 9024432 3404800
00:56:24 60101 8192000000 10233261712 10678484992 1302349152 13.717 97 19 38 0 1323328064 16618768 4360144
00:58:27 76001 8192000000 10238401808 10688192512 1337591504 14.036 100 19 38 0 1355302416 13418384 4292528
01:00:44 71435 8192000000 10256012464 10706460672 1342682112 14.082 99 14 29 0 1359444144 13342336 3419696
01:03:03 22002 8192000000 10266051664 10704625664 1346602912 14.117 99 36 70 3 1362058352 7464784 7990656
01:04:26 62775 8192000000 10260659232 10708811776 1336481872 14.026 98 21 41 0 1353263104 12827360 3953872
01:06:53 32017 8192000000 10269096528 10714357760 1363062032 14.265 100 19 38 0 1373931760 6949584 3920144
01:08:26 112119 8192000000 10304993792 10774499328 1380530864 14.421 99 20 40 1 1401873632 17125552 4217216
01:11:29 58022 8192000000 10304511840 10760675328 1402379472 14.616 100 9 18 0 1418836496 14497600 1959424
01:13:38 13808 8192000000 10315346448 10757337088 1389964768 14.506 99 47 90 1 1411097680 10650000 10482912
01:14:52 72477 8192000000 10321997680 10787315712 1412972912 14.710 100 16 32 0 1437445504 20741376 3731216
01:18:07 64616 8192000000 10317394848 10782908416 1413683600 14.717 100 19 36 0 1429051312 11520560 3847152
01:20:15 61808 8192000000 10285257840 10748448768 1366741200 14.298 97 8 16 0 1376972880 8576000 1655680
01:22:25 75137 8192000000 10274987952 10733862912 1364292176 14.276 98 10 20 0 1373365296 6847504 2225616
01:26:10 56229 8192000000 10270708352 10735955968 1350161392 14.149 98 9 18 0 1364464064 12229360 2073312
01:28:07 56944 8192000000 10281105040 10728128512 1319766400 13.875 96 14 28 0 1335082288 12321760 2994128
01:30:04 42282 8192000000 10284332160 10738339840 1375737872 14.378 100 22 45 1 1390052592 8372512 5942208
01:31:48 14537 8192000000 10296551184 10738515968 1354952592 14.192 98 37 71 2 1372408560 9461184 7994784
01:33:13 43073 8192000000 10284684928 10731294720 1360436624 14.241 99 37 72 0 1377346384 8660048 8249712
01:34:56 73559 8192000000 10254683120 10703364096 1273311376 13.452 93 18 36 0 1288159792 11190032 3658384
01:37:11 24618 8192000000 10273416224 10709401600 1323980112 13.913 98 49 98 1 1355397232 20777936 10639184

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 10-07-2022 01:39:33 to 10-07-2022 01:39:48.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 98 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 10-07-2022 01:39:49 to 10-07-2022 01:39:51.

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          237949
MON$OLDEST_ACTIVE               241605
MON$OLDEST_SNAPSHOT             241605
MON$NEXT_TRANSACTION            241605
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                       2018624
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 ---
:207: 
:208: mscs-test-161.budzdorov.ru    Sun Jul 10 01:38:17 2022
:209:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:210: 
:211: 
:212: mscs-test-161.budzdorov.ru    Sun Jul 10 01:38:36 2022
:213:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:214: 
:215: 
:216: mscs-test-161.budzdorov.ru    Sun Jul 10 01:38:37 2022
:217:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:218: 
:219: 
:220: mscs-test-161.budzdorov.ru    Sun Jul 10 01:38:37 2022
:221:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:222: 
:223: 
:224: mscs-test-161.budzdorov.ru    Sun Jul 10 01:38:38 2022
:225:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:226: 
--- end of diff output ---

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

10.07.22 01:39:52. 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-10 01:39:54 No severe PSQL-related problems occured
10.07.22 01:39:56. End of report.