Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.03.2023 10:39
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 15062304 kB
MemAvailable 30455376 kB
Buffers 0 kB
Cached 16667608 kB
SwapCached 3220 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 2023-03-13 10:37:27
DTS_END                         2023-03-13 10:37:27.1610
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/42352
TRN_ID                          216184
ATT_ID                          636
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: 9126.08 24788 1095130
customer order: creation 162.43 473 19491
customer order: refuse 14.44 404 1733
order to supplier: creation 42.88 1361 5145
order to supplier: removal 3.39 162290 407
invoice (draft): creation 43.58 3188 5230
invoice (draft): removal 53.31 960 6397
invoice accept: apply 40.23 26184 4827
invoice accept: cancel 47.92 1101 5750
customer reserve: creation 3734.79 251 448175
customer reserve: removal 4702.93 91 564352
realization accept: apply 77.59 288 9311
realization accept: cancel 77.11 154 9253
payment from customer: creation 56.37 177 6764
payment from customer: removal 5.48 144 657
payment to supplier: creation 47.73 201 5728
payment to supplier: removal 3.28 109 394
service: total inventory turnovers 3.92 3695 470
service: total monetary turnovers 7.72 346 926
service: aggregate perf. data 0.97 14319 116
service: refresh index statistics 0.03 280016 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-03-2023 10:40:17 to 13-03-2023 10:40:19.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6825
TEST_TIME 2 7021
TEST_TIME 3 6449
TEST_TIME 4 6027
TEST_TIME 5 5998
TEST_TIME 6 5666
TEST_TIME 7 5878
TEST_TIME 8 5791
TEST_TIME 9 5788
TEST_TIME 10 6084
TEST_TIME 11 6516
TEST_TIME 12 6511
TEST_TIME 13 6889
TEST_TIME 14 6878
TEST_TIME 15 6950
TEST_TIME 16 7058
TEST_TIME 17 7060
TEST_TIME 18 7063
TEST_TIME 19 7182
TEST_TIME 20 7430
TEST_TIME 21 8026
TEST_TIME 22 7953
TEST_TIME 23 7979
TEST_TIME 24 7907
TEST_TIME 25 7876
TEST_TIME 26 8432
TEST_TIME 27 8583
TEST_TIME 28 9265
TEST_TIME 29 9645
TEST_TIME 30 10277
TEST_TIME 31 10666
TEST_TIME 32 10598
TEST_TIME 33 10802
TEST_TIME 34 11167
TEST_TIME 35 10980
TEST_TIME 36 10964
TEST_TIME 37 10799
TEST_TIME 38 10699
TEST_TIME 39 10664
TEST_TIME 40 10537
TEST_TIME 41 10447
TEST_TIME 42 10419
TEST_TIME 43 10388
TEST_TIME 44 10568
TEST_TIME 45 10372
TEST_TIME 46 10156
TEST_TIME 47 10047
TEST_TIME 48 10095
TEST_TIME 49 9978
TEST_TIME 50 9908
TEST_TIME 51 9835
TEST_TIME 52 9823
TEST_TIME 53 9832
TEST_TIME 54 9787
TEST_TIME 55 9737
TEST_TIME 56 9637
TEST_TIME 57 9526
TEST_TIME 58 9417
TEST_TIME 59 9641
TEST_TIME 60 9530
TEST_TIME 61 9724
TEST_TIME 62 9644
TEST_TIME 63 9618
TEST_TIME 64 9696
TEST_TIME 65 9755
TEST_TIME 66 9787
TEST_TIME 67 9885
TEST_TIME 68 9845
TEST_TIME 69 9843
TEST_TIME 70 9828
TEST_TIME 71 9825
TEST_TIME 72 9820
TEST_TIME 73 9736
TEST_TIME 74 9682
TEST_TIME 75 9594
TEST_TIME 76 9584
TEST_TIME 77 9613
TEST_TIME 78 9565
TEST_TIME 79 9506
TEST_TIME 80 9489
TEST_TIME 81 9413
TEST_TIME 82 9421
TEST_TIME 83 9358
TEST_TIME 84 9319
TEST_TIME 85 9281
TEST_TIME 86 9316
TEST_TIME 87 9339
TEST_TIME 88 9374
TEST_TIME 89 9360
TEST_TIME 90 9356
TEST_TIME 91 9404
TEST_TIME 92 9402
TEST_TIME 93 9460
TEST_TIME 94 9458
TEST_TIME 95 9516
TEST_TIME 96 9476
TEST_TIME 97 9516
TEST_TIME 98 9430
TEST_TIME 99 9393
TEST_TIME 100 9331
TEST_TIME 101 9342
TEST_TIME 102 9283
TEST_TIME 103 9249
TEST_TIME 104 9198
TEST_TIME 105 9224
TEST_TIME 106 9187
TEST_TIME 107 9175
TEST_TIME 108 9181
TEST_TIME 109 9238
TEST_TIME 110 9244
TEST_TIME 111 9205
TEST_TIME 112 9232
TEST_TIME 113 9247
TEST_TIME 114 9277
TEST_TIME 115 9290
TEST_TIME 116 9281
TEST_TIME 117 9299
TEST_TIME 118 9227
TEST_TIME 119 9195
TEST_TIME 120 9143

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 13-03-2023 10:40:21 to 13-03-2023 10:40:24.

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 1509555 1509555 0 0.00 0 37801 13 0 0
doc_list_biud 1509554 1509554 0 0.00 0 43461 40 0 0
sp_add_invoice_to_stock 4921 4827 94 1.91 32 200462 26184 0 94
sp_cancel_adding_invoice 5750 5750 0 0.00 6 87499 1102 0 0
sp_cancel_client_order 1733 1733 0 0.00 6 12625 405 0 0
sp_cancel_customer_reserve 564352 564352 0 0.00 1 43464 91 0 0
sp_cancel_pay_from_customer 657 657 0 0.00 4 7275 144 0 0
sp_cancel_pay_to_supplier 394 394 0 0.00 3 1897 109 0 0
sp_cancel_supplier_invoice 6405 6397 8 0.12 3 23628 960 0 8
sp_cancel_supplier_order 407 407 0 0.00 11 1276263 162290 0 0
sp_cancel_write_off 9253 9253 0 0.00 2 6167 154 0 0
sp_client_order 19491 19491 0 0.00 5 41265 474 0 0
sp_customer_reserve 448175 448175 0 0.00 0 117953 252 0 0
sp_fill_shopping_cart 29859 29859 0 0.00 1 20817 83 0 0
sp_fill_shopping_cart_clo_res 448160 448160 0 0.00 0 13556 19 0 0
sp_get_clo_for_invoice 4791 4791 0 0.00 0 26768 302 0 0
sp_kill_cost_storno 16054 16054 0 0.00 0 9941 69 0 0
sp_kill_qstorno_ret_qs2qd 1144761 1144761 0 0.00 0 43459 40 0 0
sp_kill_qty_storno 601992 601992 0 0.00 0 43461 92 0 0
sp_lock_selected_doc 19642 19642 0 0.00 0 7247 7 0 0
sp_make_cost_storno 26592 26592 0 0.00 0 29576 89 0 0
sp_multiply_rows_for_pdistr 26592 26592 0 0.00 0 7416 38 0 0
sp_multiply_rows_for_qdistr 19491 19491 0 0.00 0 41200 184 0 0
sp_pay_from_customer 6764 6764 0 0.00 5 16860 177 0 0
sp_pay_to_supplier 5728 5728 0 0.00 6 21644 202 0 0
sp_payment_common 12492 12492 0 0.00 2 6689 43 0 0
sp_qd_handle_on_cancel_clo 1733 1733 0 0.00 0 12558 335 0 0
sp_qd_handle_on_invoice_upd_sts 10539 10539 0 0.00 0 18045 480 0 0
sp_qd_handle_on_reserve_upd_sts 9311 9311 0 0.00 0 15580 71 0 0
sp_reserve_write_off 9311 9311 0 0.00 8 18111 289 0 0
sp_supplier_invoice 5230 5230 0 0.00 9 79119 3188 0 0
sp_supplier_order 5145 5145 0 0.00 7 37094 1362 0 0
srv_aggregate_perf_data 116 116 0 0.00 2363 165759 14319 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 830 161959 41160 0 0
srv_find_qd_qs_mism 1049013 1049013 0 0.00 0 37775 9 0 0
srv_make_invnt_saldo 470 470 0 0.00 69 81690 3696 0 0
srv_make_money_saldo 926 926 0 0.00 0 10444 346 0 0
srv_recalc_idx_stat 4 4 0 0.00 39312 451138 280017 0 0
t$perf-abend:sp_add_invoice_to_stock 94 94 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 4827 4827 0 0.00 1 13775 841 0 0
t$perf-norm:sp_cancel_adding_invoice 382 382 0 0.00 1 6682 460 0 0
t$perf-norm:sp_cancel_client_order 1733 1733 0 0.00 0 6553 33 0 0
t$perf-norm:sp_cancel_customer_reserve 617 617 0 0.00 0 5351 32 0 0
t$perf-norm:sp_cancel_pay_from_customer 657 657 0 0.00 0 1952 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 394 394 0 0.00 0 2335 21 0 0
t$perf-norm:sp_cancel_supplier_invoice 416 416 0 0.00 0 2761 43 0 0
t$perf-norm:sp_cancel_supplier_order 407 407 0 0.00 0 93313 7234 0 0
t$perf-norm:sp_cancel_write_off 936 936 0 0.00 0 512 18 0 0
t$perf-norm:sp_client_order 19491 19491 0 0.00 0 8060 37 0 0
t$perf-norm:sp_customer_reserve 922 922 0 0.00 0 1439 16 0 0
t$perf-norm:sp_pay_from_customer 6764 6764 0 0.00 0 7696 19 0 0
t$perf-norm:sp_pay_to_supplier 5728 5728 0 0.00 0 11065 19 0 0
t$perf-norm:sp_reserve_write_off 9311 9311 0 0.00 0 9147 26 0 0
t$perf-norm:sp_supplier_invoice 5230 5230 0 0.00 0 7052 35 0 0
t$perf-norm:sp_supplier_order 5145 5145 0 0.00 0 3719 30 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 1350 27 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 32 3 0 0
t$perf-norm:srv_make_invnt_saldo 470 470 0 0.00 0 4589 41 0 0
t$perf-norm:srv_make_money_saldo 926 926 0 0.00 0 2451 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 158 45 0 0
v_add_invoice_to_stock 9766 9578 188 1.92 0 8326 23 0 188
v_all_customers 35680 35680 0 0.00 0 62 4 0 0
v_all_suppliers 29354 29354 0 0.00 0 95 4 0 0
v_all_wares 38982 38982 0 0.00 0 79 4 0 0
v_cancel_adding_invoice 764 764 0 0.00 0 615 14 0 0
v_cancel_client_order 3466 3466 0 0.00 0 4725 13 0 0
v_cancel_customer_prepayment 1314 1314 0 0.00 0 2921 17 0 0
v_cancel_customer_reserve 1234 1234 0 0.00 0 1665 10 0 0
v_cancel_payment_to_supplier 788 788 0 0.00 0 1829 12 0 0
v_cancel_supplier_invoice 848 832 16 1.88 0 2187 21 0 16
v_cancel_supplier_order 814 814 0 0.00 0 317 8 0 0
v_cancel_write_off 1872 1872 0 0.00 0 4080 28 0 0
v_max_id_clo_ord 5145 5145 0 0.00 0 83 5 0 0
v_max_id_clo_res 922 922 0 0.00 0 6254 28 0 0
v_max_id_ord_sup 5223 5223 0 0.00 0 5839 10 0 0
v_max_non_paid_invoice 1419 1419 0 0.00 0 124 8 0 0
v_max_non_paid_realizn 3442 3442 0 0.00 0 1206 7 0 0
v_min_id_clo_ord 5145 5145 0 0.00 0 4064 8 0 0
v_min_id_clo_res 922 922 0 0.00 0 5333 18 0 0
v_min_id_ord_sup 5223 5223 0 0.00 0 10242 12 0 0
v_min_non_paid_invoice 5728 5728 0 0.00 0 5261 14 0 0
v_min_non_paid_realizn 6764 6764 0 0.00 0 6685 16 0 0
v_our_firm 9946 9946 0 0.00 0 95 4 0 0
v_reserve_write_off 18622 18622 0 0.00 0 8336 11 0 0
x_lock_depdocs_on_canc_invoice 11731 11731 0 0.00 0 27085 127 0 0
x_lock_depdocs_on_canc_sup_ord 407 407 0 0.00 0 21545 365 0 0
x_make_qstorno_1000_1200 5145 5145 0 0.00 2 37004 1207 0 0
x_make_qstorno_1200_2000 5230 5230 0 0.00 4 71449 2949 0 0
x_make_qstorno_2100_3300 448175 448175 0 0.00 0 117913 221 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 13-03-2023 10:40:25 to 13-03-2023 10:40:30.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
08:07:31 952 8192000000 8944038576 9002455040 35048800 0.426 9 6 12 0 36674016 279648 1345568
08:08:33 23648 8192000000 9734724288 10034647040 811986624 9.018 100 93 187 0 842212784 7567744 22658416
08:10:00 15651 8192000000 9765505056 10075959296 844805232 9.348 100 86 172 0 877113024 12040400 20267392
08:11:22 20261 8192000000 9802758640 10120744960 874080768 9.641 100 83 164 0 910985968 18377280 18527920
08:12:45 6636 8192000000 9851787808 10183335936 908509648 9.983 100 82 161 0 962992784 36148944 18334192
08:13:54 2505 8192000000 9904515712 10244644864 948688768 10.378 100 82 161 0 1020122400 54664160 16769472
08:15:00 1853 8192000000 9958430640 10307706880 989666448 10.778 100 90 176 0 1079342464 72306080 17369936
08:16:05 1181 8192000000 10011334784 10370031616 1020885840 11.081 100 91 175 0 1129308080 89718016 18704224
08:17:07 1546 8192000000 9997132176 10363289600 1043443872 11.298 100 91 180 0 1118326544 57305360 17577312
08:18:10 1013 8192000000 10044427824 10418327552 1061439264 11.470 100 95 184 0 1166256144 86143168 18673712
08:19:12 1065 8192000000 10026839536 10404532224 1073351840 11.584 100 94 180 0 1148466832 55565456 19549536
08:20:14 1059 8192000000 10047063952 10428203008 1079455216 11.642 100 87 175 0 1169756240 72475056 17825968
08:21:16 1045 8192000000 10050695744 10436009984 1087339632 11.717 100 90 166 3 1170465360 66044352 17081376
08:22:18 2763 8192000000 10053057424 10442358784 1091418592 11.756 100 87 165 3 1168517344 62475536 14623216
08:23:21 2273 8192000000 10051550240 10444800000 1092657728 11.768 100 72 139 0 1170217792 63255696 14304368
08:24:24 5080 8192000000 10040499392 10433867776 1098345856 11.822 100 77 148 3 1164255520 51722016 14187648
08:25:31 975 8192000000 10053960448 10447179776 1105494624 11.890 100 94 176 4 1176935472 53751584 17689264
08:26:33 1294 8192000000 10066269184 10456981504 1109801776 11.931 100 98 184 0 1191022624 62030752 19190096
08:27:35 1280 8192000000 10055462864 10443808768 1092224352 11.764 99 94 183 2 1181910240 70219584 19466304
08:28:37 1393 8192000000 10059129904 10448584704 1099214512 11.830 100 93 184 2 1183121424 64396496 19510416
08:29:39 1084 8192000000 10062673504 10446725120 1093316304 11.774 100 96 181 0 1184362992 72189344 18857344
08:30:41 4454 8192000000 10015830496 10404462592 1066827552 11.522 98 67 132 1 1150887216 71657584 12402080
08:31:47 1212 8192000000 10016568576 10394300416 1053365264 11.393 99 94 173 0 1141214560 69918288 17931008
08:32:49 1219 8192000000 9977268896 10344742912 1021818672 11.090 99 94 173 1 1101940368 62728944 17392752
08:33:51 1163 8192000000 9998393040 10368864256 1041761312 11.282 100 94 178 6 1123157744 62531440 18864992
08:34:53 840 8192000000 9965534992 10340929536 1041010960 11.274 100 85 154 1 1091444240 33741472 16691808
08:35:56 7905 8192000000 9990574096 10371043328 1042676944 11.290 100 59 114 1 1104997008 50597616 11722448
08:37:07 839 8192000000 9989645920 10362736640 1041403968 11.278 100 90 175 0 1113852976 53437296 19011712
08:38:13 30459 8192000000 10015668640 10401701888 1069895744 11.551 100 61 119 0 1132876016 51395856 11584416
08:39:49 1102 8192000000 10061136896 10447806464 1099251152 11.831 100 94 186 2 1184092640 64209728 20631760
08:40:58 1923 8192000000 10080617312 10467758080 1117633040 12.005 100 80 146 3 1193638400 61381792 14623568
08:42:08 2384 8192000000 10114616224 10504118272 1128419984 12.106 100 81 149 0 1227965968 83797120 15748864
08:43:17 11823 8192000000 10145216944 10544414720 1132286240 12.143 100 66 128 2 1253456624 107281776 13888608
08:44:32 1428 8192000000 10180535216 10571988992 1139131168 12.207 100 89 172 1 1293330000 137089104 17109728
08:45:37 1601 8192000000 10230685232 10623979520 1155304064 12.359 100 86 158 3 1341233280 168644336 17284880
08:46:41 925 8192000000 10273561408 10668552192 1165172320 12.452 100 95 177 5 1387727696 202232240 20323136
08:47:47 1447 8192000000 10280477424 10685743104 1170449776 12.501 100 89 172 1 1398185968 207062736 20673456
08:48:50 2137 8192000000 10306233104 10715779072 1179255200 12.583 100 85 166 1 1416715440 218745200 18715040
08:49:55 1054 8192000000 10337657904 10759348224 1180300080 12.593 100 98 188 2 1443767056 242087952 21379024
08:51:00 1293 8192000000 10365541408 10774007808 1201655312 12.792 100 91 174 0 1475864416 255369184 18839920
08:52:05 930 8192000000 10419736416 10822627328 1208214256 12.853 100 98 189 0 1527843264 297648864 21980144
08:53:09 1730 8192000000 10461728528 10880581632 1215660304 12.922 100 96 189 2 1570206688 331305648 23240736
08:54:15 1065 8192000000 10506190096 10921877504 1219617680 12.958 100 97 188 2 1616024896 373519744 22887472
08:55:18 1507 8192000000 10555778240 10972585984 1214927488 12.915 100 95 184 0 1665750896 427507120 23316288
08:56:25 820 8192000000 10575105904 11014832128 1203639408 12.810 100 97 190 1 1678025152 451171664 23214080
08:57:28 951 8192000000 10577113328 11034951680 1209995296 12.869 100 99 192 3 1681990704 448437328 23558080
08:58:32 2070 8192000000 10548974656 11007066112 1210102368 12.870 100 72 140 0 1658071456 430613264 17355824
08:59:49 7121 8192000000 10578628144 11001069568 1181121392 12.601 99 67 130 0 1681177728 484277472 15778864
09:01:16 12229 8192000000 10653706112 11086786560 1181353376 12.603 99 50 98 1 1735799072 541818416 12627280
09:02:49 9085 8192000000 10621125664 11118665728 1167896640 12.477 100 42 82 0 1736846512 557290752 11659120
09:03:59 24301 8192000000 10610380944 11067801600 1150096576 12.310 100 41 81 0 1704883072 543937328 10849168
09:05:30 18746 8192000000 10408435680 10899566592 1139088608 12.207 99 33 64 0 1563096080 415713984 8293488
09:06:51 861 8192000000 10304082112 10839715840 1150793024 12.317 100 76 145 0 1405029552 236549984 17686544
09:07:53 2441 8192000000 10260175904 10760671232 1157678288 12.382 100 74 147 0 1362819424 187999568 17141568
09:08:57 20628 8192000000 10206108144 10633990144 1155415872 12.360 100 58 112 0 1305706432 135917936 14372624
09:10:28 37043 8192000000 10210822512 10757877760 1152234352 12.330 100 10 20 0 1312033328 157560416 2238560
09:12:40 94424 8192000000 10081384976 10564648960 1121580192 12.042 97 11 20 0 1176710656 53142400 1988064
09:15:20 51777 8192000000 10073207616 10500706304 1100325984 11.841 96 25 50 0 1148202240 43218704 4657552
09:17:12 60023 8192000000 10100559312 10541092864 1124643312 12.071 98 18 35 1 1169651920 41442256 3566352
09:19:13 47834 8192000000 10101984048 10537549824 1135909712 12.177 99 52 92 1 1188719360 43816496 8993152
09:21:02 146124 8192000000 10062793840 10496602112 1097567840 11.815 96 12 24 0 1146771200 47084976 2118384
09:24:45 145339 8192000000 10089238192 10540969984 1089965392 11.742 95 22 43 0 1136168816 41728016 4475408
09:28:11 48832 8192000000 10140338304 10574680064 1122785648 12.053 99 36 68 1 1204424624 73504256 8134720
09:30:05 28607 8192000000 10127799584 10581991424 1142610720 12.240 100 44 88 1 1235369152 83609184 9149248
09:31:34 62048 8192000000 10098381200 10562527232 1128934112 12.111 100 17 34 0 1208331440 75940336 3456992
09:33:37 136853 8192000000 10151591552 10605088768 1102313008 11.860 97 21 40 1 1218863680 112259664 4291008
09:36:54 161957 8192000000 10142883392 10651095040 1087295664 11.717 95 17 34 0 1217941808 126971216 3674928
09:40:37 48567 8192000000 10206107056 10668773376 1139749584 12.213 99 35 69 0 1253741376 106642448 7349344
09:42:30 110897 8192000000 10078540640 10614222848 1076031440 11.610 94 10 20 1 1166168080 88480384 1656256
09:45:22 18922 8192000000 10111204192 10540961792 1131791616 12.138 100 59 115 0 1185707664 42979408 10936640
09:46:41 42739 8192000000 10094610016 10522718208 1115780528 11.987 98 40 77 0 1182287040 59260768 7245744
09:48:27 75196 8192000000 10101592400 10541674496 1080093168 11.648 96 30 60 0 1171981248 85789328 6098752
09:50:44 67719 8192000000 10048384240 10482487296 1098820640 11.826 98 29 56 1 1143502272 38170592 6511040
09:52:52 39221 8192000000 10143903552 10582458368 1136668320 12.184 100 47 86 0 1207265696 61905424 8691952
09:55:43 41074 8192000000 10076559952 10558959616 1139897264 12.215 100 9 18 0 1186104832 44453472 1754096
09:57:25 55366 8192000000 10061541072 10507042816 1089060656 11.734 96 10 20 0 1124057888 32822000 2175232
09:59:26 40601 8192000000 10044122960 10543022080 1118972384 12.017 100 13 26 0 1171739712 50179920 2587408
10:01:07 42015 8192000000 10088691408 10529345536 1120383792 12.031 99 28 56 0 1182205696 54493328 7328576
10:03:08 42637 8192000000 10089280640 10547064832 1117262368 12.001 99 19 38 0 1205337712 83791760 4283584
10:04:58 58738 8192000000 10082453744 10530762752 1129974096 12.121 100 26 52 0 1192819888 58069104 4776688
10:06:58 50857 8192000000 10115800448 10573651968 1116778832 11.997 98 38 74 0 1202720224 78621504 7319888
10:08:53 32239 8192000000 10120555008 10545352704 1127173664 12.095 99 42 80 2 1180298816 45324512 7800640
10:10:43 53168 8192000000 10104583024 10563211264 1116300448 11.992 98 28 57 0 1186949776 65108480 5540848
10:12:37 59664 8192000000 10144201072 10599976960 1144815936 12.261 100 22 42 1 1208585952 59134640 4635376
10:14:41 97297 8192000000 10075274256 10535260160 1128809888 12.110 99 29 57 0 1153656352 18953504 5892960
10:17:29 5264 8192000000 10085278560 10526679040 1140201584 12.217 100 61 120 3 1195234080 43086816 11945680
10:18:45 92857 8192000000 10078302752 10568331264 1119216848 12.020 98 6 12 0 1174645952 54332992 1096112
10:21:18 73723 8192000000 10074779312 10561257472 1118794640 12.016 98 11 22 0 1173671888 52413760 2463488
10:23:35 92780 8192000000 10096991968 10564661248 1119192208 12.019 98 25 49 0 1183837456 59352144 5293104
10:26:16 92719 8192000000 10093879312 10535268352 1068243840 11.535 93 28 56 0 1137216048 62856208 6116000
10:29:32 40580 8192000000 10133776064 10576060416 1143996192 12.253 100 37 74 0 1206747984 55473488 7278304
10:31:16 61034 8192000000 10102380112 10552430592 1128829120 12.110 99 34 68 1 1202306192 66869600 6607472
10:33:17 37906 8192000000 10109213136 10554609664 1143775040 12.251 100 43 83 1 1211160608 58807936 8577632
10:35:27 59496 8192000000 10080766704 10511904768 1128397984 12.106 99 56 108 1 1172722400 32557520 11766896

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 13-03-2023 10:40:32 to 13-03-2023 10:40:48.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 94 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 13-03-2023 10:40:49 to 13-03-2023 10:40:51.

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          214858
MON$OLDEST_ACTIVE               216296
MON$OLDEST_SNAPSHOT             216296
MON$NEXT_TRANSACTION            216296
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                       2060256
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             765
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2908 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2908 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2908 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2908 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 ---
:95: 
:96: mscs-test-161.budzdorov.ru    Mon Mar 13 10:37:34 2023
:97:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:98: 
--- end of diff output ---

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

13.03.23 10:40:52. 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
2023-03-13 10:40:54 No severe PSQL-related problems occured
13.03.23 10:40:56. End of report.