Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.04.2022 16: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 16770800 kB
MemAvailable 31514884 kB
Buffers 0 kB
Cached 14933420 kB
SwapCached 2524 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.10
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-04-24 16:38:13
DTS_END                         2022-04-24 16:38:13.8370
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/35992
TRN_ID                          241002
ATT_ID                          678
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: 9118.07 20101 1094168
customer order: creation 178.80 355 21456
customer order: refuse 15.40 362 1848
order to supplier: creation 47.60 1363 5712
order to supplier: removal 3.53 85980 423
invoice (draft): creation 47.85 3053 5742
invoice (draft): removal 50.96 677 6115
invoice accept: apply 43.08 17876 5169
invoice accept: cancel 45.26 807 5431
customer reserve: creation 3957.23 179 474868
customer reserve: removal 4430.76 54 531691
realization accept: apply 86.74 194 10409
realization accept: cancel 79.53 107 9543
payment from customer: creation 60.18 115 7222
payment from customer: removal 5.63 74 675
payment to supplier: creation 50.44 134 6053
payment to supplier: removal 3.59 73 431
service: total inventory turnovers 4.51 1165 541
service: total monetary turnovers 6.05 20 726
service: aggregate perf. data 0.91 14661 109
service: refresh index statistics 0.03 274771 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-04-2022 16:39:35 to 24-04-2022 16:39:37.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4492
TEST_TIME 2 4707
TEST_TIME 3 4949
TEST_TIME 4 6443
TEST_TIME 5 5963
TEST_TIME 6 5658
TEST_TIME 7 6094
TEST_TIME 8 6566
TEST_TIME 9 6538
TEST_TIME 10 6391
TEST_TIME 11 7038
TEST_TIME 12 6928
TEST_TIME 13 6877
TEST_TIME 14 6748
TEST_TIME 15 6613
TEST_TIME 16 6839
TEST_TIME 17 6899
TEST_TIME 18 7024
TEST_TIME 19 7511
TEST_TIME 20 8134
TEST_TIME 21 8346
TEST_TIME 22 8737
TEST_TIME 23 8806
TEST_TIME 24 8953
TEST_TIME 25 8921
TEST_TIME 26 9278
TEST_TIME 27 9511
TEST_TIME 28 10286
TEST_TIME 29 10184
TEST_TIME 30 10057
TEST_TIME 31 9929
TEST_TIME 32 9936
TEST_TIME 33 10022
TEST_TIME 34 9808
TEST_TIME 35 9592
TEST_TIME 36 9655
TEST_TIME 37 9596
TEST_TIME 38 9496
TEST_TIME 39 9574
TEST_TIME 40 9482
TEST_TIME 41 9474
TEST_TIME 42 9427
TEST_TIME 43 9351
TEST_TIME 44 9245
TEST_TIME 45 9354
TEST_TIME 46 9221
TEST_TIME 47 9273
TEST_TIME 48 9248
TEST_TIME 49 9236
TEST_TIME 50 9191
TEST_TIME 51 9213
TEST_TIME 52 9162
TEST_TIME 53 9299
TEST_TIME 54 9275
TEST_TIME 55 9357
TEST_TIME 56 9336
TEST_TIME 57 9322
TEST_TIME 58 9347
TEST_TIME 59 9431
TEST_TIME 60 9393
TEST_TIME 61 9468
TEST_TIME 62 9550
TEST_TIME 63 9436
TEST_TIME 64 9360
TEST_TIME 65 9283
TEST_TIME 66 9333
TEST_TIME 67 9260
TEST_TIME 68 9253
TEST_TIME 69 9205
TEST_TIME 70 9232
TEST_TIME 71 9216
TEST_TIME 72 9187
TEST_TIME 73 9160
TEST_TIME 74 9142
TEST_TIME 75 9187
TEST_TIME 76 9116
TEST_TIME 77 9149
TEST_TIME 78 9249
TEST_TIME 79 9248
TEST_TIME 80 9192
TEST_TIME 81 9302
TEST_TIME 82 9359
TEST_TIME 83 9389
TEST_TIME 84 9421
TEST_TIME 85 9439
TEST_TIME 86 9355
TEST_TIME 87 9266
TEST_TIME 88 9224
TEST_TIME 89 9215
TEST_TIME 90 9188
TEST_TIME 91 9151
TEST_TIME 92 9151
TEST_TIME 93 9152
TEST_TIME 94 9152
TEST_TIME 95 9199
TEST_TIME 96 9218
TEST_TIME 97 9268
TEST_TIME 98 9236
TEST_TIME 99 9229
TEST_TIME 100 9252
TEST_TIME 101 9295
TEST_TIME 102 9263
TEST_TIME 103 9297
TEST_TIME 104 9307
TEST_TIME 105 9326
TEST_TIME 106 9309
TEST_TIME 107 9317
TEST_TIME 108 9290
TEST_TIME 109 9279
TEST_TIME 110 9237
TEST_TIME 111 9223
TEST_TIME 112 9202
TEST_TIME 113 9158
TEST_TIME 114 9130
TEST_TIME 115 9102
TEST_TIME 116 9147
TEST_TIME 117 9130
TEST_TIME 118 9119
TEST_TIME 119 9156
TEST_TIME 120 9124

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 24-04-2022 16:39:38 to 24-04-2022 16:39:42.

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 1534752 1534752 0 0.00 0 27036 7 0 0
doc_list_biud 1534751 1534751 0 0.00 0 28571 24 0 0
sp_add_invoice_to_stock 5259 5169 90 1.71 39 234157 17877 0 90
sp_cancel_adding_invoice 5431 5431 0 0.00 4 47343 808 0 0
sp_cancel_client_order 1848 1848 0 0.00 4 16185 363 0 0
sp_cancel_customer_reserve 531691 531691 0 0.00 1 28574 55 0 0
sp_cancel_pay_from_customer 675 675 0 0.00 3 7467 75 0 0
sp_cancel_pay_to_supplier 431 431 0 0.00 3 3767 74 0 0
sp_cancel_supplier_invoice 6124 6115 9 0.14 2 14955 678 0 9
sp_cancel_supplier_order 423 423 0 0.00 5 858407 85981 0 0
sp_cancel_write_off 9543 9543 0 0.00 1 12181 107 0 0
sp_client_order 21456 21456 0 0.00 3 16764 356 0 0
sp_customer_reserve 474868 474868 0 0.00 0 124336 180 0 0
sp_fill_shopping_cart 32900 32900 0 0.00 1 9575 35 0 0
sp_fill_shopping_cart_clo_res 474866 474866 0 0.00 0 15765 15 0 0
sp_get_clo_for_invoice 5163 5163 0 0.00 0 30071 289 0 0
sp_kill_cost_storno 16080 16080 0 0.00 0 10312 52 0 0
sp_kill_qstorno_ret_qs2qd 1079463 1079463 0 0.00 0 24254 30 0 0
sp_kill_qty_storno 570623 570623 0 0.00 0 28571 64 0 0
sp_lock_selected_doc 21497 21497 0 0.00 0 8722 6 0 0
sp_make_cost_storno 28847 28847 0 0.00 0 26571 55 0 0
sp_multiply_rows_for_pdistr 28847 28847 0 0.00 0 9027 30 0 0
sp_multiply_rows_for_qdistr 21456 21456 0 0.00 0 15797 171 0 0
sp_pay_from_customer 7222 7222 0 0.00 4 10523 115 0 0
sp_pay_to_supplier 6053 6053 0 0.00 4 12809 134 0 0
sp_payment_common 13275 13275 0 0.00 2 9384 38 0 0
sp_qd_handle_on_cancel_clo 1848 1848 0 0.00 0 16180 334 0 0
sp_qd_handle_on_invoice_upd_sts 10594 10594 0 0.00 0 19300 282 0 0
sp_qd_handle_on_reserve_upd_sts 10409 10409 0 0.00 0 21241 64 0 0
sp_reserve_write_off 10409 10409 0 0.00 5 21254 194 0 0
sp_supplier_invoice 5742 5742 0 0.00 6 80367 3053 0 0
sp_supplier_order 5712 5712 0 0.00 5 40132 1363 0 0
srv_aggregate_perf_data 109 109 0 0.00 2632 149342 14662 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 3279 112686 47079 0 0
srv_find_qd_qs_mism 1045503 1045503 0 0.00 0 20090 6 0 0
srv_make_invnt_saldo 541 541 0 0.00 29 75804 1166 0 0
srv_make_money_saldo 726 726 0 0.00 1 1328 20 0 0
srv_recalc_idx_stat 4 4 0 0.00 211337 413171 274772 0 0
t$perf-abend:sp_add_invoice_to_stock 90 90 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 5170 5170 0 0.00 0 13381 178 0 0
t$perf-norm:sp_cancel_adding_invoice 420 420 0 0.00 0 7464 106 0 0
t$perf-norm:sp_cancel_client_order 1848 1848 0 0.00 0 4584 14 0 0
t$perf-norm:sp_cancel_customer_reserve 681 681 0 0.00 0 5697 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 675 675 0 0.00 0 1502 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 431 431 0 0.00 0 4656 24 0 0
t$perf-norm:sp_cancel_supplier_invoice 427 427 0 0.00 0 496 9 0 0
t$perf-norm:sp_cancel_supplier_order 423 423 0 0.00 0 19216 1251 0 0
t$perf-norm:sp_cancel_write_off 1020 1020 0 0.00 0 5775 18 0 0
t$perf-norm:sp_client_order 21456 21456 0 0.00 0 12577 20 0 0
t$perf-norm:sp_customer_reserve 1017 1017 0 0.00 0 13495 17 0 0
t$perf-norm:sp_pay_from_customer 7222 7222 0 0.00 0 23336 16 0 0
t$perf-norm:sp_pay_to_supplier 6053 6053 0 0.00 0 8892 17 0 0
t$perf-norm:sp_reserve_write_off 10409 10409 0 0.00 0 7020 12 0 0
t$perf-norm:sp_supplier_invoice 5742 5742 0 0.00 0 16208 18 0 0
t$perf-norm:sp_supplier_order 5712 5712 0 0.00 0 10757 19 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 318 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 108 3 0 0
t$perf-norm:srv_make_invnt_saldo 541 541 0 0.00 0 983 20 0 0
t$perf-norm:srv_make_money_saldo 726 726 0 0.00 0 5360 35 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10506 10326 180 1.71 0 6209 17 0 180
v_all_customers 39406 39406 0 0.00 0 26 0 0 0
v_all_suppliers 31478 31478 0 0.00 0 83 1 0 0
v_all_wares 42912 42912 0 0.00 0 69 1 0 0
v_cancel_adding_invoice 840 840 0 0.00 0 1424 9 0 0
v_cancel_client_order 3696 3696 0 0.00 0 4203 8 0 0
v_cancel_customer_prepayment 1350 1350 0 0.00 0 1403 5 0 0
v_cancel_customer_reserve 1362 1362 0 0.00 0 2621 8 0 0
v_cancel_payment_to_supplier 862 862 0 0.00 0 3297 8 0 0
v_cancel_supplier_invoice 872 854 18 2.06 0 5970 25 0 18
v_cancel_supplier_order 846 846 0 0.00 0 3227 12 0 0
v_cancel_write_off 2040 2040 0 0.00 0 4447 18 0 0
v_max_id_clo_ord 5712 5712 0 0.00 0 1148 1 0 0
v_max_id_clo_res 1017 1017 0 0.00 0 2216 19 0 0
v_max_id_ord_sup 5731 5731 0 0.00 0 5741 7 0 0
v_max_non_paid_invoice 1757 1757 0 0.00 0 4782 6 0 0
v_max_non_paid_realizn 3728 3728 0 0.00 0 5437 8 0 0
v_min_id_clo_ord 5712 5712 0 0.00 0 4516 7 0 0
v_min_id_clo_res 1017 1017 0 0.00 0 574 2 0 0
v_min_id_ord_sup 5731 5731 0 0.00 0 4022 5 0 0
v_min_non_paid_invoice 6053 6053 0 0.00 0 9381 28 0 0
v_min_non_paid_realizn 7222 7222 0 0.00 0 7519 29 0 0
v_our_firm 10494 10494 0 0.00 0 12 1 0 0
v_reserve_write_off 20818 20818 0 0.00 0 4831 7 0 0
x_lock_depdocs_on_canc_invoice 11119 11119 0 0.00 0 46065 120 0 0
x_lock_depdocs_on_canc_sup_ord 423 423 0 0.00 0 13369 348 0 0
x_make_qstorno_1000_1200 5712 5712 0 0.00 1 40121 1288 0 0
x_make_qstorno_1200_2000 5742 5742 0 0.00 3 80343 2928 0 0
x_make_qstorno_2100_3300 474868 474868 0 0.00 0 124334 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-04-2022 16:39:43 to 24-04-2022 16:39:47.

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:08:17 12 8192000000 8811028688 8826294272 15676240 0.190 4 4 5 0 15902976 87456 139280
14:09:18 34659 8192000000 9895032448 10200514560 976634352 10.651 100 89 179 1 1005817744 5482800 23700592
14:11:00 28712 8192000000 9956357280 10279559168 1036556992 11.232 100 90 180 0 1066912928 7889424 22466512
14:12:31 22450 8192000000 10003123936 10341449728 1081816624 11.665 100 86 170 2 1112261232 9899472 20545136
14:13:57 14773 8192000000 10055055664 10405527552 1142406128 12.238 100 82 164 2 1173189184 11549376 19233680
14:15:15 9996 8192000000 10093640048 10455969792 1179860000 12.589 100 75 150 1 1209215552 12188720 17166832
14:16:28 5502 8192000000 10130249072 10502144000 1218050576 12.944 100 77 154 0 1248395504 12925456 17419472
14:17:36 5364 8192000000 10157645712 10537553920 1254132496 13.276 100 73 145 0 1281981392 12244784 15604112
14:18:44 2488 8192000000 10204861056 10585747456 1287992576 13.586 100 87 171 3 1319044912 12346864 18705472
14:19:49 1544 8192000000 10219990944 10602950656 1302304480 13.716 100 94 188 0 1335419136 11555376 21559280
14:20:51 10523 8192000000 10197821440 10604003328 1303708688 13.729 100 29 57 0 1316697648 6835776 6153184
14:22:05 3380 8192000000 10199827872 10615676928 1317282416 13.852 100 30 60 1 1330641984 6290656 7068912
14:23:09 7426 8192000000 10207506960 10627198976 1322055600 13.895 100 22 43 3 1333281744 6863664 4362480
14:24:18 4223 8192000000 10216296048 10632429568 1328857248 13.957 100 22 44 1 1339248256 5990832 4400176
14:25:24 60910 8192000000 10219725568 10640306176 1318870192 13.866 99 29 58 1 1329903712 4832016 6201504
14:27:25 5790 8192000000 10216663488 10636124160 1317354864 13.853 99 25 50 3 1328993168 6330496 5307808
14:28:32 9563 8192000000 10177601232 10589478912 1260253312 13.332 97 16 32 1 1268767376 4478304 4035760
14:29:42 13446 8192000000 10162590496 10556350464 1192967440 12.711 93 28 56 1 1204639888 4763120 6909328
14:30:56 25635 8192000000 10143602064 10545508352 1207931168 12.850 95 34 68 0 1221298976 5119360 8248448
14:32:24 9782 8192000000 10140462208 10540433408 1212660160 12.894 96 27 54 2 1225055120 5655232 6739728
14:33:36 3906 8192000000 10154792080 10553298944 1258774000 13.319 99 27 53 0 1270292624 5285536 6233088
14:34:44 12384 8192000000 10186565344 10597593088 1295869952 13.658 100 10 20 1 1303058192 4850928 2337312
14:35:57 41535 8192000000 10197072480 10615308288 1309226096 13.779 100 42 86 1 1325561632 6404256 9931280
14:37:39 6831 8192000000 10207633440 10626076672 1317563904 13.855 100 24 48 1 1329184848 6254592 5366352
14:38:52 30675 8192000000 10253592608 10661588992 1345583824 14.108 100 37 74 1 1361268624 7307616 8377184
14:40:24 38623 8192000000 10255726784 10680279040 1359096752 14.229 100 28 55 0 1374403536 9138736 6168048
14:42:10 62773 8192000000 10278589184 10708033536 1373445536 14.358 100 52 104 1 1394664112 8350480 12868096
14:44:15 14868 8192000000 10294852240 10719891456 1387956304 14.488 100 52 105 0 1411165456 11349104 11860048
14:45:41 5127 8192000000 10333458048 10751574016 1404641120 14.636 100 75 150 0 1441146608 18725808 17779680
14:46:52 8225 8192000000 10332743328 10753200128 1405678816 14.646 100 62 124 0 1439971488 20142368 14150304
14:48:08 5842 8192000000 10338381632 10764345344 1406589296 14.654 99 62 122 0 1441627504 20042624 14995584
14:49:21 5024 8192000000 10351720224 10781253632 1428012560 14.844 100 55 110 1 1465855600 23809200 14033840
14:50:29 21677 8192000000 10356902528 10790174720 1431366912 14.873 100 48 93 2 1464008288 21317248 11324128
14:52:01 15737 8192000000 10368368880 10800369664 1437868240 14.931 100 42 84 1 1473777472 25404512 10504720
14:53:21 7444 8192000000 10368000752 10800582656 1436586656 14.920 100 49 97 2 1478116944 29365728 12164560
14:54:40 3487 8192000000 10369365728 10784071680 1412016160 14.702 100 74 147 1 1468509024 38042352 18450512
14:55:47 3500 8192000000 10351169664 10770145280 1405650816 14.645 100 65 131 2 1456707040 34054624 17001600
14:56:56 3276 8192000000 10371764304 10786983936 1387225840 14.481 98 80 160 3 1457409536 50494112 19689584
14:58:06 8224 8192000000 10325200224 10744074240 1370037840 14.327 100 42 84 0 1429496704 48174752 11284112
14:59:18 11924 8192000000 10308087488 10734239744 1362579664 14.261 99 32 64 3 1404740064 33363152 8797248
15:00:34 24958 8192000000 10302800288 10731085824 1338041152 14.040 97 33 66 1 1390731888 43533904 9156832
15:02:09 15086 8192000000 10298655856 10740465664 1370472496 14.331 100 26 52 2 1411816064 34834208 6509360
15:03:27 34693 8192000000 10303296128 10747682816 1354701696 14.190 99 13 26 1 1402334272 43830352 3802224
15:05:03 53009 8192000000 10251480080 10686029824 1271264720 13.433 95 11 21 1 1318014032 43385168 3364144
15:07:12 69357 8192000000 10246964032 10668195840 1290879392 13.612 96 24 48 1 1304895024 9181472 4834160
15:09:22 17748 8192000000 10285374928 10702303232 1334469344 14.008 98 55 108 0 1358600336 11689168 12441824
15:10:40 72818 8192000000 10281372288 10709053440 1342484384 14.080 99 24 48 0 1361341648 13445840 5411424
15:12:53 57234 8192000000 10282582160 10705301504 1338478528 14.044 98 41 82 0 1357029600 8746304 9804768
15:14:57 112685 8192000000 10288151648 10720321536 1333793360 14.001 97 38 75 2 1351597168 8560016 9243792
15:17:56 112463 8192000000 10283187104 10739793920 1368769328 14.316 99 13 26 0 1383802720 11220496 3812896
15:20:49 38473 8192000000 10288686144 10742722560 1390899840 14.514 100 14 28 1 1402909184 8263680 3745664
15:23:05 44017 8192000000 10299266464 10751627264 1393688832 14.539 100 20 40 0 1409856528 10850448 5317248
15:25:14 86419 8192000000 10283756736 10755358720 1380137008 14.418 99 14 28 0 1398781104 15219264 3424832
15:27:43 44443 8192000000 10291405168 10747805696 1380453760 14.421 99 11 22 0 1390681520 7716288 2511472
15:29:29 66087 8192000000 10305417104 10765840384 1388653808 14.494 99 21 42 0 1407350592 13924768 4772016
15:32:45 85143 8192000000 10285964288 10734313472 1326363312 13.934 95 19 38 2 1339905072 9690976 3850784
15:35:10 23350 8192000000 10302495488 10745417728 1383842720 14.451 100 46 92 1 1404324256 10436976 10044560
15:36:34 37962 8192000000 10303203088 10748514304 1372603968 14.350 99 34 68 1 1391653600 11250352 7799280
15:38:13 12586 8192000000 10303020240 10740760576 1385029904 14.461 100 46 91 2 1401285328 5810096 10445328
15:39:29 51813 8192000000 10290237360 10747281408 1365703824 14.289 99 16 32 0 1386999344 17867504 3428016
15:41:21 86066 8192000000 10287908672 10738876416 1364491312 14.278 99 24 48 0 1383031664 12970624 5569728
15:44:07 53353 8192000000 10274649008 10741338112 1378260896 14.401 99 20 39 3 1399344000 16168784 4914320
15:46:20 38467 8192000000 10284391808 10743414784 1381512464 14.430 100 13 26 0 1402386128 17351552 3522112
15:48:03 65358 8192000000 10276828576 10726658048 1355313152 14.195 99 19 38 0 1370946864 10940800 4692912
15:50:13 39628 8192000000 10258366608 10712014848 1349249408 14.141 99 11 22 0 1362654000 11125648 2278944
15:51:55 42727 8192000000 10218868768 10649034752 1264239424 13.369 93 36 71 1 1278797392 6453600 8104368
15:53:38 41631 8192000000 10263688672 10707939328 1347776464 14.127 100 16 32 0 1361918032 10562128 3579440
15:55:26 46580 8192000000 10273174256 10724405248 1340199920 14.059 98 22 45 0 1360463344 15237424 5026000
15:57:29 68378 8192000000 10281458704 10723143680 1337709472 14.037 98 39 77 1 1359176176 12530416 8936288
15:59:43 49052 8192000000 10269843872 10724954112 1336557104 14.026 98 21 42 0 1353736304 12599776 4579424
16:01:33 108467 8192000000 10271127040 10723713024 1307264448 13.761 95 10 20 1 1327873776 18597360 2011968
16:04:22 103147 8192000000 10266448272 10709975040 1348993520 14.138 99 23 46 0 1363681760 9576240 5112000
16:07:09 57988 8192000000 10265616480 10724827136 1367440160 14.304 100 13 26 0 1380972016 10843552 2688304
16:09:09 61774 8192000000 10290678352 10750779392 1385304240 14.464 100 13 27 0 1402313072 14125696 2883136
16:11:11 93301 8192000000 10283812048 10747609088 1356347264 14.205 97 17 34 1 1375280064 15294416 3638384
16:14:34 62439 8192000000 10303423680 10771783680 1398158096 14.579 100 12 24 0 1415444048 14447984 2837968
16:16:47 59171 8192000000 10333366032 10778685440 1410418976 14.688 100 22 44 0 1428228320 12700720 5108624
16:19:03 76733 8192000000 10305267232 10747924480 1358843440 14.227 97 20 40 0 1374756480 11607168 4305872
16:21:23 54027 8192000000 10294256224 10750648320 1369085664 14.319 98 29 58 1 1386565568 11153600 6326304
16:23:22 71432 8192000000 10293698976 10748784640 1341220752 14.068 96 15 30 0 1356001872 11484912 3296208
16:25:35 36136 8192000000 10298862304 10741444608 1385979184 14.470 100 39 78 4 1407495280 12317344 9198752
16:27:15 63296 8192000000 10277810912 10723893248 1305908320 13.749 94 17 34 0 1315783632 6134576 3740736
16:29:21 20420 8192000000 10257981056 10713612288 1346999200 14.120 98 10 20 0 1355939744 6491984 2448560
16:30:44 49435 8192000000 10266466224 10723549184 1349300752 14.141 98 5 10 0 1360976240 10808624 866864
16:32:35 82521 8192000000 10279987008 10735525888 1359914096 14.237 99 18 36 1 1377540576 13287328 4339152
16:35:12 54540 8192000000 10263374592 10714750976 1338853904 14.047 98 20 40 1 1357517776 13875392 4788480
16:37:15 73076 8192000000 9167041952 9424281600 329262016 3.864 24 6 12 0 339571136 8869200 1439920

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 24-04-2022 16:39:49 to 24-04-2022 16:40:03.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 90 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 24-04-2022 16:40:04 to 24-04-2022 16:40:06.

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          243365
MON$OLDEST_ACTIVE               243366
MON$OLDEST_SNAPSHOT             243366
MON$NEXT_TRANSACTION            243366
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                       2010496
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33577 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33577 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33577 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33577 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 ---
:1568: 
:1569: mscs-test-161.budzdorov.ru    Sun Apr 24 16:38:30 2022
:1570:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1571: 
:1572: 
:1573: mscs-test-161.budzdorov.ru    Sun Apr 24 16:38:33 2022
:1574:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1575: 
--- end of diff output ---

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

24.04.22 16:40:07. 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-04-24 16:40:10 No severe PSQL-related problems occured
24.04.22 16:40:11. End of report.