Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.12.2022 10: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 14775240 kB
MemAvailable 30059940 kB
Buffers 0 kB
Cached 16900720 kB
SwapCached 3216 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-12-14 10:37:22
DTS_END                         2022-12-14 10:37:22.9360
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/33948
TRN_ID                          215043
ATT_ID                          592
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: 8838.57 24489 1060628
customer order: creation 165.85 466 19902
customer order: refuse 13.18 396 1582
order to supplier: creation 43.88 1357 5265
order to supplier: removal 3.62 155223 434
invoice (draft): creation 44.47 3264 5336
invoice (draft): removal 49.01 1008 5881
invoice accept: apply 40.66 25686 4879
invoice accept: cancel 44.09 1256 5291
customer reserve: creation 3815.98 241 457917
customer reserve: removal 4334.41 102 520129
realization accept: apply 79.72 283 9567
realization accept: cancel 80.47 163 9657
payment from customer: creation 56.33 177 6759
payment from customer: removal 5.21 126 625
payment to supplier: creation 46.14 190 5537
payment to supplier: removal 3.46 147 415
service: total inventory turnovers 3.89 3900 467
service: total monetary turnovers 7.27 403 872
service: aggregate perf. data 0.91 14503 109
service: refresh index statistics 0.03 280885 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-12-2022 10:38:29 to 14-12-2022 10:38:31.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5376
TEST_TIME 2 4685
TEST_TIME 3 5219
TEST_TIME 4 5249
TEST_TIME 5 5024
TEST_TIME 6 5016
TEST_TIME 7 5498
TEST_TIME 8 6323
TEST_TIME 9 6391
TEST_TIME 10 6592
TEST_TIME 11 6808
TEST_TIME 12 6974
TEST_TIME 13 7106
TEST_TIME 14 7239
TEST_TIME 15 7488
TEST_TIME 16 7470
TEST_TIME 17 7518
TEST_TIME 18 7616
TEST_TIME 19 7615
TEST_TIME 20 7613
TEST_TIME 21 7868
TEST_TIME 22 8188
TEST_TIME 23 8194
TEST_TIME 24 8099
TEST_TIME 25 8286
TEST_TIME 26 8576
TEST_TIME 27 8905
TEST_TIME 28 9082
TEST_TIME 29 9532
TEST_TIME 30 9766
TEST_TIME 31 10037
TEST_TIME 32 10687
TEST_TIME 33 10965
TEST_TIME 34 10658
TEST_TIME 35 10425
TEST_TIME 36 10663
TEST_TIME 37 10629
TEST_TIME 38 10558
TEST_TIME 39 10369
TEST_TIME 40 10254
TEST_TIME 41 10287
TEST_TIME 42 10115
TEST_TIME 43 10017
TEST_TIME 44 10132
TEST_TIME 45 10037
TEST_TIME 46 9993
TEST_TIME 47 10001
TEST_TIME 48 9890
TEST_TIME 49 9981
TEST_TIME 50 9951
TEST_TIME 51 9895
TEST_TIME 52 9780
TEST_TIME 53 9635
TEST_TIME 54 9616
TEST_TIME 55 9518
TEST_TIME 56 9506
TEST_TIME 57 9425
TEST_TIME 58 9432
TEST_TIME 59 9346
TEST_TIME 60 9388
TEST_TIME 61 9456
TEST_TIME 62 9494
TEST_TIME 63 9486
TEST_TIME 64 9494
TEST_TIME 65 9424
TEST_TIME 66 9370
TEST_TIME 67 9262
TEST_TIME 68 9250
TEST_TIME 69 9158
TEST_TIME 70 9104
TEST_TIME 71 9041
TEST_TIME 72 9085
TEST_TIME 73 9058
TEST_TIME 74 9105
TEST_TIME 75 9167
TEST_TIME 76 9144
TEST_TIME 77 9170
TEST_TIME 78 9201
TEST_TIME 79 9228
TEST_TIME 80 9263
TEST_TIME 81 9298
TEST_TIME 82 9245
TEST_TIME 83 9232
TEST_TIME 84 9230
TEST_TIME 85 9144
TEST_TIME 86 9153
TEST_TIME 87 9166
TEST_TIME 88 9134
TEST_TIME 89 9090
TEST_TIME 90 9016
TEST_TIME 91 8981
TEST_TIME 92 8937
TEST_TIME 93 8932
TEST_TIME 94 8907
TEST_TIME 95 8906
TEST_TIME 96 8951
TEST_TIME 97 8908
TEST_TIME 98 8905
TEST_TIME 99 8908
TEST_TIME 100 8953
TEST_TIME 101 9027
TEST_TIME 102 9035
TEST_TIME 103 9051
TEST_TIME 104 9098
TEST_TIME 105 9122
TEST_TIME 106 9190
TEST_TIME 107 9123
TEST_TIME 108 9062
TEST_TIME 109 9061
TEST_TIME 110 9013
TEST_TIME 111 8963
TEST_TIME 112 8941
TEST_TIME 113 8951
TEST_TIME 114 8906
TEST_TIME 115 8877
TEST_TIME 116 8867
TEST_TIME 117 8875
TEST_TIME 118 8851
TEST_TIME 119 8870
TEST_TIME 120 8860

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-12-2022 10:38:33 to 14-12-2022 10:38:36.

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 1482099 1482099 0 0.00 0 29972 13 0 0
doc_list_biud 1482088 1482088 0 0.00 0 28867 42 0 0
sp_add_invoice_to_stock 4974 4879 95 1.90 68 200270 25686 0 95
sp_cancel_adding_invoice 5291 5291 0 0.00 6 65561 1257 0 0
sp_cancel_client_order 1582 1582 0 0.00 6 14156 397 0 0
sp_cancel_customer_reserve 520129 520129 0 0.00 1 22894 103 0 0
sp_cancel_pay_from_customer 625 625 0 0.00 4 8549 127 0 0
sp_cancel_pay_to_supplier 415 415 0 0.00 4 4191 147 0 0
sp_cancel_supplier_invoice 5887 5881 6 0.10 3 17926 1009 0 6
sp_cancel_supplier_order 434 434 0 0.00 13 1422699 155223 0 0
sp_cancel_write_off 9657 9657 0 0.00 2 12152 163 0 0
sp_client_order 19902 19902 0 0.00 5 27964 467 0 0
sp_customer_reserve 457917 457917 0 0.00 1 68508 241 0 0
sp_fill_shopping_cart 30490 30490 0 0.00 1 23787 90 0 0
sp_fill_shopping_cart_clo_res 457882 457882 0 0.00 0 16920 19 0 0
sp_get_clo_for_invoice 4840 4840 0 0.00 0 26863 347 0 0
sp_kill_cost_storno 15988 15988 0 0.00 0 10381 72 0 0
sp_kill_qstorno_ret_qs2qd 1056230 1056230 0 0.00 0 21927 45 0 0
sp_kill_qty_storno 557380 557380 0 0.00 0 28861 102 0 0
sp_lock_selected_doc 19884 19884 0 0.00 0 8903 8 0 0
sp_make_cost_storno 26703 26703 0 0.00 0 16523 84 0 0
sp_multiply_rows_for_pdistr 26702 26702 0 0.00 0 8180 37 0 0
sp_multiply_rows_for_qdistr 19902 19902 0 0.00 0 22094 187 0 0
sp_pay_from_customer 6759 6759 0 0.00 5 16581 177 0 0
sp_pay_to_supplier 5537 5537 0 0.00 6 15599 191 0 0
sp_payment_common 12296 12296 0 0.00 2 14437 48 0 0
sp_qd_handle_on_cancel_clo 1582 1582 0 0.00 1 14139 315 0 0
sp_qd_handle_on_invoice_upd_sts 10130 10130 0 0.00 0 28856 536 0 0
sp_qd_handle_on_reserve_upd_sts 9567 9567 0 0.00 0 18667 62 0 0
sp_reserve_write_off 9567 9567 0 0.00 8 18820 283 0 0
sp_supplier_invoice 5336 5336 0 0.00 10 70215 3264 0 0
sp_supplier_order 5265 5265 0 0.00 7 25010 1358 0 0
srv_aggregate_perf_data 109 109 0 0.00 2546 217852 14504 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 929 110874 35810 0 0
srv_find_qd_qs_mism 1013082 1013082 0 0.00 0 29968 10 0 0
srv_make_invnt_saldo 467 467 0 0.00 60 67647 3901 0 0
srv_make_money_saldo 872 872 0 0.00 1 12250 404 0 0
srv_recalc_idx_stat 4 4 0 0.00 87304 387471 280885 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 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4886 4886 0 0.00 1 15293 923 0 0
t$perf-norm:sp_cancel_adding_invoice 394 394 0 0.00 1 10769 574 0 0
t$perf-norm:sp_cancel_client_order 1582 1582 0 0.00 0 12970 36 0 0
t$perf-norm:sp_cancel_customer_reserve 631 631 0 0.00 0 1610 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 625 625 0 0.00 0 310 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 415 415 0 0.00 0 461 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 404 404 0 0.00 0 536 30 0 0
t$perf-norm:sp_cancel_supplier_order 434 434 0 0.00 0 101797 5690 0 0
t$perf-norm:sp_cancel_write_off 993 993 0 0.00 0 9291 39 0 0
t$perf-norm:sp_client_order 19902 19902 0 0.00 0 12639 39 0 0
t$perf-norm:sp_customer_reserve 979 979 0 0.00 0 7164 23 0 0
t$perf-norm:sp_pay_from_customer 6759 6759 0 0.00 0 12438 25 0 0
t$perf-norm:sp_pay_to_supplier 5537 5537 0 0.00 0 6792 22 0 0
t$perf-norm:sp_reserve_write_off 9567 9567 0 0.00 0 14304 26 0 0
t$perf-norm:sp_supplier_invoice 5336 5336 0 0.00 0 12901 43 0 0
t$perf-norm:sp_supplier_order 5265 5265 0 0.00 0 8899 32 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 500 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 133 5 0 0
t$perf-norm:srv_make_invnt_saldo 467 467 0 0.00 0 1974 18 0 0
t$perf-norm:srv_make_money_saldo 872 872 0 0.00 0 424 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 614 191 0 0
v_add_invoice_to_stock 9868 9678 190 1.92 0 6879 26 0 190
v_all_customers 36012 36012 0 0.00 0 134 4 0 0
v_all_suppliers 29222 29222 0 0.00 0 142 4 0 0
v_all_wares 39804 39804 0 0.00 0 94 4 0 0
v_cancel_adding_invoice 788 788 0 0.00 0 3899 22 0 0
v_cancel_client_order 3164 3164 0 0.00 0 3080 10 0 0
v_cancel_customer_prepayment 1250 1250 0 0.00 0 354 10 0 0
v_cancel_customer_reserve 1262 1262 0 0.00 0 169 9 0 0
v_cancel_payment_to_supplier 830 830 0 0.00 0 4164 21 0 0
v_cancel_supplier_invoice 820 808 12 1.46 0 481 22 0 12
v_cancel_supplier_order 868 868 0 0.00 0 4641 23 0 0
v_cancel_write_off 1986 1986 0 0.00 0 7828 32 0 0
v_max_id_clo_ord 5264 5264 0 0.00 0 256 5 0 0
v_max_id_clo_res 979 979 0 0.00 0 2070 13 0 0
v_max_id_ord_sup 5324 5324 0 0.00 0 5909 7 0 0
v_max_non_paid_invoice 1514 1514 0 0.00 0 139 8 0 0
v_max_non_paid_realizn 3591 3591 0 0.00 0 1185 8 0 0
v_min_id_clo_ord 5264 5264 0 0.00 0 21859 17 0 0
v_min_id_clo_res 979 979 0 0.00 0 1091 9 0 0
v_min_id_ord_sup 5324 5324 0 0.00 0 4423 11 0 0
v_min_non_paid_invoice 5537 5537 0 0.00 0 7611 18 0 0
v_min_non_paid_realizn 6759 6759 0 0.00 0 14428 22 0 0
v_our_firm 10128 10128 0 0.00 0 76 5 0 0
v_reserve_write_off 19134 19134 0 0.00 0 5368 11 0 0
x_lock_depdocs_on_canc_invoice 10768 10768 0 0.00 0 14540 132 0 0
x_lock_depdocs_on_canc_sup_ord 434 434 0 0.00 0 21516 409 0 0
x_make_qstorno_1000_1200 5265 5265 0 0.00 2 22243 1172 0 0
x_make_qstorno_1200_2000 5336 5336 0 0.00 5 68474 3017 0 0
x_make_qstorno_2100_3300 457915 457915 0 0.00 0 68504 211 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-12-2022 10:38:37 to 14-12-2022 10:38:41.

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 1 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
08:08:28 17153 8192000000 9708383392 10005024768 788788704 8.783 100 85 170 0 817354496 7476336 21089456
08:09:50 18777 8192000000 9753235744 10056695808 827123792 9.170 100 91 182 0 861250864 12162848 21964224
08:11:18 11192 8192000000 9780876064 10097229824 855333728 9.453 100 90 180 0 895140224 18988608 20817888
08:12:32 9793 8192000000 9851295696 10178142208 893466448 9.834 100 75 148 1 958516064 48384688 16664928
08:13:46 5208 8192000000 9884635424 10224156672 940009472 10.293 100 77 152 0 998799808 42494064 16296272
08:14:55 3016 8192000000 9949137344 10299514880 991899520 10.800 100 89 171 0 1061367104 51923424 17544160
08:16:02 1675 8192000000 10013111392 10369445888 1015246320 11.026 100 91 177 1 1122700928 90406896 17047712
08:17:06 2097 8192000000 9997018016 10360963072 1027996992 11.149 100 89 171 1 1110958192 65653984 17307216
08:18:10 902 8192000000 10015482160 10385715200 1055943536 11.418 100 94 177 1 1139773536 65084256 18745744
08:19:13 1075 8192000000 10053062192 10431381504 1064060096 11.495 100 97 189 0 1173652528 90639520 18952912
08:20:15 1197 8192000000 10042066992 10421006336 1072486320 11.576 100 90 172 1 1160379104 70658160 17234624
08:21:18 836 8192000000 10046850992 10428940288 1074763216 11.598 100 90 179 2 1169524896 76775552 17986128
08:22:22 1315 8192000000 10053413504 10438524928 1085402416 11.699 100 90 178 0 1171297344 67423504 18471424
08:23:25 986 8192000000 10041736160 10425593856 1088489136 11.728 100 92 181 0 1163907072 57824160 17593776
08:24:27 906 8192000000 10053177776 10437095424 1088596608 11.729 100 91 172 1 1173957888 66057088 19304192
08:25:33 5645 8192000000 10044219552 10430492672 1090350256 11.746 100 74 147 2 1169911792 64437248 15124288
08:26:39 1025 8192000000 10053324640 10441994240 1095828080 11.798 100 94 183 0 1176122000 62655296 17638624
08:27:41 1243 8192000000 10065491088 10452451328 1095647520 11.796 100 99 193 0 1191972864 77031360 19293984
08:28:43 1571 8192000000 10058138544 10443497472 1092762192 11.769 100 98 182 3 1176344800 66289056 17293552
08:29:46 1321 8192000000 10029981696 10408407040 1069848224 11.551 98 93 183 3 1152064848 64700704 17515920
08:30:48 966 8192000000 10073917424 10462420992 1083267232 11.679 100 91 177 2 1198438896 98019856 17151808
08:31:50 1038 8192000000 10035253056 10415587328 1062486704 11.480 100 94 185 1 1159125168 77892304 18746160
08:32:53 1072 8192000000 10010571984 10386194432 1047722784 11.339 100 92 180 0 1137378368 71338592 18316992
08:33:55 1714 8192000000 9975228832 10355834880 1027216992 11.142 100 81 160 0 1104651344 61132752 16301600
08:34:57 1092 8192000000 9978381008 10352795648 1040552256 11.270 100 88 166 2 1104072576 45153104 18367216
08:35:59 7549 8192000000 9985942704 10373926912 1049157920 11.353 100 70 130 0 1119063088 55820848 14084320
08:37:07 1078 8192000000 10025372960 10403545088 1048604448 11.347 100 91 175 0 1146121328 79929216 17587664
08:38:17 10676 8192000000 10012175152 10401013760 1073528800 11.586 100 59 114 1 1129962320 45567040 10866480
08:39:37 8072 8192000000 10038948960 10428669952 1083760624 11.683 100 69 134 2 1161340880 63318736 14261520
08:40:51 10457 8192000000 10064335408 10449436672 1090716992 11.749 100 78 150 1 1174819088 68874160 15227936
08:42:04 9675 8192000000 10095067232 10481098752 1092385760 11.765 99 70 135 1 1203989600 97566560 14037280
08:43:17 1595 8192000000 10145738320 10534830080 1124089040 12.066 100 83 157 1 1261552432 120675632 16787760
08:44:23 1221 8192000000 10200308064 10596438016 1120076656 12.028 100 89 163 3 1315572688 178393328 17102704
08:45:27 2131 8192000000 10174837056 10593574912 1135109296 12.170 100 79 145 0 1291598688 140846384 15643008
08:46:31 2495 8192000000 10201786064 10606616576 1144922416 12.262 100 72 140 0 1315524864 156028336 14574112
08:47:38 1266 8192000000 10247640928 10645864448 1155655280 12.363 100 94 180 0 1358965184 183657184 19652720
08:48:42 998 8192000000 10240305376 10648379392 1169088320 12.488 100 92 176 1 1361741824 173091264 19562240
08:49:46 3010 8192000000 10289083792 10690887680 1166451616 12.464 100 81 155 2 1404311472 221214368 16645488
08:50:52 1091 8192000000 10296804752 10714726400 1172923136 12.524 100 89 171 3 1409377136 217722192 18731808
08:51:56 1805 8192000000 10329793648 10754285568 1180511248 12.595 100 82 158 0 1437467600 239901488 17054864
08:53:00 1103 8192000000 10359275680 10767630336 1175184768 12.545 100 88 171 1 1466321184 272514560 18621856
08:54:06 4163 8192000000 10398195024 10809233408 1195687664 12.736 100 83 158 1 1507297136 293522272 18087200
08:55:12 1516 8192000000 10452582672 10873942016 1190577344 12.689 100 92 182 1 1562446608 350757184 21112080
08:56:17 1179 8192000000 10488516144 10904080384 1204956176 12.822 100 92 184 0 1599912496 372969648 21986672
08:57:20 926 8192000000 10506107312 10940948480 1201247200 12.788 100 95 183 2 1606909312 383501120 22160992
08:58:25 914 8192000000 10520153248 10955669504 1189705200 12.681 100 95 188 0 1625727824 412378720 23643904
08:59:28 2799 8192000000 10499860176 10954436608 1183927760 12.627 100 62 122 1 1612809152 412921536 15959856
09:00:34 7604 8192000000 10545712160 10981867520 1185740384 12.644 100 65 130 1 1663926032 461421120 16764528
09:01:54 1833 8192000000 10570965888 11018285056 1193748912 12.718 100 82 164 2 1669689936 455524352 20416672
09:03:00 3901 8192000000 10585885728 11023069184 1185351696 12.640 100 66 124 0 1684763952 481916848 17495408
09:04:06 12447 8192000000 10530492176 11034398720 1183035072 12.618 100 43 86 0 1631079312 436658864 11385376
09:05:29 11254 8192000000 10510428832 11017830400 1181043056 12.600 100 43 86 0 1615918960 423709072 11166832
09:06:53 19837 8192000000 10403009840 10913800192 1145238128 12.265 99 24 48 0 1497356288 345524896 6593264
09:08:15 57077 8192000000 10237534976 10750586880 1115608624 11.985 97 21 42 0 1429220816 307985136 5627056
09:10:12 5342 8192000000 10167538384 10643890176 1155550672 12.362 100 45 86 1 1274331120 109417712 9362736
09:11:19 37324 8192000000 10169427712 10599280640 1150238032 12.312 100 63 97 2 1267576288 108041392 9296864
09:12:57 14711 8192000000 10184249312 10644287488 1131916112 12.139 99 59 113 1 1292981872 149273712 11792048
09:14:25 66262 8192000000 10119265616 10552053760 1116584048 11.995 98 24 47 1 1182524848 59543200 6397600
09:16:48 109282 8192000000 10079481840 10561228800 1088749088 11.731 96 18 36 0 1134012112 41521056 3741968
09:19:39 59632 8192000000 10126992112 10570133504 1115116144 11.981 98 29 56 1 1199372816 78139920 6116752
09:21:40 97011 8192000000 10075218960 10529148928 1105669872 11.891 98 9 18 0 1140498912 32987024 1842016
09:24:42 110874 8192000000 10118285792 10606653440 1093514080 11.776 96 9 19 0 1139943856 44541200 1888576
09:27:41 109464 8192000000 10043595744 10502524928 1088276992 11.726 96 11 22 0 1115411792 25237056 1897744
09:31:34 93582 8192000000 10149602384 10583539712 1114287840 11.973 98 28 57 0 1202164800 81584256 6292704
09:34:14 38306 8192000000 10155974096 10630561792 1127837248 12.101 99 24 48 0 1243724256 110893280 4993728
09:35:57 61699 8192000000 10188219216 10622418944 1125079232 12.075 99 26 52 0 1237293392 105831504 6382656
09:38:08 51051 8192000000 10162986448 10661769216 1143355664 12.247 100 31 60 0 1286144864 135700576 7088624
09:40:07 37716 8192000000 10104948400 10586697728 1124463840 12.069 99 14 28 0 1220806896 93513104 2829952
09:41:46 74006 8192000000 10033469568 10495746048 1117952864 12.008 100 12 24 0 1140476288 20225072 2298352
09:44:11 61798 8192000000 10060583408 10531131392 1127331984 12.096 99 32 64 1 1164413344 30644848 6436512
09:46:17 84384 8192000000 10071046704 10533068800 1119867952 12.026 99 14 28 0 1163109200 40555584 2685664
09:48:43 63729 8192000000 10111160240 10599608320 1119703536 12.024 99 24 48 0 1221863248 96758592 5401120
09:51:02 30592 8192000000 10106213488 10585595904 1099371296 11.832 97 22 44 0 1176419808 72739584 4308928
09:52:40 31826 8192000000 10067621872 10501103616 1105003840 11.885 99 33 66 0 1162499584 50689792 6805952
09:54:13 22159 8192000000 10125029136 10573094912 1114608272 11.976 100 38 76 1 1209598848 87617152 7373424
09:55:36 54722 8192000000 10063131920 10487349248 1109863968 11.931 99 22 43 0 1158538128 44266928 4407232
09:57:32 33615 8192000000 10120925824 10569035776 1130448432 12.126 100 43 84 0 1204654144 66259792 7945920
09:59:07 21914 8192000000 10119157744 10542452736 1137945616 12.196 100 58 115 0 1199486688 50067184 11473888
10:00:29 98521 8192000000 10082595232 10560958464 1107796336 11.912 98 17 33 0 1157762768 46332176 3634256
10:03:42 87830 8192000000 10098455776 10647326720 1136880816 12.186 100 4 8 0 1207598384 69788912 928656
10:06:14 60883 8192000000 10059655088 10509021184 1124644816 12.071 99 13 26 0 1155400944 27519632 3236496
10:08:15 55223 8192000000 10059565296 10521808896 1098905424 11.827 96 22 44 0 1160434576 57035616 4493536
10:10:12 50590 8192000000 10075958320 10521784320 1108294720 11.916 97 14 28 0 1140784928 29550304 2939904
10:12:03 13951 8192000000 10080655056 10508394496 1100854544 11.846 98 35 68 1 1164631824 57082208 6695072
10:13:18 32928 8192000000 10088073584 10550730752 1122637584 12.052 100 24 48 0 1190372784 62550480 5184720
10:14:51 10221 8192000000 10138521296 10559660032 1134029200 12.159 100 65 125 1 1229817360 82355536 13432624
10:16:01 31499 8192000000 10202462016 10635042816 1138135984 12.198 100 51 102 0 1283165520 134651280 10378256
10:17:33 69689 8192000000 10119633328 10599571456 1115825152 11.988 98 17 34 0 1252581200 132562496 4193552
10:19:43 37182 8192000000 10139539376 10573766656 1112450048 11.956 98 43 86 0 1197155712 75926880 8778784
10:21:23 37759 8192000000 10129195936 10596843520 1122877216 12.054 99 31 62 0 1235201008 105805952 6517840
10:23:01 43604 8192000000 10122566768 10641620992 1143514320 12.249 100 36 71 1 1244070912 93278960 7277632
10:24:47 84892 8192000000 10077979120 10562392064 1138363760 12.200 99 39 76 0 1201810064 55949344 7496960
10:27:21 39037 8192000000 10047291840 10527608832 1124268800 12.067 99 2 4 0 1147982784 23511856 202128
10:29:14 70272 8192000000 10086911984 10554789888 1122223568 12.048 99 11 22 0 1166914800 42250160 2441072
10:31:25 57891 8192000000 10078456224 10521915392 1098079360 11.819 96 23 44 0 1129191024 25910000 5201664
10:33:31 31118 8192000000 10117314784 10571894784 1135664960 12.175 99 30 59 0 1216187824 73837424 6685440
10:35:03 39883 8192000000 10138303984 10581262336 1133075904 12.150 99 28 56 0 1229073712 89635824 6361984
10:36:55 37299 8192000000 9284012016 9642762240 414226272 4.813 36 11 20 1 460216288 43537984 2452032

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 14-12-2022 10:38:43 to 14-12-2022 10:38:59.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 95 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-12-2022 10:39:00 to 14-12-2022 10:39:02.

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          215621
MON$OLDEST_ACTIVE               215622
MON$OLDEST_SNAPSHOT             215622
MON$NEXT_TRANSACTION            215622
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                       2064608
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             763
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2885 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2885 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2885 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2885 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 ---
:23: 
:24: mscs-test-161.budzdorov.ru    Wed Dec 14 10:37:46 2022
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:26: 
--- end of diff output ---

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

14.12.22 10:39:03. 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-12-14 10:39:06 No severe PSQL-related problems occured
14.12.22 10:39:07. End of report.