Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 01.10.2022 10: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 15363824 kB
MemAvailable 30597044 kB
Buffers 0 kB
Cached 16374856 kB
SwapCached 2044 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 2022-10-01 10:37:22
DTS_END                         2022-10-01 10:37:22.2850
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(68:17)
IP                              ::1/49348
TRN_ID                          212609
ATT_ID                          621
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: 8888.08 21183 1066569
customer order: creation 166.81 512 20017
customer order: refuse 13.85 412 1662
order to supplier: creation 43.66 1390 5239
order to supplier: removal 3.34 168251 401
invoice (draft): creation 43.51 3419 5221
invoice (draft): removal 49.63 949 5956
invoice accept: apply 40.69 24729 4883
invoice accept: cancel 45.03 1123 5404
customer reserve: creation 3775.75 233 453090
customer reserve: removal 4420.12 100 530414
realization accept: apply 80.77 304 9692
realization accept: cancel 79.32 177 9518
payment from customer: creation 57.11 192 6853
payment from customer: removal 5.13 130 615
payment to supplier: creation 47.47 209 5696
payment to supplier: removal 3.29 111 395
service: total inventory turnovers 4.08 3719 489
service: total monetary turnovers 7.57 337 908
service: aggregate perf. data 0.93 16220 112
service: refresh index statistics 0.03 201144 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 01-10-2022 10:39:59 to 01-10-2022 10:40:02.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9413
TEST_TIME 2 6900
TEST_TIME 3 6522
TEST_TIME 4 6965
TEST_TIME 5 7136
TEST_TIME 6 6946
TEST_TIME 7 7022
TEST_TIME 8 6888
TEST_TIME 9 6606
TEST_TIME 10 6631
TEST_TIME 11 6770
TEST_TIME 12 6749
TEST_TIME 13 7005
TEST_TIME 14 7204
TEST_TIME 15 7343
TEST_TIME 16 7454
TEST_TIME 17 7528
TEST_TIME 18 7769
TEST_TIME 19 8019
TEST_TIME 20 8057
TEST_TIME 21 8042
TEST_TIME 22 8234
TEST_TIME 23 8184
TEST_TIME 24 8522
TEST_TIME 25 8795
TEST_TIME 26 9069
TEST_TIME 27 9224
TEST_TIME 28 9121
TEST_TIME 29 9758
TEST_TIME 30 10287
TEST_TIME 31 10914
TEST_TIME 32 11492
TEST_TIME 33 11256
TEST_TIME 34 10967
TEST_TIME 35 10913
TEST_TIME 36 10849
TEST_TIME 37 10869
TEST_TIME 38 10693
TEST_TIME 39 10545
TEST_TIME 40 10548
TEST_TIME 41 10522
TEST_TIME 42 10349
TEST_TIME 43 10301
TEST_TIME 44 10180
TEST_TIME 45 10053
TEST_TIME 46 9923
TEST_TIME 47 9775
TEST_TIME 48 9846
TEST_TIME 49 9777
TEST_TIME 50 9640
TEST_TIME 51 9561
TEST_TIME 52 9443
TEST_TIME 53 9457
TEST_TIME 54 9522
TEST_TIME 55 9401
TEST_TIME 56 9348
TEST_TIME 57 9265
TEST_TIME 58 9258
TEST_TIME 59 9209
TEST_TIME 60 9195
TEST_TIME 61 9160
TEST_TIME 62 9192
TEST_TIME 63 9187
TEST_TIME 64 9252
TEST_TIME 65 9319
TEST_TIME 66 9303
TEST_TIME 67 9324
TEST_TIME 68 9265
TEST_TIME 69 9355
TEST_TIME 70 9337
TEST_TIME 71 9459
TEST_TIME 72 9416
TEST_TIME 73 9432
TEST_TIME 74 9307
TEST_TIME 75 9243
TEST_TIME 76 9230
TEST_TIME 77 9200
TEST_TIME 78 9161
TEST_TIME 79 9116
TEST_TIME 80 9065
TEST_TIME 81 9052
TEST_TIME 82 9038
TEST_TIME 83 9029
TEST_TIME 84 8998
TEST_TIME 85 8998
TEST_TIME 86 8957
TEST_TIME 87 9002
TEST_TIME 88 9025
TEST_TIME 89 9056
TEST_TIME 90 9008
TEST_TIME 91 9016
TEST_TIME 92 9051
TEST_TIME 93 9148
TEST_TIME 94 9129
TEST_TIME 95 9119
TEST_TIME 96 9041
TEST_TIME 97 9130
TEST_TIME 98 9137
TEST_TIME 99 9101
TEST_TIME 100 9075
TEST_TIME 101 9046
TEST_TIME 102 9021
TEST_TIME 103 9009
TEST_TIME 104 9034
TEST_TIME 105 9012
TEST_TIME 106 8970
TEST_TIME 107 9019
TEST_TIME 108 9101
TEST_TIME 109 9077
TEST_TIME 110 9126
TEST_TIME 111 9133
TEST_TIME 112 9111
TEST_TIME 113 9086
TEST_TIME 114 9046
TEST_TIME 115 9013
TEST_TIME 116 9034
TEST_TIME 117 9029
TEST_TIME 118 8990
TEST_TIME 119 8958
TEST_TIME 120 8920

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 01-10-2022 10:40:03 to 01-10-2022 10:40:06.

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 1484027 1484027 0 0.00 0 17177 13 0 0
doc_list_biud 1484010 1484010 0 0.00 0 49750 41 0 0
sp_add_invoice_to_stock 4978 4883 95 1.90 63 187019 24729 0 95
sp_cancel_adding_invoice 5404 5404 0 0.00 6 90221 1124 0 0
sp_cancel_client_order 1662 1662 0 0.00 7 13101 413 0 0
sp_cancel_customer_reserve 530414 530414 0 0.00 1 49752 100 0 0
sp_cancel_pay_from_customer 615 615 0 0.00 3 4763 131 0 0
sp_cancel_pay_to_supplier 395 395 0 0.00 4 2206 111 0 0
sp_cancel_supplier_invoice 5968 5956 12 0.20 2 19458 949 0 12
sp_cancel_supplier_order 401 401 0 0.00 15 1542679 168252 0 0
sp_cancel_write_off 9518 9518 0 0.00 2 11570 178 0 0
sp_client_order 20017 20017 0 0.00 6 25863 512 0 0
sp_customer_reserve 453090 453090 0 0.00 1 53058 234 0 0
sp_fill_shopping_cart 30471 30471 0 0.00 1 23637 87 0 0
sp_fill_shopping_cart_clo_res 453048 453048 0 0.00 0 19030 22 0 0
sp_get_clo_for_invoice 4830 4830 0 0.00 0 34562 422 0 0
sp_kill_cost_storno 15932 15932 0 0.00 0 12007 80 0 0
sp_kill_qstorno_ret_qs2qd 1076703 1076703 0 0.00 0 49747 43 0 0
sp_kill_qty_storno 567876 567876 0 0.00 0 49749 99 0 0
sp_lock_selected_doc 19888 19888 0 0.00 0 7579 7 0 0
sp_make_cost_storno 27069 27069 0 0.00 0 14989 88 0 0
sp_multiply_rows_for_pdistr 27068 27068 0 0.00 0 11729 41 0 0
sp_multiply_rows_for_qdistr 20017 20017 0 0.00 0 23353 197 0 0
sp_pay_from_customer 6853 6853 0 0.00 5 24674 193 0 0
sp_pay_to_supplier 5696 5696 0 0.00 6 15951 209 0 0
sp_payment_common 12547 12547 0 0.00 2 24647 49 0 0
sp_qd_handle_on_cancel_clo 1662 1662 0 0.00 0 13095 344 0 0
sp_qd_handle_on_invoice_upd_sts 10233 10233 0 0.00 0 21874 505 0 0
sp_qd_handle_on_reserve_upd_sts 9692 9692 0 0.00 0 10939 66 0 0
sp_reserve_write_off 9692 9692 0 0.00 8 18013 305 0 0
sp_supplier_invoice 5221 5221 0 0.00 10 95983 3419 0 0
sp_supplier_order 5239 5239 0 0.00 7 76312 1390 0 0
srv_aggregate_perf_data 112 112 0 0.00 2604 176001 16220 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 836 130444 39369 0 0
srv_find_qd_qs_mism 1019185 1019185 0 0.00 0 13149 9 0 0
srv_make_invnt_saldo 489 489 0 0.00 47 98939 3720 0 0
srv_make_money_saldo 908 908 0 0.00 1 13602 338 0 0
srv_recalc_idx_stat 4 4 0 0.00 81038 453147 201145 0 0
t$perf-abend:sp_add_invoice_to_stock 95 95 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 4893 4893 0 0.00 1 9116 895 0 0
t$perf-norm:sp_cancel_adding_invoice 401 401 0 0.00 1 6197 423 0 0
t$perf-norm:sp_cancel_client_order 1662 1662 0 0.00 0 3746 29 0 0
t$perf-norm:sp_cancel_customer_reserve 639 639 0 0.00 0 832 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 615 615 0 0.00 0 4358 24 0 0
t$perf-norm:sp_cancel_pay_to_supplier 395 395 0 0.00 0 755 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 382 382 0 0.00 0 2505 37 0 0
t$perf-norm:sp_cancel_supplier_order 401 401 0 0.00 0 88833 6431 0 0
t$perf-norm:sp_cancel_write_off 875 875 0 0.00 0 1317 19 0 0
t$perf-norm:sp_client_order 20017 20017 0 0.00 0 12941 36 0 0
t$perf-norm:sp_customer_reserve 984 984 0 0.00 0 4951 20 0 0
t$perf-norm:sp_pay_from_customer 6853 6853 0 0.00 0 10630 20 0 0
t$perf-norm:sp_pay_to_supplier 5696 5696 0 0.00 0 10626 23 0 0
t$perf-norm:sp_reserve_write_off 9693 9693 0 0.00 0 13236 25 0 0
t$perf-norm:sp_supplier_invoice 5221 5221 0 0.00 0 9249 32 0 0
t$perf-norm:sp_supplier_order 5239 5239 0 0.00 0 4099 30 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 222 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 3697 76 0 0
t$perf-norm:srv_make_invnt_saldo 489 489 0 0.00 0 3357 23 0 0
t$perf-norm:srv_make_money_saldo 908 908 0 0.00 0 1861 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 127 39 0 0
v_add_invoice_to_stock 9842 9652 190 1.93 0 10507 29 0 190
v_all_customers 36786 36786 0 0.00 0 86 4 0 0
v_all_suppliers 29578 29578 0 0.00 0 70 4 0 0
v_all_wares 40030 40030 0 0.00 0 64 4 0 0
v_cancel_adding_invoice 802 802 0 0.00 0 1144 16 0 0
v_cancel_client_order 3324 3324 0 0.00 0 4573 12 0 0
v_cancel_customer_prepayment 1230 1230 0 0.00 0 4573 21 0 0
v_cancel_customer_reserve 1278 1278 0 0.00 0 7857 17 0 0
v_cancel_payment_to_supplier 790 790 0 0.00 0 564 13 0 0
v_cancel_supplier_invoice 788 764 24 3.04 0 2568 26 0 24
v_cancel_supplier_order 802 802 0 0.00 0 3103 17 0 0
v_cancel_write_off 1750 1750 0 0.00 0 6654 32 0 0
v_max_id_clo_ord 5236 5236 0 0.00 0 11014 8 0 0
v_max_id_clo_res 984 984 0 0.00 0 1021 8 0 0
v_max_id_ord_sup 5217 5217 0 0.00 0 6749 9 0 0
v_max_non_paid_invoice 1358 1358 0 0.00 0 514 9 0 0
v_max_non_paid_realizn 3500 3500 0 0.00 0 2016 8 0 0
v_min_id_clo_ord 5236 5236 0 0.00 0 4044 13 0 0
v_min_id_clo_res 984 984 0 0.00 0 6296 18 0 0
v_min_id_ord_sup 5217 5217 0 0.00 0 7907 13 0 0
v_min_non_paid_invoice 5695 5695 0 0.00 0 5991 17 0 0
v_min_non_paid_realizn 6851 6851 0 0.00 0 24628 23 0 0
v_our_firm 9944 9944 0 0.00 0 68 5 0 0
v_reserve_write_off 19382 19382 0 0.00 0 10775 14 0 0
x_lock_depdocs_on_canc_invoice 10978 10978 0 0.00 0 15407 123 0 0
x_lock_depdocs_on_canc_sup_ord 401 401 0 0.00 0 12032 387 0 0
x_make_qstorno_1000_1200 5239 5239 0 0.00 2 76279 1225 0 0
x_make_qstorno_1200_2000 5221 5221 0 0.00 4 92370 3170 0 0
x_make_qstorno_2100_3300 453089 453089 0 0.00 0 53052 200 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 01-10-2022 10:40:07 to 01-10-2022 10:40:12.

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
08:07:26 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
08:08:27 27561 8192000000 9735829120 10036027392 806646672 8.964 100 92 184 0 836349936 7385024 22318240
08:10:00 13981 8192000000 9771455296 10079502336 843965712 9.340 100 88 176 0 877149296 12402256 20781328
08:11:28 15290 8192000000 9807665216 10126688256 873433152 9.634 100 87 172 0 915318896 22100032 19785712
08:12:47 6228 8192000000 9861118624 10193010688 923653168 10.132 100 87 172 0 975738176 33531904 18553104
08:13:56 5948 8192000000 9912771440 10252550144 950140464 10.392 100 82 161 1 1020083424 52672272 17270688
08:15:10 1995 8192000000 9971439936 10323951616 996644560 10.846 100 88 173 0 1082652944 68413504 17594880
08:16:14 1409 8192000000 10013589120 10374373376 1017257584 11.046 100 97 190 1 1130251408 93181488 19812336
08:17:18 1478 8192000000 10001514448 10366722048 1035966992 11.226 100 92 170 1 1116476096 63274784 17234320
08:18:21 1014 8192000000 10020234992 10399109120 1052382768 11.384 100 92 187 0 1141869872 70704432 18782672
08:19:23 853 8192000000 10019613216 10392506368 1063572224 11.491 100 99 182 1 1139998416 57599136 18827056
08:20:25 3174 8192000000 10033237328 10411040768 1069843776 11.551 100 86 157 1 1152651024 66748816 16058432
08:21:29 973 8192000000 10039743680 10420293632 1073193408 11.583 100 95 184 0 1160419136 68458816 18766912
08:22:31 967 8192000000 10020836432 10403516416 1081860800 11.665 100 90 172 0 1145631296 46505232 17265264
08:23:34 2278 8192000000 10052599856 10441805824 1081107312 11.658 100 80 150 1 1177331040 81208864 15014864
08:24:36 5993 8192000000 10038525472 10429804544 1086679168 11.711 100 68 132 2 1159564528 58979040 13906320
08:25:48 1886 8192000000 10068227344 10460561408 1092980912 11.771 100 86 161 1 1186446528 77019824 16445792
08:26:50 935 8192000000 10050471248 10440925184 1092072400 11.762 100 91 173 1 1169859024 59567616 18219008
08:27:52 1080 8192000000 10078097920 10464210944 1092066880 11.762 100 96 186 1 1193592032 83389136 18136016
08:28:54 1252 8192000000 10042810128 10434547712 1097732272 11.816 100 81 149 5 1164123456 50795648 15595536
08:29:56 6062 8192000000 10025293648 10415169536 1086045392 11.705 100 70 133 1 1151228192 51462720 13720080
08:31:02 1126 8192000000 10051725824 10431340544 1059757824 11.454 100 95 182 0 1168334528 89762128 18814576
08:32:04 959 8192000000 10033163392 10413875200 1073708544 11.587 100 91 171 1 1160121952 68987136 17426272
08:33:06 1315 8192000000 10032477152 10411433984 1057840336 11.436 100 94 186 3 1151892496 76174896 17877264
08:34:08 12033 8192000000 9973161632 10351632384 997225712 10.852 96 61 115 1 1064202960 55622496 11354752
08:35:21 989 8192000000 10005202864 10372587520 1025908016 11.129 100 93 182 0 1125110928 80480576 18722336
08:36:24 2854 8192000000 9977727968 10362556416 1039098656 11.256 100 69 139 0 1103052192 49618864 14334672
08:37:27 5776 8192000000 9996236512 10377269248 1054507952 11.404 100 68 116 2 1117446160 51880016 11058192
08:38:41 13223 8192000000 10021676576 10405711872 1061851792 11.474 100 71 134 0 1143124384 67850208 13422384
08:39:59 5993 8192000000 10049289024 10437361664 1079165552 11.640 100 71 142 0 1169343024 74600624 15576848
08:41:10 1136 8192000000 10086149936 10470256640 1101072336 11.848 100 97 193 1 1203985056 83192752 19719968
08:42:15 1607 8192000000 10120252832 10507575296 1113774288 11.968 100 90 168 2 1233634832 101047328 18813216
08:43:20 4509 8192000000 10134334096 10530766848 1121813472 12.044 100 72 137 0 1245100304 108967088 14319744
08:44:26 1412 8192000000 10189006128 10591395840 1124628288 12.071 100 91 175 1 1308365856 164549424 19188144
08:45:31 9725 8192000000 10176297120 10588626944 1138476496 12.201 100 70 138 0 1291220048 137401840 15341712
08:46:46 2420 8192000000 10224235904 10635247616 1148629392 12.297 100 80 154 0 1338257232 172296928 17330912
08:47:52 1602 8192000000 10232964032 10638245888 1157684048 12.382 100 81 158 0 1351324560 176205536 17434976
08:48:57 1801 8192000000 10301353328 10701369344 1179290736 12.584 100 83 161 0 1406722560 209272752 18159072
08:50:02 1131 8192000000 10348218768 10768613376 1198512432 12.763 100 86 166 1 1457833248 239633904 19686912
08:51:05 1564 8192000000 10375325760 10790752256 1210259424 12.872 100 93 179 2 1482619648 251219120 21141104
08:52:10 1862 8192000000 10439745216 10851610624 1214174912 12.908 100 93 182 0 1548520640 311539632 22806096
08:53:14 1847 8192000000 10460757056 10887389184 1216878592 12.933 100 96 188 1 1569343776 329828720 22636464
08:54:20 1611 8192000000 10489123488 10921197568 1216713360 12.931 100 94 179 1 1596400944 358795312 20892272
08:55:23 1768 8192000000 10506890720 10959175680 1221763408 12.978 100 89 170 2 1611681376 368053472 21864496
08:56:28 990 8192000000 10529865600 10950356992 1215707872 12.922 100 94 180 0 1634962832 397621264 21633696
08:57:31 1127 8192000000 10526686864 10956115968 1205398192 12.826 100 96 186 0 1632715408 405392288 21924928
08:58:36 1572 8192000000 10540045328 10960957440 1197931248 12.757 99 93 181 1 1650860384 431465968 21463168
08:59:39 828 8192000000 10563683232 10999816192 1199223536 12.769 100 90 170 1 1672724624 451788240 21712848
09:00:44 1083 8192000000 10577972208 11018747904 1199267664 12.770 100 78 156 1 1681429008 462108560 20052784
09:01:57 832 8192000000 10574852784 11014012928 1197084896 12.749 100 83 161 1 1676705376 459637424 19983056
09:03:06 9869 8192000000 10595716656 11047067648 1198790864 12.765 100 49 98 1 1694957424 483557872 12608688
09:04:44 50399 8192000000 10559129088 11057422336 1190908832 12.692 100 34 68 0 1679294656 478991856 9393968
09:07:37 24374 8192000000 10327092288 10832363520 1169760432 12.495 99 32 62 0 1464323472 287588432 6974608
09:09:07 75547 8192000000 10104552640 10548961280 1111848976 11.950 96 9 18 0 1167338176 53716368 1772832
09:11:23 55958 8192000000 10139069312 10600075264 1155437072 12.361 100 41 81 0 1257725728 92995632 9293024
09:13:30 74154 8192000000 10124322928 10632880128 1135562416 12.174 99 11 21 0 1225739648 88186192 1991040
09:16:47 113468 8192000000 10106400272 10588499968 1114384256 11.974 97 24 43 1 1181734880 63280960 4069664
09:19:51 40945 8192000000 10110411824 10567475200 1132889536 12.149 99 55 108 3 1190334048 46624864 10819648
09:22:16 82989 8192000000 10119695536 10576572416 1130713696 12.128 99 23 46 1 1223571200 86894256 5963248
09:24:39 67270 8192000000 10075449360 10518425600 1124371072 12.068 99 45 87 1 1203177504 69096960 9709472
09:26:57 46339 8192000000 10068870528 10509156352 1124130448 12.066 99 34 66 0 1165741040 34253072 7357520
09:28:45 13985 8192000000 10109169776 10552713216 1144267424 12.256 100 37 74 0 1215690064 63830336 7592304
09:30:09 44193 8192000000 10136497824 10613542912 1133850992 12.158 99 30 61 0 1228246896 87712576 6683328
09:32:03 27122 8192000000 10083275296 10579968000 1118499824 12.013 98 10 20 0 1179358480 58804592 2054064
09:33:36 55038 8192000000 10077781040 10571960320 1129089680 12.113 99 11 22 0 1185620640 53945232 2585728
09:35:31 57634 8192000000 10055160576 10523619328 1109131792 11.924 97 4 8 0 1142601744 32798608 671344
09:37:29 62206 8192000000 10025213024 10471067648 1036501408 11.231 92 21 40 1 1071819760 30758384 4559968
09:39:33 51734 8192000000 10091988640 10533216256 1100607216 11.843 97 20 39 0 1155228816 51066848 3554752
09:41:37 91695 8192000000 10072937248 10529218560 1133458288 12.154 100 13 26 0 1186939072 50890896 2589888
09:44:12 118788 8192000000 10113455024 10599899136 1100366256 11.841 96 13 26 0 1167180944 63854880 2959808
09:47:20 78659 8192000000 10136061008 10651164672 1125263248 12.077 98 27 50 0 1195136992 64746928 5126816
09:49:40 47988 8192000000 10075103744 10550726656 1127989488 12.102 99 20 39 0 1198115168 65938240 4187440
09:51:28 55367 8192000000 10063745152 10504478720 1124683072 12.071 98 42 82 2 1177594416 43458880 9452464
09:53:32 38494 8192000000 10062682592 10498981888 1112007616 11.951 97 21 41 0 1149261696 32980432 4273648
09:55:11 52934 8192000000 10056597808 10516025344 1102564304 11.862 97 7 15 0 1142754032 38887824 1301904
09:57:11 47502 8192000000 10061807264 10536570880 1111943168 11.951 98 9 18 0 1163210096 49333024 1933904
09:59:05 68116 8192000000 10084138176 10536800256 1142576304 12.240 100 22 45 0 1198085120 50563408 4945408
10:01:18 25729 8192000000 10134950880 10589315072 1157089920 12.376 100 41 82 0 1230275504 64865920 8319664
10:02:48 25716 8192000000 10123764112 10572394496 1126480624 12.088 97 22 44 0 1203914832 72699664 4734544
10:04:23 31686 8192000000 10133475088 10584408064 1121009952 12.037 98 22 44 0 1211896848 85527584 5359312
10:05:57 60430 8192000000 10135844736 10597875712 1129943984 12.121 99 23 46 0 1209564080 74701184 4918912
10:08:00 46263 8192000000 10174973952 10624524288 1153949936 12.347 100 32 58 2 1267285136 106908784 6426416
10:09:52 72248 8192000000 10159117600 10603110400 1135883168 12.177 98 25 48 0 1236103392 95305216 4915008
10:12:09 130442 8192000000 10133160080 10630930432 1136948560 12.187 98 23 46 0 1259172800 117575936 4648304
10:15:30 87161 8192000000 10076906176 10520920064 1146377536 12.275 100 9 18 1 1183113520 34697904 2038080
10:18:03 25146 8192000000 10068924896 10506661888 1133667552 12.156 99 34 65 0 1177023392 35764048 7591792
10:19:30 32224 8192000000 10133856800 10575843328 1137497600 12.192 99 51 97 1 1229437824 82237792 9702432
10:21:11 61537 8192000000 10121010928 10564284416 1136220848 12.180 99 24 48 0 1206729024 64915936 5592240
10:23:12 28123 8192000000 10164477024 10597265408 1154019504 12.347 100 36 70 0 1251258112 89763024 7475584
10:24:41 91361 8192000000 10061132880 10504077312 1071600672 11.567 95 16 32 0 1133156768 58170928 3385168
10:27:15 73078 8192000000 10070943920 10519273472 1101087696 11.848 97 26 52 0 1162777584 56711728 4978160
10:29:32 117100 8192000000 10048974272 10484871168 1094404368 11.785 97 32 64 0 1167428880 66324464 6700048
10:32:45 52468 8192000000 10086429472 10546925568 1134216688 12.161 100 41 82 0 1199988496 57644912 8126896
10:35:42 58557 8192000000 10071910128 10513207296 1096644080 11.806 96 24 47 0 1141171472 39658144 4869248

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 95 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 01-10-2022 10:40:31 to 01-10-2022 10:40:33.

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          210807
MON$OLDEST_ACTIVE               214307
MON$OLDEST_SNAPSHOT             214307
MON$NEXT_TRANSACTION            214307
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                       2058848
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             766
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2853 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2853 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2853 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2853 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 ---
:11: 
:12: mscs-test-161.budzdorov.ru    Sat Oct  1 10:37:31 2022
:13:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:14: 
:15: 
:16: mscs-test-161.budzdorov.ru    Sat Oct  1 10:37:33 2022
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:18: 
:19: 
:20: mscs-test-161.budzdorov.ru    Sat Oct  1 10:37:54 2022
:21:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:22: 
--- end of diff output ---

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

01.10.22 10:40:34. 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
2022-10-01 10:40:36 No severe PSQL-related problems occured
01.10.22 10:40:38. End of report.