Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.12.2021 16:43
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 16722708 kB
MemAvailable 31386080 kB
Buffers 0 kB
Cached 14979444 kB
SwapCached 436 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 2021-12-28 16:42:40
DTS_END                         2021-12-28 16:42:40.6250
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/41554
TRN_ID                          236694
ATT_ID                          613
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: 9022.93 23126 1082752
customer order: creation 170.29 339 20435
customer order: refuse 14.86 378 1783
order to supplier: creation 45.01 1257 5401
order to supplier: removal 3.40 102871 408
invoice (draft): creation 45.74 3492 5489
invoice (draft): removal 50.83 741 6099
invoice accept: apply 41.32 18642 4958
invoice accept: cancel 46.08 790 5530
customer reserve: creation 3785.18 186 454221
customer reserve: removal 4532.43 61 543891
realization accept: apply 81.45 168 9774
realization accept: cancel 78.72 107 9447
payment from customer: creation 58.72 103 7046
payment from customer: removal 5.57 64 668
payment to supplier: creation 47.71 118 5725
payment to supplier: removal 3.53 59 424
service: total inventory turnovers 4.88 1317 585
service: total monetary turnovers 6.38 30 766
service: aggregate perf. data 0.82 22339 98
service: refresh index statistics 0.03 309463 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-12-2021 16:44:08 to 28-12-2021 16:44:10.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4932
TEST_TIME 2 4399
TEST_TIME 3 4191
TEST_TIME 4 5287
TEST_TIME 5 5329
TEST_TIME 6 5121
TEST_TIME 7 5054
TEST_TIME 8 5042
TEST_TIME 9 5008
TEST_TIME 10 5028
TEST_TIME 11 5357
TEST_TIME 12 5565
TEST_TIME 13 6205
TEST_TIME 14 6275
TEST_TIME 15 6418
TEST_TIME 16 6391
TEST_TIME 17 6579
TEST_TIME 18 7438
TEST_TIME 19 7629
TEST_TIME 20 7857
TEST_TIME 21 7968
TEST_TIME 22 8227
TEST_TIME 23 8537
TEST_TIME 24 8991
TEST_TIME 25 9441
TEST_TIME 26 9930
TEST_TIME 27 10467
TEST_TIME 28 10719
TEST_TIME 29 10392
TEST_TIME 30 10225
TEST_TIME 31 10191
TEST_TIME 32 10131
TEST_TIME 33 10001
TEST_TIME 34 9850
TEST_TIME 35 9853
TEST_TIME 36 9865
TEST_TIME 37 9721
TEST_TIME 38 9667
TEST_TIME 39 9511
TEST_TIME 40 9527
TEST_TIME 41 9496
TEST_TIME 42 9387
TEST_TIME 43 9361
TEST_TIME 44 9453
TEST_TIME 45 9354
TEST_TIME 46 9274
TEST_TIME 47 9160
TEST_TIME 48 9088
TEST_TIME 49 9167
TEST_TIME 50 9141
TEST_TIME 51 9160
TEST_TIME 52 9205
TEST_TIME 53 9163
TEST_TIME 54 9256
TEST_TIME 55 9195
TEST_TIME 56 9231
TEST_TIME 57 9166
TEST_TIME 58 9350
TEST_TIME 59 9426
TEST_TIME 60 9403
TEST_TIME 61 9373
TEST_TIME 62 9433
TEST_TIME 63 9356
TEST_TIME 64 9344
TEST_TIME 65 9296
TEST_TIME 66 9197
TEST_TIME 67 9251
TEST_TIME 68 9167
TEST_TIME 69 9146
TEST_TIME 70 9079
TEST_TIME 71 9092
TEST_TIME 72 9031
TEST_TIME 73 9032
TEST_TIME 74 9205
TEST_TIME 75 9173
TEST_TIME 76 9166
TEST_TIME 77 9149
TEST_TIME 78 9160
TEST_TIME 79 9152
TEST_TIME 80 9109
TEST_TIME 81 9186
TEST_TIME 82 9197
TEST_TIME 83 9190
TEST_TIME 84 9242
TEST_TIME 85 9197
TEST_TIME 86 9129
TEST_TIME 87 9065
TEST_TIME 88 9071
TEST_TIME 89 9034
TEST_TIME 90 9035
TEST_TIME 91 9006
TEST_TIME 92 9011
TEST_TIME 93 9022
TEST_TIME 94 8982
TEST_TIME 95 9029
TEST_TIME 96 9053
TEST_TIME 97 9098
TEST_TIME 98 9115
TEST_TIME 99 9099
TEST_TIME 100 9105
TEST_TIME 101 9100
TEST_TIME 102 9070
TEST_TIME 103 9171
TEST_TIME 104 9151
TEST_TIME 105 9257
TEST_TIME 106 9228
TEST_TIME 107 9175
TEST_TIME 108 9138
TEST_TIME 109 9131
TEST_TIME 110 9105
TEST_TIME 111 9103
TEST_TIME 112 9102
TEST_TIME 113 9074
TEST_TIME 114 9076
TEST_TIME 115 9058
TEST_TIME 116 8994
TEST_TIME 117 8967
TEST_TIME 118 9015
TEST_TIME 119 9030
TEST_TIME 120 9029

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 28-12-2021 16:44:12 to 28-12-2021 16:44:15.

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 1502928 1502928 0 0.00 0 20842 6 0 0
doc_list_biud 1502928 1502928 0 0.00 0 56300 28 0 0
sp_add_invoice_to_stock 5072 4958 114 2.24 25 200206 18643 0 114
sp_cancel_adding_invoice 5530 5530 0 0.00 5 55115 790 0 0
sp_cancel_client_order 1783 1783 0 0.00 4 32221 378 0 0
sp_cancel_customer_reserve 543891 543891 0 0.00 1 56305 62 0 0
sp_cancel_pay_from_customer 668 668 0 0.00 2 4376 64 0 0
sp_cancel_pay_to_supplier 424 424 0 0.00 3 1328 60 0 0
sp_cancel_supplier_invoice 6111 6099 12 0.19 1 33613 742 0 12
sp_cancel_supplier_order 408 408 0 0.00 9 1023806 102872 0 0
sp_cancel_write_off 9447 9447 0 0.00 1 10217 107 0 0
sp_client_order 20435 20435 0 0.00 3 30974 339 0 0
sp_customer_reserve 454221 454221 0 0.00 0 107105 187 0 0
sp_fill_shopping_cart 31302 31302 0 0.00 1 14170 40 0 0
sp_fill_shopping_cart_clo_res 454209 454209 0 0.00 0 25430 13 0 0
sp_get_clo_for_invoice 4945 4945 0 0.00 0 34813 326 0 0
sp_kill_cost_storno 16069 16069 0 0.00 0 5313 55 0 0
sp_kill_qstorno_ret_qs2qd 1103736 1103736 0 0.00 0 55841 33 0 0
sp_kill_qty_storno 581877 581877 0 0.00 0 56299 71 0 0
sp_lock_selected_doc 20436 20436 0 0.00 0 5427 4 0 0
sp_make_cost_storno 27490 27490 0 0.00 0 15399 45 0 0
sp_multiply_rows_for_pdistr 27490 27490 0 0.00 0 6975 26 0 0
sp_multiply_rows_for_qdistr 20435 20435 0 0.00 0 17399 152 0 0
sp_pay_from_customer 7046 7046 0 0.00 4 25104 103 0 0
sp_pay_to_supplier 5725 5725 0 0.00 4 25719 118 0 0
sp_payment_common 12771 12771 0 0.00 1 8409 19 0 0
sp_qd_handle_on_cancel_clo 1783 1783 0 0.00 0 23599 339 0 0
sp_qd_handle_on_invoice_upd_sts 10475 10475 0 0.00 0 30121 326 0 0
sp_qd_handle_on_reserve_upd_sts 9774 9774 0 0.00 0 18832 57 0 0
sp_reserve_write_off 9774 9774 0 0.00 6 25913 169 0 0
sp_supplier_invoice 5489 5489 0 0.00 5 81189 3493 0 0
sp_supplier_order 5401 5401 0 0.00 4 39567 1258 0 0
srv_aggregate_perf_data 98 98 0 0.00 2974 248369 22339 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 899 146075 45954 0 0
srv_find_qd_qs_mism 1035487 1035487 0 0.00 0 20841 6 0 0
srv_make_invnt_saldo 585 585 0 0.00 43 148116 1318 0 0
srv_make_money_saldo 766 766 0 0.00 1 2795 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 156164 486498 309464 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 4958 4958 0 0.00 0 17800 164 0 0
t$perf-norm:sp_cancel_adding_invoice 413 413 0 0.00 0 7539 109 0 0
t$perf-norm:sp_cancel_client_order 1783 1783 0 0.00 0 6507 18 0 0
t$perf-norm:sp_cancel_customer_reserve 645 645 0 0.00 0 7466 30 0 0
t$perf-norm:sp_cancel_pay_from_customer 668 668 0 0.00 0 338 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 424 424 0 0.00 0 1056 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 398 398 0 0.00 0 3176 29 0 0
t$perf-norm:sp_cancel_supplier_order 408 408 0 0.00 0 29160 1655 0 0
t$perf-norm:sp_cancel_write_off 978 978 0 0.00 0 12387 29 0 0
t$perf-norm:sp_client_order 20435 20435 0 0.00 0 7511 17 0 0
t$perf-norm:sp_customer_reserve 954 954 0 0.00 0 3768 12 0 0
t$perf-norm:sp_pay_from_customer 7046 7046 0 0.00 0 11396 10 0 0
t$perf-norm:sp_pay_to_supplier 5725 5725 0 0.00 0 7775 11 0 0
t$perf-norm:sp_reserve_write_off 9774 9774 0 0.00 0 12597 13 0 0
t$perf-norm:sp_supplier_invoice 5489 5489 0 0.00 0 15490 21 0 0
t$perf-norm:sp_supplier_order 5401 5401 0 0.00 0 7774 23 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 6995 81 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 585 585 0 0.00 0 4577 31 0 0
t$perf-norm:srv_make_money_saldo 766 766 0 0.00 0 4061 23 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 3 0 0
v_add_invoice_to_stock 10118 9890 228 2.25 0 25000 24 0 228
v_all_customers 37232 37232 0 0.00 0 15 1 0 0
v_all_suppliers 29742 29742 0 0.00 0 26 1 0 0
v_all_wares 40870 40870 0 0.00 0 12 1 0 0
v_cancel_adding_invoice 826 826 0 0.00 0 5288 17 0 0
v_cancel_client_order 3566 3566 0 0.00 0 32150 14 0 0
v_cancel_customer_prepayment 1336 1336 0 0.00 0 2553 5 0 0
v_cancel_customer_reserve 1290 1290 0 0.00 0 836 4 0 0
v_cancel_payment_to_supplier 848 848 0 0.00 0 1320 5 0 0
v_cancel_supplier_invoice 820 796 24 2.92 0 4737 23 0 24
v_cancel_supplier_order 816 816 0 0.00 0 902 5 0 0
v_cancel_write_off 1956 1956 0 0.00 0 1956 13 0 0
v_max_id_clo_ord 5401 5401 0 0.00 0 577 1 0 0
v_max_id_clo_res 954 954 0 0.00 0 1769 18 0 0
v_max_id_ord_sup 5466 5466 0 0.00 0 5955 4 0 0
v_max_non_paid_invoice 1721 1721 0 0.00 0 2451 4 0 0
v_max_non_paid_realizn 3712 3712 0 0.00 0 2859 5 0 0
v_min_id_clo_ord 5401 5401 0 0.00 0 2166 5 0 0
v_min_id_clo_res 954 954 0 0.00 0 2375 12 0 0
v_min_id_ord_sup 5466 5466 0 0.00 0 5483 10 0 0
v_min_non_paid_invoice 5725 5725 0 0.00 0 5809 10 0 0
v_min_non_paid_realizn 7046 7046 0 0.00 0 8404 16 0 0
v_our_firm 10306 10306 0 0.00 0 12 1 0 0
v_reserve_write_off 19548 19548 0 0.00 0 25649 9 0 0
x_lock_depdocs_on_canc_invoice 11231 11231 0 0.00 0 22906 118 0 0
x_lock_depdocs_on_canc_sup_ord 408 408 0 0.00 0 16772 397 0 0
x_make_qstorno_1000_1200 5401 5401 0 0.00 1 39439 1181 0 0
x_make_qstorno_1200_2000 5489 5489 0 0.00 2 81172 3334 0 0
x_make_qstorno_2100_3300 454221 454221 0 0.00 0 107100 171 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-12-2021 16:44:16 to 28-12-2021 16:44:20.

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
14:12:44 10 8192000000 8806518704 8820199424 11798416 0.143 3 3 5 0 11992896 77360 117120
14:13:45 28294 8192000000 9919589536 10235248640 1002820896 10.906 100 89 178 0 1031231776 6219296 22191584
14:15:19 9978 8192000000 9950753632 10273886208 1033014416 11.197 100 88 176 0 1064362016 8873056 22474544
14:16:37 20161 8192000000 9986175056 10318696448 1066008992 11.514 100 87 171 1 1097687280 10544512 21133776
14:18:04 15704 8192000000 10036895200 10384334848 1117973360 12.008 100 84 168 0 1149684032 12028576 19682096
14:19:21 6624 8192000000 10073495776 10431569920 1157116624 12.376 100 81 162 1 1188879328 12807440 18955264
14:20:29 6183 8192000000 10122193808 10494586880 1214641520 12.912 100 85 170 0 1246513056 12442672 19428864
14:21:37 2188 8192000000 10153510736 10528669696 1241611936 13.161 100 87 174 1 1275301440 14247584 19441920
14:22:42 2039 8192000000 10189283280 10569977856 1281998704 13.531 100 85 170 1 1313899344 12594528 19306112
14:23:46 1489 8192000000 10215590080 10598363136 1299923040 13.695 100 98 196 0 1335652656 13042272 22687344
14:24:49 4217 8192000000 10201752240 10610315264 1303839936 13.730 100 37 75 0 1324468896 12767104 7861856
14:25:54 5947 8192000000 10203086992 10617462784 1312995216 13.813 100 36 72 0 1328110544 7224928 7890400
14:27:01 13420 8192000000 10204766016 10624286720 1316705456 13.847 100 18 34 3 1330909920 10151920 4052544
14:28:19 23919 8192000000 10236810944 10640154624 1327528048 13.945 100 44 90 1 1342942672 4989600 10425024
14:29:44 10506 8192000000 10230523600 10647293952 1330648624 13.973 100 26 52 1 1342434640 5509600 6276416
14:30:57 7827 8192000000 10227128128 10653130752 1336009456 14.021 100 22 44 1 1347372288 6443696 4919136
14:32:05 15470 8192000000 10199277712 10614992896 1268698592 13.410 96 24 48 1 1280657616 5020608 6938416
14:33:25 24420 8192000000 10188288352 10605400064 1239701296 13.143 94 20 41 0 1249619184 5014784 4903104
14:34:52 8559 8192000000 10166781696 10570428416 1251371680 13.251 98 15 30 0 1260694864 6125936 3197248
14:36:03 9049 8192000000 10131027520 10526932992 1198674080 12.764 95 15 30 1 1207794000 5348032 3771888
14:37:16 49932 8192000000 10148283488 10549035008 1244147248 13.184 99 42 85 3 1259654256 5017536 10489472
14:39:07 6983 8192000000 10182265696 10587815936 1282380512 13.535 100 38 77 0 1298681920 7374624 8926784
14:40:15 7790 8192000000 10187326608 10596630528 1295974656 13.659 100 11 22 0 1304118080 5532656 2610768
14:41:26 5726 8192000000 10209100048 10618331136 1310161600 13.788 100 35 70 1 1323845968 5219280 8465088
14:42:34 29067 8192000000 10239316688 10645192704 1322652592 13.901 100 54 109 0 1346515344 10509472 13353280
14:44:12 12467 8192000000 10250915632 10664730624 1347743456 14.127 100 35 70 2 1362841392 7045808 8052128
14:45:30 46716 8192000000 10276029360 10698379264 1363726992 14.271 100 41 80 0 1382364912 9808416 8829504
14:47:20 4893 8192000000 10301002896 10723868672 1384743216 14.459 100 40 80 1 1412246160 18179392 9323552
14:48:29 8824 8192000000 10314276320 10740809728 1403997680 14.631 100 51 98 1 1435416400 20098704 11320016
14:49:41 22666 8192000000 10322567968 10752147456 1408740496 14.673 100 50 99 1 1440200608 19727968 11732144
14:51:28 6710 8192000000 10345142992 10772570112 1423435632 14.803 100 41 80 1 1454256768 21024688 9796448
14:52:43 3961 8192000000 10370472160 10790522880 1429917376 14.861 100 69 138 1 1474123296 27453136 16752784
14:53:49 4325 8192000000 10355807328 10778271744 1420387488 14.776 100 65 130 1 1470422288 33865904 16168896
14:54:57 9205 8192000000 10366179488 10781057024 1418339792 14.758 100 77 153 0 1471308544 34300256 18668496
14:56:11 12774 8192000000 10354964400 10776252416 1405099312 14.640 99 59 117 0 1454517968 34395248 15023408
14:57:31 3747 8192000000 10348579488 10764107776 1395553376 14.555 99 75 148 0 1450991712 35962304 19476032
14:58:43 1362 8192000000 10356266688 10767593472 1402995888 14.622 100 85 168 0 1464984144 40507232 21481024
14:59:49 2099 8192000000 10368488720 10770423808 1395211152 14.552 100 92 182 0 1467550752 48540224 23799376
15:00:57 2305 8192000000 10372829520 10781614080 1404832688 14.638 100 91 178 1 1473832400 45733040 23266672
15:02:07 3883 8192000000 10356578736 10768814080 1377073312 14.390 98 74 148 3 1438694688 42788624 18832752
15:03:19 17054 8192000000 10359519344 10786189312 1404835328 14.638 100 38 76 0 1465122880 49471376 10816176
15:04:46 4806 8192000000 10353018256 10774110208 1392902240 14.532 100 47 92 1 1456250432 50647168 12701024
15:05:52 18949 8192000000 10318350736 10751598592 1345563952 14.108 98 29 58 0 1411218272 56248992 9405328
15:07:58 53082 8192000000 10284802336 10737528832 1348875968 14.137 100 21 40 3 1407419856 53031712 5512176
15:09:52 37667 8192000000 10253606672 10697424896 1318039744 13.859 98 11 23 0 1336366288 16080016 2246528
15:11:39 44262 8192000000 10277586464 10704211968 1357199664 14.212 100 40 76 2 1373421280 7353440 8868176
15:13:24 27293 8192000000 10268334048 10707468288 1350899232 14.156 99 28 55 1 1364459728 6611984 6948512
15:14:56 108218 8192000000 10262539552 10709086208 1316758512 13.847 96 19 38 1 1336060576 15361200 3940864
15:18:06 61696 8192000000 10273447104 10716950528 1365769008 14.289 100 13 25 1 1384531328 15829104 2933216
15:20:08 73890 8192000000 10264526160 10724016128 1360512432 14.242 99 21 40 3 1375982496 9300624 6169440
15:22:23 124474 8192000000 10283120000 10733813760 1377390560 14.393 100 18 36 1 1395899536 14412096 4096880
15:25:29 55350 8192000000 10292150112 10744651776 1381581568 14.431 100 25 49 0 1409299744 22105088 5613088
15:27:39 51580 8192000000 10293518176 10750164992 1392945216 14.532 100 10 20 0 1409604864 14564912 2094736
15:29:32 71529 8192000000 10284195408 10736340992 1366915440 14.299 98 26 52 0 1387916384 14796848 6204096
15:31:44 28765 8192000000 10281174992 10752335872 1381043792 14.426 100 18 36 1 1398190688 12842064 4304832
15:33:18 57699 8192000000 10289817104 10748993536 1392082064 14.524 100 15 30 0 1403427312 8262144 3083104
15:35:17 72956 8192000000 10294957760 10755620864 1371539824 14.341 98 16 32 0 1386213728 11124448 3549456
15:37:30 19896 8192000000 10300977872 10743279616 1374635152 14.369 99 32 64 0 1389455632 7695248 7125232
15:38:52 64425 8192000000 10298790608 10745270272 1375079552 14.373 99 19 38 1 1396150944 16715280 4356112
15:40:58 94754 8192000000 10296306480 10751930368 1360644384 14.243 98 5 10 0 1374954832 13325296 985152
15:43:35 72214 8192000000 10283664560 10739310592 1357364480 14.214 98 17 33 0 1370580368 9921184 3294704
15:45:47 91886 8192000000 10272307040 10722164736 1353231216 14.177 98 20 40 0 1370045840 12357200 4457424
15:48:49 16778 8192000000 10280929680 10728525824 1359859504 14.236 99 32 64 1 1383672880 16838816 6974560
15:50:06 31296 8192000000 10252222192 10716389376 1348842688 14.137 99 7 14 0 1363420736 13108288 1469760
15:51:37 70677 8192000000 10263857344 10720079872 1329018192 13.958 97 13 25 1 1344000960 11966784 3015984
15:53:50 111083 8192000000 10279507328 10734911488 1330428496 13.971 97 7 14 2 1348452208 16376240 1647472
15:57:18 34804 8192000000 10266087936 10712317952 1344516848 14.098 98 19 38 0 1357132160 8270096 4345216
15:58:57 58125 8192000000 10267999104 10704318464 1314491552 13.827 96 31 62 0 1331163264 9576272 7095440
16:01:02 50398 8192000000 10259237408 10699976704 1318510464 13.863 97 19 38 0 1332300464 9787280 4002720
16:02:57 37643 8192000000 10265771536 10715738112 1354745744 14.190 100 27 54 1 1378507280 17609232 6152304
16:05:12 47463 8192000000 10251078064 10678943744 1290250720 13.607 95 24 48 0 1304090624 8535232 5304672
16:07:00 117680 8192000000 10259448528 10699354112 1328555696 13.954 98 14 28 0 1342169040 10848864 2764480
16:09:59 897 8192000000 10267489568 10689163264 1344218368 14.095 99 82 166 1 1373422432 10546064 18658000
16:11:06 85483 8192000000 10261209696 10714800128 1362575472 14.260 100 11 22 0 1378806880 13547072 2684336
16:13:32 41214 8192000000 10275907568 10722164736 1371228448 14.338 100 11 22 1 1382631712 9607088 1796176
16:15:28 65121 8192000000 10284393152 10732212224 1355195968 14.194 98 15 30 0 1370390288 11839472 3354848
16:17:34 79476 8192000000 10286929744 10744397824 1335947872 14.021 96 13 26 1 1352601536 13886544 2767120
16:19:53 43310 8192000000 10281548768 10733219840 1359893552 14.236 98 21 42 0 1375423824 10771856 4758416
16:21:38 53994 8192000000 10310177936 10744164352 1375842176 14.379 100 40 81 1 1398114176 12607312 9664688
16:23:34 20604 8192000000 10306347312 10746728448 1390533184 14.511 100 51 102 1 1414980208 11734992 12712032
16:24:59 65444 8192000000 10295992848 10760241152 1367111728 14.301 98 24 48 0 1389287424 17090112 5085584
16:28:06 146074 8192000000 10271710512 10727071744 1309823984 13.784 94 11 22 1 1325285584 13185936 2275664
16:31:53 33607 8192000000 10293514752 10739417088 1382550064 14.439 100 27 54 0 1401021408 12655504 5815840
16:33:31 45090 8192000000 10280609104 10742603776 1386962032 14.479 100 21 42 0 1403121440 11153584 5005824
16:35:18 65895 8192000000 10258717888 10708795392 1297183520 13.670 94 16 30 1 1308521488 7178656 4159312
16:37:31 69800 8192000000 10266297968 10721034240 1366491504 14.296 100 10 20 0 1378493232 9846400 2155328
16:40:20 75662 8192000000 10261963600 10719223808 1311735648 13.802 95 15 30 0 1333709696 18122880 3851168

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 28-12-2021 16:44:22 to 28-12-2021 16:44:37.

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 28-12-2021 16:44:38 to 28-12-2021 16:44:40.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          235145
MON$OLDEST_ACTIVE               236813
MON$OLDEST_SNAPSHOT             236813
MON$NEXT_TRANSACTION            236813
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                       2014080
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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Tue Dec 28 16:42:47 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Tue Dec 28 16:42:49 2021
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Tue Dec 28 16:42:49 2021
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:17: 
--- end of diff output ---

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

28.12.21 16:44:41. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-12-28 16:44:43 No severe PSQL-related problems occured
28.12.21 16:44:44. End of report.