Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.02.2023 13: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 15119168 kB
MemAvailable 30383260 kB
Buffers 0 kB
Cached 16560784 kB
SwapCached 3576 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 4.0.3
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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 2023-02-04 13:37:27
DTS_END                         2023-02-04 13:37:27.4960
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/39564
TRN_ID                          220710
ATT_ID                          619
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: 8576.08 21931 1029130
customer order: creation 173.93 506 20871
customer order: refuse 14.73 432 1767
order to supplier: creation 46.05 1404 5526
order to supplier: removal 3.15 157132 378
invoice (draft): creation 46.62 3643 5594
invoice (draft): removal 44.55 1100 5346
invoice accept: apply 42.15 27863 5058
invoice accept: cancel 40.30 1205 4836
customer reserve: creation 3916.10 265 469932
customer reserve: removal 3966.00 97 475920
realization accept: apply 84.49 289 10139
realization accept: cancel 67.55 175 8106
payment from customer: creation 58.90 180 7068
payment from customer: removal 5.97 129 716
payment to supplier: creation 49.29 217 5915
payment to supplier: removal 3.49 134 419
service: total inventory turnovers 4.06 3786 487
service: total monetary turnovers 7.79 316 935
service: aggregate perf. data 0.94 14104 113
service: refresh index statistics 0.03 225647 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 04-02-2023 13:38:51 to 04-02-2023 13:38:54.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5875
TEST_TIME 2 6020
TEST_TIME 3 5940
TEST_TIME 4 6097
TEST_TIME 5 6213
TEST_TIME 6 5996
TEST_TIME 7 6299
TEST_TIME 8 6974
TEST_TIME 9 7058
TEST_TIME 10 7082
TEST_TIME 11 7220
TEST_TIME 12 7293
TEST_TIME 13 7387
TEST_TIME 14 7447
TEST_TIME 15 7454
TEST_TIME 16 7531
TEST_TIME 17 7767
TEST_TIME 18 7715
TEST_TIME 19 8030
TEST_TIME 20 8338
TEST_TIME 21 8465
TEST_TIME 22 8439
TEST_TIME 23 8505
TEST_TIME 24 8552
TEST_TIME 25 8802
TEST_TIME 26 8641
TEST_TIME 27 8753
TEST_TIME 28 8638
TEST_TIME 29 8857
TEST_TIME 30 9190
TEST_TIME 31 9697
TEST_TIME 32 10077
TEST_TIME 33 10102
TEST_TIME 34 10324
TEST_TIME 35 10452
TEST_TIME 36 10410
TEST_TIME 37 10152
TEST_TIME 38 10044
TEST_TIME 39 10165
TEST_TIME 40 10037
TEST_TIME 41 9966
TEST_TIME 42 9919
TEST_TIME 43 9833
TEST_TIME 44 9776
TEST_TIME 45 9655
TEST_TIME 46 9573
TEST_TIME 47 9462
TEST_TIME 48 9475
TEST_TIME 49 9342
TEST_TIME 50 9278
TEST_TIME 51 9315
TEST_TIME 52 9286
TEST_TIME 53 9233
TEST_TIME 54 9100
TEST_TIME 55 9042
TEST_TIME 56 9069
TEST_TIME 57 9050
TEST_TIME 58 9059
TEST_TIME 59 9023
TEST_TIME 60 8901
TEST_TIME 61 8876
TEST_TIME 62 8874
TEST_TIME 63 8888
TEST_TIME 64 8880
TEST_TIME 65 8853
TEST_TIME 66 8825
TEST_TIME 67 8833
TEST_TIME 68 8959
TEST_TIME 69 8882
TEST_TIME 70 8868
TEST_TIME 71 8904
TEST_TIME 72 8941
TEST_TIME 73 8932
TEST_TIME 74 8919
TEST_TIME 75 8879
TEST_TIME 76 8961
TEST_TIME 77 9038
TEST_TIME 78 8936
TEST_TIME 79 8869
TEST_TIME 80 8826
TEST_TIME 81 8772
TEST_TIME 82 8781
TEST_TIME 83 8748
TEST_TIME 84 8711
TEST_TIME 85 8741
TEST_TIME 86 8671
TEST_TIME 87 8643
TEST_TIME 88 8620
TEST_TIME 89 8616
TEST_TIME 90 8582
TEST_TIME 91 8606
TEST_TIME 92 8618
TEST_TIME 93 8634
TEST_TIME 94 8642
TEST_TIME 95 8633
TEST_TIME 96 8624
TEST_TIME 97 8679
TEST_TIME 98 8659
TEST_TIME 99 8657
TEST_TIME 100 8699
TEST_TIME 101 8720
TEST_TIME 102 8764
TEST_TIME 103 8766
TEST_TIME 104 8703
TEST_TIME 105 8665
TEST_TIME 106 8648
TEST_TIME 107 8608
TEST_TIME 108 8589
TEST_TIME 109 8548
TEST_TIME 110 8569
TEST_TIME 111 8589
TEST_TIME 112 8571
TEST_TIME 113 8550
TEST_TIME 114 8537
TEST_TIME 115 8530
TEST_TIME 116 8572
TEST_TIME 117 8533
TEST_TIME 118 8578
TEST_TIME 119 8575
TEST_TIME 120 8597

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 04-02-2023 13:38:55 to 04-02-2023 13:38:59.

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 1460477 1460477 0 0.00 0 17933 14 0 0
doc_list_biud 1460470 1460470 0 0.00 0 61659 38 0 0
sp_add_invoice_to_stock 5167 5058 109 2.10 27 225481 27863 0 109
sp_cancel_adding_invoice 4836 4836 0 0.00 7 110472 1205 0 0
sp_cancel_client_order 1767 1767 0 0.00 6 10816 432 0 0
sp_cancel_customer_reserve 475920 475920 0 0.00 1 20751 97 0 0
sp_cancel_pay_from_customer 716 716 0 0.00 4 11511 129 0 0
sp_cancel_pay_to_supplier 419 419 0 0.00 4 1902 135 0 0
sp_cancel_supplier_invoice 5358 5346 12 0.22 4 61666 1101 0 12
sp_cancel_supplier_order 378 378 0 0.00 9 1186715 157132 0 0
sp_cancel_write_off 8106 8106 0 0.00 2 12472 175 0 0
sp_client_order 20871 20871 0 0.00 5 40481 506 0 0
sp_customer_reserve 469932 469932 0 0.00 1 42601 266 0 0
sp_fill_shopping_cart 31976 31976 0 0.00 1 57851 93 0 0
sp_fill_shopping_cart_clo_res 469916 469916 0 0.00 0 14602 20 0 0
sp_get_clo_for_invoice 5036 5036 0 0.00 0 29327 308 0 0
sp_kill_cost_storno 14077 14077 0 0.00 0 11629 76 0 0
sp_kill_qstorno_ret_qs2qd 965670 965670 0 0.00 0 61657 43 0 0
sp_kill_qty_storno 511524 511524 0 0.00 0 61658 99 0 0
sp_lock_selected_doc 20995 20995 0 0.00 0 11435 8 0 0
sp_make_cost_storno 28158 28158 0 0.00 0 17829 85 0 0
sp_multiply_rows_for_pdistr 28155 28155 0 0.00 0 10141 38 0 0
sp_multiply_rows_for_qdistr 20871 20871 0 0.00 0 39037 186 0 0
sp_pay_from_customer 7068 7068 0 0.00 6 10944 180 0 0
sp_pay_to_supplier 5915 5915 0 0.00 6 17284 217 0 0
sp_payment_common 12982 12982 0 0.00 2 6701 46 0 0
sp_qd_handle_on_cancel_clo 1767 1767 0 0.00 0 10809 359 0 0
sp_qd_handle_on_invoice_upd_sts 9871 9871 0 0.00 0 20761 520 0 0
sp_qd_handle_on_reserve_upd_sts 10136 10136 0 0.00 0 8459 66 0 0
sp_reserve_write_off 10139 10139 0 0.00 8 13887 289 0 0
sp_supplier_invoice 5594 5594 0 0.00 8 141812 3644 0 0
sp_supplier_order 5526 5526 0 0.00 6 47059 1404 0 0
srv_aggregate_perf_data 113 113 0 0.00 2549 127285 14105 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 936 188591 34603 0 0
srv_find_qd_qs_mism 978950 978950 0 0.00 0 12383 11 0 0
srv_make_invnt_saldo 487 487 0 0.00 50 63387 3787 0 0
srv_make_money_saldo 935 935 0 0.00 1 11563 316 0 0
srv_recalc_idx_stat 4 4 0 0.00 89773 335166 225647 0 0
t$perf-abend:sp_add_invoice_to_stock 109 109 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5064 5064 0 0.00 1 11618 1007 0 0
t$perf-norm:sp_cancel_adding_invoice 400 400 0 0.00 2 7210 430 0 0
t$perf-norm:sp_cancel_client_order 1767 1767 0 0.00 0 9722 36 0 0
t$perf-norm:sp_cancel_customer_reserve 712 712 0 0.00 0 948 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 716 716 0 0.00 0 986 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 419 419 0 0.00 0 372 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 374 374 0 0.00 0 2002 41 0 0
t$perf-norm:sp_cancel_supplier_order 378 378 0 0.00 0 104898 7176 0 0
t$perf-norm:sp_cancel_write_off 1061 1061 0 0.00 0 3433 22 0 0
t$perf-norm:sp_client_order 20871 20871 0 0.00 0 7542 37 0 0
t$perf-norm:sp_customer_reserve 1039 1039 0 0.00 0 3498 28 0 0
t$perf-norm:sp_pay_from_customer 7068 7068 0 0.00 0 8132 22 0 0
t$perf-norm:sp_pay_to_supplier 5915 5915 0 0.00 0 5086 23 0 0
t$perf-norm:sp_reserve_write_off 10139 10139 0 0.00 0 3741 22 0 0
t$perf-norm:sp_supplier_invoice 5594 5594 0 0.00 0 6062 35 0 0
t$perf-norm:sp_supplier_order 5526 5526 0 0.00 0 4438 34 0 0
t$perf-norm:srv_aggregate_perf_data 113 113 0 0.00 0 2241 40 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 5147 76 0 0
t$perf-norm:srv_make_invnt_saldo 487 487 0 0.00 0 853 11 0 0
t$perf-norm:srv_make_money_saldo 935 935 0 0.00 0 210 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 9 185 112 0 0
v_add_invoice_to_stock 10282 10064 218 2.12 0 6573 30 0 218
v_all_customers 37758 37758 0 0.00 0 121 4 0 0
v_all_suppliers 30646 30646 0 0.00 0 107 4 0 0
v_all_wares 41734 41734 0 0.00 0 194 4 0 0
v_cancel_adding_invoice 800 800 0 0.00 0 4162 23 0 0
v_cancel_client_order 3534 3534 0 0.00 0 4384 10 0 0
v_cancel_customer_prepayment 1432 1432 0 0.00 0 11129 18 0 0
v_cancel_customer_reserve 1424 1424 0 0.00 1 2277 14 0 0
v_cancel_payment_to_supplier 838 838 0 0.00 0 1361 15 0 0
v_cancel_supplier_invoice 772 748 24 3.10 0 5776 35 0 24
v_cancel_supplier_order 756 756 0 0.00 0 2075 15 0 0
v_cancel_write_off 2122 2122 0 0.00 0 12097 32 0 0
v_max_id_clo_ord 5522 5522 0 0.00 0 241 5 0 0
v_max_id_clo_res 1039 1039 0 0.00 0 2139 15 0 0
v_max_id_ord_sup 5584 5584 0 0.00 0 7505 8 0 0
v_max_non_paid_invoice 1696 1696 0 0.00 0 94 9 0 0
v_max_non_paid_realizn 3859 3859 0 0.00 0 414 7 0 0
v_min_id_clo_ord 5522 5522 0 0.00 0 4484 9 0 0
v_min_id_clo_res 1039 1039 0 0.00 0 8285 23 0 0
v_min_id_ord_sup 5584 5584 0 0.00 0 5452 10 0 0
v_min_non_paid_invoice 5914 5914 0 0.00 0 6693 14 0 0
v_min_non_paid_realizn 7068 7068 0 0.00 0 4996 17 0 0
v_our_firm 10392 10392 0 0.00 0 69 5 0 0
v_reserve_write_off 20270 20270 0 0.00 0 6577 13 0 0
x_lock_depdocs_on_canc_invoice 9808 9808 0 0.00 0 22302 122 0 0
x_lock_depdocs_on_canc_sup_ord 378 378 0 0.00 0 23095 397 0 0
x_make_qstorno_1000_1200 5526 5526 0 0.00 2 46988 1235 0 0
x_make_qstorno_1200_2000 5594 5594 0 0.00 3 140797 3361 0 0
x_make_qstorno_2100_3300 469931 469931 0 0.00 0 42597 232 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-02-2023 13:39:00 to 04-02-2023 13:39:04.

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
11:07:31 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
11:08:31 25416 8192000000 9711431024 10008076288 788981088 8.785 100 86 170 0 816999488 7327856 20690544
11:10:03 22753 8192000000 9754647152 10061639680 829660736 9.196 100 87 174 0 862445104 11764928 21019440
11:11:39 7883 8192000000 9782496496 10100396032 853424752 9.434 100 84 165 0 893029984 20320976 19284256
11:12:51 9867 8192000000 9838600704 10171256832 899615024 9.894 100 79 157 0 954436480 37852064 16969392
11:14:03 5123 8192000000 9910457616 10252804096 944043536 10.333 100 92 177 1 1018509344 55902144 18563664
11:15:13 1566 8192000000 9960632464 10309255168 978274528 10.667 100 90 166 0 1073119520 78061584 16783408
11:16:18 1517 8192000000 9971916656 10330370048 1002266448 10.900 100 93 180 1 1095910688 76186512 17457728
11:17:22 1393 8192000000 10002318944 10364379136 1030612768 11.174 100 92 176 2 1120955840 72444688 17898384
11:18:25 1239 8192000000 10011087152 10382098432 1041645120 11.280 100 97 187 2 1138182000 77082992 19453888
11:19:29 1208 8192000000 10007273616 10380206080 1047567168 11.337 100 92 181 2 1129089088 63654608 17867312
11:20:33 768 8192000000 10027312416 10398892032 1053219728 11.392 100 94 180 0 1145245232 74247664 17777840
11:21:35 955 8192000000 10053150336 10429374464 1060952336 11.466 100 95 191 1 1168440720 87686032 19802352
11:22:38 1230 8192000000 10050626464 10431926272 1068359184 11.536 100 95 189 3 1171002320 83189392 19453744
11:23:40 917 8192000000 10034085760 10418298880 1072353648 11.575 100 95 181 2 1157590112 66201344 19035120
11:24:43 4168 8192000000 10023691168 10402721792 1069269376 11.545 100 92 175 5 1143218512 57705904 16243232
11:25:50 1296 8192000000 10048497904 10434662400 1078181984 11.630 100 96 191 2 1174583296 77565248 18836064
11:26:52 1149 8192000000 10058148096 10446000128 1086315952 11.708 100 94 182 0 1179500384 74868304 18316128
11:27:54 1555 8192000000 10053820784 10448470016 1089545568 11.738 100 91 171 2 1182546832 73482496 19518768
11:28:57 1191 8192000000 10076186848 10465898496 1082909392 11.675 100 97 188 1 1201341216 99558944 18872880
11:29:59 1899 8192000000 10029915552 10421825536 1082744288 11.674 100 68 124 2 1153807968 58196496 12867184
11:31:02 6049 8192000000 10034024784 10419888128 1069542096 11.548 100 62 120 0 1142366592 61134608 11689888
11:32:10 3594 8192000000 10008217760 10393403392 1058052192 11.438 100 60 111 2 1133543680 64170288 11321200
11:33:15 1082 8192000000 10021112272 10400681984 1057828736 11.436 100 97 190 0 1148106720 69882576 20395408
11:34:17 1218 8192000000 9986213600 10355658752 1030666896 11.175 100 98 191 1 1108398944 58339632 19392416
11:35:19 1107 8192000000 10007587264 10379616256 1036824080 11.234 100 92 181 2 1130345248 74519264 19001904
11:36:21 4803 8192000000 9936573824 10326806528 1028921312 11.158 99 44 90 1 1073562256 35196512 9444432
11:37:28 3430 8192000000 9974828208 10356748288 1049965424 11.360 100 69 117 2 1093711712 32127744 11618544
11:38:45 13937 8192000000 10031990016 10407895040 1067821968 11.531 100 74 144 0 1159061168 77026336 14212864
11:40:14 7557 8192000000 10035640096 10416427008 1076577408 11.615 100 81 151 2 1159102944 66983824 15541712
11:41:25 2691 8192000000 10064566672 10444353536 1092445344 11.766 100 83 162 0 1182027392 73106768 16475280
11:42:34 958 8192000000 10121069328 10505932800 1110412352 11.936 100 90 171 0 1238001984 108738512 18851120
11:43:37 8775 8192000000 10140654592 10530701312 1113777184 11.968 100 75 146 1 1254869456 125622320 15469952
11:44:51 1311 8192000000 10159922560 10551963648 1125973824 12.083 100 85 168 1 1277880976 134288784 17618368
11:45:56 2216 8192000000 10173916928 10582904832 1136184960 12.180 100 76 143 0 1295203936 142960944 16058032
11:47:01 1801 8192000000 10171703376 10576297984 1142787088 12.242 100 69 132 0 1285306032 129412384 13106560
11:48:07 4590 8192000000 10208116368 10608451584 1153004128 12.338 100 75 144 1 1316899424 148597600 15297696
11:49:14 972 8192000000 10235751408 10632245248 1157893424 12.384 100 99 193 0 1343541296 164506944 21140928
11:50:17 3690 8192000000 10261072800 10669400064 1164094016 12.442 100 80 158 1 1376555168 195438432 17022720
11:51:25 1541 8192000000 10269255392 10673258496 1165759376 12.457 100 96 188 1 1380582096 194768880 20053840
11:52:32 970 8192000000 10302987648 10706128896 1169117104 12.489 100 95 182 1 1413754992 223325200 21312688
11:53:36 3532 8192000000 10327523072 10749632512 1173392944 12.529 100 90 175 2 1434830256 239417744 22019568
11:54:45 2310 8192000000 10353293568 10771922944 1190425744 12.687 100 88 176 1 1463398896 250676128 22297024
11:55:53 1091 8192000000 10348241712 10798608384 1190443696 12.687 100 96 190 1 1458227744 244875104 22908944
11:56:56 1495 8192000000 10322769312 10781229056 1186746704 12.653 100 95 181 1 1432035536 223944288 21344544
11:58:01 3121 8192000000 10364952480 10793832448 1182932816 12.618 100 82 162 0 1471169712 269713744 18523152
11:59:09 926 8192000000 10377998480 10792505344 1191702640 12.699 100 89 174 1 1481281232 269464048 20114544
12:00:14 1067 8192000000 10374673792 10790150144 1181311216 12.602 100 94 184 1 1483893248 281417872 21164160
12:01:19 1192 8192000000 10432670128 10854096896 1190092080 12.684 100 98 191 3 1536141744 323256704 22792960
12:02:23 8859 8192000000 10418255440 10861355008 1172831696 12.523 98 60 118 0 1514207456 327047872 14327888
12:03:54 5686 8192000000 10438336400 10887016448 1171382944 12.510 99 54 106 0 1547386144 362348272 13654928
12:05:08 14919 8192000000 10447899200 10878140416 1162552288 12.427 100 38 74 0 1549172768 376525472 10095008
12:06:35 12468 8192000000 10411635072 10876190720 1138720208 12.203 99 39 78 1 1530151376 380978992 10452176
12:08:03 18201 8192000000 10279408480 10785013760 1115627712 11.986 98 29 55 0 1409979712 287039104 7312896
12:09:25 3649 8192000000 10240044112 10715975680 1130494032 12.126 100 65 120 0 1340875600 197113232 13268336
12:10:30 13021 8192000000 10179004480 10618281984 1134416256 12.163 99 60 114 1 1294969136 147541264 13011616
12:11:47 81469 8192000000 10141508144 10599145472 1081038256 11.657 94 22 44 1 1247839424 161147600 5653568
12:14:43 9528 8192000000 10141860560 10578329600 1131472528 12.135 100 78 146 1 1241081968 92915952 16693488
12:16:43 30229 8192000000 10111865344 10575212544 1097564192 11.815 96 36 66 2 1164307584 59653072 7090320
12:18:20 48946 8192000000 10102122576 10577317888 1098135744 11.820 97 11 22 0 1172497056 71830944 2530368
12:20:53 100772 8192000000 10045173552 10495533056 1082057408 11.667 97 21 42 0 1107011296 20871504 4082384
12:23:40 40516 8192000000 10099023104 10546507776 1123408192 12.059 99 40 76 0 1202778544 71441232 7929120
12:25:32 188591 8192000000 10076379600 10620866560 1089782144 11.741 96 31 63 0 1165453984 68504192 7167648
12:29:52 73279 8192000000 10071169936 10518540288 1114447632 11.975 99 19 37 0 1165592384 47397232 3747520
12:32:14 4558 8192000000 10088552304 10523791360 1129288608 12.115 100 36 72 0 1199004336 61935376 7780352
12:33:20 31005 8192000000 10084221056 10526228480 1137733968 12.194 100 54 106 0 1199483008 49446384 12302656
12:35:04 62970 8192000000 10101801152 10606477312 1136475440 12.182 99 13 26 0 1217215536 77464096 3276000
12:37:09 82498 8192000000 10088761264 10577518592 1134047344 12.159 99 17 34 0 1227333232 88922928 4362960
12:39:57 34095 8192000000 10059635520 10518564864 1148073888 12.291 100 16 32 0 1166838224 15139248 3625088
12:41:32 41094 8192000000 10083555152 10551345152 1129368448 12.115 98 16 32 0 1177203408 44882256 2952704
12:43:13 26561 8192000000 10111972976 10551566336 1141648752 12.231 99 41 82 0 1212268576 61427584 9192240
12:44:40 56425 8192000000 10091010064 10579959808 1113949840 11.970 97 16 32 0 1161094096 44242784 2901472
12:46:37 86791 8192000000 10095262992 10527891456 1095898192 11.799 97 21 38 0 1144847936 44734640 4215104
12:49:16 67375 8192000000 10070997200 10538668032 1114730448 11.977 98 26 52 0 1185118720 65253376 5134896
12:51:24 51388 8192000000 10100880736 10543042560 1126823616 12.091 99 42 80 1 1193942464 58947584 8171264
12:53:16 68660 8192000000 10118395648 10604142592 1129056912 12.112 99 29 56 0 1258504560 123585088 5862560
12:55:41 52796 8192000000 10062928720 10523746304 1125139312 12.076 99 38 71 2 1162364944 30184816 7040816
12:57:37 44750 8192000000 10086466384 10538676224 1123234048 12.058 100 36 71 1 1204909072 74335504 7339520
13:00:19 71397 8192000000 10075786912 10523668480 1088978368 11.733 97 23 46 0 1162514032 68308656 5227008
13:02:32 60402 8192000000 10054189072 10523738112 1114423680 11.974 99 16 32 0 1150935184 33228608 3282896
13:05:11 101199 8192000000 10061405984 10543706112 1111198832 11.944 99 12 23 0 1160652240 47080560 2372848
13:08:08 52001 8192000000 10067234976 10500980736 1098629584 11.825 97 20 40 0 1166033520 62946688 4457248
13:10:00 48888 8192000000 10061267936 10512375808 1104243872 11.878 98 28 56 0 1166683248 56111520 6327856
13:11:52 31530 8192000000 10091208400 10515939328 1098239872 11.821 97 46 92 0 1163625488 56106912 9278704
13:13:26 41912 8192000000 10132507632 10562744320 1133564912 12.155 100 35 70 0 1206159984 65591104 7003968
13:15:12 2776 8192000000 10088227904 10506518528 1128488608 12.107 100 69 128 3 1192100496 49644064 13967824
13:16:15 11131 8192000000 10094152912 10515476480 1118802288 12.016 100 45 90 0 1199591264 71476848 9312128
13:17:27 28121 8192000000 10094959680 10517803008 1109440288 11.927 99 40 80 0 1195087792 77579536 8067968
13:19:00 27348 8192000000 10126405568 10569011200 1125976384 12.083 100 47 94 0 1225157664 89926768 9254512
13:20:28 117508 8192000000 10049935840 10508697600 1123601392 12.061 99 37 72 0 1201596672 70153488 7841792
13:23:27 81297 8192000000 10105232832 10598277120 1131959072 12.140 99 7 14 0 1200979664 67576720 1443872
13:26:51 78935 8192000000 10054241328 10535591936 1106721840 11.901 97 27 54 0 1180300000 67721792 5856368
13:29:11 36161 8192000000 10066884656 10505080832 1079110368 11.639 95 29 58 1 1123209296 38087888 6011040
13:30:53 30456 8192000000 10136143952 10576252928 1127162416 12.095 99 34 68 0 1238094768 103720112 7212240
13:32:26 97248 8192000000 10150010688 10599751680 1123255264 12.058 98 24 48 0 1244678640 115151376 6272000
13:35:04 15889 8192000000 10168991264 10638381056 1128380048 12.106 100 37 74 0 1284975776 147315392 9280336
13:36:22 57471 8192000000 10208839520 10700402688 1124941120 12.074 99 19 38 0 1298345408 169292672 4111616

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 04-02-2023 13:39:06 to 04-02-2023 13:39:22.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 109 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 04-02-2023 13:39:23 to 04-02-2023 13:39:25.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          215234
MON$OLDEST_ACTIVE               220927
MON$OLDEST_SNAPSHOT             220927
MON$NEXT_TRANSACTION            220927
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-30 07:30:08.6030 GMT
MON$PAGES                       2055584
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             778
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2895 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2895 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2895 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2895 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.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 ---
:100: 
:101: mscs-test-161.budzdorov.ru    Sat Feb  4 13:37:51 2023
:102:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:103: 
:104: 
:105: mscs-test-161.budzdorov.ru    Sat Feb  4 13:37:52 2023
:106:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:107: 
--- end of diff output ---

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

04.02.23 13:39:27. There are 3 logs in /var/tmp/logs-oltp40 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
2023-02-04 13:39:29 No severe PSQL-related problems occured
04.02.23 13:39:31. End of report.