Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.10.2022 04: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 14832912 kB
MemAvailable 30475436 kB
Buffers 20 kB
Cached 16774300 kB
SwapCached 2064 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-06 04:37:22
DTS_END                         2022-10-06 04:37:22.4760
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/37986
TRN_ID                          220426
ATT_ID                          647
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: 9334.64 23486 1120157
customer order: creation 171.72 468 20606
customer order: refuse 14.73 407 1767
order to supplier: creation 46.05 1366 5526
order to supplier: removal 3.34 176259 401
invoice (draft): creation 45.13 3070 5416
invoice (draft): removal 52.88 1013 6345
invoice accept: apply 42.46 25178 5095
invoice accept: cancel 48.54 1313 5825
customer reserve: creation 3918.48 240 470217
customer reserve: removal 4695.58 98 563469
realization accept: apply 81.72 300 9806
realization accept: cancel 83.70 168 10044
payment from customer: creation 59.45 181 7134
payment from customer: removal 5.77 140 692
payment to supplier: creation 49.16 190 5899
payment to supplier: removal 3.46 136 415
service: total inventory turnovers 3.86 4256 463
service: total monetary turnovers 7.69 392 923
service: aggregate perf. data 0.91 16238 109
service: refresh index statistics 0.04 238315 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 06-10-2022 04:38:27 to 06-10-2022 04:38:30.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6153
TEST_TIME 2 5466
TEST_TIME 3 5553
TEST_TIME 4 5875
TEST_TIME 5 6188
TEST_TIME 6 6936
TEST_TIME 7 7331
TEST_TIME 8 7097
TEST_TIME 9 7376
TEST_TIME 10 7373
TEST_TIME 11 7473
TEST_TIME 12 7617
TEST_TIME 13 7832
TEST_TIME 14 7894
TEST_TIME 15 7894
TEST_TIME 16 8012
TEST_TIME 17 8276
TEST_TIME 18 8194
TEST_TIME 19 8195
TEST_TIME 20 8072
TEST_TIME 21 8120
TEST_TIME 22 8547
TEST_TIME 23 8562
TEST_TIME 24 8623
TEST_TIME 25 8981
TEST_TIME 26 9186
TEST_TIME 27 9214
TEST_TIME 28 9096
TEST_TIME 29 9257
TEST_TIME 30 9510
TEST_TIME 31 9781
TEST_TIME 32 10085
TEST_TIME 33 10327
TEST_TIME 34 10339
TEST_TIME 35 10977
TEST_TIME 36 10936
TEST_TIME 37 11169
TEST_TIME 38 11496
TEST_TIME 39 11366
TEST_TIME 40 11153
TEST_TIME 41 11022
TEST_TIME 42 11113
TEST_TIME 43 10952
TEST_TIME 44 10827
TEST_TIME 45 10859
TEST_TIME 46 10745
TEST_TIME 47 10608
TEST_TIME 48 10919
TEST_TIME 49 10892
TEST_TIME 50 10713
TEST_TIME 51 10575
TEST_TIME 52 10577
TEST_TIME 53 10457
TEST_TIME 54 10354
TEST_TIME 55 10294
TEST_TIME 56 10261
TEST_TIME 57 10202
TEST_TIME 58 10270
TEST_TIME 59 10244
TEST_TIME 60 10215
TEST_TIME 61 10210
TEST_TIME 62 10092
TEST_TIME 63 10093
TEST_TIME 64 10011
TEST_TIME 65 10025
TEST_TIME 66 10041
TEST_TIME 67 9958
TEST_TIME 68 9972
TEST_TIME 69 9957
TEST_TIME 70 9889
TEST_TIME 71 9876
TEST_TIME 72 9799
TEST_TIME 73 9806
TEST_TIME 74 9936
TEST_TIME 75 9921
TEST_TIME 76 9980
TEST_TIME 77 10021
TEST_TIME 78 10105
TEST_TIME 79 10040
TEST_TIME 80 10167
TEST_TIME 81 10080
TEST_TIME 82 9984
TEST_TIME 83 9942
TEST_TIME 84 9865
TEST_TIME 85 9830
TEST_TIME 86 9857
TEST_TIME 87 9797
TEST_TIME 88 9762
TEST_TIME 89 9726
TEST_TIME 90 9681
TEST_TIME 91 9640
TEST_TIME 92 9565
TEST_TIME 93 9602
TEST_TIME 94 9626
TEST_TIME 95 9593
TEST_TIME 96 9593
TEST_TIME 97 9593
TEST_TIME 98 9574
TEST_TIME 99 9598
TEST_TIME 100 9582
TEST_TIME 101 9552
TEST_TIME 102 9534
TEST_TIME 103 9558
TEST_TIME 104 9637
TEST_TIME 105 9663
TEST_TIME 106 9706
TEST_TIME 107 9715
TEST_TIME 108 9639
TEST_TIME 109 9569
TEST_TIME 110 9556
TEST_TIME 111 9540
TEST_TIME 112 9486
TEST_TIME 113 9459
TEST_TIME 114 9420
TEST_TIME 115 9387
TEST_TIME 116 9322
TEST_TIME 117 9389
TEST_TIME 118 9403
TEST_TIME 119 9407
TEST_TIME 120 9351

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 06-10-2022 04:38:31 to 06-10-2022 04:38:35.

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 1552384 1552384 0 0.00 0 22411 13 0 0
doc_list_biud 1552384 1552384 0 0.00 0 40576 41 0 0
sp_add_invoice_to_stock 5217 5095 122 2.33 48 196797 25179 0 122
sp_cancel_adding_invoice 5825 5825 0 0.00 6 112925 1314 0 0
sp_cancel_client_order 1767 1767 0 0.00 5 11368 408 0 0
sp_cancel_customer_reserve 563469 563469 0 0.00 1 20921 98 0 0
sp_cancel_pay_from_customer 692 692 0 0.00 4 7643 140 0 0
sp_cancel_pay_to_supplier 415 415 0 0.00 4 6656 136 0 0
sp_cancel_supplier_invoice 6360 6345 15 0.23 3 22692 1014 0 15
sp_cancel_supplier_order 401 401 0 0.00 10 1492887 176259 0 0
sp_cancel_write_off 10044 10044 0 0.00 2 9148 168 0 0
sp_client_order 20606 20606 0 0.00 6 19103 469 0 0
sp_customer_reserve 470217 470217 0 0.00 0 138542 240 0 0
sp_fill_shopping_cart 31538 31538 0 0.00 1 8892 83 0 0
sp_fill_shopping_cart_clo_res 470179 470179 0 0.00 0 11341 17 0 0
sp_get_clo_for_invoice 5057 5057 0 0.00 0 21958 248 0 0
sp_kill_cost_storno 16976 16976 0 0.00 0 22389 79 0 0
sp_kill_qstorno_ret_qs2qd 1143728 1143728 0 0.00 0 22571 42 0 0
sp_kill_qty_storno 602714 602714 0 0.00 0 40574 98 0 0
sp_lock_selected_doc 20636 20636 0 0.00 0 8242 9 0 0
sp_make_cost_storno 27896 27896 0 0.00 0 21749 84 0 0
sp_multiply_rows_for_pdistr 27896 27896 0 0.00 0 10817 40 0 0
sp_multiply_rows_for_qdistr 20606 20606 0 0.00 0 15171 164 0 0
sp_pay_from_customer 7134 7134 0 0.00 5 17253 182 0 0
sp_pay_to_supplier 5899 5899 0 0.00 6 12682 191 0 0
sp_payment_common 13033 13033 0 0.00 2 8109 52 0 0
sp_qd_handle_on_cancel_clo 1767 1767 0 0.00 0 11318 322 0 0
sp_qd_handle_on_invoice_upd_sts 10882 10882 0 0.00 0 40574 551 0 0
sp_qd_handle_on_reserve_upd_sts 9806 9806 0 0.00 0 19477 68 0 0
sp_reserve_write_off 9806 9806 0 0.00 8 23492 301 0 0
sp_supplier_invoice 5416 5416 0 0.00 9 80231 3071 0 0
sp_supplier_order 5526 5526 0 0.00 7 29244 1367 0 0
srv_aggregate_perf_data 109 109 0 0.00 2272 209801 16238 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 799 140691 33203 0 0
srv_find_qd_qs_mism 1070503 1070503 0 0.00 0 16111 9 0 0
srv_make_invnt_saldo 463 463 0 0.00 62 87969 4257 0 0
srv_make_money_saldo 923 923 0 0.00 1 12630 393 0 0
srv_recalc_idx_stat 5 5 0 0.00 79829 383800 238315 0 0
t$perf-abend:sp_add_invoice_to_stock 122 122 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5095 5095 0 0.00 1 9162 1003 0 0
t$perf-norm:sp_cancel_adding_invoice 427 427 0 0.00 1 7874 559 0 0
t$perf-norm:sp_cancel_client_order 1767 1767 0 0.00 0 4215 34 0 0
t$perf-norm:sp_cancel_customer_reserve 652 652 0 0.00 0 1200 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 692 692 0 0.00 0 5756 29 0 0
t$perf-norm:sp_cancel_pay_to_supplier 415 415 0 0.00 0 376 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 406 406 0 0.00 0 5637 50 0 0
t$perf-norm:sp_cancel_supplier_order 401 401 0 0.00 0 99071 7756 0 0
t$perf-norm:sp_cancel_write_off 1013 1013 0 0.00 0 2548 23 0 0
t$perf-norm:sp_client_order 20606 20606 0 0.00 0 6627 38 0 0
t$perf-norm:sp_customer_reserve 1068 1068 0 0.00 0 3154 18 0 0
t$perf-norm:sp_pay_from_customer 7134 7134 0 0.00 0 5672 20 0 0
t$perf-norm:sp_pay_to_supplier 5899 5899 0 0.00 0 6665 21 0 0
t$perf-norm:sp_reserve_write_off 9806 9806 0 0.00 0 6399 25 0 0
t$perf-norm:sp_supplier_invoice 5416 5416 0 0.00 0 5730 33 0 0
t$perf-norm:sp_supplier_order 5526 5526 0 0.00 0 1660 30 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 568 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 5689 134 0 0
t$perf-norm:srv_make_invnt_saldo 463 463 0 0.00 0 314 10 0 0
t$perf-norm:srv_make_money_saldo 923 923 0 0.00 0 1712 10 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 7 190 73 0 0
v_add_invoice_to_stock 10358 10114 244 2.35 0 6840 27 0 244
v_all_customers 38106 38106 0 0.00 0 133 4 0 0
v_all_suppliers 30566 30566 0 0.00 0 73 5 0 0
v_all_wares 41212 41212 0 0.00 0 96 4 0 0
v_cancel_adding_invoice 854 854 0 0.00 0 2165 17 0 0
v_cancel_client_order 3534 3534 0 0.00 0 5887 13 0 0
v_cancel_customer_prepayment 1384 1384 0 0.00 0 1384 14 0 0
v_cancel_customer_reserve 1304 1304 0 0.00 0 5141 16 0 0
v_cancel_payment_to_supplier 830 830 0 0.00 0 2123 16 0 0
v_cancel_supplier_invoice 842 812 30 3.56 0 451 23 0 30
v_cancel_supplier_order 802 802 0 0.00 0 2683 16 0 0
v_cancel_write_off 2026 2026 0 0.00 0 6043 30 0 0
v_max_id_clo_ord 5526 5526 0 0.00 0 2267 6 0 0
v_max_id_clo_res 1068 1068 0 0.00 0 806 10 0 0
v_max_id_ord_sup 5406 5406 0 0.00 0 2977 7 0 0
v_max_non_paid_invoice 1548 1548 0 0.00 0 163 8 0 0
v_max_non_paid_realizn 3470 3470 0 0.00 0 1239 8 0 0
v_min_id_clo_ord 5526 5526 0 0.00 0 4732 9 0 0
v_min_id_clo_res 1068 1068 0 0.00 0 4023 12 0 0
v_min_id_ord_sup 5406 5406 0 0.00 0 6600 9 0 0
v_min_non_paid_invoice 5899 5899 0 0.00 0 6463 16 0 0
v_min_non_paid_realizn 7134 7134 0 0.00 0 8102 24 0 0
v_our_firm 10434 10434 0 0.00 0 68 5 0 0
v_reserve_write_off 19612 19612 0 0.00 0 6186 11 0 0
x_lock_depdocs_on_canc_invoice 11764 11764 0 0.00 0 14263 137 0 0
x_lock_depdocs_on_canc_sup_ord 401 401 0 0.00 0 3853 378 0 0
x_make_qstorno_1000_1200 5526 5526 0 0.00 2 29223 1190 0 0
x_make_qstorno_1200_2000 5416 5416 0 0.00 5 80209 2858 0 0
x_make_qstorno_2100_3300 470217 470217 0 0.00 0 138536 210 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-10-2022 04:38:36 to 06-10-2022 04:38:40.

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
02:07:26 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
02:08:27 28663 8192000000 9727666464 10023272448 798184592 8.878 100 91 182 0 828498096 8387968 21925536
02:10:00 8987 8192000000 9764057488 10069274624 836616240 9.266 100 92 184 0 874263328 15723184 21923904
02:11:14 17279 8192000000 9789655008 10105626624 857392560 9.474 100 84 168 0 896553616 19559856 19601200
02:12:43 8634 8192000000 9862732416 10195255296 916637104 10.063 100 86 164 0 977389776 42885024 17867648
02:13:56 5262 8192000000 9921074720 10263810048 950856640 10.399 100 80 159 1 1034755936 66772896 17126400
02:15:04 963 8192000000 9962733264 10314944512 988455632 10.766 100 96 187 1 1081837840 74479856 18902352
02:16:08 1505 8192000000 9986391456 10352160768 1014282208 11.017 100 91 173 1 1110457600 79479408 16695984
02:17:12 1348 8192000000 10022767408 10388283392 1036946304 11.235 100 94 176 0 1139504240 84017024 18540912
02:18:14 850 8192000000 10039197296 10408448000 1056718896 11.425 100 96 185 2 1157006064 83049920 17237248
02:19:16 982 8192000000 10058185072 10439114752 1064461488 11.499 100 95 183 0 1178500752 95080800 18958464
02:20:19 8812 8192000000 10013936800 10391953408 1070748464 11.559 100 95 182 2 1133096864 45642416 16705984
02:21:28 8823 8192000000 10041980096 10428379136 1074622256 11.596 100 77 151 0 1163030176 73573824 14834096
02:22:38 1314 8192000000 10043846816 10434396160 1077986016 11.628 100 78 154 0 1169546448 76309872 15250560
02:23:40 4049 8192000000 10029241680 10421477376 1083968896 11.685 100 71 137 2 1149801712 51947424 13885392
02:24:45 3243 8192000000 10043409136 10445975552 1084950848 11.695 100 65 117 3 1168770384 71827808 11991728
02:25:50 1129 8192000000 10043586096 10431700992 1090916544 11.751 100 91 169 1 1164535184 56666848 16951792
02:26:52 1296 8192000000 10052127344 10440220672 1089480592 11.738 100 96 187 0 1174794512 64788656 20525264
02:27:55 990 8192000000 10052676512 10441052160 1088807552 11.731 100 93 180 0 1173069248 66045376 18216320
02:28:57 1039 8192000000 10051374800 10452250624 1090604912 11.748 100 73 137 0 1174558096 68968272 14984912
02:29:59 1135 8192000000 10035098192 10420858880 1072895312 11.580 100 92 179 1 1154949936 64671440 17383184
02:31:01 1286 8192000000 10035445104 10421317632 1073533392 11.586 100 87 166 3 1157737040 67345680 16857968
02:32:03 2962 8192000000 9983406672 10358366208 1037726496 11.243 98 75 139 2 1098362720 46637168 13999056
02:33:08 1116 8192000000 10017711760 10399588352 1062062640 11.476 100 80 158 0 1138242896 59461504 16718752
02:34:10 2195 8192000000 9997265856 10380980224 1055509536 11.413 100 69 128 0 1119263824 50619840 13134448
02:35:15 1218 8192000000 10008641056 10379968512 1034074896 11.208 100 97 185 3 1132611072 77662448 20873728
02:36:17 991 8192000000 10007959408 10383028224 1054839136 11.407 100 94 170 0 1128673184 55052608 18781440
02:37:19 1013 8192000000 10004392768 10382675968 1059700048 11.454 100 89 164 3 1125156768 48963680 16493040
02:38:26 5672 8192000000 10002861296 10381025280 1063083856 11.486 99 71 134 0 1125430128 49727760 12618512
02:39:56 2462 8192000000 10062035904 10441895936 1086936880 11.714 99 92 178 0 1176403232 71481280 17985072
02:41:06 1539 8192000000 10089685616 10481254400 1094930480 11.790 100 96 183 1 1208982592 92687104 21365008
02:42:16 927 8192000000 10123504368 10514841600 1096709696 11.806 99 95 183 2 1231540736 116972080 17858960
02:43:19 1470 8192000000 10169956064 10571583488 1124009824 12.065 100 97 188 0 1284858784 140848464 20000496
02:44:25 18027 8192000000 10139076144 10562658304 1135680544 12.175 100 66 130 1 1265925344 115999808 14244992
02:45:49 916 8192000000 10196252368 10590072832 1146221392 12.274 100 90 167 3 1312077760 148186720 17669648
02:46:54 6335 8192000000 10207436048 10611703808 1151542128 12.324 100 83 166 1 1326464880 156658608 18264144
02:48:03 1398 8192000000 10247758720 10650988544 1165301264 12.453 100 89 172 2 1359282144 174561936 19418944
02:49:07 1433 8192000000 10290814432 10692210688 1173397664 12.529 100 96 191 0 1402861936 207579568 21884704
02:50:12 1589 8192000000 10305247760 10721472512 1175322592 12.547 100 88 173 1 1413852080 220298000 18231488
02:51:16 1385 8192000000 10318131520 10724569088 1181600160 12.605 100 93 181 0 1428775280 226869840 20305280
02:52:20 984 8192000000 10366372320 10770137088 1183806768 12.626 100 96 183 0 1473838768 268988960 21043040
02:53:26 1397 8192000000 10367808688 10811367424 1192360048 12.705 100 84 160 2 1477869136 267084016 18425072
02:54:31 949 8192000000 10405789344 10813644800 1197364864 12.752 100 95 187 0 1516184192 296972192 21847136
02:55:35 1714 8192000000 10474834400 10885832704 1210735856 12.876 100 83 160 0 1582802032 353349936 18716240
02:56:40 829 8192000000 10492144368 10903531520 1209110288 12.861 100 94 183 1 1600311808 368327792 22873728
02:57:45 945 8192000000 10542053856 10965581824 1208537632 12.856 100 99 192 2 1648550160 416060304 23952224
02:58:49 789 8192000000 10533639520 10994401280 1200240128 12.779 100 96 188 0 1638311936 413035360 25036448
02:59:51 1502 8192000000 10576995712 11003031552 1212444976 12.892 100 92 180 2 1681116336 446937584 21733776
03:00:59 1128 8192000000 10530568624 10985385984 1203377552 12.808 100 100 197 1 1636926192 409547776 24000864
03:02:03 1694 8192000000 10585490544 11050127360 1196260736 12.742 100 94 185 3 1686266992 466908720 23097536
03:03:06 4986 8192000000 10557513824 11016617984 1194811120 12.728 100 60 120 0 1683892736 474101216 14980400
03:04:11 10008 8192000000 10573045984 11024359424 1188285152 12.667 100 55 106 1 1676675104 474952576 13437376
03:05:24 7269 8192000000 10600644384 11049652224 1189529232 12.679 100 53 104 0 1714979456 512340688 13109536
03:06:36 9026 8192000000 10570876624 11031498752 1158923056 12.393 99 48 94 0 1672264736 500824928 12516752
03:07:50 14877 8192000000 10532633600 11013439488 1152793024 12.336 98 45 89 1 1621386704 457338960 11254720
03:09:10 26966 8192000000 10518066912 11012145152 1180809216 12.598 100 35 68 0 1655996912 466181280 9006416
03:10:41 24737 8192000000 10417423824 10894045184 1145591920 12.268 99 22 44 0 1531562192 380285680 5684592
03:12:34 25508 8192000000 10251328352 10754400256 1147074928 12.282 100 20 39 1 1406909600 254799296 5035376
03:14:29 56304 8192000000 10120352208 10648039424 1133736624 12.157 99 12 24 0 1224158704 87699312 2722768
03:16:41 66652 8192000000 10057454576 10489397248 1108494544 11.918 97 44 83 2 1136174144 18160352 9519248
03:18:51 119650 8192000000 10086976288 10539163648 1091509584 11.757 97 7 14 0 1144279312 51409088 1360640
03:21:51 73987 8192000000 10086339952 10521772032 1122892912 12.054 99 16 30 1 1158876720 33521280 2462528
03:24:14 77635 8192000000 10119235888 10640003072 1133193088 12.151 99 27 53 0 1247741776 108998896 5549792
03:26:38 140690 8192000000 10094659232 10552803328 1102887168 11.865 97 24 46 0 1139464064 31202832 5374064
03:30:02 31800 8192000000 10096961200 10532012032 1115336000 11.983 99 61 114 4 1158461552 31994944 11130608
03:32:40 7715 8192000000 10126954912 10557362176 1138666768 12.203 100 59 112 3 1222464608 72334672 11463168
03:33:52 16780 8192000000 10138838496 10577571840 1135815168 12.176 100 49 95 0 1231303040 84150528 11337344
03:35:10 17461 8192000000 10132234080 10592731136 1108555504 11.919 98 39 72 0 1216504624 99434592 8514528
03:36:34 3406 8192000000 10095617568 10543136768 1130887696 12.130 100 48 96 0 1190438528 49187872 10362960
03:37:42 43699 8192000000 10111991120 10596171776 1121619456 12.042 99 17 34 0 1207812816 82173328 4020032
03:39:27 49887 8192000000 10125894944 10589626368 1119392752 12.021 98 19 36 0 1197828192 74370272 4065168
03:41:22 98287 8192000000 10067644160 10574708736 1128128288 12.104 100 15 30 0 1202830656 71816640 2885728
03:44:00 52866 8192000000 10097784464 10554023936 1129773440 12.119 99 20 40 0 1169506896 36048480 3684976
03:46:00 29526 8192000000 10075538832 10538086400 1108957744 11.923 97 23 46 0 1140956528 27427616 4571168
03:47:33 38592 8192000000 10161222848 10616889344 1131576848 12.136 98 32 62 1 1223632512 84877088 7178576
03:49:15 17821 8192000000 10181951056 10616922112 1137416912 12.191 99 32 62 0 1257378400 112909536 7051952
03:50:37 51722 8192000000 10165115632 10630131712 1151599424 12.325 100 27 54 0 1315703968 157354848 6749696
03:52:34 55687 8192000000 10153543104 10655367168 1131114576 12.132 98 30 58 0 1273846384 136362448 6369360
03:54:34 59767 8192000000 10150585312 10611531776 1119285280 12.020 98 27 53 1 1210174032 84021104 6867648
03:56:40 55894 8192000000 10126184800 10628534272 1116781920 11.997 97 24 48 0 1229059104 107085184 5192000
03:59:15 91798 8192000000 10075993152 10533855232 1135530992 12.173 100 27 54 0 1163893264 22969360 5392912
04:01:50 7039 8192000000 10102996912 10533306368 1144591344 12.259 100 59 113 2 1195466928 38588528 12287056
04:02:59 53306 8192000000 10093769488 10580627456 1130758080 12.129 99 36 72 2 1214804576 76492800 7553696
04:04:56 41981 8192000000 10086621760 10543091712 1138277520 12.199 100 16 32 0 1191364640 49609824 3477296
04:06:42 65117 8192000000 10101283392 10573807616 1135071184 12.169 99 17 34 0 1203346016 64263056 4011776
04:08:59 70601 8192000000 10128791424 10614415360 1144394624 12.257 100 27 55 0 1232903920 82418736 6090560
04:11:10 52289 8192000000 10071684976 10527109120 1145234064 12.265 100 19 40 0 1172898112 24373584 3290464
04:13:03 29989 8192000000 10106070016 10561290240 1092124144 11.763 95 45 87 0 1174603584 73447568 9031872
04:14:35 46213 8192000000 10103743104 10555248640 1129628224 12.118 100 31 60 0 1199508896 63253392 6627280
04:16:23 31516 8192000000 10074362192 10514882560 1084502528 11.690 96 26 50 0 1110636288 20847152 5286608
04:17:57 14940 8192000000 10115756368 10545508352 1113994656 11.970 98 47 90 1 1179662384 56657008 9010720
04:19:20 26566 8192000000 10148643456 10587426816 1120920272 12.036 99 39 77 0 1234596608 105827424 7848912
04:20:53 49560 8192000000 10123628416 10575482880 1121567952 12.042 100 39 78 1 1236110224 106079536 8462736
04:22:56 45295 8192000000 10160682992 10637881344 1123736624 12.062 99 26 52 1 1243368880 114235104 5397152
04:25:23 96145 8192000000 10016766240 10451173376 1076876688 11.618 96 31 56 0 1105628144 23279696 5471760
04:28:00 27786 8192000000 10039847376 10502205440 1118433920 12.012 100 14 28 0 1157681024 36837520 2409584
04:29:39 113652 8192000000 10120125472 10569494528 1128986464 12.112 99 19 39 1 1190494112 57824368 3683280
04:32:35 103310 8192000000 10128259232 10595635200 1123685520 12.062 99 18 34 0 1227414672 100081216 3647936
04:35:45 45700 8192000000 10083748336 10523361280 1136108592 12.179 100 42 83 1 1186225872 42213280 7904000

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 06-10-2022 04:38:42 to 06-10-2022 04:38:58.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 122 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-10-2022 04:38:59 to 06-10-2022 04:39:01.

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          217071
MON$OLDEST_ACTIVE               220580
MON$OLDEST_SNAPSHOT             220580
MON$NEXT_TRANSACTION            220580
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                       2098624
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             775
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2855 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2855 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2855 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2855 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 ---
:41: 
:42: mscs-test-161.budzdorov.ru    Thu Oct  6 04:37:25 2022
:43:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:44: 
:45: 
:46: mscs-test-161.budzdorov.ru    Thu Oct  6 04:37:25 2022
:47:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:48: 
:49: 
:50: mscs-test-161.budzdorov.ru    Thu Oct  6 04:37:25 2022
:51:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:52: 
:53: 
:54: mscs-test-161.budzdorov.ru    Thu Oct  6 04:37:26 2022
:55:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:56: 
--- end of diff output ---

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

06.10.22 04:39:02. 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-06 04:39:05 No severe PSQL-related problems occured
06.10.22 04:39:06. End of report.