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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16424440 kB
MemAvailable 31240600 kB
Buffers 0 kB
Cached 15272312 kB
SwapCached 2448 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-06-16 22:38:15
DTS_END                         2022-06-16 22:38:15.9010
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/58670
TRN_ID                          243841
ATT_ID                          646
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: 9137.22 21466 1096466
customer order: creation 177.43 354 21292
customer order: refuse 14.90 358 1788
order to supplier: creation 47.66 1226 5719
order to supplier: removal 3.10 119401 372
invoice (draft): creation 47.87 3289 5744
invoice (draft): removal 50.58 862 6069
invoice accept: apply 42.77 17722 5132
invoice accept: cancel 45.83 731 5499
customer reserve: creation 3942.50 175 473100
customer reserve: removal 4470.02 65 536402
realization accept: apply 86.01 163 10321
realization accept: cancel 74.66 124 8959
payment from customer: creation 60.47 106 7256
payment from customer: removal 5.86 91 703
payment to supplier: creation 50.65 138 6078
payment to supplier: removal 3.63 73 435
service: total inventory turnovers 5.47 796 656
service: total monetary turnovers 7.02 20 842
service: aggregate perf. data 0.79 20876 95
service: refresh index statistics 0.03 262758 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 16-06-2022 22:39:16 to 16-06-2022 22:39:19.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6172
TEST_TIME 2 4258
TEST_TIME 3 4097
TEST_TIME 4 4417
TEST_TIME 5 4792
TEST_TIME 6 5871
TEST_TIME 7 5808
TEST_TIME 8 5505
TEST_TIME 9 5548
TEST_TIME 10 5497
TEST_TIME 11 5731
TEST_TIME 12 5814
TEST_TIME 13 5946
TEST_TIME 14 6550
TEST_TIME 15 6583
TEST_TIME 16 6893
TEST_TIME 17 7057
TEST_TIME 18 7115
TEST_TIME 19 7239
TEST_TIME 20 8120
TEST_TIME 21 8155
TEST_TIME 22 8343
TEST_TIME 23 8556
TEST_TIME 24 8526
TEST_TIME 25 8620
TEST_TIME 26 8864
TEST_TIME 27 8955
TEST_TIME 28 10456
TEST_TIME 29 11077
TEST_TIME 30 10726
TEST_TIME 31 10512
TEST_TIME 32 10355
TEST_TIME 33 10220
TEST_TIME 34 10072
TEST_TIME 35 9955
TEST_TIME 36 9882
TEST_TIME 37 9873
TEST_TIME 38 9798
TEST_TIME 39 9757
TEST_TIME 40 9616
TEST_TIME 41 9648
TEST_TIME 42 9561
TEST_TIME 43 9422
TEST_TIME 44 9400
TEST_TIME 45 9366
TEST_TIME 46 9261
TEST_TIME 47 9197
TEST_TIME 48 9100
TEST_TIME 49 9311
TEST_TIME 50 9317
TEST_TIME 51 9287
TEST_TIME 52 9309
TEST_TIME 53 9353
TEST_TIME 54 9499
TEST_TIME 55 9524
TEST_TIME 56 9433
TEST_TIME 57 9584
TEST_TIME 58 9611
TEST_TIME 59 9543
TEST_TIME 60 9598
TEST_TIME 61 9541
TEST_TIME 62 9419
TEST_TIME 63 9416
TEST_TIME 64 9373
TEST_TIME 65 9311
TEST_TIME 66 9239
TEST_TIME 67 9195
TEST_TIME 68 9213
TEST_TIME 69 9294
TEST_TIME 70 9259
TEST_TIME 71 9291
TEST_TIME 72 9275
TEST_TIME 73 9264
TEST_TIME 74 9298
TEST_TIME 75 9427
TEST_TIME 76 9430
TEST_TIME 77 9381
TEST_TIME 78 9409
TEST_TIME 79 9443
TEST_TIME 80 9399
TEST_TIME 81 9309
TEST_TIME 82 9289
TEST_TIME 83 9257
TEST_TIME 84 9256
TEST_TIME 85 9192
TEST_TIME 86 9210
TEST_TIME 87 9187
TEST_TIME 88 9138
TEST_TIME 89 9155
TEST_TIME 90 9153
TEST_TIME 91 9153
TEST_TIME 92 9223
TEST_TIME 93 9192
TEST_TIME 94 9235
TEST_TIME 95 9265
TEST_TIME 96 9307
TEST_TIME 97 9286
TEST_TIME 98 9270
TEST_TIME 99 9290
TEST_TIME 100 9294
TEST_TIME 101 9284
TEST_TIME 102 9334
TEST_TIME 103 9350
TEST_TIME 104 9391
TEST_TIME 105 9348
TEST_TIME 106 9298
TEST_TIME 107 9285
TEST_TIME 108 9267
TEST_TIME 109 9229
TEST_TIME 110 9219
TEST_TIME 111 9197
TEST_TIME 112 9159
TEST_TIME 113 9133
TEST_TIME 114 9116
TEST_TIME 115 9106
TEST_TIME 116 9147
TEST_TIME 117 9132
TEST_TIME 118 9112
TEST_TIME 119 9211
TEST_TIME 120 9140

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 16-06-2022 22:39:20 to 16-06-2022 22:39:24.

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 1532983 1532983 0 0.00 0 28778 7 0 0
doc_list_biud 1532983 1532983 0 0.00 0 36293 29 0 0
sp_add_invoice_to_stock 5244 5132 112 2.13 20 189924 17723 0 112
sp_cancel_adding_invoice 5499 5499 0 0.00 4 45883 731 0 0
sp_cancel_client_order 1788 1788 0 0.00 4 18873 359 0 0
sp_cancel_customer_reserve 536402 536402 0 0.00 1 36294 65 0 0
sp_cancel_pay_from_customer 703 703 0 0.00 3 14290 92 0 0
sp_cancel_pay_to_supplier 435 435 0 0.00 3 3088 74 0 0
sp_cancel_supplier_invoice 6080 6069 11 0.18 1 27885 863 0 11
sp_cancel_supplier_order 372 372 0 0.00 11 1218600 119402 0 0
sp_cancel_write_off 8959 8959 0 0.00 1 11533 124 0 0
sp_client_order 21292 21292 0 0.00 3 29266 354 0 0
sp_customer_reserve 473100 473100 0 0.00 0 71368 176 0 0
sp_fill_shopping_cart 32752 32752 0 0.00 1 17066 43 0 0
sp_fill_shopping_cart_clo_res 473094 473094 0 0.00 0 15549 13 0 0
sp_get_clo_for_invoice 5126 5126 0 0.00 0 32343 333 0 0
sp_kill_cost_storno 15596 15596 0 0.00 0 14281 63 0 0
sp_kill_qstorno_ret_qs2qd 1088204 1088204 0 0.00 0 36272 35 0 0
sp_kill_qty_storno 574536 574536 0 0.00 0 36292 75 0 0
sp_lock_selected_doc 21236 21236 0 0.00 0 8556 5 0 0
sp_make_cost_storno 28781 28781 0 0.00 0 28763 59 0 0
sp_multiply_rows_for_pdistr 28781 28781 0 0.00 0 8014 29 0 0
sp_multiply_rows_for_qdistr 21292 21292 0 0.00 0 29258 174 0 0
sp_pay_from_customer 7256 7256 0 0.00 4 21058 107 0 0
sp_pay_to_supplier 6078 6078 0 0.00 4 21552 138 0 0
sp_payment_common 13334 13334 0 0.00 1 9593 27 0 0
sp_qd_handle_on_cancel_clo 1788 1788 0 0.00 0 18863 327 0 0
sp_qd_handle_on_invoice_upd_sts 10625 10625 0 0.00 0 15701 327 0 0
sp_qd_handle_on_reserve_upd_sts 10321 10321 0 0.00 0 15801 51 0 0
sp_reserve_write_off 10321 10321 0 0.00 6 16923 164 0 0
sp_supplier_invoice 5744 5744 0 0.00 6 76981 3290 0 0
sp_supplier_order 5719 5719 0 0.00 5 30444 1226 0 0
srv_aggregate_perf_data 95 95 0 0.00 2959 237827 20876 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1877 144801 43854 0 0
srv_find_qd_qs_mism 1046527 1046527 0 0.00 0 12128 6 0 0
srv_make_invnt_saldo 656 656 0 0.00 28 41646 796 0 0
srv_make_money_saldo 842 842 0 0.00 1 780 21 0 0
srv_recalc_idx_stat 4 4 0 0.00 184494 353524 262758 0 0
t$perf-abend:sp_add_invoice_to_stock 112 112 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5132 5132 0 0.00 0 9141 144 0 0
t$perf-norm:sp_cancel_adding_invoice 427 427 0 0.00 0 3680 79 0 0
t$perf-norm:sp_cancel_client_order 1788 1788 0 0.00 0 1603 10 0 0
t$perf-norm:sp_cancel_customer_reserve 659 659 0 0.00 0 4436 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 703 703 0 0.00 0 705 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 435 435 0 0.00 0 785 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 423 423 0 0.00 0 1828 19 0 0
t$perf-norm:sp_cancel_supplier_order 372 372 0 0.00 0 26910 1687 0 0
t$perf-norm:sp_cancel_write_off 982 982 0 0.00 0 396 4 0 0
t$perf-norm:sp_client_order 21292 21292 0 0.00 0 11402 16 0 0
t$perf-norm:sp_customer_reserve 1094 1094 0 0.00 0 2238 7 0 0
t$perf-norm:sp_pay_from_customer 7256 7256 0 0.00 0 5557 11 0 0
t$perf-norm:sp_pay_to_supplier 6078 6078 0 0.00 0 12673 9 0 0
t$perf-norm:sp_reserve_write_off 10321 10321 0 0.00 0 4957 9 0 0
t$perf-norm:sp_supplier_invoice 5744 5744 0 0.00 0 5699 17 0 0
t$perf-norm:sp_supplier_order 5719 5719 0 0.00 0 4634 13 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 580 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 5 0 0 0
t$perf-norm:srv_make_invnt_saldo 656 656 0 0.00 0 1117 18 0 0
t$perf-norm:srv_make_money_saldo 842 842 0 0.00 0 2514 22 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 4 0 0
v_add_invoice_to_stock 10474 10250 224 2.13 0 6384 16 0 224
v_all_customers 38784 38784 0 0.00 0 20 1 0 0
v_all_suppliers 31590 31590 0 0.00 0 24 1 0 0
v_all_wares 42582 42582 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 854 854 0 0.00 0 16112 27 0 0
v_cancel_client_order 3576 3576 0 0.00 0 17324 8 0 0
v_cancel_customer_prepayment 1406 1406 0 0.00 0 5086 10 0 0
v_cancel_customer_reserve 1318 1318 0 0.00 0 16125 28 0 0
v_cancel_payment_to_supplier 870 870 0 0.00 0 1721 6 0 0
v_cancel_supplier_invoice 868 846 22 2.53 0 1567 14 0 22
v_cancel_supplier_order 744 744 0 0.00 0 87 2 0 0
v_cancel_write_off 1964 1964 0 0.00 0 1001 11 0 0
v_max_id_clo_ord 5717 5717 0 0.00 0 5052 3 0 0
v_max_id_clo_res 1094 1094 0 0.00 0 3730 20 0 0
v_max_id_ord_sup 5743 5743 0 0.00 0 8240 7 0 0
v_max_non_paid_invoice 1743 1743 0 0.00 0 5725 6 0 0
v_max_non_paid_realizn 3879 3879 0 0.00 0 4088 5 0 0
v_min_id_clo_ord 5717 5717 0 0.00 0 12501 19 0 0
v_min_id_clo_res 1094 1094 0 0.00 0 6901 13 0 0
v_min_id_ord_sup 5743 5743 0 0.00 0 5147 8 0 0
v_min_non_paid_invoice 6078 6078 0 0.00 0 7863 21 0 0
v_min_non_paid_realizn 7256 7256 0 0.00 0 7464 17 0 0
v_our_firm 10552 10552 0 0.00 0 23 1 0 0
v_reserve_write_off 20642 20642 0 0.00 0 4263 5 0 0
x_lock_depdocs_on_canc_invoice 11145 11145 0 0.00 0 13008 116 0 0
x_lock_depdocs_on_canc_sup_ord 372 372 0 0.00 0 3222 312 0 0
x_make_qstorno_1000_1200 5719 5719 0 0.00 2 30303 1131 0 0
x_make_qstorno_1200_2000 5744 5744 0 0.00 2 76945 3144 0 0
x_make_qstorno_2100_3300 473100 473100 0 0.00 0 71352 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-06-2022 22:39:25 to 16-06-2022 22:39:29.

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
20:08:19 60 8192000000 8825545488 8846221312 20521824 0.249 6 5 6 0 20773376 92896 158656
20:09:21 31962 8192000000 9903842384 10214232064 983868816 10.722 100 85 170 0 1011912096 5864016 22179264
20:10:56 23600 8192000000 9940388304 10257133568 1020598016 11.078 100 94 188 0 1052773136 8131760 24043360
20:12:25 16265 8192000000 9969620512 10304344064 1055028064 11.409 100 76 152 0 1083274304 8797424 19448816
20:13:43 8748 8192000000 10034764304 10381443072 1120918128 12.036 100 84 168 0 1152525248 11414880 20192240
20:14:56 9130 8192000000 10091110992 10454507520 1185013200 12.637 100 79 158 1 1215571104 13183488 17374416
20:16:09 4877 8192000000 10140115824 10511069184 1225468784 13.012 100 85 170 0 1259223200 13970224 19784192
20:17:16 5633 8192000000 10166381856 10548576256 1259065584 13.321 100 73 146 1 1286829040 11374000 16389456
20:18:23 1446 8192000000 10204854112 10584780800 1288531248 13.591 100 86 171 1 1321300480 13003712 19765520
20:19:27 1546 8192000000 10212933056 10597552128 1299054944 13.687 100 86 172 0 1332038576 12595136 20388496
20:20:29 1795 8192000000 10206189536 10605633536 1303866224 13.730 100 54 109 0 1326517248 10676688 11974336
20:21:32 15477 8192000000 10197081120 10607456256 1311978704 13.804 100 40 82 2 1327507920 5915344 9613872
20:22:48 9368 8192000000 10203414560 10616623104 1316371600 13.844 100 16 32 1 1329704992 10097968 3235424
20:24:00 15508 8192000000 10206069968 10628796416 1323044240 13.904 100 17 34 0 1332413968 5529472 3840256
20:25:19 23128 8192000000 10209516384 10633187328 1329056240 13.959 100 39 78 2 1343091152 5191168 8843744
20:26:45 5003 8192000000 10231016016 10645508096 1332176336 13.987 100 32 64 1 1345902672 7342400 6383936
20:27:51 4888 8192000000 10202132880 10616086528 1303339088 13.726 99 32 64 1 1317288544 6447984 7501472
20:28:58 15685 8192000000 10181907824 10581753856 1267640144 13.400 97 33 66 3 1281786784 6091264 8055376
20:30:14 11486 8192000000 10157612720 10564026368 1239783232 13.144 96 17 33 1 1250555632 5988624 4783776
20:31:30 10226 8192000000 10137934800 10535178240 1185150704 12.638 94 27 51 1 1195583088 4579152 5853232
20:32:41 4818 8192000000 10144675520 10540486656 1240728656 13.153 99 37 74 0 1255477744 5398160 9350928
20:33:48 59530 8192000000 10178142928 10580574208 1281098176 13.523 100 26 51 1 1293455360 5208368 7148816
20:35:49 4008 8192000000 10198332928 10603970560 1296790528 13.666 100 42 80 1 1311858544 5750160 9317856
20:36:57 10473 8192000000 10192186592 10607898624 1305349552 13.744 100 21 42 1 1315776896 5611008 4816336
20:38:08 10157 8192000000 10211787648 10629283840 1322107072 13.896 100 19 38 0 1331147952 4712672 4328208
20:39:35 31035 8192000000 10248528416 10658922496 1346276832 14.114 100 41 83 0 1363872592 7785552 9810208
20:41:32 15678 8192000000 10264436656 10681081856 1359489312 14.233 100 33 66 0 1375992880 9022224 7481344
20:42:59 8525 8192000000 10295463632 10709610496 1374089184 14.364 100 52 104 0 1400079456 13738112 12252160
20:44:17 10944 8192000000 10296522560 10719219712 1381974128 14.434 100 53 105 0 1408752128 14734064 12043936
20:45:32 5410 8192000000 10320911328 10738253824 1401138064 14.605 100 62 124 0 1429900976 14642320 14120592
20:46:44 16160 8192000000 10319399616 10743156736 1382138320 14.436 98 39 78 0 1412295408 21118688 9038400
20:48:03 5501 8192000000 10323863760 10743332864 1400105488 14.596 100 60 120 2 1436804800 22894160 13805152
20:49:13 7648 8192000000 10299768976 10707300352 1368469696 14.313 98 68 136 1 1409737424 25355040 15912688
20:50:27 5537 8192000000 10326940480 10742579200 1392670480 14.530 100 65 128 1 1441703040 33229760 15802800
20:51:39 6347 8192000000 10350265248 10767339520 1399831440 14.593 100 62 124 1 1459724928 43995168 15898320
20:52:49 7055 8192000000 10353327568 10772234240 1405905424 14.648 100 50 98 0 1459500528 40507728 13087376
20:54:01 5584 8192000000 10350077520 10754424832 1385447024 14.465 100 83 166 1 1452346352 46044176 20855152
20:55:13 5529 8192000000 10368312816 10772054016 1397350176 14.571 100 84 169 1 1468352496 49245488 21756832
20:56:27 1875 8192000000 10366343776 10765168640 1387136384 14.480 100 90 180 2 1465669280 55786208 22746688
20:57:34 8668 8192000000 10372776256 10781487104 1386871168 14.478 99 76 152 2 1469125664 61778144 20476352
20:58:46 6136 8192000000 10360725184 10777726976 1387768992 14.486 99 50 99 1 1458026160 56681680 13575488
21:00:06 12460 8192000000 10360899168 10788356096 1389936640 14.505 99 45 90 1 1460906048 58701920 12267488
21:01:26 10333 8192000000 10348383040 10781716480 1379906432 14.416 99 37 72 1 1448494160 58440128 10147600
21:02:49 11149 8192000000 10340240944 10764570624 1353903280 14.183 98 37 74 1 1427242224 63602992 9735952
21:04:05 15828 8192000000 10338414512 10770776064 1365546288 14.287 100 29 58 2 1442323552 68113296 8663968
21:05:52 37460 8192000000 10310104352 10752950272 1344151392 14.095 99 25 51 0 1432907376 81170672 7585312
21:07:45 97075 8192000000 10279974064 10719625216 1349916176 14.147 99 27 54 0 1366021936 9698256 6407504
21:10:30 53839 8192000000 10255425376 10703441920 1341099680 14.067 99 24 49 1 1352319200 6250272 4969248
21:12:35 38521 8192000000 10242991296 10688176128 1328741712 13.956 98 29 58 0 1342544464 7288896 6513856
21:14:14 46082 8192000000 10256580256 10707746816 1349051088 14.139 99 12 24 0 1361245360 9133968 3060304
21:16:09 41397 8192000000 10275107664 10720497664 1367165168 14.302 100 18 36 0 1384608976 13270784 4173024
21:17:52 52909 8192000000 10257675664 10707116032 1355299232 14.195 99 16 33 0 1370125520 10819008 4007280
21:20:36 76534 8192000000 10277937952 10731810816 1377342352 14.393 100 20 40 0 1394497952 12520896 4634704
21:22:58 28462 8192000000 10287971584 10742681600 1388505088 14.493 100 18 36 0 1400998976 8433216 4060672
21:24:33 120228 8192000000 10307468960 10780446720 1399757072 14.593 100 19 38 0 1423781024 19315856 4708096
21:28:10 49818 8192000000 10293745792 10750812160 1383494864 14.448 99 9 18 0 1394124864 8685792 1944208
21:30:07 32682 8192000000 10315608432 10766848000 1402155488 14.614 100 30 61 0 1423686064 14918784 6611792
21:31:42 144800 8192000000 10295562032 10756562944 1278074272 13.495 91 9 18 0 1296956928 17066112 1816544
21:35:07 59260 8192000000 10286929840 10752884736 1370779232 14.334 99 15 30 0 1390570976 16016928 3774816
21:37:08 37132 8192000000 10302206880 10744160256 1369904592 14.326 99 26 52 1 1382786848 7182160 5700096
21:38:49 51090 8192000000 10281984992 10733944832 1368513280 14.314 99 26 52 1 1385375008 11086464 5775264
21:40:42 65852 8192000000 10255384992 10702684160 1344753872 14.100 98 24 48 0 1355555104 6378688 4422544
21:42:50 72344 8192000000 10256674608 10712539136 1348853312 14.137 99 9 18 2 1361795232 10860304 2081616
21:45:03 43445 8192000000 10266276784 10720550912 1351556192 14.161 99 17 34 0 1367547152 11831632 4159328
21:46:47 48125 8192000000 10264793856 10720006144 1356181120 14.203 99 22 45 3 1371540368 9752496 5606752
21:48:38 50464 8192000000 10249480768 10697515008 1315355744 13.835 96 17 33 2 1329351104 10095856 3899504
21:50:29 91256 8192000000 10284714768 10735955968 1333981104 14.003 97 21 42 0 1350983392 12037440 4964848
21:53:08 88513 8192000000 10267839824 10713309184 1302382464 13.717 95 10 20 0 1327155808 22733408 2039936
21:55:47 9682 8192000000 10286738368 10716016640 1357906768 14.219 100 52 104 1 1383061920 13604768 11550384
21:57:03 39016 8192000000 10268839840 10710237184 1312280096 13.807 96 39 78 1 1339756048 19239616 8236336
21:58:49 77068 8192000000 10278033104 10719354880 1354638544 14.189 99 45 90 0 1374054208 9035648 10380016
22:01:08 45449 8192000000 10257918256 10714845184 1334455344 14.007 97 23 44 1 1349123888 9702864 4965680
22:02:54 37945 8192000000 10270028208 10731069440 1378029376 14.399 100 12 24 0 1390602800 9917248 2656176
22:04:32 55781 8192000000 10259212800 10717044736 1341372864 14.070 98 16 32 0 1353511968 9041584 3097520
22:07:04 94659 8192000000 10266869392 10724909056 1312694608 13.811 95 18 37 1 1331354928 14522576 4137744
22:09:40 123708 8192000000 10291489808 10741415936 1320165888 13.878 95 14 28 0 1336500000 13300336 3033776
22:12:47 57646 8192000000 10307204672 10765070336 1388722880 14.494 100 8 16 0 1406739984 15986480 2030624
22:14:56 60842 8192000000 10305412016 10756788224 1397746864 14.575 100 16 31 1 1410295856 9057744 3491248
22:16:58 21772 8192000000 10308124576 10754596864 1384221824 14.454 99 32 64 0 1401742176 10213264 7307088
22:18:25 33194 8192000000 10297221760 10750455808 1377157984 14.391 99 28 56 1 1396180832 12618496 6404352
22:20:04 29971 8192000000 10271503072 10712932352 1342705552 14.082 98 31 62 0 1360340624 10852048 6783024
22:21:44 90578 8192000000 10289790112 10758819840 1354435088 14.187 98 21 42 0 1378498176 19131168 4931920
22:24:19 98031 8192000000 10266416960 10725912576 1322887184 13.903 96 21 42 0 1340650688 12899296 4864208
22:26:57 46391 8192000000 10248354736 10705932288 1322268992 13.897 97 10 20 0 1330559968 6587024 1703952
22:28:43 74151 8192000000 10244621504 10702495744 1331426592 13.980 98 15 30 0 1342400688 7634640 3339456
22:31:18 67535 8192000000 10261715744 10715373568 1342403328 14.079 99 12 24 1 1351672960 6153232 3116400
22:33:26 50737 8192000000 10266451264 10718203904 1344734352 14.100 98 21 42 0 1360633936 10766464 5133120
22:35:37 28822 8192000000 10269377712 10726014976 1345805072 14.110 98 21 42 0 1362485392 11825168 4855152
22:37:08 72551 8192000000 9472640624 9798959104 589809360 6.716 43 3 6 0 599293200 8900240 583600

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 16-06-2022 22:39:31 to 16-06-2022 22:39:45.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 112 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-06-2022 22:39:46 to 16-06-2022 22:39:48.

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          242855
MON$OLDEST_ACTIVE               244101
MON$OLDEST_SNAPSHOT             244101
MON$NEXT_TRANSACTION            244101
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                       2014592
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.33602 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33602 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33602 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33602 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 ---
:736: 
:737: mscs-test-161.budzdorov.ru    Thu Jun 16 22:38:23 2022
:738:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:739: 
:740: 
:741: mscs-test-161.budzdorov.ru    Thu Jun 16 22:38:23 2022
:742:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:743: 
:744: 
:745: mscs-test-161.budzdorov.ru    Thu Jun 16 22:38:27 2022
:746:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:747: 
--- end of diff output ---

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

16.06.22 22:39:49. 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-06-16 22:39:52 No severe PSQL-related problems occured
16.06.22 22:39:53. End of report.