Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.02.2023 04:40
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 14922672 kB
MemAvailable 30409232 kB
Buffers 20 kB
Cached 16741476 kB
SwapCached 3356 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-02-12 04:37:26
DTS_END                         2023-02-12 04:37:26.8950
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/44208
TRN_ID                          210270
ATT_ID                          592
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: 9040.43 23738 1084852
customer order: creation 165.08 480 19809
customer order: refuse 13.95 460 1674
order to supplier: creation 43.04 1376 5165
order to supplier: removal 3.33 203939 400
invoice (draft): creation 42.78 3298 5133
invoice (draft): removal 52.63 1027 6316
invoice accept: apply 39.36 29438 4723
invoice accept: cancel 47.49 1306 5699
customer reserve: creation 3664.87 278 439784
customer reserve: removal 4682.06 113 561847
realization accept: apply 80.01 339 9601
realization accept: cancel 82.21 204 9865
payment from customer: creation 56.22 202 6746
payment from customer: removal 5.58 175 670
payment to supplier: creation 46.62 219 5594
payment to supplier: removal 3.36 151 403
service: total inventory turnovers 3.78 4664 454
service: total monetary turnovers 7.13 462 856
service: aggregate perf. data 0.91 16204 109
service: refresh index statistics 0.03 210432 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-02-2023 04:40:23 to 12-02-2023 04:40:25.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7127
TEST_TIME 2 5639
TEST_TIME 3 5445
TEST_TIME 4 5664
TEST_TIME 5 5289
TEST_TIME 6 4946
TEST_TIME 7 5477
TEST_TIME 8 5457
TEST_TIME 9 5473
TEST_TIME 10 5524
TEST_TIME 11 5661
TEST_TIME 12 5837
TEST_TIME 13 6264
TEST_TIME 14 6382
TEST_TIME 15 6616
TEST_TIME 16 6668
TEST_TIME 17 6870
TEST_TIME 18 7016
TEST_TIME 19 7249
TEST_TIME 20 7352
TEST_TIME 21 7290
TEST_TIME 22 7556
TEST_TIME 23 7665
TEST_TIME 24 7825
TEST_TIME 25 8021
TEST_TIME 26 8088
TEST_TIME 27 7998
TEST_TIME 28 8149
TEST_TIME 29 8459
TEST_TIME 30 8560
TEST_TIME 31 9007
TEST_TIME 32 9354
TEST_TIME 33 9508
TEST_TIME 34 9769
TEST_TIME 35 9962
TEST_TIME 36 10283
TEST_TIME 37 10946
TEST_TIME 38 10822
TEST_TIME 39 10696
TEST_TIME 40 10522
TEST_TIME 41 10448
TEST_TIME 42 10335
TEST_TIME 43 10371
TEST_TIME 44 10283
TEST_TIME 45 10153
TEST_TIME 46 9991
TEST_TIME 47 9981
TEST_TIME 48 9972
TEST_TIME 49 9903
TEST_TIME 50 9929
TEST_TIME 51 9823
TEST_TIME 52 9748
TEST_TIME 53 9678
TEST_TIME 54 9581
TEST_TIME 55 9482
TEST_TIME 56 9571
TEST_TIME 57 9701
TEST_TIME 58 9610
TEST_TIME 59 9645
TEST_TIME 60 9607
TEST_TIME 61 9553
TEST_TIME 62 9696
TEST_TIME 63 9659
TEST_TIME 64 9684
TEST_TIME 65 9666
TEST_TIME 66 9613
TEST_TIME 67 9589
TEST_TIME 68 9598
TEST_TIME 69 9655
TEST_TIME 70 9638
TEST_TIME 71 9623
TEST_TIME 72 9526
TEST_TIME 73 9447
TEST_TIME 74 9511
TEST_TIME 75 9444
TEST_TIME 76 9388
TEST_TIME 77 9335
TEST_TIME 78 9302
TEST_TIME 79 9295
TEST_TIME 80 9238
TEST_TIME 81 9186
TEST_TIME 82 9186
TEST_TIME 83 9134
TEST_TIME 84 9069
TEST_TIME 85 9048
TEST_TIME 86 9029
TEST_TIME 87 9041
TEST_TIME 88 9036
TEST_TIME 89 9020
TEST_TIME 90 8991
TEST_TIME 91 9008
TEST_TIME 92 8999
TEST_TIME 93 9083
TEST_TIME 94 9129
TEST_TIME 95 9234
TEST_TIME 96 9290
TEST_TIME 97 9234
TEST_TIME 98 9342
TEST_TIME 99 9297
TEST_TIME 100 9232
TEST_TIME 101 9172
TEST_TIME 102 9187
TEST_TIME 103 9185
TEST_TIME 104 9129
TEST_TIME 105 9073
TEST_TIME 106 9026
TEST_TIME 107 9010
TEST_TIME 108 8987
TEST_TIME 109 9049
TEST_TIME 110 9018
TEST_TIME 111 9006
TEST_TIME 112 9027
TEST_TIME 113 9007
TEST_TIME 114 9025
TEST_TIME 115 8998
TEST_TIME 116 9066
TEST_TIME 117 9116
TEST_TIME 118 9111
TEST_TIME 119 9126
TEST_TIME 120 9069

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 12-02-2023 04:40:26 to 12-02-2023 04:40:30.

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 1491079 1491079 0 0.00 0 34131 16 0 0
doc_list_biud 1491065 1491065 0 0.00 0 28229 48 0 0
sp_add_invoice_to_stock 4868 4723 145 2.97 84 207713 29438 0 145
sp_cancel_adding_invoice 5699 5699 0 0.00 6 75064 1307 0 0
sp_cancel_client_order 1674 1674 0 0.00 6 10194 461 0 0
sp_cancel_customer_reserve 561847 561847 0 0.00 1 28250 114 0 0
sp_cancel_pay_from_customer 670 670 0 0.00 3 8778 176 0 0
sp_cancel_pay_to_supplier 403 403 0 0.00 4 4092 151 0 0
sp_cancel_supplier_invoice 6336 6316 20 0.31 3 16796 1027 0 20
sp_cancel_supplier_order 400 400 0 0.00 13 1764290 203940 0 0
sp_cancel_write_off 9865 9865 0 0.00 2 12089 205 0 0
sp_client_order 19809 19809 0 0.00 5 36304 480 0 0
sp_customer_reserve 439784 439784 0 0.00 0 123224 279 0 0
sp_fill_shopping_cart 30092 30092 0 0.00 1 23714 86 0 0
sp_fill_shopping_cart_clo_res 439747 439747 0 0.00 0 12423 19 0 0
sp_get_clo_for_invoice 4674 4674 0 0.00 0 24284 272 0 0
sp_kill_cost_storno 16637 16637 0 0.00 0 29050 93 0 0
sp_kill_qstorno_ret_qs2qd 1140275 1140275 0 0.00 0 27965 49 0 0
sp_kill_qty_storno 600075 600075 0 0.00 0 28225 111 0 0
sp_lock_selected_doc 19766 19766 0 0.00 0 8613 10 0 0
sp_make_cost_storno 26615 26615 0 0.00 0 16386 96 0 0
sp_multiply_rows_for_pdistr 26613 26613 0 0.00 0 9684 44 0 0
sp_multiply_rows_for_qdistr 19809 19809 0 0.00 0 11684 181 0 0
sp_pay_from_customer 6746 6746 0 0.00 5 20442 202 0 0
sp_pay_to_supplier 5594 5594 0 0.00 7 17881 220 0 0
sp_payment_common 12339 12339 0 0.00 2 13219 54 0 0
sp_qd_handle_on_cancel_clo 1674 1674 0 0.00 0 9269 379 0 0
sp_qd_handle_on_invoice_upd_sts 10373 10373 0 0.00 0 11779 577 0 0
sp_qd_handle_on_reserve_upd_sts 9600 9600 0 0.00 0 16242 65 0 0
sp_reserve_write_off 9601 9601 0 0.00 7 24695 339 0 0
sp_supplier_invoice 5133 5133 0 0.00 13 71402 3298 0 0
sp_supplier_order 5165 5165 0 0.00 6 51377 1376 0 0
srv_aggregate_perf_data 109 109 0 0.00 2450 121123 16205 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 847 129361 33015 0 0
srv_find_qd_qs_mism 1038886 1038886 0 0.00 0 15249 12 0 0
srv_make_invnt_saldo 454 454 0 0.00 72 85171 4664 0 0
srv_make_money_saldo 856 856 0 0.00 1 12222 463 0 0
srv_recalc_idx_stat 4 4 0 0.00 34417 373273 210433 0 0
t$perf-abend:sp_add_invoice_to_stock 145 145 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 20 20 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4729 4729 0 0.00 1 11925 1024 0 0
t$perf-norm:sp_cancel_adding_invoice 391 391 0 0.00 1 6159 468 0 0
t$perf-norm:sp_cancel_client_order 1674 1674 0 0.00 0 4950 36 0 0
t$perf-norm:sp_cancel_customer_reserve 629 629 0 0.00 0 656 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 670 670 0 0.00 0 560 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 403 403 0 0.00 0 866 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 374 374 0 0.00 0 1189 41 0 0
t$perf-norm:sp_cancel_supplier_order 400 400 0 0.00 0 92120 8542 0 0
t$perf-norm:sp_cancel_write_off 957 957 0 0.00 0 1982 24 0 0
t$perf-norm:sp_client_order 19809 19809 0 0.00 0 5896 39 0 0
t$perf-norm:sp_customer_reserve 938 938 0 0.00 0 1836 16 0 0
t$perf-norm:sp_pay_from_customer 6747 6747 0 0.00 0 9686 21 0 0
t$perf-norm:sp_pay_to_supplier 5594 5594 0 0.00 0 6537 23 0 0
t$perf-norm:sp_reserve_write_off 9601 9601 0 0.00 0 22186 27 0 0
t$perf-norm:sp_supplier_invoice 5133 5133 0 0.00 0 6902 33 0 0
t$perf-norm:sp_supplier_order 5165 5165 0 0.00 0 5781 33 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 620 23 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 643 16 0 0
t$perf-norm:srv_make_invnt_saldo 454 454 0 0.00 0 4727 28 0 0
t$perf-norm:srv_make_money_saldo 856 856 0 0.00 0 389 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 7 810 223 0 0
v_add_invoice_to_stock 9628 9338 290 3.01 0 20805 34 0 290
v_all_customers 36492 36492 0 0.00 0 123 4 0 0
v_all_suppliers 29267 29267 0 0.00 0 75 5 0 0
v_all_wares 39612 39612 0 0.00 0 66 4 0 0
v_cancel_adding_invoice 782 782 0 0.00 0 2578 17 0 0
v_cancel_client_order 3348 3348 0 0.00 0 10011 13 0 0
v_cancel_customer_prepayment 1340 1340 0 0.00 0 1605 15 0 0
v_cancel_customer_reserve 1258 1258 0 0.00 0 1197 11 0 0
v_cancel_payment_to_supplier 806 806 0 0.00 0 4086 22 0 0
v_cancel_supplier_invoice 788 748 40 5.07 0 1816 28 0 40
v_cancel_supplier_order 800 800 0 0.00 0 3252 18 0 0
v_cancel_write_off 1914 1914 0 0.00 0 8885 30 0 0
v_max_id_clo_ord 5163 5163 0 0.00 0 2925 6 0 0
v_max_id_clo_res 938 938 0 0.00 0 2874 18 0 0
v_max_id_ord_sup 5123 5123 0 0.00 0 4733 9 0 0
v_max_non_paid_invoice 1245 1245 0 0.00 0 68 9 0 0
v_max_non_paid_realizn 3398 3398 0 0.00 0 3319 10 0 0
v_min_id_clo_ord 5163 5163 0 0.00 0 4054 10 0 0
v_min_id_clo_res 938 938 0 0.00 0 715 8 0 0
v_min_id_ord_sup 5123 5123 0 0.00 0 10102 10 0 0
v_min_non_paid_invoice 5593 5593 0 0.00 0 6835 22 0 0
v_min_non_paid_realizn 6746 6746 0 0.00 0 13205 25 0 0
v_our_firm 9816 9816 0 0.00 0 265 5 0 0
v_reserve_write_off 19198 19198 0 0.00 0 21383 18 0 0
x_lock_depdocs_on_canc_invoice 11641 11641 0 0.00 0 22360 144 0 0
x_lock_depdocs_on_canc_sup_ord 400 400 0 0.00 0 9770 454 0 0
x_make_qstorno_1000_1200 5165 5165 0 0.00 2 51368 1197 0 0
x_make_qstorno_1200_2000 5133 5133 0 0.00 7 66016 3066 0 0
x_make_qstorno_2100_3300 439780 439780 0 0.00 0 123219 245 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-02-2023 04:40:31 to 12-02-2023 04:40:35.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
02:07:30 16 8192000000 8803045712 8816156672 5757808 0.070 3 1 2 0 5858976 65392 35776
02:08:32 27873 8192000000 9719239360 10015784960 796859488 8.864 100 88 174 1 825902320 7530992 21511840
02:10:05 23661 8192000000 9762578880 10067611648 834285744 9.242 100 92 184 0 869205984 12355232 22565008
02:11:44 15309 8192000000 9812851584 10130223104 867865200 9.579 100 81 162 0 916207808 29526080 18816528
02:13:02 6093 8192000000 9864708432 10198290432 919743920 10.094 100 77 154 0 973671360 36735152 17192288
02:14:14 5866 8192000000 9929684464 10276855808 971835712 10.605 100 81 162 0 1036830448 48076544 16918192
02:15:22 2009 8192000000 9972818736 10325213184 1004717872 10.924 100 93 183 1 1084890000 61980128 18192000
02:16:26 982 8192000000 10017985904 10382274560 1029593040 11.165 100 97 189 0 1136875136 89290096 17992000
02:17:29 1558 8192000000 10028645312 10398113792 1045777520 11.320 100 96 187 0 1150360160 84833552 19749088
02:18:32 1111 8192000000 10016846544 10395885568 1057097680 11.429 100 92 177 0 1141965024 66202272 18665072
02:19:35 1193 8192000000 10030714672 10403962880 1065296096 11.507 100 93 177 3 1156292656 71810560 19186000
02:20:39 1084 8192000000 10059087536 10442076160 1080297184 11.650 100 94 187 1 1175592688 75854912 19440592
02:21:43 1219 8192000000 10075331008 10455138304 1084714512 11.692 100 94 183 0 1198026464 93549232 19762720
02:22:45 1431 8192000000 10061136208 10449018880 1083148736 11.677 100 96 180 0 1183634976 80650656 19835584
02:23:48 1144 8192000000 10070907600 10458963968 1094170000 11.782 100 93 178 3 1195546672 83406464 17970208
02:24:50 1172 8192000000 10065536128 10456686592 1096776432 11.807 100 95 172 2 1189835568 74863328 18195808
02:25:52 1185 8192000000 10062991104 10454102016 1100028976 11.838 100 99 173 1 1188098704 68382288 19687440
02:26:55 946 8192000000 10073223680 10462531584 1100980432 11.847 100 93 182 1 1195082752 75464096 18638224
02:27:57 1245 8192000000 10043347408 10432454656 1091321536 11.755 100 92 175 2 1164322624 54945408 18055680
02:29:00 5920 8192000000 10048576240 10447118336 1098761696 11.826 100 62 116 1 1182823440 72105456 11956288
02:30:07 1183 8192000000 10052219584 10442153984 1098559776 11.824 100 92 173 3 1175699408 59731328 17408304
02:31:09 1175 8192000000 10075073552 10463633408 1086754416 11.712 100 95 188 0 1196419600 90865344 18799840
02:32:11 1106 8192000000 10054506016 10441973760 1083090000 11.677 100 95 180 2 1179392720 77023968 19278752
02:33:13 1195 8192000000 10024486912 10403909632 1058196896 11.439 100 93 177 0 1149788576 72331200 19260480
02:34:15 1918 8192000000 9982208128 10359386112 1038438624 11.250 99 79 151 0 1106004352 52054256 15511472
02:35:18 1334 8192000000 9997352368 10368159744 1037640160 11.242 100 95 178 3 1121381808 64661888 19079760
02:36:20 1691 8192000000 9958591008 10338697216 1036878016 11.235 100 66 124 0 1085355152 35565776 12911360
02:37:23 844 8192000000 10027272896 10407583744 1061465328 11.471 100 86 164 1 1150264000 72184768 16613904
02:38:34 9536 8192000000 10043308880 10439843840 1070146224 11.553 100 78 142 0 1164139280 79964864 14028192
02:39:54 14769 8192000000 10030961744 10421841920 1089588144 11.739 100 56 110 1 1150236448 48893488 11754816
02:41:12 5906 8192000000 10069613168 10458726400 1105855216 11.893 100 74 142 2 1188972160 68392336 14724608
02:42:24 7889 8192000000 10109073168 10505687040 1116387200 11.993 100 79 150 3 1223626768 90407936 16831632
02:43:59 2986 8192000000 10170652832 10564751360 1121568576 12.042 99 83 158 4 1287980736 148730528 17681632
02:45:05 834 8192000000 10228329424 10620071936 1146412112 12.276 100 97 185 1 1342280176 176520128 19347936
02:46:11 1653 8192000000 10250995760 10653208576 1158617568 12.390 100 88 161 1 1362636224 186639056 17379600
02:47:16 2666 8192000000 10295045648 10694660096 1166140816 12.461 100 87 168 1 1404469984 219191520 19137648
02:48:24 928 8192000000 10351358224 10748305408 1180673056 12.596 100 98 194 1 1462304448 259514336 22117056
02:49:29 1583 8192000000 10340637120 10763255808 1181374000 12.603 100 77 152 0 1455900704 256450656 18076048
02:50:34 1316 8192000000 10382712624 10812301312 1185904176 12.645 100 97 186 3 1496150688 288324992 21921520
02:51:40 1856 8192000000 10379224928 10803097600 1179659264 12.587 100 93 178 1 1492456896 292926592 19871040
02:52:47 3237 8192000000 10414852800 10831863808 1194407760 12.724 100 83 164 0 1528212416 314807040 18997616
02:53:55 1050 8192000000 10444875936 10879315968 1199052240 12.768 100 95 187 1 1556952480 335948656 21951584
02:54:59 1326 8192000000 10455861824 10882871296 1201670720 12.792 100 94 181 1 1562954912 340714464 20569728
02:56:05 1852 8192000000 10471923056 10909503488 1205791616 12.830 100 91 176 2 1580959632 354013392 21154624
02:57:10 1517 8192000000 10485202416 10911363072 1215658912 12.922 100 95 192 0 1594309392 354581632 24068848
02:58:15 1333 8192000000 10499254752 10923433984 1195891488 12.738 99 91 179 1 1619238384 402790672 20556224
02:59:20 1246 8192000000 10561075312 10997460992 1202449088 12.799 100 96 188 1 1665835088 439883984 23502016
03:00:24 1776 8192000000 10557753856 10987782144 1191344448 12.696 100 95 186 0 1664487504 449897696 23245360
03:01:31 1158 8192000000 10566820656 11019034624 1188162800 12.666 100 98 189 0 1671973232 460878848 22931584
03:02:34 858 8192000000 10580440960 11024285696 1198746240 12.765 100 96 189 1 1684435984 462614448 23075296
03:03:41 1553 8192000000 10603163616 11036209152 1205296320 12.825 100 96 190 0 1704864096 476392448 23175328
03:04:45 1303 8192000000 10627758368 11064811520 1199253088 12.769 100 93 177 0 1730572832 509963312 21356432
03:05:50 1086 8192000000 10607487424 11079118848 1205813872 12.830 100 79 154 0 1714218080 489149696 19254512
03:06:58 5979 8192000000 10625816464 11091046400 1206888656 12.840 100 66 126 0 1732664080 509805264 15970160
03:08:06 8851 8192000000 10564577024 11054604288 1184670256 12.634 99 54 105 1 1658351024 460251168 13429600
03:09:15 14134 8192000000 10516890144 11027349504 1188846832 12.673 100 39 78 0 1642648784 443590144 10211808
03:10:56 26613 8192000000 10405795600 10915921920 1162413296 12.426 100 26 52 0 1532270688 362986064 6871328
03:12:36 16756 8192000000 10299052992 10825297920 1164216448 12.443 100 24 48 0 1405558752 235233728 6108576
03:13:54 64572 8192000000 10101230944 10642210816 1153395456 12.341 100 8 16 0 1310327120 154857440 2074224
03:15:59 4766 8192000000 10084110640 10510839808 1138796432 12.204 100 62 114 1 1181580256 30164464 12619360
03:17:08 115699 8192000000 10052745568 10531942400 1110011840 11.933 98 22 44 1 1160126192 45607904 4506448
03:20:22 60322 8192000000 10041149552 10476634112 1090173760 11.744 97 36 69 3 1122401344 25290192 6937392
03:22:24 80287 8192000000 10069352048 10498793472 1093795184 11.779 97 47 92 0 1139847488 36038544 10013760
03:24:51 45049 8192000000 10107759168 10544037888 1093507744 11.776 97 41 79 0 1187974784 85487520 8979520
03:26:41 5481 8192000000 10148945952 10571386880 1137189552 12.189 100 61 120 0 1230788448 80967984 12630912
03:27:49 48266 8192000000 10169699184 10641997824 1146832656 12.280 100 17 34 0 1268481168 117599280 4049232
03:29:38 99658 8192000000 10196006976 10653495296 1141127824 12.226 100 17 34 0 1278266784 133018752 4120208
03:32:44 97939 8192000000 10083014592 10540879872 1097317984 11.812 98 26 52 0 1277689008 173890736 6480288
03:35:22 64115 8192000000 10059797024 10518630400 1114851072 11.978 99 20 40 0 1170341232 51571040 3919120
03:37:26 35584 8192000000 10078912288 10515996672 1114080112 11.971 98 20 40 0 1155596272 37178896 4337264
03:39:07 75603 8192000000 10114585872 10600427520 1122729408 12.053 98 13 26 0 1190707488 65546928 2431152
03:42:36 32788 8192000000 10089474032 10536951808 1143744384 12.251 100 32 62 0 1206033696 56108112 6181200
03:44:09 8836 8192000000 10126714992 10554916864 1140557088 12.221 100 38 74 1 1217299072 69261024 7480960
03:45:34 35824 8192000000 10102619024 10581164032 1135280976 12.171 99 28 56 0 1230982784 90197840 5503968
03:47:10 5809 8192000000 10136909216 10557837312 1150035616 12.310 100 68 133 0 1228184832 64423808 13725408
03:48:23 99903 8192000000 10118166384 10573864960 1127222800 12.095 99 43 84 1 1216917568 81212608 8482160
03:51:07 42121 8192000000 10112008912 10620395520 1134439088 12.163 100 18 36 0 1218435824 80212944 3783792
03:52:53 50763 8192000000 10070327424 10577801216 1137342432 12.191 100 6 12 0 1182218192 44207104 668656
03:54:44 60312 8192000000 10107543056 10565230592 1115679632 11.986 97 18 37 0 1178478928 59113984 3685312
03:56:47 27403 8192000000 10085431440 10597785600 1133701392 12.156 100 10 20 0 1191567488 55523856 2342240
03:58:41 35930 8192000000 10093371776 10590797824 1149343616 12.303 100 26 48 0 1207580592 53219648 5017328
04:01:15 101390 8192000000 10088753808 10583801856 1097126000 11.810 95 16 32 0 1160984800 60305472 3553328
04:04:00 60516 8192000000 10083934656 10556592128 1096938720 11.809 96 17 32 0 1159550448 58472320 4139408
04:06:06 44151 8192000000 10090517216 10534641664 1101751808 11.854 97 28 54 0 1170504304 62823856 5928640
04:08:03 28192 8192000000 10191234192 10632081408 1140190128 12.217 100 55 108 0 1283056272 131566672 11299472
04:10:11 24929 8192000000 10152333856 10642571264 1083064000 11.677 95 32 63 0 1266093888 175947376 7082512
04:11:42 30671 8192000000 10192348016 10665361408 1124325440 12.068 100 43 86 0 1281254080 147994480 8934160
04:13:19 49121 8192000000 10082969376 10527375360 1111031424 11.942 98 34 62 1 1188179520 70835072 6313024
04:15:11 25987 8192000000 10137905504 10611924992 1115781120 11.987 99 48 96 0 1252674768 127173984 9719664
04:16:44 82511 8192000000 10075021104 10515640320 1070652944 11.558 95 38 76 0 1127933008 48064400 9215664
04:19:12 46275 8192000000 10063477264 10536579072 1106639840 11.901 100 29 58 0 1183796592 71120080 6036672
04:20:58 53124 8192000000 10063831296 10562940928 1103613712 11.872 99 8 17 1 1169881712 64764368 1503632
04:22:51 129360 8192000000 10100513952 10590937088 1102543440 11.862 98 14 28 0 1192066000 86390016 3132544
04:26:01 44917 8192000000 10063104000 10516656128 1115969488 11.989 99 12 22 0 1164093600 45999616 2124496
04:27:53 52049 8192000000 10132453872 10584346624 1121941792 12.045 98 35 70 1 1198303584 68794064 7567728
04:29:47 47596 8192000000 10146087632 10581942272 1113479712 11.965 98 41 80 2 1218850960 94988096 10383152
04:31:35 76565 8192000000 10157789248 10593476608 1115605664 11.985 98 33 66 0 1213486624 91069072 6811888
04:33:52 50925 8192000000 10120480240 10632630272 1125768656 12.081 100 31 62 0 1234895664 103021072 6105936
04:35:47 54870 8192000000 10116472416 10572890112 1093086896 11.772 97 39 76 0 1171146448 70663776 7395776

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 12-02-2023 04:40:37 to 12-02-2023 04:40:53.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 145 v_add_invoice_to_stock 335544517
except 20 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:03, from 12-02-2023 04:40:54 to 12-02-2023 04:40:57.

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          210269
MON$OLDEST_ACTIVE               212414
MON$OLDEST_SNAPSHOT             212414
MON$NEXT_TRANSACTION            212414
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                       2072608
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             764
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2899 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2899 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2899 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2899 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 ---
:157: 
:158: mscs-test-161.budzdorov.ru    Sun Feb 12 04:37:30 2023
:159:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:160: 
:161: 
:162: mscs-test-161.budzdorov.ru    Sun Feb 12 04:37:31 2023
:163:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:164: 
:165: 
:166: mscs-test-161.budzdorov.ru    Sun Feb 12 04:37:45 2023
:167:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:168: 
:169: 
:170: mscs-test-161.budzdorov.ru    Sun Feb 12 04:38:47 2023
:171:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:172: 
:173: 
:174: mscs-test-161.budzdorov.ru    Sun Feb 12 04:38:48 2023
:175:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:176: 
--- end of diff output ---

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

12.02.23 04:40:58. 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-02-12 04:41:00 No severe PSQL-related problems occured
12.02.23 04:41:02. End of report.