Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.01.2022 19: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 16661408 kB
MemAvailable 31331064 kB
Buffers 0 kB
Cached 15043728 kB
SwapCached 1220 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.9
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-01-06 19:38:03
DTS_END                         2022-01-06 19:38:03.7810
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/42792
TRN_ID                          231533
ATT_ID                          701
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: 8901.70 22721 1068204
customer order: creation 163.22 357 19586
customer order: refuse 13.59 360 1631
order to supplier: creation 42.93 1361 5152
order to supplier: removal 3.48 104424 417
invoice (draft): creation 43.93 3551 5271
invoice (draft): removal 52.10 752 6252
invoice accept: apply 39.38 18741 4726
invoice accept: cancel 46.38 782 5566
customer reserve: creation 3632.76 184 435931
customer reserve: removal 4582.15 63 549858
realization accept: apply 79.81 181 9577
realization accept: cancel 79.27 109 9512
payment from customer: creation 55.75 109 6690
payment from customer: removal 5.18 86 621
payment to supplier: creation 46.51 103 5581
payment to supplier: removal 3.31 118 397
service: total inventory turnovers 4.70 1057 564
service: total monetary turnovers 6.50 27 780
service: aggregate perf. data 0.73 22120 88
service: refresh index statistics 0.03 299943 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-01-2022 19:39:48 to 06-01-2022 19:39:50.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5999
TEST_TIME 2 4964
TEST_TIME 3 5479
TEST_TIME 4 5731
TEST_TIME 5 5986
TEST_TIME 6 6005
TEST_TIME 7 6122
TEST_TIME 8 5916
TEST_TIME 9 6089
TEST_TIME 10 6318
TEST_TIME 11 6708
TEST_TIME 12 6471
TEST_TIME 13 6510
TEST_TIME 14 6776
TEST_TIME 15 7102
TEST_TIME 16 7167
TEST_TIME 17 7557
TEST_TIME 18 7672
TEST_TIME 19 8180
TEST_TIME 20 8556
TEST_TIME 21 8717
TEST_TIME 22 8941
TEST_TIME 23 9384
TEST_TIME 24 9628
TEST_TIME 25 10011
TEST_TIME 26 9982
TEST_TIME 27 10558
TEST_TIME 28 11016
TEST_TIME 29 10664
TEST_TIME 30 10524
TEST_TIME 31 10564
TEST_TIME 32 10585
TEST_TIME 33 10448
TEST_TIME 34 10307
TEST_TIME 35 10101
TEST_TIME 36 9999
TEST_TIME 37 9823
TEST_TIME 38 9677
TEST_TIME 39 9706
TEST_TIME 40 9707
TEST_TIME 41 9597
TEST_TIME 42 9450
TEST_TIME 43 9412
TEST_TIME 44 9447
TEST_TIME 45 9346
TEST_TIME 46 9280
TEST_TIME 47 9275
TEST_TIME 48 9341
TEST_TIME 49 9282
TEST_TIME 50 9218
TEST_TIME 51 9253
TEST_TIME 52 9270
TEST_TIME 53 9358
TEST_TIME 54 9324
TEST_TIME 55 9258
TEST_TIME 56 9347
TEST_TIME 57 9383
TEST_TIME 58 9348
TEST_TIME 59 9380
TEST_TIME 60 9434
TEST_TIME 61 9445
TEST_TIME 62 9342
TEST_TIME 63 9245
TEST_TIME 64 9177
TEST_TIME 65 9169
TEST_TIME 66 9076
TEST_TIME 67 9057
TEST_TIME 68 9049
TEST_TIME 69 9092
TEST_TIME 70 9036
TEST_TIME 71 8996
TEST_TIME 72 8998
TEST_TIME 73 9078
TEST_TIME 74 9008
TEST_TIME 75 9159
TEST_TIME 76 9152
TEST_TIME 77 9105
TEST_TIME 78 9156
TEST_TIME 79 9158
TEST_TIME 80 9189
TEST_TIME 81 9165
TEST_TIME 82 9074
TEST_TIME 83 9089
TEST_TIME 84 9085
TEST_TIME 85 9053
TEST_TIME 86 9026
TEST_TIME 87 8974
TEST_TIME 88 8949
TEST_TIME 89 8951
TEST_TIME 90 8932
TEST_TIME 91 8921
TEST_TIME 92 8988
TEST_TIME 93 9084
TEST_TIME 94 9096
TEST_TIME 95 9072
TEST_TIME 96 9139
TEST_TIME 97 9101
TEST_TIME 98 9163
TEST_TIME 99 9176
TEST_TIME 100 9169
TEST_TIME 101 9180
TEST_TIME 102 9331
TEST_TIME 103 9326
TEST_TIME 104 9290
TEST_TIME 105 9222
TEST_TIME 106 9202
TEST_TIME 107 9168
TEST_TIME 108 9177
TEST_TIME 109 9130
TEST_TIME 110 9148
TEST_TIME 111 9111
TEST_TIME 112 9082
TEST_TIME 113 9085
TEST_TIME 114 9055
TEST_TIME 115 9065
TEST_TIME 116 9057
TEST_TIME 117 9019
TEST_TIME 118 8981
TEST_TIME 119 8976
TEST_TIME 120 8904

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 06-01-2022 19:39:51 to 06-01-2022 19:39:55.

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 1471795 1471795 0 0.00 0 16198 6 0 0
doc_list_biud 1471794 1471794 0 0.00 0 55682 29 0 0
sp_add_invoice_to_stock 4901 4726 175 3.57 41 174549 18742 0 175
sp_cancel_adding_invoice 5566 5566 0 0.00 5 83403 783 0 0
sp_cancel_client_order 1631 1631 0 0.00 4 7449 361 0 0
sp_cancel_customer_reserve 549858 549858 0 0.00 1 55685 64 0 0
sp_cancel_pay_from_customer 621 621 0 0.00 3 7137 86 0 0
sp_cancel_pay_to_supplier 397 397 0 0.00 3 11454 118 0 0
sp_cancel_supplier_invoice 6263 6252 11 0.17 2 26633 753 0 11
sp_cancel_supplier_order 417 417 0 0.00 7 949858 104424 0 0
sp_cancel_write_off 9512 9512 0 0.00 1 10863 110 0 0
sp_client_order 19586 19586 0 0.00 3 16881 358 0 0
sp_customer_reserve 435931 435931 0 0.00 0 54079 184 0 0
sp_fill_shopping_cart 29986 29986 0 0.00 1 31356 40 0 0
sp_fill_shopping_cart_clo_res 435922 435922 0 0.00 0 15806 15 0 0
sp_get_clo_for_invoice 4717 4717 0 0.00 0 16516 323 0 0
sp_kill_cost_storno 16096 16096 0 0.00 0 7134 57 0 0
sp_kill_qstorno_ret_qs2qd 1115897 1115897 0 0.00 0 55677 34 0 0
sp_kill_qty_storno 587530 587530 0 0.00 0 55681 73 0 0
sp_lock_selected_doc 19715 19715 0 0.00 0 10906 7 0 0
sp_make_cost_storno 26565 26565 0 0.00 0 16186 52 0 0
sp_multiply_rows_for_pdistr 26565 26565 0 0.00 0 8648 29 0 0
sp_multiply_rows_for_qdistr 19586 19586 0 0.00 0 16874 167 0 0
sp_pay_from_customer 6690 6690 0 0.00 4 9687 110 0 0
sp_pay_to_supplier 5581 5581 0 0.00 5 6998 104 0 0
sp_payment_common 12271 12271 0 0.00 1 7076 22 0 0
sp_qd_handle_on_cancel_clo 1631 1631 0 0.00 0 7114 329 0 0
sp_qd_handle_on_invoice_upd_sts 10283 10283 0 0.00 0 37613 344 0 0
sp_qd_handle_on_reserve_upd_sts 9577 9577 0 0.00 0 19876 63 0 0
sp_reserve_write_off 9577 9577 0 0.00 6 19893 181 0 0
sp_supplier_invoice 5271 5271 0 0.00 6 90209 3552 0 0
sp_supplier_order 5152 5152 0 0.00 6 40659 1361 0 0
srv_aggregate_perf_data 88 88 0 0.00 2891 389271 22121 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1353 133156 49104 0 0
srv_find_qd_qs_mism 1023030 1023030 0 0.00 0 13328 5 0 0
srv_make_invnt_saldo 564 564 0 0.00 34 47488 1057 0 0
srv_make_money_saldo 780 780 0 0.00 0 3377 27 0 0
srv_recalc_idx_stat 4 4 0 0.00 160541 373884 299944 0 0
t$perf-abend:sp_add_invoice_to_stock 175 175 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 4726 4726 0 0.00 0 42657 190 0 0
t$perf-norm:sp_cancel_adding_invoice 360 360 0 0.00 0 3785 107 0 0
t$perf-norm:sp_cancel_client_order 1631 1631 0 0.00 0 4430 19 0 0
t$perf-norm:sp_cancel_customer_reserve 644 644 0 0.00 0 2069 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 621 621 0 0.00 0 3275 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 397 397 0 0.00 0 4508 26 0 0
t$perf-norm:sp_cancel_supplier_invoice 417 417 0 0.00 0 1853 21 0 0
t$perf-norm:sp_cancel_supplier_order 417 417 0 0.00 0 20131 1567 0 0
t$perf-norm:sp_cancel_write_off 934 934 0 0.00 0 9014 15 0 0
t$perf-norm:sp_client_order 19586 19586 0 0.00 0 21926 21 0 0
t$perf-norm:sp_customer_reserve 967 967 0 0.00 0 1451 6 0 0
t$perf-norm:sp_pay_from_customer 6690 6690 0 0.00 0 4159 10 0 0
t$perf-norm:sp_pay_to_supplier 5581 5581 0 0.00 0 6888 11 0 0
t$perf-norm:sp_reserve_write_off 9577 9577 0 0.00 0 20017 17 0 0
t$perf-norm:sp_supplier_invoice 5271 5271 0 0.00 0 6300 17 0 0
t$perf-norm:sp_supplier_order 5152 5152 0 0.00 0 8185 17 0 0
t$perf-norm:srv_aggregate_perf_data 88 88 0 0.00 0 668 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 47 1 0 0
t$perf-norm:srv_make_invnt_saldo 564 564 0 0.00 0 5638 54 0 0
t$perf-norm:srv_make_money_saldo 780 780 0 0.00 0 1052 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 9784 9434 350 3.57 0 7222 18 0 350
v_all_customers 35354 35354 0 0.00 0 19 1 0 0
v_all_suppliers 28900 28900 0 0.00 0 38 1 0 0
v_all_wares 39172 39172 0 0.00 0 37 1 0 0
v_cancel_adding_invoice 720 720 0 0.00 0 1540 8 0 0
v_cancel_client_order 3262 3262 0 0.00 0 2487 4 0 0
v_cancel_customer_prepayment 1242 1242 0 0.00 0 1548 7 0 0
v_cancel_customer_reserve 1288 1288 0 0.00 0 606 3 0 0
v_cancel_payment_to_supplier 794 794 0 0.00 0 1236 6 0 0
v_cancel_supplier_invoice 856 834 22 2.57 0 2128 15 0 22
v_cancel_supplier_order 834 834 0 0.00 0 206 3 0 0
v_cancel_write_off 1868 1868 0 0.00 0 1272 12 0 0
v_max_id_clo_ord 5152 5152 0 0.00 0 78 1 0 0
v_max_id_clo_res 967 967 0 0.00 0 1018 4 0 0
v_max_id_ord_sup 5248 5248 0 0.00 0 4402 4 0 0
v_max_non_paid_invoice 1531 1531 0 0.00 0 263 2 0 0
v_max_non_paid_realizn 3665 3665 0 0.00 0 2990 4 0 0
v_min_id_clo_ord 5152 5152 0 0.00 0 13844 13 0 0
v_min_id_clo_res 967 967 0 0.00 0 3961 23 0 0
v_min_id_ord_sup 5248 5248 0 0.00 0 4747 5 0 0
v_min_non_paid_invoice 5581 5581 0 0.00 0 6622 15 0 0
v_min_non_paid_realizn 6690 6690 0 0.00 0 6990 18 0 0
v_our_firm 9868 9868 0 0.00 0 19 1 0 0
v_reserve_write_off 19154 19154 0 0.00 0 2487 4 0 0
x_lock_depdocs_on_canc_invoice 11401 11401 0 0.00 0 13491 116 0 0
x_lock_depdocs_on_canc_sup_ord 417 417 0 0.00 0 5348 356 0 0
x_make_qstorno_1000_1200 5152 5152 0 0.00 2 40647 1281 0 0
x_make_qstorno_1200_2000 5271 5271 0 0.00 3 69797 3391 0 0
x_make_qstorno_2100_3300 435931 435931 0 0.00 0 54079 167 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-01-2022 19:39:56 to 06-01-2022 19:40:00.

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
17:08:07 36 8192000000 8812359120 8828522496 15173328 0.184 5 3 4 0 15361328 80896 107104
17:09:09 23370 8192000000 9909102864 10222915584 991999136 10.801 100 87 174 0 1020264608 6176896 22088576
17:10:41 16898 8192000000 9950765296 10276274176 1035066864 11.217 100 84 168 1 1065992304 8748896 22176544
17:12:01 17335 8192000000 9986448128 10322714624 1069848288 11.551 100 84 168 0 1100487728 9490128 21149312
17:13:25 13427 8192000000 10027272688 10374864896 1112150272 11.953 100 79 158 1 1141498000 10667632 18680096
17:14:41 9379 8192000000 10080201328 10442076160 1165896000 12.458 100 76 152 1 1196617696 13267360 17454336
17:15:52 6489 8192000000 10122139328 10493255680 1215813440 12.923 100 76 152 0 1245154608 12339664 17001504
17:17:00 10005 8192000000 10144404976 10520137728 1240400816 13.150 100 71 142 0 1269252256 13170192 15681248
17:18:13 1908 8192000000 10190655568 10573275136 1278014800 13.495 100 87 178 2 1310010992 12037520 19958672
17:19:16 1965 8192000000 10215209360 10600034304 1297936848 13.676 100 90 180 0 1332480704 13921376 20622480
17:20:20 3774 8192000000 10201522112 10605039616 1306483792 13.754 100 62 124 1 1329450464 8303280 14663392
17:21:29 6192 8192000000 10199943264 10611945472 1312173120 13.806 100 37 72 1 1327671024 7017808 8480096
17:22:37 10254 8192000000 10200343248 10620170240 1318608240 13.864 100 24 46 2 1329540896 5001904 5930752
17:23:49 47004 8192000000 10220881328 10638491648 1324571216 13.918 100 36 72 3 1338866144 5791360 8503568
17:25:36 6045 8192000000 10223318192 10643210240 1330698048 13.973 100 30 61 0 1343838432 6166672 6973712
17:26:43 9715 8192000000 10230703248 10651938816 1333378640 13.998 100 23 46 0 1348747424 9542720 5826064
17:27:54 64659 8192000000 10236450768 10641678336 1308457312 13.772 98 38 77 0 1323119168 4854576 9807280
17:29:59 5500 8192000000 10124442208 10529701888 1194739936 12.727 94 17 34 0 1203979360 4584640 4654784
17:31:07 6677 8192000000 10121458640 10523103232 1206140272 12.833 97 28 56 2 1219008816 6189856 6678688
17:32:15 4775 8192000000 10149752944 10549444608 1239205056 13.139 99 27 54 1 1250556784 4915728 6436000
17:33:20 29141 8192000000 10160592176 10570690560 1249577008 13.234 98 31 62 0 1262303952 5418528 7308416
17:34:50 5130 8192000000 10200992320 10605142016 1297616672 13.674 100 37 71 0 1311690560 6273520 7800368
17:35:56 10536 8192000000 10197339200 10617749504 1312268384 13.807 100 26 55 1 1324167088 6305632 5593072
17:37:07 45901 8192000000 10227159872 10636640256 1321548448 13.891 100 34 67 2 1334647792 5184352 7914992
17:39:22 1709 8192000000 10259107680 10665750528 1348144448 14.131 100 73 141 1 1372458832 8167200 16147184
17:40:28 17222 8192000000 10269174048 10683568128 1366549344 14.296 100 54 108 1 1389366496 10773504 12043648
17:41:51 21161 8192000000 10276829696 10700828672 1371133232 14.337 100 38 75 1 1391446960 11640560 8673168
17:43:22 8483 8192000000 10303886656 10724130816 1392594496 14.529 100 48 96 0 1416354288 11689552 12070240
17:44:34 4721 8192000000 10316546896 10738741248 1401261136 14.606 100 56 114 1 1428062880 14199712 12602032
17:45:43 2833 8192000000 10319439808 10744590336 1406196224 14.650 100 46 90 0 1430069536 13078432 10794880
17:46:55 16127 8192000000 10342571664 10766356480 1420734592 14.779 100 64 128 1 1455070032 18283632 16051808
17:48:17 4961 8192000000 10362225664 10781659136 1428175808 14.845 100 71 139 0 1468739872 24014160 16549904
17:49:29 7837 8192000000 10353651152 10777997312 1420746528 14.779 100 56 112 0 1461224000 26904496 13572976
17:50:50 11255 8192000000 10362138640 10789728256 1421015584 14.782 99 63 126 3 1466491296 30016640 15459072
17:52:08 3342 8192000000 10370280544 10784456704 1420265152 14.775 99 75 147 2 1473040912 34763328 18012432
17:53:18 1351 8192000000 10374504752 10783215616 1414367520 14.723 99 80 161 1 1473569632 39202160 19999952
17:54:25 2846 8192000000 10367491584 10775519232 1406121760 14.649 100 77 154 2 1470215504 44725392 19368352
17:55:35 7013 8192000000 10367519840 10779684864 1402355920 14.616 100 65 130 0 1469676512 50227440 17093152
17:56:51 2664 8192000000 10351385264 10756046848 1367293760 14.303 99 80 159 1 1443683328 55162560 21227008
17:57:57 16469 8192000000 10328281440 10752794624 1366074256 14.292 99 50 98 1 1429510304 49926144 13509904
17:59:17 7844 8192000000 10330127616 10745737216 1372491168 14.349 99 52 105 3 1435776752 48243456 15042128
18:00:29 16394 8192000000 10329283632 10759557120 1374892400 14.371 99 39 78 1 1435505616 49644384 10968832
18:01:50 11282 8192000000 10326412928 10755928064 1373331808 14.357 100 43 86 2 1433129856 48126528 11671520
18:03:04 23580 8192000000 10308954256 10744967168 1369003952 14.318 100 26 53 2 1421000144 44832352 7163840
18:04:34 66200 8192000000 10277109312 10735185920 1325782128 13.929 98 15 30 2 1383943536 53840656 4320752
18:06:42 59148 8192000000 10269617984 10708684800 1356851600 14.209 100 35 67 0 1376159392 11597504 7710288
18:08:45 51322 8192000000 10275149712 10715254784 1353045168 14.175 99 29 57 1 1376676992 17238048 6393776
18:10:38 24211 8192000000 10268665680 10737987584 1363043936 14.265 100 9 17 1 1382797536 17956224 1797376
18:12:24 42537 8192000000 10276338800 10719055872 1370578912 14.332 100 15 30 0 1379869264 6271056 3019296
18:14:11 90036 8192000000 10254614368 10702422016 1345655808 14.108 98 23 44 1 1364498496 13209728 5632960
18:16:41 102170 8192000000 10265593776 10726952960 1346370352 14.115 98 22 45 1 1361538992 9872032 5296608
18:19:26 69910 8192000000 10253464448 10699943936 1344743280 14.100 98 11 22 0 1365390224 17940560 2706384
18:22:07 57331 8192000000 10277652848 10742841344 1374196272 14.365 99 31 61 0 1392999696 11744080 7059344
18:24:27 56236 8192000000 10287951696 10742145024 1378578944 14.404 99 17 34 1 1394335376 11489584 4266848
18:26:38 76101 8192000000 10291779664 10751275008 1383350480 14.446 100 10 20 0 1393882560 8420368 2111712
18:28:55 77774 8192000000 10309045776 10779242496 1401738176 14.610 100 17 34 0 1416798480 10845136 4215168
18:31:14 101644 8192000000 10297025840 10756489216 1358531776 14.224 97 13 24 1 1371456080 9711776 3212528
18:33:55 75188 8192000000 10292521024 10759766016 1361722928 14.253 97 17 34 2 1382491328 16554368 4214032
18:36:11 35353 8192000000 10301505888 10751008768 1360466848 14.242 98 35 68 2 1378610064 10914832 7228384
18:37:48 103518 8192000000 10298937552 10755391488 1337461536 14.035 96 20 40 0 1355276112 13615040 4199536
18:40:36 83945 8192000000 10246798064 10693832704 1300104448 13.696 94 24 48 1 1311551200 6490352 4956400
18:43:01 47590 8192000000 10258191568 10716135424 1334912592 14.012 97 23 46 0 1347377600 7285456 5179552
18:44:50 78121 8192000000 10274492272 10739757056 1371082944 14.337 99 12 24 1 1383879616 9557488 3239184
18:47:43 88146 8192000000 10274243504 10724012032 1309987776 13.786 95 14 28 1 1326438896 13376736 3074384
18:50:12 18587 8192000000 10262114128 10705092608 1334390160 14.007 98 37 72 1 1359096848 15755120 8951568
18:51:32 52579 8192000000 10266748480 10712961024 1336852192 14.029 99 22 44 0 1356446992 13853632 5741168
18:53:25 106245 8192000000 10250848336 10698014720 1282667904 13.537 94 22 44 0 1300209536 12865264 4676368
18:56:13 16379 8192000000 10286570832 10715918336 1349535568 14.143 99 50 99 1 1374471872 13369024 11567280
18:57:32 56627 8192000000 10250537632 10690056192 1284388656 13.553 95 23 46 0 1302117824 12275504 5453664
18:59:36 132784 8192000000 10265333728 10726883328 1367190912 14.302 100 31 62 1 1388611488 14947760 6472816
19:02:50 17966 8192000000 10243607600 10709561344 1336685520 14.028 98 6 12 0 1348368480 10351232 1331728
19:04:10 62534 8192000000 10277339424 10744115200 1377346592 14.393 100 5 10 0 1394712528 16439936 926000
19:06:33 51726 8192000000 10291678064 10747187200 1367007232 14.300 99 15 30 0 1392499520 22280304 3211984
19:08:28 46363 8192000000 10296512848 10746621952 1365316480 14.285 98 17 34 0 1382962672 13682896 3963296
19:10:20 86754 8192000000 10300118176 10755596288 1387943632 14.488 100 17 34 0 1412274976 20108448 4222896
19:12:55 52429 8192000000 10325668064 10765991936 1394886784 14.549 100 24 48 0 1407669776 7679216 5103776
19:14:49 51345 8192000000 10306367072 10754269184 1362725136 14.262 97 16 31 3 1378476016 12074912 3675968
19:16:57 45170 8192000000 10302927376 10748919808 1385190640 14.463 100 32 64 1 1403878544 11935856 6752048
19:18:44 71703 8192000000 10307845632 10766536704 1372309984 14.348 98 24 48 1 1394037312 16652256 5075072
19:20:58 133156 8192000000 10307526896 10774491136 1349361136 14.142 97 12 24 0 1374910112 22966608 2582368
19:24:16 76135 8192000000 10272943824 10738794496 1330168448 13.969 96 5 10 0 1340078208 8857568 1052192
19:26:56 93910 8192000000 10272351392 10736918528 1347830496 14.128 97 13 26 0 1360499904 9435312 3234096
19:29:41 74935 8192000000 10277132400 10737119232 1360598848 14.243 98 12 24 1 1372014496 8654544 2761104
19:31:59 73068 8192000000 10259933056 10728624128 1337062688 14.031 97 14 27 0 1349619808 9424960 3132160
19:34:46 101139 8192000000 10267986896 10733371392 1353037088 14.175 98 16 32 2 1365854640 9151232 3666320
19:37:28 47229 8192000000 9464571136 9793368064 607805536 6.907 45 3 6 0 616717168 8378064 533568

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 06-01-2022 19:40:02 to 06-01-2022 19:40:16.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 175 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 06-01-2022 19:40:17 to 06-01-2022 19:40:19.

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          231532
MON$OLDEST_ACTIVE               232674
MON$OLDEST_SNAPSHOT             232674
MON$NEXT_TRANSACTION            232674
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                       2016320
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 ---
:547: 
:548: mscs-test-161.budzdorov.ru    Thu Jan  6 19:38:17 2022
:549:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:550: 
--- end of diff output ---

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

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