Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.10.2022 07:45
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 15142064 kB
MemAvailable 30485588 kB
Buffers 0 kB
Cached 16539828 kB
SwapCached 2172 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-14 07:45:02
DTS_END                         2022-10-14 07:45:02.1120
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/50162
TRN_ID                          217215
ATT_ID                          608
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: 9011.75 21586 1081410
customer order: creation 171.83 511 20619
customer order: refuse 14.28 471 1713
order to supplier: creation 44.38 1519 5326
order to supplier: removal 3.32 188331 398
invoice (draft): creation 44.08 3334 5289
invoice (draft): removal 51.19 1106 6143
invoice accept: apply 41.38 28433 4965
invoice accept: cancel 46.21 1296 5545
customer reserve: creation 3836.65 270 460398
customer reserve: removal 4465.25 107 535830
realization accept: apply 81.63 312 9795
realization accept: cancel 83.07 198 9968
payment from customer: creation 58.71 189 7045
payment from customer: removal 5.52 154 662
payment to supplier: creation 48.12 205 5774
payment to supplier: removal 3.57 152 428
service: total inventory turnovers 4.15 4375 498
service: total monetary turnovers 7.65 399 918
service: aggregate perf. data 0.76 18501 91
service: refresh index statistics 0.04 181847 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 14-10-2022 07:46:05 to 14-10-2022 07:46:08.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5512
TEST_TIME 2 5278
TEST_TIME 3 5031
TEST_TIME 4 5355
TEST_TIME 5 5302
TEST_TIME 6 5253
TEST_TIME 7 5469
TEST_TIME 8 5796
TEST_TIME 9 5958
TEST_TIME 10 6083
TEST_TIME 11 6244
TEST_TIME 12 6368
TEST_TIME 13 6700
TEST_TIME 14 6745
TEST_TIME 15 6638
TEST_TIME 16 6826
TEST_TIME 17 6866
TEST_TIME 18 6895
TEST_TIME 19 6867
TEST_TIME 20 7088
TEST_TIME 21 7149
TEST_TIME 22 7220
TEST_TIME 23 7392
TEST_TIME 24 7546
TEST_TIME 25 7655
TEST_TIME 26 7850
TEST_TIME 27 7975
TEST_TIME 28 7951
TEST_TIME 29 7815
TEST_TIME 30 8103
TEST_TIME 31 8770
TEST_TIME 32 9051
TEST_TIME 33 9211
TEST_TIME 34 9714
TEST_TIME 35 9794
TEST_TIME 36 10196
TEST_TIME 37 10581
TEST_TIME 38 10425
TEST_TIME 39 10334
TEST_TIME 40 10553
TEST_TIME 41 10480
TEST_TIME 42 10386
TEST_TIME 43 10302
TEST_TIME 44 10289
TEST_TIME 45 10151
TEST_TIME 46 10000
TEST_TIME 47 10035
TEST_TIME 48 9836
TEST_TIME 49 9883
TEST_TIME 50 10003
TEST_TIME 51 9878
TEST_TIME 52 9776
TEST_TIME 53 9767
TEST_TIME 54 9693
TEST_TIME 55 9604
TEST_TIME 56 9614
TEST_TIME 57 9568
TEST_TIME 58 9607
TEST_TIME 59 9605
TEST_TIME 60 9655
TEST_TIME 61 9699
TEST_TIME 62 9653
TEST_TIME 63 9703
TEST_TIME 64 9630
TEST_TIME 65 9618
TEST_TIME 66 9589
TEST_TIME 67 9558
TEST_TIME 68 9622
TEST_TIME 69 9670
TEST_TIME 70 9655
TEST_TIME 71 9625
TEST_TIME 72 9658
TEST_TIME 73 9654
TEST_TIME 74 9690
TEST_TIME 75 9653
TEST_TIME 76 9645
TEST_TIME 77 9652
TEST_TIME 78 9544
TEST_TIME 79 9476
TEST_TIME 80 9419
TEST_TIME 81 9436
TEST_TIME 82 9388
TEST_TIME 83 9332
TEST_TIME 84 9324
TEST_TIME 85 9290
TEST_TIME 86 9237
TEST_TIME 87 9183
TEST_TIME 88 9229
TEST_TIME 89 9215
TEST_TIME 90 9261
TEST_TIME 91 9256
TEST_TIME 92 9288
TEST_TIME 93 9296
TEST_TIME 94 9281
TEST_TIME 95 9315
TEST_TIME 96 9284
TEST_TIME 97 9339
TEST_TIME 98 9323
TEST_TIME 99 9370
TEST_TIME 100 9365
TEST_TIME 101 9410
TEST_TIME 102 9346
TEST_TIME 103 9295
TEST_TIME 104 9266
TEST_TIME 105 9264
TEST_TIME 106 9234
TEST_TIME 107 9181
TEST_TIME 108 9159
TEST_TIME 109 9108
TEST_TIME 110 9123
TEST_TIME 111 9100
TEST_TIME 112 9098
TEST_TIME 113 9107
TEST_TIME 114 9081
TEST_TIME 115 9064
TEST_TIME 116 9075
TEST_TIME 117 9062
TEST_TIME 118 9080
TEST_TIME 119 9104
TEST_TIME 120 9028

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-10-2022 07:46:09 to 14-10-2022 07:46:12.

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 1505204 1505204 0 0.00 0 33727 15 0 0
doc_list_biud 1505197 1505197 0 0.00 0 74087 45 0 0
sp_add_invoice_to_stock 5117 4965 152 2.97 36 254999 28434 0 152
sp_cancel_adding_invoice 5545 5545 0 0.00 7 114212 1297 0 0
sp_cancel_client_order 1713 1713 0 0.00 6 16747 471 0 0
sp_cancel_customer_reserve 535830 535830 0 0.00 1 74091 108 0 0
sp_cancel_pay_from_customer 662 662 0 0.00 4 7819 154 0 0
sp_cancel_pay_to_supplier 428 428 0 0.00 4 5676 153 0 0
sp_cancel_supplier_invoice 6152 6143 9 0.14 2 17241 1106 0 9
sp_cancel_supplier_order 398 398 0 0.00 11 1556859 188332 0 0
sp_cancel_write_off 9968 9968 0 0.00 2 27633 198 0 0
sp_client_order 20619 20619 0 0.00 6 37600 512 0 0
sp_customer_reserve 460398 460398 0 0.00 1 53270 270 0 0
sp_fill_shopping_cart 31218 31218 0 0.00 2 13191 87 0 0
sp_fill_shopping_cart_clo_res 460372 460372 0 0.00 0 19512 19 0 0
sp_get_clo_for_invoice 4931 4931 0 0.00 0 36749 285 0 0
sp_kill_cost_storno 16603 16603 0 0.00 0 27625 91 0 0
sp_kill_qstorno_ret_qs2qd 1088169 1088169 0 0.00 0 74077 47 0 0
sp_kill_qty_storno 574323 574323 0 0.00 0 74082 109 0 0
sp_lock_selected_doc 20358 20358 0 0.00 0 7221 8 0 0
sp_make_cost_storno 27544 27544 0 0.00 0 33717 92 0 0
sp_multiply_rows_for_pdistr 27544 27544 0 0.00 0 7801 42 0 0
sp_multiply_rows_for_qdistr 20619 20619 0 0.00 0 13523 181 0 0
sp_pay_from_customer 7045 7045 0 0.00 6 13114 190 0 0
sp_pay_to_supplier 5774 5774 0 0.00 7 8415 205 0 0
sp_payment_common 12818 12818 0 0.00 2 13089 50 0 0
sp_qd_handle_on_cancel_clo 1713 1713 0 0.00 0 16722 402 0 0
sp_qd_handle_on_invoice_upd_sts 10476 10476 0 0.00 0 20751 589 0 0
sp_qd_handle_on_reserve_upd_sts 9795 9795 0 0.00 0 23532 75 0 0
sp_reserve_write_off 9795 9795 0 0.00 8 23573 313 0 0
sp_supplier_invoice 5289 5289 0 0.00 8 96271 3334 0 0
sp_supplier_order 5326 5326 0 0.00 7 21461 1519 0 0
srv_aggregate_perf_data 91 91 0 0.00 2695 167650 18501 0 0
srv_fill_mon_cache_memory 74 74 0 0.00 779 176645 30620 0 0
srv_find_qd_qs_mism 1032825 1032825 0 0.00 0 19654 11 0 0
srv_make_invnt_saldo 498 498 0 0.00 48 86216 4375 0 0
srv_make_money_saldo 918 918 0 0.00 1 10237 399 0 0
srv_recalc_idx_stat 5 5 0 0.00 111738 336708 181847 0 0
t$perf-abend:sp_add_invoice_to_stock 152 152 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4971 4971 0 0.00 1 14458 1034 0 0
t$perf-norm:sp_cancel_adding_invoice 409 409 0 0.00 1 6929 507 0 0
t$perf-norm:sp_cancel_client_order 1713 1713 0 0.00 0 1769 26 0 0
t$perf-norm:sp_cancel_customer_reserve 651 651 0 0.00 0 1251 33 0 0
t$perf-norm:sp_cancel_pay_from_customer 662 662 0 0.00 0 5267 29 0 0
t$perf-norm:sp_cancel_pay_to_supplier 428 428 0 0.00 0 2728 20 0 0
t$perf-norm:sp_cancel_supplier_invoice 407 407 0 0.00 0 1637 33 0 0
t$perf-norm:sp_cancel_supplier_order 398 398 0 0.00 0 123848 7865 0 0
t$perf-norm:sp_cancel_write_off 966 966 0 0.00 0 7476 29 0 0
t$perf-norm:sp_client_order 20619 20619 0 0.00 0 5132 41 0 0
t$perf-norm:sp_customer_reserve 938 938 0 0.00 0 2360 20 0 0
t$perf-norm:sp_pay_from_customer 7045 7045 0 0.00 0 4733 24 0 0
t$perf-norm:sp_pay_to_supplier 5774 5774 0 0.00 0 2650 20 0 0
t$perf-norm:sp_reserve_write_off 9795 9795 0 0.00 0 3618 24 0 0
t$perf-norm:sp_supplier_invoice 5289 5289 0 0.00 0 4754 33 0 0
t$perf-norm:sp_supplier_order 5326 5326 0 0.00 0 13879 38 0 0
t$perf-norm:srv_aggregate_perf_data 91 91 0 0.00 0 354 25 0 0
t$perf-norm:srv_fill_mon_cache_memory 74 74 0 0.00 0 865 27 0 0
t$perf-norm:srv_make_invnt_saldo 498 498 0 0.00 0 1821 25 0 0
t$perf-norm:srv_make_money_saldo 918 918 0 0.00 0 8474 14 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 7 414 116 0 0
v_add_invoice_to_stock 10162 9858 304 2.99 0 4228 26 0 304
v_all_customers 37720 37720 0 0.00 0 73 4 0 0
v_all_suppliers 29944 29944 0 0.00 0 469 5 0 0
v_all_wares 41238 41238 0 0.00 0 170 4 0 0
v_cancel_adding_invoice 818 818 0 0.00 0 1261 15 0 0
v_cancel_client_order 3426 3426 0 0.00 0 1311 10 0 0
v_cancel_customer_prepayment 1324 1324 0 0.00 0 3003 17 0 0
v_cancel_customer_reserve 1302 1302 0 0.00 1 3292 15 0 0
v_cancel_payment_to_supplier 856 856 0 0.00 0 4856 20 0 0
v_cancel_supplier_invoice 832 814 18 2.16 0 1382 24 0 18
v_cancel_supplier_order 796 796 0 0.00 0 1671 17 0 0
v_cancel_write_off 1932 1932 0 0.00 0 2944 26 0 0
v_max_id_clo_ord 5322 5322 0 0.00 0 355 5 0 0
v_max_id_clo_res 938 938 0 0.00 0 2045 15 0 0
v_max_id_ord_sup 5277 5277 0 0.00 0 3019 6 0 0
v_max_non_paid_invoice 1401 1401 0 0.00 0 223 8 0 0
v_max_non_paid_realizn 3699 3699 0 0.00 0 4532 10 0 0
v_min_id_clo_ord 5322 5322 0 0.00 0 5544 11 0 0
v_min_id_clo_res 938 938 0 0.00 0 1045 10 0 0
v_min_id_ord_sup 5277 5277 0 0.00 0 9215 10 0 0
v_min_non_paid_invoice 5774 5774 0 0.00 0 3377 12 0 0
v_min_non_paid_realizn 7044 7044 0 0.00 0 8026 22 0 0
v_our_firm 10208 10208 0 0.00 0 48 5 0 0
v_reserve_write_off 19590 19590 0 0.00 0 2516 11 0 0
x_lock_depdocs_on_canc_invoice 11281 11281 0 0.00 0 27382 138 0 0
x_lock_depdocs_on_canc_sup_ord 398 398 0 0.00 0 20589 484 0 0
x_make_qstorno_1000_1200 5326 5326 0 0.00 3 21447 1341 0 0
x_make_qstorno_1200_2000 5289 5289 0 0.00 3 95691 3086 0 0
x_make_qstorno_2100_3300 460397 460397 0 0.00 0 47950 237 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-10-2022 07:46:13 to 14-10-2022 07:46:17.

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
05:15:06 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
05:16:06 32221 8192000000 9728833616 10026528768 799876288 8.895 100 87 174 0 828741200 7457712 21407200
05:17:56 19425 8192000000 9773754576 10082553856 845823712 9.358 100 88 176 0 880660208 13628304 21208192
05:19:28 9711 8192000000 9819259040 10138451968 879805216 9.698 100 90 180 0 928402928 27520512 21077200
05:20:41 12165 8192000000 9867315232 10207211520 921871712 10.115 100 69 138 0 979767472 43165824 14729936
05:22:01 3396 8192000000 9933606400 10279473152 961479472 10.503 100 89 176 0 1044766784 64923584 18363728
05:23:09 1817 8192000000 9967517216 10318860288 993987440 10.820 100 90 172 0 1078095536 67241456 16866640
05:24:13 1333 8192000000 10016073504 10378993664 1017307296 11.046 100 94 182 1 1137386656 102633600 17445760
05:25:17 1086 8192000000 9999159264 10369568768 1036162320 11.228 100 95 180 1 1123921280 69737712 18021248
05:26:19 1351 8192000000 10033695296 10408935424 1058585760 11.443 100 96 185 1 1145434384 68777008 18071616
05:27:22 985 8192000000 10054350512 10430713856 1068988736 11.542 100 92 180 0 1177701216 90729488 17982992
05:28:25 1095 8192000000 10040169376 10422132736 1078079472 11.629 100 86 162 1 1161284464 66360192 16844800
05:29:27 1701 8192000000 10031629936 10415386624 1079259152 11.640 100 85 137 2 1157702304 64359360 14083792
05:30:30 5885 8192000000 10045530944 10438639616 1082861744 11.675 100 62 118 1 1168286640 73472944 11951952
05:31:38 1220 8192000000 10067070352 10452295680 1089338352 11.736 100 95 183 1 1190944896 82507952 19098592
05:32:41 1051 8192000000 10067097168 10455212032 1090437504 11.747 100 94 180 0 1188757872 79842480 18477888
05:33:43 1427 8192000000 10067060016 10459566080 1093074848 11.772 100 83 165 1 1192475328 82928864 16471616
05:34:46 1242 8192000000 10076256992 10463371264 1095765008 11.797 100 99 191 2 1198406560 83888512 18753040
05:35:48 1034 8192000000 10040216672 10425245696 1078824752 11.636 100 95 186 1 1162525472 65258368 18442352
05:36:50 1271 8192000000 10052785056 10440847360 1082598608 11.672 100 97 188 2 1176226240 73004736 20622896
05:37:53 1070 8192000000 10049347280 10432720896 1081073040 11.658 100 93 183 2 1170849440 70803280 18973120
05:38:55 819 8192000000 9999031136 10391195648 1061285808 11.469 99 73 130 0 1125660368 50679024 13695536
05:39:57 998 8192000000 10035388480 10416726016 1068274352 11.536 100 95 180 0 1152988320 65416624 19297344
05:40:59 1206 8192000000 10000044256 10374074368 1040506544 11.270 100 95 176 2 1118628496 61201568 16920384
05:42:00 7178 8192000000 9972489408 10339381248 1000753568 10.886 97 69 136 1 1059165696 45098288 13313840
05:43:09 1073 8192000000 10000495584 10373062656 1031863968 11.186 100 94 181 0 1122749712 72417392 18468352
05:44:11 4942 8192000000 9995227456 10380247040 1036404160 11.230 100 59 114 0 1117419648 69432064 11583424
05:45:23 2116 8192000000 9975871840 10349150208 1048511792 11.346 100 77 129 2 1091328080 29799136 13017152
05:46:30 12321 8192000000 10031585040 10417053696 1071371072 11.565 100 67 131 0 1149780784 65479104 12930608
05:47:46 3686 8192000000 10082867200 10470854656 1088852768 11.732 100 93 182 0 1208680864 100651200 19176896
05:48:59 4533 8192000000 10099686880 10494021632 1097816496 11.817 100 69 128 0 1222473616 111178000 13479120
05:50:10 5472 8192000000 10113747968 10519040000 1100322224 11.841 100 81 156 0 1225118592 107789072 17007296
05:51:50 15138 8192000000 10136015200 10524897280 1111595936 11.948 100 78 144 2 1239057008 113204176 14256896
05:53:19 2362 8192000000 10165008176 10565898240 1123641360 12.061 100 91 168 2 1275093728 134381872 17070496
05:54:27 1084 8192000000 10189564336 10585718784 1125068368 12.075 99 89 168 1 1297423728 153702944 18652416
05:55:31 2761 8192000000 10214733472 10624917504 1143571216 12.249 100 83 164 0 1332244144 170824080 17848848
05:56:38 6086 8192000000 10245949968 10645143552 1157452624 12.379 100 82 161 1 1358187952 182526256 18209072
05:57:49 1801 8192000000 10318460016 10719145984 1161567296 12.418 100 92 179 0 1424707872 243402672 19737904
05:58:55 1011 8192000000 10301197696 10707619840 1161836576 12.420 100 93 176 0 1407583104 226025232 19721296
06:00:02 913 8192000000 10332978976 10741989376 1180094304 12.591 100 90 178 0 1448142912 246688880 21359728
06:01:11 1224 8192000000 10370628976 10787069952 1182722000 12.616 100 96 191 0 1481087632 276876704 21488928
06:02:17 2912 8192000000 10397732848 10803560448 1190566816 12.689 100 92 177 3 1509883520 298926800 20389904
06:03:25 966 8192000000 10430025712 10835902464 1190551296 12.688 100 98 183 2 1536600128 325100096 20948736
06:04:29 1302 8192000000 10479978192 10899546112 1198910112 12.766 100 97 189 2 1589050816 367856240 22284464
06:05:37 1450 8192000000 10498207168 10915422208 1201006928 12.786 100 91 171 1 1600144976 379214272 19923776
06:06:42 798 8192000000 10521003200 10946404352 1204861024 12.821 100 97 191 0 1626941504 399137936 22942544
06:07:45 885 8192000000 10514263712 10948218880 1191541024 12.698 100 99 196 1 1619971600 403643008 24787568
06:08:50 1296 8192000000 10558287824 10988658688 1201079696 12.786 100 97 195 0 1664729936 441018912 22631328
06:09:56 775 8192000000 10534138704 10972082176 1183863616 12.626 100 99 188 1 1637922128 431134592 22923920
06:11:00 787 8192000000 10562891264 11001774080 1183686144 12.625 100 95 185 0 1667991488 461624256 22681088
06:12:04 796 8192000000 10589355600 11013865472 1185174816 12.638 100 78 155 0 1697876912 493719152 18982944
06:13:16 7999 8192000000 10594215536 11041689600 1163769280 12.439 99 62 119 1 1696219072 516305840 16143952
06:14:42 8129 8192000000 10645397520 11110588416 1160144048 12.405 100 51 102 0 1759227792 584402800 14680944
06:15:59 7318 8192000000 10562051360 11080691712 1154008544 12.347 100 54 107 0 1695812720 527821520 13982656
06:17:08 26667 8192000000 10464182640 10976022528 1152617168 12.334 100 39 78 0 1580656176 418039264 9999744
06:18:38 13852 8192000000 10409497296 10900545536 1132846032 12.148 99 29 58 0 1534924192 394820080 7258080
06:19:55 14740 8192000000 10311425728 10799411200 1135019520 12.169 100 25 48 0 1407471296 266669376 5782400
06:21:14 46897 8192000000 10097252128 10594734080 1075485040 11.604 96 27 52 0 1294441120 212442752 6513328
06:23:06 52545 8192000000 10086904176 10518802432 1103665104 11.872 98 20 37 0 1152062944 45058928 3338912
06:25:03 72140 8192000000 10076563728 10581069824 1131621984 12.137 100 12 24 1 1185185840 51357200 2206656
06:27:20 50315 8192000000 10073219776 10531409920 1131277520 12.133 99 32 58 0 1184971904 47454848 6239536
06:29:11 57530 8192000000 10090659392 10546290688 1150519328 12.314 100 19 38 0 1215891872 61430656 3941888
06:31:10 176645 8192000000 10174053904 10638262272 1122564496 12.051 98 39 75 0 1233843200 102103712 9174992
06:35:16 95718 8192000000 10090569664 10550710272 1149452928 12.304 100 31 62 1 1213034800 56945552 6636320
06:38:02 2344 8192000000 10116785664 10543620096 1146160816 12.273 100 69 128 0 1207746352 46403872 15181664
06:39:08 13407 8192000000 10136722480 10568896512 1147832640 12.289 100 50 96 1 1227609024 69314896 10461488
06:40:22 14314 8192000000 10185261296 10613547008 1151270320 12.321 100 65 125 0 1263895408 98360144 14264944
06:41:39 46036 8192000000 10209455664 10664050688 1135188464 12.170 98 33 66 1 1311033888 168565504 7279920
06:43:27 43337 8192000000 10212747696 10723422208 1147401632 12.285 100 18 37 0 1319499792 167322320 4775840
06:45:55 30873 8192000000 10128988320 10612649984 1139240832 12.208 100 14 28 0 1251143216 108659264 3243120
06:48:38 53265 8192000000 10067983152 10559070208 1117989216 12.008 98 10 20 0 1156051344 36040304 2021824
06:50:37 40465 8192000000 10110681936 10580471808 1152156192 12.330 100 26 51 1 1215018176 57669424 5192560
06:52:30 22353 8192000000 10141367264 10578980864 1144555728 12.258 100 49 98 0 1220984384 66894688 9533968
06:53:56 31262 8192000000 10126111568 10581426176 1134189376 12.161 99 28 56 0 1214386272 74885136 5311760
06:55:27 47888 8192000000 10094178416 10543902720 1090563040 11.748 95 34 68 0 1124332176 26934384 6834752
06:57:16 42061 8192000000 10099276320 10581397504 1133883856 12.158 100 16 32 0 1186525984 49580048 3062080
06:58:59 49174 8192000000 10080136976 10569850880 1114323728 11.973 99 19 37 1 1171224480 53521056 3379696
07:00:51 22088 8192000000 10114787200 10552020992 1134042960 12.159 100 42 82 0 1197661376 55284816 8333600
07:02:21 111290 8192000000 10061127072 10528612352 1103211648 11.868 98 29 57 0 1162080272 52819936 6048688
07:05:18 29306 8192000000 10072603008 10511921152 1099580736 11.834 99 34 68 0 1169956400 63503088 6872576
07:06:49 51890 8192000000 10059972016 10496983040 1083405664 11.680 97 27 54 0 1124292688 35271760 5615264
07:08:43 37927 8192000000 10082600624 10536611840 1110068352 11.933 99 24 48 0 1198292752 83050384 5174016
07:10:30 102718 8192000000 10098124240 10570801152 1059727040 11.454 96 25 49 1 1173713712 108116368 5870304
07:13:14 12705 8192000000 10099082496 10532270080 1099587264 11.834 100 32 62 1 1185722720 79611776 6523680
07:14:30 76797 8192000000 10062653888 10556379136 1086727728 11.712 98 22 44 0 1190078672 98641024 4709920
07:16:47 33843 8192000000 10042939984 10488913920 1096834896 11.808 99 22 42 0 1124017952 22707088 4475968
07:18:21 21344 8192000000 10092749520 10525212672 1090080400 11.743 97 41 82 0 1149893760 51746976 8066384
07:19:58 12169 8192000000 10112551888 10538184704 1128823824 12.110 100 50 98 0 1188608448 50071744 9712880
07:21:15 14878 8192000000 10086204032 10516926464 1117573248 12.004 99 36 70 1 1155290480 31030672 6686560
07:22:30 95550 8192000000 10111641840 10587242496 1091101536 11.753 96 18 36 1 1194546576 99334400 4110640
07:25:07 9696 8192000000 10128680912 10541727744 1132648800 12.146 100 60 118 0 1204133584 59661232 11823552
07:26:19 56352 8192000000 10083574144 10529869824 1118729136 12.015 99 32 64 0 1189198016 64393568 6075312
07:28:17 105450 8192000000 10081918752 10559389696 1108131776 11.915 98 30 60 0 1184951776 70007664 6812336
07:31:03 53185 8192000000 10056478800 10538565632 1108517632 11.918 98 14 28 0 1153647920 42308640 2821648
07:32:58 55797 8192000000 10092850096 10572734464 1136324736 12.181 100 13 26 0 1191282272 52296624 2660912
07:35:05 82136 8192000000 10086267872 10540310528 1110724272 11.939 97 16 32 1 1160710528 46560080 3426176
07:37:56 42812 8192000000 10046020000 10506543104 1101439712 11.851 98 17 34 1 1149243840 44204864 3599264
07:39:40 11496 8192000000 10100227600 10525941760 1124037600 12.065 99 53 104 2 1169626160 35252064 10336496
07:40:54 7005 8192000000 10141581184 10579701760 1136782576 12.185 100 50 98 1 1247801968 100430112 10589280
07:42:03 19169 8192000000 10151436496 10603184128 1132217056 12.142 99 43 83 1 1244132656 102266640 9648960
07:43:29 60216 8192000000 10167249504 10616377344 1132642656 12.146 99 29 58 0 1236027728 97290864 6094208

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 14-10-2022 07:46:19 to 14-10-2022 07:46:36.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 152 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

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

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          211322
MON$OLDEST_ACTIVE               218046
MON$OLDEST_SNAPSHOT             218046
MON$NEXT_TRANSACTION            218046
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                       2069728
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             767
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2859 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2859 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2859 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2859 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'

14.10.22 07:46:40. 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-14 07:46:42 No severe PSQL-related problems occured
14.10.22 07:46:44. End of report.