Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.07.2022 07:48
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 15782532 kB
MemAvailable 31030756 kB
Buffers 0 kB
Cached 15902556 kB
SwapCached 2500 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.2
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-07-23 07:47:43
DTS_END                         2022-07-23 07:47:43.7800
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/33984
TRN_ID                          220059
ATT_ID                          589
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: 9251.08 24335 1110129
customer order: creation 171.38 464 20565
customer order: refuse 15.05 376 1806
order to supplier: creation 46.92 1245 5630
order to supplier: removal 3.64 139680 437
invoice (draft): creation 45.83 3021 5500
invoice (draft): removal 53.76 821 6451
invoice accept: apply 41.83 25712 5019
invoice accept: cancel 48.38 1053 5806
customer reserve: creation 3788.93 251 454672
customer reserve: removal 4725.11 84 567013
realization accept: apply 83.61 282 10033
realization accept: cancel 96.40 146 11568
payment from customer: creation 60.63 179 7276
payment from customer: removal 5.68 118 681
payment to supplier: creation 48.42 189 5810
payment to supplier: removal 3.25 159 390
service: total inventory turnovers 3.98 3702 477
service: total monetary turnovers 7.23 294 868
service: aggregate perf. data 1.03 13103 124
service: refresh index statistics 0.03 295825 3

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 23-07-2022 07:48:27 to 23-07-2022 07:48:29.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5505
TEST_TIME 2 4888
TEST_TIME 3 4892
TEST_TIME 4 4459
TEST_TIME 5 4503
TEST_TIME 6 5028
TEST_TIME 7 5177
TEST_TIME 8 5711
TEST_TIME 9 6034
TEST_TIME 10 6148
TEST_TIME 11 6327
TEST_TIME 12 6706
TEST_TIME 13 7007
TEST_TIME 14 7091
TEST_TIME 15 7082
TEST_TIME 16 7249
TEST_TIME 17 7419
TEST_TIME 18 7420
TEST_TIME 19 7591
TEST_TIME 20 7910
TEST_TIME 21 8061
TEST_TIME 22 8930
TEST_TIME 23 9087
TEST_TIME 24 9057
TEST_TIME 25 9271
TEST_TIME 26 9428
TEST_TIME 27 9561
TEST_TIME 28 9954
TEST_TIME 29 9900
TEST_TIME 30 10336
TEST_TIME 31 10528
TEST_TIME 32 10553
TEST_TIME 33 10776
TEST_TIME 34 10751
TEST_TIME 35 10786
TEST_TIME 36 10667
TEST_TIME 37 10767
TEST_TIME 38 10692
TEST_TIME 39 10524
TEST_TIME 40 10394
TEST_TIME 41 10464
TEST_TIME 42 10574
TEST_TIME 43 10527
TEST_TIME 44 10533
TEST_TIME 45 10328
TEST_TIME 46 10260
TEST_TIME 47 10291
TEST_TIME 48 10101
TEST_TIME 49 10078
TEST_TIME 50 10039
TEST_TIME 51 9974
TEST_TIME 52 9931
TEST_TIME 53 9933
TEST_TIME 54 9826
TEST_TIME 55 9787
TEST_TIME 56 9840
TEST_TIME 57 9852
TEST_TIME 58 9729
TEST_TIME 59 9768
TEST_TIME 60 9668
TEST_TIME 61 9666
TEST_TIME 62 9755
TEST_TIME 63 9703
TEST_TIME 64 9731
TEST_TIME 65 9767
TEST_TIME 66 9770
TEST_TIME 67 9816
TEST_TIME 68 9771
TEST_TIME 69 9770
TEST_TIME 70 9804
TEST_TIME 71 9771
TEST_TIME 72 9729
TEST_TIME 73 9875
TEST_TIME 74 9862
TEST_TIME 75 9795
TEST_TIME 76 9688
TEST_TIME 77 9632
TEST_TIME 78 9621
TEST_TIME 79 9598
TEST_TIME 80 9541
TEST_TIME 81 9507
TEST_TIME 82 9452
TEST_TIME 83 9393
TEST_TIME 84 9358
TEST_TIME 85 9333
TEST_TIME 86 9357
TEST_TIME 87 9312
TEST_TIME 88 9292
TEST_TIME 89 9252
TEST_TIME 90 9224
TEST_TIME 91 9268
TEST_TIME 92 9288
TEST_TIME 93 9304
TEST_TIME 94 9394
TEST_TIME 95 9414
TEST_TIME 96 9400
TEST_TIME 97 9439
TEST_TIME 98 9417
TEST_TIME 99 9431
TEST_TIME 100 9355
TEST_TIME 101 9349
TEST_TIME 102 9332
TEST_TIME 103 9289
TEST_TIME 104 9243
TEST_TIME 105 9178
TEST_TIME 106 9215
TEST_TIME 107 9151
TEST_TIME 108 9120
TEST_TIME 109 9089
TEST_TIME 110 9114
TEST_TIME 111 9207
TEST_TIME 112 9191
TEST_TIME 113 9171
TEST_TIME 114 9187
TEST_TIME 115 9132
TEST_TIME 116 9180
TEST_TIME 117 9213
TEST_TIME 118 9260
TEST_TIME 119 9269
TEST_TIME 120 9277

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 23-07-2022 07:48:31 to 23-07-2022 07:48:34.

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 1530085 1530085 0 0.00 0 18600 13 0 0
doc_list_biud 1530074 1530074 0 0.00 0 48638 37 0 0
sp_add_invoice_to_stock 5157 5019 138 2.67 43 194324 25713 0 138
sp_cancel_adding_invoice 5806 5806 0 0.00 6 116420 1054 0 0
sp_cancel_client_order 1806 1806 0 0.00 5 10955 376 0 0
sp_cancel_customer_reserve 567013 567013 0 0.00 1 35861 85 0 0
sp_cancel_pay_from_customer 681 681 0 0.00 3 6412 119 0 0
sp_cancel_pay_to_supplier 390 390 0 0.00 4 12768 160 0 0
sp_cancel_supplier_invoice 6465 6451 14 0.21 3 48751 822 0 14
sp_cancel_supplier_order 437 437 0 0.00 8 1246897 139681 0 0
sp_cancel_write_off 11568 11568 0 0.00 2 11396 147 0 0
sp_client_order 20565 20565 0 0.00 5 31329 465 0 0
sp_customer_reserve 454672 454672 0 0.00 1 132034 251 0 0
sp_fill_shopping_cart 31682 31682 0 0.00 1 12839 78 0 0
sp_fill_shopping_cart_clo_res 454646 454646 0 0.00 0 13767 17 0 0
sp_get_clo_for_invoice 4983 4983 0 0.00 0 33905 272 0 0
sp_kill_cost_storno 18445 18445 0 0.00 0 6896 63 0 0
sp_kill_qstorno_ret_qs2qd 1152482 1152482 0 0.00 0 48635 37 0 0
sp_kill_qty_storno 608096 608096 0 0.00 0 48638 86 0 0
sp_lock_selected_doc 20814 20814 0 0.00 0 7729 6 0 0
sp_make_cost_storno 28102 28102 0 0.00 0 13641 77 0 0
sp_multiply_rows_for_pdistr 28102 28102 0 0.00 0 12570 39 0 0
sp_multiply_rows_for_qdistr 20563 20563 0 0.00 0 15294 177 0 0
sp_pay_from_customer 7276 7276 0 0.00 6 12621 180 0 0
sp_pay_to_supplier 5810 5810 0 0.00 6 14781 190 0 0
sp_payment_common 13084 13084 0 0.00 2 13578 44 0 0
sp_qd_handle_on_cancel_clo 1806 1806 0 0.00 0 10948 311 0 0
sp_qd_handle_on_invoice_upd_sts 10788 10788 0 0.00 0 32388 481 0 0
sp_qd_handle_on_reserve_upd_sts 10033 10033 0 0.00 0 19232 77 0 0
sp_reserve_write_off 10033 10033 0 0.00 8 19622 282 0 0
sp_supplier_invoice 5500 5500 0 0.00 10 104308 3022 0 0
sp_supplier_order 5630 5630 0 0.00 8 46075 1246 0 0
srv_aggregate_perf_data 124 124 0 0.00 2223 173934 13104 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 781 152131 38366 0 0
srv_find_qd_qs_mism 1062026 1062026 0 0.00 0 11454 10 0 0
srv_make_invnt_saldo 477 477 0 0.00 25 111549 3702 0 0
srv_make_money_saldo 868 868 0 0.00 1 6553 294 0 0
srv_recalc_idx_stat 3 3 0 0.00 248334 381858 295825 0 0
t$perf-abend:sp_add_invoice_to_stock 138 138 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5027 5027 0 0.00 1 10009 896 0 0
t$perf-norm:sp_cancel_adding_invoice 394 394 0 0.00 1 7225 507 0 0
t$perf-norm:sp_cancel_client_order 1806 1806 0 0.00 0 1105 22 0 0
t$perf-norm:sp_cancel_customer_reserve 635 635 0 0.00 0 2982 32 0 0
t$perf-norm:sp_cancel_pay_from_customer 681 681 0 0.00 0 559 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 390 390 0 0.00 0 327 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 435 435 0 0.00 0 663 38 0 0
t$perf-norm:sp_cancel_supplier_order 437 437 0 0.00 0 91739 6525 0 0
t$perf-norm:sp_cancel_write_off 1026 1026 0 0.00 0 820 17 0 0
t$perf-norm:sp_client_order 20565 20565 0 0.00 0 8673 35 0 0
t$perf-norm:sp_customer_reserve 1063 1063 0 0.00 0 2941 17 0 0
t$perf-norm:sp_pay_from_customer 7276 7276 0 0.00 0 3962 17 0 0
t$perf-norm:sp_pay_to_supplier 5810 5810 0 0.00 0 8856 22 0 0
t$perf-norm:sp_reserve_write_off 10033 10033 0 0.00 0 24652 25 0 0
t$perf-norm:sp_supplier_invoice 5500 5500 0 0.00 0 12605 33 0 0
t$perf-norm:sp_supplier_order 5630 5630 0 0.00 0 23652 36 0 0
t$perf-norm:srv_aggregate_perf_data 124 124 0 0.00 0 458 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 51 4 0 0
t$perf-norm:srv_make_invnt_saldo 477 477 0 0.00 0 728 20 0 0
t$perf-norm:srv_make_money_saldo 868 868 0 0.00 0 1645 9 0 0
t$perf-norm:srv_recalc_idx_stat 3 3 0 0.00 4 18 10 0 0
v_add_invoice_to_stock 10230 9954 276 2.69 0 5077 24 0 276
v_all_customers 38027 38027 0 0.00 0 179 4 0 0
v_all_suppliers 30580 30580 0 0.00 0 120 4 0 0
v_all_wares 41120 41120 0 0.00 0 98 4 0 0
v_cancel_adding_invoice 788 788 0 0.00 0 2192 16 0 0
v_cancel_client_order 3612 3612 0 0.00 0 1937 10 0 0
v_cancel_customer_prepayment 1362 1362 0 0.00 0 1650 12 0 0
v_cancel_customer_reserve 1270 1270 0 0.00 0 6984 17 0 0
v_cancel_payment_to_supplier 780 780 0 0.00 0 9014 22 0 0
v_cancel_supplier_invoice 898 870 28 3.11 0 7321 51 0 28
v_cancel_supplier_order 874 874 0 0.00 0 6918 20 0 0
v_cancel_write_off 2052 2052 0 0.00 0 7047 32 0 0
v_max_id_clo_ord 5628 5628 0 0.00 0 6561 6 0 0
v_max_id_clo_res 1063 1063 0 0.00 0 4656 18 0 0
v_max_id_ord_sup 5493 5493 0 0.00 0 1668 5 0 0
v_max_non_paid_invoice 1641 1641 0 0.00 0 112 7 0 0
v_max_non_paid_realizn 3647 3647 0 0.00 0 336 7 0 0
v_min_id_clo_ord 5628 5628 0 0.00 0 2137 8 0 0
v_min_id_clo_res 1063 1063 0 0.00 0 854 7 0 0
v_min_id_ord_sup 5493 5493 0 0.00 0 3983 8 0 0
v_min_non_paid_invoice 5810 5810 0 0.00 0 13573 17 0 0
v_min_non_paid_realizn 7274 7274 0 0.00 0 5440 15 0 0
v_our_firm 10346 10346 0 0.00 0 333 4 0 0
v_reserve_write_off 20066 20066 0 0.00 0 2415 10 0 0
x_lock_depdocs_on_canc_invoice 11822 11822 0 0.00 0 12072 119 0 0
x_lock_depdocs_on_canc_sup_ord 437 437 0 0.00 0 11874 335 0 0
x_make_qstorno_1000_1200 5630 5630 0 0.00 3 42681 1089 0 0
x_make_qstorno_1200_2000 5500 5500 0 0.00 6 103925 2816 0 0
x_make_qstorno_2100_3300 454672 454672 0 0.00 0 132015 221 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-07-2022 07:48:35 to 23-07-2022 07:48:39.

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:17:47 1 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
05:18:48 26068 8192000000 9725679792 10022744064 794498416 8.841 100 87 172 1 822843984 7601792 20743776
05:20:16 18705 8192000000 9760763392 10068652032 835296192 9.253 100 85 170 0 866745072 11500944 19947936
05:21:38 14653 8192000000 9798999328 10117222400 868926992 9.589 100 86 172 0 905690480 17087872 19675616
05:22:57 7328 8192000000 9849879440 10185211904 910138880 9.999 100 78 156 0 966831968 39325248 17367840
05:24:08 2595 8192000000 9916590064 10258784256 956471712 10.454 100 87 175 0 1032455088 57460960 18522416
05:25:13 2233 8192000000 9947119360 10297180160 986540112 10.748 100 87 170 1 1062236016 58521168 17174736
05:26:19 1056 8192000000 10008540016 10366623744 1014998592 11.024 100 94 181 1 1125102432 90982224 19121616
05:27:24 1017 8192000000 9997889648 10364465152 1038252800 11.248 100 96 184 2 1120788208 62911264 19624144
05:28:26 1134 8192000000 10039704176 10412724224 1055575616 11.414 100 92 179 0 1158111552 85280128 17255808
05:29:29 1157 8192000000 10019166576 10396852224 1064461520 11.499 100 96 182 0 1143764224 60749872 18552832
05:30:35 1209 8192000000 10043904160 10418515968 1067836960 11.531 100 96 190 2 1163480176 76645776 18997440
05:31:38 1142 8192000000 10036462608 10418765824 1074252032 11.593 100 97 178 1 1155141712 61698944 19190736
05:32:40 1133 8192000000 10064764912 10449571840 1083409600 11.680 100 97 190 1 1188794352 85489568 19895184
05:33:42 1117 8192000000 10073133424 10458198016 1089553200 11.738 100 90 170 2 1196341808 90147808 16640800
05:34:46 1365 8192000000 10076891664 10464657408 1093204352 11.773 100 96 190 0 1196749072 85488592 18056128
05:35:48 1084 8192000000 10062993136 10456502272 1099025328 11.828 100 91 170 2 1191383264 72433552 19924384
05:36:50 2254 8192000000 10064238448 10456764416 1100093792 11.839 100 61 122 0 1186392816 74509808 11789216
05:37:54 2082 8192000000 10057414368 10449690624 1103032176 11.866 100 78 147 3 1182862160 64643744 15186240
05:38:57 7116 8192000000 10045529472 10446577664 1093841408 11.779 100 60 119 0 1168345152 62398496 12105248
05:40:10 1026 8192000000 10051588288 10437271552 1092723648 11.769 100 97 181 3 1172114144 61549088 17841408
05:41:12 1021 8192000000 10047032880 10431582208 1073316800 11.584 100 90 164 1 1169946256 78887024 17742432
05:42:15 1169 8192000000 10030140976 10413039616 1074370656 11.594 100 78 154 0 1146774576 57264352 15139568
05:43:17 1526 8192000000 10024545792 10409107456 1064053840 11.495 100 77 140 3 1150474720 73296496 13124384
05:44:20 4111 8192000000 9982597648 10367537152 1040776528 11.272 99 65 129 0 1100741552 46167392 13797632
05:45:26 5675 8192000000 10000949408 10390265856 1047372816 11.335 99 62 117 1 1107950480 48448800 12128864
05:46:37 7270 8192000000 9981804416 10355449856 1029034192 11.159 99 74 138 1 1096721392 53837664 13849536
05:47:47 1442 8192000000 10008131616 10388590592 1051914816 11.379 100 83 147 1 1126477200 60601760 13960624
05:49:00 5880 8192000000 10025924400 10409922560 1067331296 11.527 100 71 142 0 1151871360 70019120 14520944
05:50:17 4156 8192000000 10075301712 10459672576 1093176784 11.773 100 78 155 0 1199963248 90718576 16067888
05:51:27 20558 8192000000 10102732208 10498166784 1103249680 11.868 100 65 128 0 1218097600 100670448 14177472
05:52:52 4450 8192000000 10139723360 10534514688 1114481536 11.975 100 74 141 0 1266422096 137529904 14410656
05:54:01 970 8192000000 10193413664 10583175168 1135628752 12.174 100 95 186 0 1307046576 150685392 20732432
05:55:05 4132 8192000000 10173190128 10594177024 1131334768 12.134 99 65 125 2 1289994336 144673952 13985616
05:56:14 3566 8192000000 10247753376 10650169344 1154010976 12.347 100 78 147 2 1361659072 191272192 16375904
05:57:21 3571 8192000000 10278455248 10677760000 1166512032 12.464 100 86 166 1 1388107648 203831200 17764416
05:58:28 793 8192000000 10315557840 10713903104 1171084256 12.507 100 92 179 1 1428150272 237355072 19710944
05:59:34 1385 8192000000 10303624576 10730688512 1175941104 12.552 100 89 171 0 1415275216 219369504 19964608
06:00:39 1859 8192000000 10351339904 10755969024 1176809616 12.560 100 89 170 1 1463680976 266579728 20291632
06:01:46 1178 8192000000 10364400592 10767986688 1168728800 12.485 99 93 180 1 1469626256 280916976 19980480
06:02:51 1482 8192000000 10402952576 10823987200 1190655984 12.689 100 89 173 3 1513050560 303045904 19348672
06:03:58 813 8192000000 10421848048 10837110784 1196625168 12.745 100 100 193 1 1529923200 311334336 21963696
06:05:03 1752 8192000000 10442931264 10854547456 1191988704 12.702 100 95 187 3 1550163872 335865872 22309296
06:06:07 1074 8192000000 10503306976 10930315264 1201686864 12.792 100 96 187 1 1608482080 385381168 21414048
06:07:11 778 8192000000 10475817712 10929557504 1192445680 12.706 100 96 186 0 1581381216 366609344 22326192
06:08:14 1017 8192000000 10452442944 10889011200 1196148736 12.741 100 88 170 0 1562764048 346943104 19672208
06:09:17 969 8192000000 10405681472 10908188672 1192897920 12.710 100 97 189 1 1514292800 297336160 24058720
06:10:20 5814 8192000000 10416324512 10847662080 1185428560 12.641 100 52 104 0 1511368912 313380384 12559968
06:12:17 11422 8192000000 10413726992 10885918720 1189640272 12.680 100 48 94 1 1515939888 314935776 11363840
06:13:38 13945 8192000000 10379307408 10877440000 1182144416 12.610 100 27 54 0 1482694224 292933952 7615856
06:15:07 22791 8192000000 10280431152 10746703872 1147662304 12.288 98 37 71 0 1373314000 216948944 8702752
06:17:19 35400 8192000000 10184389200 10654138368 1132962464 12.149 98 21 42 0 1288675568 150949312 4763792
06:18:56 17909 8192000000 10162712960 10613161984 1152476768 12.333 100 63 115 3 1255223792 91469424 11277600
06:20:15 18482 8192000000 10180519536 10648961024 1149138192 12.301 99 16 30 0 1272923216 120438528 3346496
06:22:08 22754 8192000000 10128840400 10574274560 1139356480 12.209 100 22 44 0 1214892752 71115296 4420976
06:23:42 72196 8192000000 10104340688 10597257216 1120272768 12.030 98 13 26 0 1205320096 82315680 2731648
06:25:55 42114 8192000000 10097590208 10531115008 1112020656 11.952 98 45 90 1 1169794992 49084384 8689952
06:27:38 26710 8192000000 10106281872 10554691584 1136445152 12.182 100 48 89 0 1222740144 77161680 9133312
06:29:06 54207 8192000000 10106716496 10612252672 1100668608 11.844 98 10 20 0 1198304784 95254720 2381456
06:31:04 78118 8192000000 10094223248 10554048512 1119826640 12.025 100 21 42 0 1191965984 67928208 4211136
06:33:24 58979 8192000000 10094528144 10556997632 1123822528 12.063 100 35 67 0 1210381664 79014432 7544704
06:35:25 13376 8192000000 10075826592 10497875968 1135844384 12.176 100 80 154 2 1166627152 12939344 17843424
06:36:39 26501 8192000000 10064128096 10516787200 1095867792 11.798 96 42 84 1 1156149232 51566992 8714448
06:38:14 44585 8192000000 10099757728 10557583360 1136087120 12.179 100 37 73 0 1203444768 59307648 8050000
06:40:06 142988 8192000000 10135850192 10598932480 1078148816 11.630 94 21 42 0 1147122176 64338160 4635200
06:43:30 29243 8192000000 10097989760 10548187136 1077781184 11.626 96 23 44 0 1167007904 82864288 6362432
06:45:00 92504 8192000000 10117438192 10591289344 1114229760 11.972 99 11 22 0 1183929360 67474448 2225152
06:47:33 28310 8192000000 10092785040 10528296960 1120911792 12.036 99 31 60 1 1167801904 39588544 7301568
06:49:20 39221 8192000000 10076319728 10559012864 1122773968 12.053 99 21 41 0 1186962640 60276064 3912608
06:50:59 70393 8192000000 10089296080 10539806720 1090744432 11.750 96 24 46 1 1181839648 86162096 4933120
06:53:16 19840 8192000000 10131535424 10559279104 1130391536 12.125 100 61 119 0 1215145328 71331872 13421920
06:54:38 97402 8192000000 10106327280 10565545984 1087100128 11.715 96 32 64 0 1161459280 67798848 6560304
06:57:16 29738 8192000000 10091791648 10537885696 1127321040 12.096 100 41 81 0 1202150768 66254512 8575216
06:58:49 25042 8192000000 10164497856 10588987392 1135792800 12.176 100 49 95 0 1224858896 79410000 9656096
07:00:52 149869 8192000000 10046698752 10517168128 1123748784 12.062 99 16 26 0 1161033792 34764016 2520992
07:05:01 72256 8192000000 10077664912 10552123392 1131049232 12.131 100 18 36 0 1200579728 65979024 3551472
07:07:15 86750 8192000000 10034697936 10490761216 1091299120 11.755 97 15 30 0 1133846640 39698992 2848528
07:09:53 95099 8192000000 10116318320 10586497024 1121051264 12.037 100 20 40 0 1182366928 56955680 4359984
07:12:29 45114 8192000000 10106357648 10574278656 1128750448 12.110 100 12 24 0 1221952864 90619408 2583008
07:14:14 38896 8192000000 10062438112 10521645056 1104337264 11.879 98 21 42 0 1147519408 38342960 4839184
07:15:54 35584 8192000000 10117857856 10569752576 1127613408 12.099 99 28 56 0 1203536032 69734400 6188224
07:17:30 43183 8192000000 10174981968 10618445824 1121682080 12.043 98 27 54 0 1255317104 127502304 6132720
07:19:13 90297 8192000000 10118723568 10568462336 1087746848 11.721 96 24 47 1 1202466448 109719008 5000592
07:22:53 57257 8192000000 10126151568 10574888960 1132464576 12.145 100 41 80 0 1193938064 53700880 7772608
07:24:54 40193 8192000000 10100641536 10528800768 1111395056 11.946 98 42 84 0 1173627072 53676352 8555664
07:26:41 152131 8192000000 10116395088 10579120128 1129475968 12.116 99 27 52 0 1203266432 68148032 5642432
07:30:14 40564 8192000000 10103709024 10553806848 1135544928 12.174 99 23 46 0 1199586816 59169824 4872064
07:32:01 58384 8192000000 10166693264 10616938496 1142787024 12.242 99 23 47 0 1250138176 101707184 5643968
07:34:33 62164 8192000000 10167094976 10645143552 1128058960 12.103 98 15 30 0 1252237632 120715632 3463040
07:36:45 98613 8192000000 10159462272 10635948032 1070491552 11.557 94 14 28 0 1168833360 95232288 3109520
07:39:25 48038 8192000000 10084136976 10522902528 1087818208 11.722 96 31 53 0 1136480624 43038176 5624240
07:41:19 63619 8192000000 10128996672 10581696512 1114377424 11.974 99 14 28 0 1211035168 93421424 3236320
07:43:23 74503 8192000000 10142494240 10628591616 1108756112 11.921 97 26 52 0 1253913248 139303424 5853712
07:45:56 38039 8192000000 10103794080 10526535680 1092898336 11.770 97 32 57 1 1182332112 83624112 5809664
07:47:42 16301 8192000000 8998788832 9213878272 150290720 1.801 14 2 4 0 159679216 9159248 229248

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 23-07-2022 07:48:41 to 23-07-2022 07:48:57.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 138 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 23-07-2022 07:48:58 to 23-07-2022 07:48:59.

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          220375
MON$OLDEST_ACTIVE               220376
MON$OLDEST_SNAPSHOT             220376
MON$NEXT_TRANSACTION            220376
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                       2060320
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             781
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

23.07.22 07:49:01. 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-07-23 07:49:03 No severe PSQL-related problems occured
23.07.22 07:49:04. End of report.