Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.12.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 14582300 kB
MemAvailable 30024708 kB
Buffers 20 kB
Cached 17020428 kB
SwapCached 3408 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-22 04:37:22
DTS_END                         2022-12-22 04:37:22.8110
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/37704
TRN_ID                          213581
ATT_ID                          639
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: 8855.85 25452 1062702
customer order: creation 162.21 481 19465
customer order: refuse 13.26 410 1591
order to supplier: creation 42.38 1384 5086
order to supplier: removal 3.37 170978 404
invoice (draft): creation 42.15 3226 5058
invoice (draft): removal 51.65 977 6198
invoice accept: apply 39.37 26060 4724
invoice accept: cancel 46.83 1196 5620
customer reserve: creation 3631.73 250 435807
customer reserve: removal 4550.08 101 546010
realization accept: apply 78.29 287 9395
realization accept: cancel 74.52 176 8942
payment from customer: creation 54.33 177 6519
payment from customer: removal 5.27 127 632
payment to supplier: creation 45.76 180 5491
payment to supplier: removal 3.32 94 398
service: total inventory turnovers 3.58 4133 429
service: total monetary turnovers 6.86 426 823
service: aggregate perf. data 0.88 17564 105
service: refresh index statistics 0.04 280809 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-12-2022 04:38:34 to 22-12-2022 04:38:36.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8088
TEST_TIME 2 6128
TEST_TIME 3 5533
TEST_TIME 4 6152
TEST_TIME 5 6259
TEST_TIME 6 6184
TEST_TIME 7 6578
TEST_TIME 8 6310
TEST_TIME 9 6339
TEST_TIME 10 6368
TEST_TIME 11 6508
TEST_TIME 12 6599
TEST_TIME 13 6862
TEST_TIME 14 6825
TEST_TIME 15 6887
TEST_TIME 16 6896
TEST_TIME 17 7479
TEST_TIME 18 7455
TEST_TIME 19 7675
TEST_TIME 20 8184
TEST_TIME 21 8468
TEST_TIME 22 8749
TEST_TIME 23 8669
TEST_TIME 24 8902
TEST_TIME 25 9191
TEST_TIME 26 9210
TEST_TIME 27 9842
TEST_TIME 28 10065
TEST_TIME 29 10175
TEST_TIME 30 10232
TEST_TIME 31 10736
TEST_TIME 32 11145
TEST_TIME 33 10893
TEST_TIME 34 10724
TEST_TIME 35 10746
TEST_TIME 36 10729
TEST_TIME 37 10817
TEST_TIME 38 10702
TEST_TIME 39 10524
TEST_TIME 40 10368
TEST_TIME 41 10234
TEST_TIME 42 10194
TEST_TIME 43 10146
TEST_TIME 44 10157
TEST_TIME 45 10207
TEST_TIME 46 10258
TEST_TIME 47 10236
TEST_TIME 48 10107
TEST_TIME 49 10063
TEST_TIME 50 10186
TEST_TIME 51 10058
TEST_TIME 52 9924
TEST_TIME 53 9838
TEST_TIME 54 9741
TEST_TIME 55 9608
TEST_TIME 56 9598
TEST_TIME 57 9588
TEST_TIME 58 9515
TEST_TIME 59 9586
TEST_TIME 60 9575
TEST_TIME 61 9533
TEST_TIME 62 9463
TEST_TIME 63 9350
TEST_TIME 64 9257
TEST_TIME 65 9231
TEST_TIME 66 9181
TEST_TIME 67 9135
TEST_TIME 68 9197
TEST_TIME 69 9163
TEST_TIME 70 9133
TEST_TIME 71 9154
TEST_TIME 72 9176
TEST_TIME 73 9214
TEST_TIME 74 9362
TEST_TIME 75 9391
TEST_TIME 76 9443
TEST_TIME 77 9400
TEST_TIME 78 9389
TEST_TIME 79 9389
TEST_TIME 80 9359
TEST_TIME 81 9272
TEST_TIME 82 9274
TEST_TIME 83 9204
TEST_TIME 84 9221
TEST_TIME 85 9211
TEST_TIME 86 9166
TEST_TIME 87 9111
TEST_TIME 88 9070
TEST_TIME 89 9024
TEST_TIME 90 8961
TEST_TIME 91 9019
TEST_TIME 92 9046
TEST_TIME 93 9053
TEST_TIME 94 9003
TEST_TIME 95 9006
TEST_TIME 96 8967
TEST_TIME 97 8958
TEST_TIME 98 8973
TEST_TIME 99 9000
TEST_TIME 100 9017
TEST_TIME 101 9108
TEST_TIME 102 9151
TEST_TIME 103 9285
TEST_TIME 104 9244
TEST_TIME 105 9233
TEST_TIME 106 9185
TEST_TIME 107 9159
TEST_TIME 108 9142
TEST_TIME 109 9101
TEST_TIME 110 9046
TEST_TIME 111 9045
TEST_TIME 112 9037
TEST_TIME 113 9000
TEST_TIME 114 8969
TEST_TIME 115 8958
TEST_TIME 116 8971
TEST_TIME 117 8958
TEST_TIME 118 8942
TEST_TIME 119 8922
TEST_TIME 120 8872

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 22-12-2022 04:38:38 to 22-12-2022 04:38:41.

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 1464825 1464825 0 0.00 0 21130 13 0 0
doc_list_biud 1464808 1464808 0 0.00 0 51100 43 0 0
sp_add_invoice_to_stock 4830 4724 106 2.19 36 247765 26060 0 106
sp_cancel_adding_invoice 5620 5620 0 0.00 6 93142 1196 0 0
sp_cancel_client_order 1591 1591 0 0.00 7 8334 410 0 0
sp_cancel_customer_reserve 546010 546010 0 0.00 1 41373 101 0 0
sp_cancel_pay_from_customer 632 632 0 0.00 4 4180 127 0 0
sp_cancel_pay_to_supplier 398 398 0 0.00 3 747 94 0 0
sp_cancel_supplier_invoice 6206 6198 8 0.12 3 51144 977 0 8
sp_cancel_supplier_order 404 404 0 0.00 16 1299246 170979 0 0
sp_cancel_write_off 8942 8942 0 0.00 2 11257 176 0 0
sp_client_order 19465 19465 0 0.00 5 13739 482 0 0
sp_customer_reserve 435807 435807 0 0.00 0 79670 251 0 0
sp_fill_shopping_cart 29598 29598 0 0.00 1 29327 87 0 0
sp_fill_shopping_cart_clo_res 435782 435782 0 0.00 0 15178 19 0 0
sp_get_clo_for_invoice 4694 4694 0 0.00 0 31264 324 0 0
sp_kill_cost_storno 15592 15592 0 0.00 0 9511 73 0 0
sp_kill_qstorno_ret_qs2qd 1107564 1107564 0 0.00 0 51098 44 0 0
sp_kill_qty_storno 582852 582852 0 0.00 0 51099 101 0 0
sp_lock_selected_doc 19471 19471 0 0.00 0 9935 8 0 0
sp_make_cost_storno 26099 26099 0 0.00 0 21104 82 0 0
sp_multiply_rows_for_pdistr 26097 26097 0 0.00 0 7864 35 0 0
sp_multiply_rows_for_qdistr 19465 19465 0 0.00 0 13539 189 0 0
sp_pay_from_customer 6519 6519 0 0.00 5 12322 177 0 0
sp_pay_to_supplier 5491 5491 0 0.00 6 12344 181 0 0
sp_payment_common 12008 12008 0 0.00 2 7651 44 0 0
sp_qd_handle_on_cancel_clo 1591 1591 0 0.00 1 8324 334 0 0
sp_qd_handle_on_invoice_upd_sts 10313 10313 0 0.00 0 26615 524 0 0
sp_qd_handle_on_reserve_upd_sts 9394 9394 0 0.00 0 11491 69 0 0
sp_reserve_write_off 9395 9395 0 0.00 8 18253 288 0 0
sp_supplier_invoice 5058 5058 0 0.00 7 78934 3227 0 0
sp_supplier_order 5086 5086 0 0.00 7 55523 1385 0 0
srv_aggregate_perf_data 105 105 0 0.00 2255 158953 17564 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 815 116381 37149 0 0
srv_find_qd_qs_mism 1017328 1017328 0 0.00 0 18623 9 0 0
srv_make_invnt_saldo 429 429 0 0.00 82 59608 4133 0 0
srv_make_money_saldo 823 823 0 0.00 1 29516 427 0 0
srv_recalc_idx_stat 5 5 0 0.00 74635 538630 280809 0 0
t$perf-abend:sp_add_invoice_to_stock 106 106 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4725 4725 0 0.00 1 18757 882 0 0
t$perf-norm:sp_cancel_adding_invoice 421 421 0 0.00 1 6139 442 0 0
t$perf-norm:sp_cancel_client_order 1591 1591 0 0.00 0 4011 33 0 0
t$perf-norm:sp_cancel_customer_reserve 586 586 0 0.00 0 1797 32 0 0
t$perf-norm:sp_cancel_pay_from_customer 632 632 0 0.00 0 8534 29 0 0
t$perf-norm:sp_cancel_pay_to_supplier 398 398 0 0.00 0 462 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 381 381 0 0.00 0 7409 75 0 0
t$perf-norm:sp_cancel_supplier_order 404 404 0 0.00 0 103589 6925 0 0
t$perf-norm:sp_cancel_write_off 977 977 0 0.00 0 7472 28 0 0
t$perf-norm:sp_client_order 19467 19467 0 0.00 0 12492 34 0 0
t$perf-norm:sp_customer_reserve 940 940 0 0.00 0 1608 17 0 0
t$perf-norm:sp_pay_from_customer 6520 6520 0 0.00 0 7577 22 0 0
t$perf-norm:sp_pay_to_supplier 5491 5491 0 0.00 0 3635 19 0 0
t$perf-norm:sp_reserve_write_off 9395 9395 0 0.00 0 10004 27 0 0
t$perf-norm:sp_supplier_invoice 5058 5058 0 0.00 0 9469 29 0 0
t$perf-norm:sp_supplier_order 5086 5086 0 0.00 0 2947 29 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 209 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 4618 85 0 0
t$perf-norm:srv_make_invnt_saldo 429 429 0 0.00 0 473 16 0 0
t$perf-norm:srv_make_money_saldo 823 823 0 0.00 0 405 4 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 210 103 0 0
v_add_invoice_to_stock 9586 9374 212 2.21 0 7501 27 0 212
v_all_customers 35222 35222 0 0.00 0 106 4 0 0
v_all_suppliers 28538 28538 0 0.00 0 73 4 0 0
v_all_wares 38926 38926 0 0.00 0 128 4 0 0
v_cancel_adding_invoice 842 842 0 0.00 0 3284 22 0 0
v_cancel_client_order 3182 3182 0 0.00 0 1843 11 0 0
v_cancel_customer_prepayment 1264 1264 0 0.00 0 2628 11 0 0
v_cancel_customer_reserve 1172 1172 0 0.00 1 2276 14 0 0
v_cancel_payment_to_supplier 796 796 0 0.00 0 373 9 0 0
v_cancel_supplier_invoice 778 762 16 2.05 0 3145 25 0 16
v_cancel_supplier_order 808 808 0 0.00 0 3436 18 0 0
v_cancel_write_off 1954 1954 0 0.00 0 3027 33 0 0
v_max_id_clo_ord 5080 5080 0 0.00 0 1854 5 0 0
v_max_id_clo_res 940 940 0 0.00 0 1180 8 0 0
v_max_id_ord_sup 5051 5051 0 0.00 0 2969 7 0 0
v_max_non_paid_invoice 1351 1351 0 0.00 0 516 8 0 0
v_max_non_paid_realizn 3549 3549 0 0.00 0 825 8 0 0
v_min_id_clo_ord 5080 5080 0 0.00 0 2343 8 0 0
v_min_id_clo_res 940 940 0 0.00 0 2900 14 0 0
v_min_id_ord_sup 5051 5051 0 0.00 0 5126 9 0 0
v_min_non_paid_invoice 5490 5490 0 0.00 0 7633 15 0 0
v_min_non_paid_realizn 6518 6518 0 0.00 0 4281 19 0 0
v_our_firm 9640 9640 0 0.00 0 56 5 0 0
v_reserve_write_off 18787 18787 0 0.00 0 3522 12 0 0
x_lock_depdocs_on_canc_invoice 11437 11437 0 0.00 0 27556 141 0 0
x_lock_depdocs_on_canc_sup_ord 404 404 0 0.00 0 9470 373 0 0
x_make_qstorno_1000_1200 5086 5086 0 0.00 2 50132 1222 0 0
x_make_qstorno_1200_2000 5058 5058 0 0.00 3 78922 2975 0 0
x_make_qstorno_2100_3300 435806 435806 0 0.00 0 79623 220 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-12-2022 04:38:42 to 22-12-2022 04:38:46.

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 49 8192000000 8830559168 8853839872 24464800 0.297 14 3 4 0 24696768 145024 86944
02:08:27 28897 8192000000 9742645440 10043596800 821244416 9.111 100 89 178 0 849460832 7250944 20965472
02:10:06 14734 8192000000 9775418864 10087276544 848025296 9.380 100 89 178 0 883406240 14267792 21113152
02:11:26 13279 8192000000 9818121056 10138787840 877673520 9.677 100 83 164 1 921788944 25776384 18339040
02:12:44 9925 8192000000 9882231776 10215583744 918436288 10.081 100 87 172 1 988412976 50991184 18985504
02:13:59 4709 8192000000 9920261808 10263248896 962687296 10.515 100 89 175 0 1038725584 57515712 18522576
02:15:07 1522 8192000000 9974671584 10329264128 993552624 10.816 100 94 183 1 1087678288 75355216 18770448
02:16:10 1451 8192000000 9992398416 10356088832 1020702128 11.079 100 93 175 3 1103325872 65484960 17138784
02:17:13 990 8192000000 10023841200 10396299264 1040781040 11.272 100 92 176 1 1149370912 89654928 18934944
02:18:15 1066 8192000000 10030337632 10405253120 1057368432 11.431 100 98 191 1 1152748432 76594272 18785728
02:19:17 8011 8192000000 10042162224 10422947840 1068269408 11.536 100 87 172 1 1173188176 87388688 17530080
02:20:26 1097 8192000000 10054159072 10438926336 1074732016 11.597 100 76 150 3 1172653792 83574528 14347248
02:21:28 1361 8192000000 10063793616 10451546112 1078800080 11.636 100 85 164 1 1183667120 88027344 16839696
02:22:31 1002 8192000000 10028418816 10414940160 1087473920 11.719 100 92 177 2 1151804656 45838992 18491744
02:23:32 1138 8192000000 10053920960 10447499264 1089288176 11.736 100 89 176 1 1168095424 62137776 16669472
02:24:35 1167 8192000000 10065182208 10457821184 1094874800 11.789 100 91 173 1 1184829392 72062560 17892032
02:25:39 1453 8192000000 10042893136 10435940352 1101870272 11.855 100 83 154 1 1165200816 46616064 16714480
02:26:41 4176 8192000000 10042369536 10438381568 1098459120 11.823 100 69 134 2 1166504656 54542688 13502848
02:27:48 976 8192000000 10055813296 10447863808 1103855632 11.874 100 87 164 1 1177353312 56061584 17436096
02:28:50 1864 8192000000 10061779552 10455277568 1092208208 11.764 100 66 127 0 1177464736 72887424 12369104
02:29:53 11157 8192000000 10039485584 10420768768 1071033840 11.562 99 74 137 2 1151259856 65716336 14509680
02:31:05 1213 8192000000 10034856432 10415374336 1065409456 11.508 100 88 166 3 1156021568 73381344 17230768
02:32:08 1578 8192000000 9990675328 10371735552 1042113264 11.285 99 83 161 1 1116201072 57469264 16618544
02:33:11 1084 8192000000 10005619264 10375135232 1033753968 11.205 100 96 177 0 1126455456 74305824 18395664
02:34:13 1148 8192000000 9993690608 10367483904 1027418720 11.144 99 96 187 0 1114209360 67872000 18918640
02:35:15 1298 8192000000 9990056880 10367021056 1036931216 11.235 99 91 164 1 1111454272 57333632 17189424
02:36:17 6452 8192000000 9993073376 10378706944 1045110592 11.314 100 56 100 1 1123931424 67879104 10941728
02:37:24 938 8192000000 9992335072 10372247552 1063595648 11.491 100 86 162 4 1116871680 36588432 16687600
02:38:31 6384 8192000000 10019011328 10396905472 1070018320 11.552 99 76 144 0 1141151424 56384336 14748768
02:39:43 3643 8192000000 10047554944 10437021696 1091097264 11.753 100 69 127 1 1168870016 65111728 12661024
02:40:51 2306 8192000000 10086278336 10476986368 1102929552 11.865 100 71 131 0 1204671280 87003120 14738608
02:41:55 1565 8192000000 10088013568 10480091136 1117454320 12.003 100 98 184 2 1209487232 71787824 20245088
02:43:02 1619 8192000000 10131924784 10529898496 1122264112 12.048 100 76 148 1 1255512416 117692176 15556128
02:44:16 2179 8192000000 10187003920 10580398080 1142055280 12.235 100 86 167 2 1295112304 134547488 18509536
02:45:22 2495 8192000000 10193377440 10595725312 1149272672 12.303 100 74 137 0 1307398192 142513472 15612048
02:46:29 8332 8192000000 10241947632 10645946368 1168181792 12.480 100 82 152 0 1357773712 171796352 17795568
02:47:40 1691 8192000000 10291477136 10693570560 1178988016 12.581 100 96 182 3 1408939280 208621888 21329376
02:48:44 1020 8192000000 10321946112 10724233216 1177872288 12.570 100 92 174 2 1436787248 238504336 20410624
02:49:48 993 8192000000 10372135712 10779893760 1186164304 12.648 100 97 187 2 1481950592 273198816 22587472
02:50:54 1332 8192000000 10399375456 10802761728 1194216704 12.723 100 92 178 1 1503778192 289715200 19846288
02:51:58 807 8192000000 10436331456 10836295680 1188102656 12.666 100 98 191 0 1545470192 334947056 22420480
02:53:03 885 8192000000 10482175616 10890149888 1194128672 12.722 100 96 188 0 1592396880 376844736 21423472
02:54:06 811 8192000000 10487430496 10941095936 1190297152 12.686 100 98 191 0 1596522768 383007440 23218176
02:55:09 1128 8192000000 10520325568 10929147904 1189744464 12.681 100 96 188 0 1622921696 412002240 21174992
02:56:13 899 8192000000 10492218608 10949836800 1191160624 12.694 100 95 190 0 1596227088 383566752 21499712
02:57:16 1020 8192000000 10526385808 11000803328 1191471280 12.697 100 85 167 1 1629556080 417561568 20523232
02:58:21 10828 8192000000 10440923072 10872332288 1161349328 12.416 99 47 92 0 1533459200 359787360 12322512
02:59:58 8525 8192000000 10435511920 10882420736 1171948576 12.515 99 46 88 1 1532986400 350024752 11013072
03:01:11 6130 8192000000 10468987680 10960228352 1192000288 12.702 100 61 122 2 1582926640 376074096 14852256
03:02:29 15805 8192000000 10431417184 10883428352 1158692752 12.391 99 38 74 0 1525967040 356871440 10402848
03:03:47 9995 8192000000 10468032496 10936414208 1166176176 12.461 100 38 70 2 1582764192 407384720 9203296
03:05:01 33517 8192000000 10332534368 10791264256 1124418384 12.069 97 21 41 0 1418618160 288937728 5262048
03:06:35 33283 8192000000 10295352000 10754404352 1121997824 12.046 98 26 53 0 1380190272 252030912 6161536
03:08:10 2333 8192000000 10204086320 10703278080 1136746080 12.185 100 76 142 2 1305732528 152172736 16813712
03:09:14 17654 8192000000 10166748560 10618241024 1134222096 12.161 99 53 100 1 1243657968 98093872 11342000
03:10:43 71509 8192000000 10193980976 10641440768 1142723360 12.241 99 36 70 0 1294109648 143080784 8305504
03:13:14 65910 8192000000 10131245200 10668748800 1132506800 12.145 100 14 28 0 1251487920 115929600 3051520
03:15:24 55428 8192000000 10090149968 10530693120 1144835968 12.261 100 32 65 0 1205529184 53866512 6826704
03:17:21 49701 8192000000 10112317840 10539008000 1133524864 12.155 99 34 66 0 1199353712 58501536 7327312
03:19:13 46170 8192000000 10127457296 10592882688 1110341456 11.936 97 32 61 1 1200528016 83148320 7038240
03:21:15 85012 8192000000 10129475920 10629017600 1112012000 11.951 97 12 24 0 1185662512 70479440 3171072
03:23:40 99200 8192000000 10044925168 10475421696 1081147808 11.658 96 20 40 0 1116260656 30713584 4399264
03:26:28 32608 8192000000 10132720672 10588459008 1113942064 11.970 99 29 58 0 1200714368 80956496 5815808
03:28:59 116380 8192000000 10055752160 10541539328 1107488720 11.909 99 21 40 1 1159686704 47533088 4664896
03:31:56 15672 8192000000 10088371232 10513883136 1129173792 12.114 100 63 123 2 1182803824 39143808 14486224
03:33:16 28090 8192000000 10095869184 10522828800 1142147312 12.236 100 77 146 3 1203443440 42946752 18349376
03:34:45 50931 8192000000 10091460512 10534694912 1120627632 12.033 98 21 42 0 1177595952 52291872 4676448
03:36:36 68021 8192000000 10070005088 10564177920 1142388704 12.238 100 27 54 0 1191284016 44428224 4467088
03:38:47 101147 8192000000 10089521152 10553298944 1102746192 11.864 96 15 30 0 1131734768 26030608 2957968
03:41:28 96980 8192000000 10116436672 10618912768 1145634000 12.268 100 9 18 0 1210278080 62812688 1831392
03:44:10 82805 8192000000 10129138320 10595897344 1139354784 12.209 99 36 72 0 1254081152 107019488 7706880
03:46:34 40694 8192000000 10195608752 10622205952 1130573072 12.127 97 34 68 1 1240741104 102556080 7611952
03:48:15 106874 8192000000 10205405552 10686980096 1135902096 12.177 98 23 46 0 1299670912 158394816 5374000
03:51:09 52205 8192000000 10127097040 10561232896 1127196912 12.095 99 26 53 0 1209716528 76345776 6173840
03:53:02 31039 8192000000 10113653488 10543759360 1096771952 11.807 96 33 64 0 1165017792 61575968 6669872
03:54:34 7145 8192000000 10108069424 10526371840 1136465056 12.182 100 72 135 2 1191734304 41510112 13759136
03:55:45 17476 8192000000 10118178816 10547138560 1115106192 11.981 98 49 98 0 1196236080 71357520 9772368
03:57:07 98887 8192000000 10108819296 10572439552 1129676096 12.118 100 32 64 3 1249601120 112892880 7032144
03:59:54 36666 8192000000 10074680608 10500481024 1105858688 11.893 97 51 91 1 1154724112 40163696 8701728
04:01:31 73661 8192000000 10045627264 10543349760 1114302944 11.973 99 14 28 0 1177944496 60441568 3199984
04:03:49 59937 8192000000 10084960368 10535702528 1121549616 12.042 99 30 60 0 1173279856 45319648 6410592
04:05:50 90671 8192000000 10084919888 10559713280 1097654288 11.815 98 16 32 0 1199550752 97732480 4163984
04:08:33 104360 8192000000 10049202736 10525356032 1090337472 11.746 98 7 14 0 1147477504 55884176 1255856
04:11:21 34646 8192000000 10132077120 10559291392 1130112016 12.122 100 35 70 0 1208700464 71197584 7390864
04:12:58 23709 8192000000 10192258256 10624409600 1159335216 12.397 100 36 72 1 1282097664 113904464 8857984
04:14:27 11115 8192000000 10254248736 10685063168 1148668352 12.297 100 52 102 1 1352439296 192191568 11579376
04:15:45 35549 8192000000 10256540064 10696146944 1148640112 12.297 100 30 60 0 1348421456 192460672 7320672
04:17:24 20281 8192000000 10246717312 10679525376 1120956256 12.036 98 47 94 0 1323756880 191810080 10990544
04:18:46 36571 8192000000 10197147520 10679742464 1122044464 12.046 99 32 64 0 1300246704 171084032 7118208
04:20:36 56104 8192000000 10087364000 10521272320 1112327152 11.954 98 28 56 0 1196978992 79168848 5482992
04:22:44 100128 8192000000 10129959744 10588913664 1136977760 12.187 100 44 88 1 1236990448 90684560 9328128
04:25:39 63039 8192000000 10076890608 10540589056 1123132368 12.057 99 19 39 0 1185737840 58689488 3915984
04:27:43 81213 8192000000 10070508784 10562502656 1125850928 12.082 100 13 26 0 1211046592 81907040 3288624
04:31:06 44379 8192000000 10083349024 10561306624 1132186048 12.142 100 15 30 0 1194923904 59177792 3560064
04:32:50 34087 8192000000 10065148784 10540580864 1127129376 12.094 99 37 74 1 1178914816 43468064 8317376
04:34:32 68550 8192000000 10073051408 10561167360 1126856304 12.092 99 16 32 0 1170530976 39903536 3771136
04:37:01 27499 8192000000 9247800656 9547911168 368237920 4.301 32 15 30 0 427166128 55614352 3313856

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 22-12-2022 04:38:48 to 22-12-2022 04:39:04.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 106 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-12-2022 04:39:05 to 22-12-2022 04:39:07.

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          213892
MON$OLDEST_ACTIVE               213893
MON$OLDEST_SNAPSHOT             213893
MON$NEXT_TRANSACTION            213893
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                       2077408
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             760
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 ---
--- end of diff output ---

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

22.12.22 04:39:08. 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-22 04:39:11 No severe PSQL-related problems occured
22.12.22 04:39:12. End of report.