Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.02.2023 10:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15380624 kB
MemAvailable 30423776 kB
Buffers 0 kB
Cached 16346872 kB
SwapCached 3140 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-25 10:37:25
DTS_END                         2023-02-25 10:37:25.9500
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/50350
TRN_ID                          218595
ATT_ID                          647
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: 8549.97 18860 1025996
customer order: creation 168.43 522 20211
customer order: refuse 13.73 395 1647
order to supplier: creation 44.51 1455 5341
order to supplier: removal 3.43 160155 411
invoice (draft): creation 44.27 3512 5312
invoice (draft): removal 47.34 1042 5681
invoice accept: apply 39.72 27562 4766
invoice accept: cancel 42.21 1259 5065
customer reserve: creation 3699.70 263 443964
customer reserve: removal 4161.13 104 499336
realization accept: apply 80.62 277 9674
realization accept: cancel 78.50 173 9420
payment from customer: creation 57.76 173 6931
payment from customer: removal 5.63 137 676
payment to supplier: creation 47.46 190 5695
payment to supplier: removal 3.16 120 379
service: total inventory turnovers 4.18 3668 502
service: total monetary turnovers 7.25 410 870
service: aggregate perf. data 0.93 15185 111
service: refresh index statistics 0.03 160599 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 25-02-2023 10:38:55 to 25-02-2023 10:38:57.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6039
TEST_TIME 2 5423
TEST_TIME 3 5417
TEST_TIME 4 6251
TEST_TIME 5 5594
TEST_TIME 6 5514
TEST_TIME 7 6135
TEST_TIME 8 6355
TEST_TIME 9 6391
TEST_TIME 10 6555
TEST_TIME 11 6518
TEST_TIME 12 6611
TEST_TIME 13 6576
TEST_TIME 14 6599
TEST_TIME 15 6670
TEST_TIME 16 6877
TEST_TIME 17 6862
TEST_TIME 18 7188
TEST_TIME 19 7316
TEST_TIME 20 7544
TEST_TIME 21 7699
TEST_TIME 22 7915
TEST_TIME 23 8367
TEST_TIME 24 8229
TEST_TIME 25 8506
TEST_TIME 26 8518
TEST_TIME 27 8589
TEST_TIME 28 9551
TEST_TIME 29 9989
TEST_TIME 30 10468
TEST_TIME 31 10731
TEST_TIME 32 10595
TEST_TIME 33 10381
TEST_TIME 34 10340
TEST_TIME 35 10122
TEST_TIME 36 9964
TEST_TIME 37 9849
TEST_TIME 38 9833
TEST_TIME 39 9856
TEST_TIME 40 9734
TEST_TIME 41 9586
TEST_TIME 42 9557
TEST_TIME 43 9536
TEST_TIME 44 9458
TEST_TIME 45 9345
TEST_TIME 46 9272
TEST_TIME 47 9138
TEST_TIME 48 9023
TEST_TIME 49 9001
TEST_TIME 50 9049
TEST_TIME 51 9098
TEST_TIME 52 9050
TEST_TIME 53 8918
TEST_TIME 54 8817
TEST_TIME 55 8927
TEST_TIME 56 9053
TEST_TIME 57 9002
TEST_TIME 58 8971
TEST_TIME 59 8973
TEST_TIME 60 9005
TEST_TIME 61 9037
TEST_TIME 62 8965
TEST_TIME 63 9011
TEST_TIME 64 8965
TEST_TIME 65 8986
TEST_TIME 66 8998
TEST_TIME 67 8995
TEST_TIME 68 9051
TEST_TIME 69 9102
TEST_TIME 70 9029
TEST_TIME 71 8945
TEST_TIME 72 8890
TEST_TIME 73 8861
TEST_TIME 74 8888
TEST_TIME 75 8845
TEST_TIME 76 8801
TEST_TIME 77 8752
TEST_TIME 78 8720
TEST_TIME 79 8647
TEST_TIME 80 8742
TEST_TIME 81 8728
TEST_TIME 82 8787
TEST_TIME 83 8809
TEST_TIME 84 8808
TEST_TIME 85 8796
TEST_TIME 86 8847
TEST_TIME 87 8886
TEST_TIME 88 8864
TEST_TIME 89 8948
TEST_TIME 90 8950
TEST_TIME 91 8989
TEST_TIME 92 8929
TEST_TIME 93 8888
TEST_TIME 94 8830
TEST_TIME 95 8805
TEST_TIME 96 8761
TEST_TIME 97 8747
TEST_TIME 98 8713
TEST_TIME 99 8720
TEST_TIME 100 8698
TEST_TIME 101 8698
TEST_TIME 102 8669
TEST_TIME 103 8648
TEST_TIME 104 8647
TEST_TIME 105 8617
TEST_TIME 106 8610
TEST_TIME 107 8706
TEST_TIME 108 8701
TEST_TIME 109 8844
TEST_TIME 110 8827
TEST_TIME 111 8794
TEST_TIME 112 8783
TEST_TIME 113 8752
TEST_TIME 114 8698
TEST_TIME 115 8702
TEST_TIME 116 8673
TEST_TIME 117 8653
TEST_TIME 118 8635
TEST_TIME 119 8620
TEST_TIME 120 8571

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 25-02-2023 10:38:59 to 25-02-2023 10:39:02.

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 1435713 1435713 0 0.00 0 28879 14 0 0
doc_list_biud 1435707 1435707 0 0.00 0 34169 42 0 0
sp_add_invoice_to_stock 4881 4766 115 2.35 66 251017 27563 0 115
sp_cancel_adding_invoice 5065 5065 0 0.00 7 79393 1260 0 0
sp_cancel_client_order 1647 1647 0 0.00 5 10609 395 0 0
sp_cancel_customer_reserve 499336 499336 0 0.00 1 22397 104 0 0
sp_cancel_pay_from_customer 676 676 0 0.00 4 2668 138 0 0
sp_cancel_pay_to_supplier 379 379 0 0.00 4 3938 120 0 0
sp_cancel_supplier_invoice 5689 5681 8 0.14 3 33150 1043 0 8
sp_cancel_supplier_order 411 411 0 0.00 13 1390619 160156 0 0
sp_cancel_write_off 9420 9420 0 0.00 2 9474 174 0 0
sp_client_order 20211 20211 0 0.00 5 29684 522 0 0
sp_customer_reserve 443964 443964 0 0.00 1 59994 263 0 0
sp_fill_shopping_cart 30851 30851 0 0.00 1 23184 87 0 0
sp_fill_shopping_cart_clo_res 443942 443942 0 0.00 0 15449 21 0 0
sp_get_clo_for_invoice 4733 4733 0 0.00 0 54823 324 0 0
sp_kill_cost_storno 15540 15540 0 0.00 0 6154 73 0 0
sp_kill_qstorno_ret_qs2qd 1014184 1014184 0 0.00 0 34158 47 0 0
sp_kill_qty_storno 535965 535965 0 0.00 0 34165 105 0 0
sp_lock_selected_doc 19947 19947 0 0.00 0 9265 7 0 0
sp_make_cost_storno 27031 27031 0 0.00 0 12133 73 0 0
sp_multiply_rows_for_pdistr 27031 27031 0 0.00 0 8022 39 0 0
sp_multiply_rows_for_qdistr 20211 20211 0 0.00 0 14511 201 0 0
sp_pay_from_customer 6931 6931 0 0.00 6 13391 173 0 0
sp_pay_to_supplier 5695 5695 0 0.00 6 14308 191 0 0
sp_payment_common 12625 12625 0 0.00 2 10090 43 0 0
sp_qd_handle_on_cancel_clo 1647 1647 0 0.00 0 10596 328 0 0
sp_qd_handle_on_invoice_upd_sts 9796 9796 0 0.00 0 32163 506 0 0
sp_qd_handle_on_reserve_upd_sts 9674 9674 0 0.00 0 11864 62 0 0
sp_reserve_write_off 9674 9674 0 0.00 7 12505 277 0 0
sp_supplier_invoice 5312 5312 0 0.00 9 113339 3512 0 0
sp_supplier_order 5341 5341 0 0.00 7 40318 1455 0 0
srv_aggregate_perf_data 111 111 0 0.00 2485 167255 15186 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 731 140289 36202 0 0
srv_find_qd_qs_mism 979009 979009 0 0.00 0 28876 11 0 0
srv_make_invnt_saldo 502 502 0 0.00 57 102064 3669 0 0
srv_make_money_saldo 870 870 0 0.00 1 16924 410 0 0
srv_recalc_idx_stat 4 4 0 0.00 61909 281671 160599 0 0
t$perf-abend:sp_add_invoice_to_stock 115 115 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 4768 4768 0 0.00 0 21827 880 0 0
t$perf-norm:sp_cancel_adding_invoice 399 399 0 0.00 1 7685 447 0 0
t$perf-norm:sp_cancel_client_order 1647 1647 0 0.00 0 9006 37 0 0
t$perf-norm:sp_cancel_customer_reserve 668 668 0 0.00 0 1751 25 0 0
t$perf-norm:sp_cancel_pay_from_customer 676 676 0 0.00 0 2153 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 379 379 0 0.00 0 13874 51 0 0
t$perf-norm:sp_cancel_supplier_invoice 391 391 0 0.00 0 2786 45 0 0
t$perf-norm:sp_cancel_supplier_order 411 411 0 0.00 0 98873 6231 0 0
t$perf-norm:sp_cancel_write_off 974 974 0 0.00 0 2446 19 0 0
t$perf-norm:sp_client_order 20211 20211 0 0.00 0 10581 37 0 0
t$perf-norm:sp_customer_reserve 942 942 0 0.00 0 4560 18 0 0
t$perf-norm:sp_pay_from_customer 6931 6931 0 0.00 0 3977 19 0 0
t$perf-norm:sp_pay_to_supplier 5696 5696 0 0.00 0 21430 22 0 0
t$perf-norm:sp_reserve_write_off 9674 9674 0 0.00 0 10286 26 0 0
t$perf-norm:sp_supplier_invoice 5312 5312 0 0.00 0 14908 34 0 0
t$perf-norm:sp_supplier_order 5341 5341 0 0.00 0 2118 29 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 274 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 102 5 0 0
t$perf-norm:srv_make_invnt_saldo 502 502 0 0.00 0 1270 21 0 0
t$perf-norm:srv_make_money_saldo 870 870 0 0.00 0 4001 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 6 172 48 0 0
v_add_invoice_to_stock 9688 9458 230 2.37 0 5010 23 0 230
v_all_customers 37192 37192 0 0.00 0 82 4 0 0
v_all_suppliers 29954 29954 0 0.00 0 56 4 0 0
v_all_wares 40420 40420 0 0.00 0 123 4 0 0
v_cancel_adding_invoice 798 798 0 0.00 0 1169 16 0 0
v_cancel_client_order 3294 3294 0 0.00 0 3550 11 0 0
v_cancel_customer_prepayment 1352 1352 0 0.00 0 2585 18 0 0
v_cancel_customer_reserve 1336 1336 0 0.00 0 2253 11 0 0
v_cancel_payment_to_supplier 758 758 0 0.00 0 3917 19 0 0
v_cancel_supplier_invoice 798 782 16 2.00 0 4144 28 0 16
v_cancel_supplier_order 822 822 0 0.00 0 3611 16 0 0
v_cancel_write_off 1948 1948 0 0.00 0 5379 26 0 0
v_max_id_clo_ord 5338 5338 0 0.00 0 969 5 0 0
v_max_id_clo_res 942 942 0 0.00 0 1468 16 0 0
v_max_id_ord_sup 5300 5300 0 0.00 0 6404 10 0 0
v_max_non_paid_invoice 1355 1355 0 0.00 0 5982 12 0 0
v_max_non_paid_realizn 3617 3617 0 0.00 0 2468 7 0 0
v_min_id_clo_ord 5338 5338 0 0.00 0 5917 11 0 0
v_min_id_clo_res 942 942 0 0.00 0 7050 22 0 0
v_min_id_ord_sup 5300 5300 0 0.00 0 8525 15 0 0
v_min_non_paid_invoice 5694 5694 0 0.00 0 4020 12 0 0
v_min_non_paid_realizn 6931 6931 0 0.00 0 5247 18 0 0
v_our_firm 9856 9856 0 0.00 0 76 4 0 0
v_reserve_write_off 19346 19346 0 0.00 0 5358 14 0 0
x_lock_depdocs_on_canc_invoice 10355 10355 0 0.00 0 20747 136 0 0
x_lock_depdocs_on_canc_sup_ord 411 411 0 0.00 0 3380 330 0 0
x_make_qstorno_1000_1200 5341 5341 0 0.00 3 40310 1291 0 0
x_make_qstorno_1200_2000 5312 5312 0 0.00 4 113103 3249 0 0
x_make_qstorno_2100_3300 443962 443962 0 0.00 0 59981 230 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 25-02-2023 10:39:03 to 25-02-2023 10:39:08.

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:29 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
08:08:30 25160 8192000000 9737178688 10038661120 812598512 9.024 100 91 178 0 840564528 7334880 20631136
08:10:04 19887 8192000000 9773126624 10082611200 846472432 9.365 100 87 174 0 879751248 12662480 20616336
08:11:29 17752 8192000000 9800404208 10123309056 870448016 9.604 100 85 168 0 909747632 19657648 19641968
08:12:52 6712 8192000000 9874627840 10207588352 920435120 10.100 100 76 150 1 979047856 42601552 16011184
08:14:00 2680 8192000000 9927957424 10271559680 957170576 10.461 100 94 183 0 1048405616 71942192 19292848
08:15:05 2066 8192000000 9980041568 10335752192 995903216 10.839 100 87 172 0 1089481328 75494160 18083952
08:16:10 1401 8192000000 10007347152 10369228800 1018561424 11.058 100 93 182 1 1128782464 91819808 18401232
08:17:13 969 8192000000 10011802480 10382598144 1042296912 11.287 100 92 177 1 1133061008 72224224 18539872
08:18:15 753 8192000000 10032176592 10407731200 1058217488 11.439 100 98 186 0 1153843776 77409712 18216576
08:19:17 839 8192000000 10040446896 10413412352 1066003520 11.514 100 97 192 1 1162623104 77615760 19003824
08:20:18 1166 8192000000 10029449232 10410672128 1070392560 11.556 100 84 167 2 1151184688 64212288 16579840
08:21:26 1168 8192000000 10025202944 10408890368 1079019376 11.638 100 87 175 1 1150665488 53233104 18413008
08:22:28 4845 8192000000 10057283600 10442690560 1084015744 11.686 100 73 140 3 1181295648 83454272 13825632
08:23:34 2762 8192000000 10046063680 10440978432 1086096656 11.706 100 89 173 2 1170462144 66995488 17370000
08:24:38 1221 8192000000 10063581040 10451894272 1090337696 11.746 100 96 185 2 1184392288 75435856 18618736
08:25:41 1024 8192000000 10056267552 10445070336 1090877664 11.751 100 85 164 0 1176560432 69269232 16413536
08:26:44 1498 8192000000 10027335312 10419470336 1088958304 11.733 100 73 145 2 1149841200 45758304 15124592
08:27:46 9450 8192000000 10037966608 10434121728 1090989424 11.752 100 61 118 0 1170502192 67625232 11887536
08:28:56 846 8192000000 10039218800 10430763008 1079136560 11.639 100 89 176 1 1160928528 62102848 19689120
08:29:59 3179 8192000000 10021722336 10413916160 1074677664 11.597 100 67 126 5 1149812512 62260144 12874704
08:31:04 1207 8192000000 9984788160 10357882880 1031754928 11.185 98 93 178 0 1107406640 57508624 18143088
08:32:06 1846 8192000000 9983662128 10356678656 1027434592 11.144 99 79 152 3 1097777632 55853296 14489744
08:33:09 921 8192000000 9931950032 10299523072 995243920 10.832 99 87 157 2 1057581920 45263712 17074288
08:34:11 1001 8192000000 9951702176 10320338944 1021219536 11.084 100 86 165 1 1077756272 38983920 17552816
08:35:13 961 8192000000 9985137120 10359558144 1042249552 11.286 100 90 169 1 1111056272 48881392 19925328
08:36:15 1595 8192000000 9989249840 10368700416 1033817968 11.205 100 72 128 6 1111853136 64864048 13171120
08:37:17 922 8192000000 9995973472 10373107712 1050150096 11.362 100 86 157 1 1117930128 51578864 16201168
08:38:28 3142 8192000000 10028367728 10407399424 1073583328 11.586 100 86 155 5 1137646016 47918736 16143952
08:39:36 11873 8192000000 10051469456 10447822848 1086735744 11.712 100 61 118 0 1180652448 81539744 12376960
08:40:53 7154 8192000000 10069012112 10455789568 1100558336 11.843 100 90 176 1 1181586864 63400096 17628432
08:42:04 12259 8192000000 10096366656 10488541184 1115891440 11.988 100 63 119 1 1218931744 90692464 12347840
08:43:22 2454 8192000000 10146798512 10540658688 1122703808 12.053 100 84 154 3 1261672672 123520256 15448608
08:44:33 877 8192000000 10210283120 10604937216 1132373456 12.144 100 95 183 0 1324581984 172198688 20009840
08:45:41 2517 8192000000 10192638656 10591596544 1144761024 12.260 100 85 157 1 1306908144 146390560 15756560
08:46:48 6692 8192000000 10208475552 10613813248 1157213568 12.377 100 68 134 1 1328610128 156967584 14428976
08:47:58 1022 8192000000 10236400768 10640842752 1165381184 12.454 100 85 159 2 1350302176 166751840 18169152
08:49:04 1208 8192000000 10289844336 10697740288 1176945248 12.562 100 97 181 3 1397663072 200132240 20585584
08:50:09 2971 8192000000 10317993776 10719674368 1182569488 12.614 100 85 155 2 1426475680 226927888 16978304
08:51:18 842 8192000000 10371631536 10773831680 1193951488 12.720 100 96 185 1 1480857904 264967440 21938976
08:52:23 1038 8192000000 10388430064 10812059648 1191784512 12.700 100 95 187 1 1497685440 284689808 21211120
08:53:29 1164 8192000000 10421853296 10839322624 1196728704 12.746 100 90 168 0 1525523104 309220624 19573776
08:54:32 840 8192000000 10457075552 10864103424 1184495696 12.632 100 97 192 1 1564784288 356356288 23932304
08:55:35 1198 8192000000 10460456944 10887753728 1186265584 12.649 100 95 184 1 1566962560 358712976 21984000
08:56:39 3112 8192000000 10468870384 10895527936 1183226144 12.620 100 82 157 1 1577997392 374344240 20427008
08:57:50 721 8192000000 10482498256 10914009088 1201332064 12.789 100 92 180 1 1590770112 367228720 22209328
08:58:54 1101 8192000000 10489738928 10925178880 1188567904 12.670 100 93 183 2 1595573680 385292032 21713744
08:59:58 1477 8192000000 10481304656 10939465728 1189009056 12.674 100 98 192 0 1583817648 372447648 22360944
09:01:01 7745 8192000000 10490537744 10908344320 1183304544 12.621 100 58 114 1 1602281440 404330528 14646368
09:02:19 8443 8192000000 10469296816 10942771200 1170272368 12.499 99 49 98 0 1588790320 405543936 12974016
09:03:37 11419 8192000000 10490160336 10943164416 1158741648 12.391 100 38 76 1 1602678704 433658112 10278944
09:05:14 9784 8192000000 10370642960 10854440960 1143027184 12.244 100 37 74 0 1510922800 358150992 9744624
09:06:28 39299 8192000000 10231698912 10677788672 1134459968 12.163 98 22 41 0 1337686800 198411664 4815168
09:08:12 64778 8192000000 10078152096 10533089280 1079795968 11.646 94 22 35 1 1118823840 34700352 4327520
09:10:18 28639 8192000000 10081011232 10517798912 1118779648 12.015 99 48 83 2 1171074128 42649648 9644832
09:11:50 31895 8192000000 10066306080 10512965632 1124175536 12.066 100 24 48 0 1159941024 30256848 5508640
09:13:26 114747 8192000000 10120136544 10593210368 1142065056 12.235 100 19 37 0 1233076864 86829520 4182288
09:18:02 46056 8192000000 10087783232 10544386048 1121912752 12.045 99 13 25 0 1191074320 65633696 3527872
09:19:53 140289 8192000000 10057743568 10528124928 1099821008 11.836 97 26 50 1 1172644080 66869232 5953840
09:23:27 59077 8192000000 10109077136 10586128384 1138040032 12.197 100 33 64 3 1215195104 70532880 6622192
09:25:28 39106 8192000000 10159970496 10616074240 1136370048 12.181 99 38 75 0 1244765152 100097200 8297904
09:27:17 63035 8192000000 10162771072 10628616192 1147088480 12.282 100 20 40 0 1292017120 139998832 4929808
09:29:38 77440 8192000000 10113468144 10558914560 1129452592 12.116 99 19 38 2 1209206080 75428384 4325104
09:32:23 89959 8192000000 10071882608 10569891840 1100118384 11.839 97 8 15 0 1184844768 83325616 1400768
09:34:53 34438 8192000000 10103493584 10546585600 1122351536 12.049 98 54 104 0 1183662800 50937248 10374016
09:36:29 41222 8192000000 10095815840 10535415808 1120103616 12.028 98 19 38 0 1160920752 36614768 4202368
09:38:16 61141 8192000000 10077822624 10511298560 1148059424 12.291 100 23 46 0 1183205552 31072576 4073552
09:40:24 50159 8192000000 10095272672 10552147968 1114603008 11.976 98 42 84 1 1196696416 73551392 8542016
09:42:16 23233 8192000000 10100046368 10529615872 1116886496 11.998 98 48 95 2 1198380352 71342080 10151776
09:43:39 33415 8192000000 10111023744 10556801024 1131299200 12.134 99 40 78 0 1199836240 59993664 8543376
09:45:48 72907 8192000000 10079284496 10542972928 1133821712 12.157 99 12 22 0 1176488032 40608304 2058016
09:48:05 81883 8192000000 10081823664 10515824640 1112679856 11.958 97 34 67 0 1182416448 63082832 6653760
09:50:29 29845 8192000000 10125436704 10554372096 1146213616 12.274 100 53 104 0 1220138416 63496496 10428304
09:52:00 45647 8192000000 10126179856 10581348352 1142491392 12.239 99 26 49 0 1211891520 64711248 4688880
09:53:46 44233 8192000000 10116263440 10558578688 1131823760 12.139 98 22 42 1 1204812208 68498512 4489936
09:55:31 57982 8192000000 10167499520 10629414912 1156884256 12.374 100 15 30 0 1260444016 100102512 3457248
09:57:29 28118 8192000000 10098082656 10557366272 1103211232 11.868 97 23 45 1 1175476672 67516512 4748928
09:59:08 41453 8192000000 10096895472 10538455040 1091990960 11.762 96 22 44 0 1175387216 78220976 5175280
10:00:56 69970 8192000000 10118610432 10560921600 1105571264 11.890 97 17 34 0 1174291712 64531472 4188976
10:03:07 45328 8192000000 10131232336 10624258048 1123570688 12.061 100 29 58 0 1239788368 109838144 6379536
10:05:03 34612 8192000000 10132513504 10554380288 1115585920 11.985 99 38 72 2 1208407200 85021952 7799328
10:06:39 71320 8192000000 10103869600 10534907904 1103736112 11.873 98 21 42 0 1204897344 96787344 4373888
10:08:51 14127 8192000000 10077995936 10508865536 1107670944 11.910 99 48 94 0 1170846016 53689984 9485088
10:10:32 53601 8192000000 10032659008 10457989120 1082248112 11.669 97 37 70 0 1119529504 29872144 7409248
10:12:27 85648 8192000000 10085127456 10541072384 1124893872 12.073 100 18 36 0 1193561248 64656848 4010528
10:15:44 58345 8192000000 10065339152 10559053824 1119297376 12.020 99 16 33 0 1187031504 63989088 3745040
10:17:58 44763 8192000000 10046620112 10498125824 1114083104 11.971 98 20 40 1 1157109120 38762048 4263968
10:19:46 51390 8192000000 10122381216 10565103616 1141230720 12.227 100 34 66 3 1223052944 74998352 6823872
10:21:38 31511 8192000000 10152050992 10590801920 1136354768 12.181 99 25 48 0 1241595456 99717040 5523648
10:23:14 27540 8192000000 10213027280 10658377728 1150200304 12.311 100 40 80 0 1301765280 142516864 9048112
10:24:42 75721 8192000000 10183122112 10643533824 1135823424 12.176 99 27 54 1 1268144176 126665808 5654944
10:26:59 35633 8192000000 10065879104 10494234624 1088550544 11.729 96 28 56 0 1113058064 18074160 6433360
10:28:35 8424 8192000000 10112286272 10531250176 1129444576 12.116 100 54 104 0 1194329088 54565600 10318912
10:29:46 107211 8192000000 10084577824 10518110208 1114446432 11.974 99 35 70 1 1205128272 83469792 7212048
10:32:34 65960 8192000000 10060593808 10550628352 1112045280 11.952 99 10 20 0 1177508992 63371936 2091776
10:34:40 34656 8192000000 10057335152 10506776576 1117172608 12.000 99 25 49 1 1162672128 39443024 6056496
10:36:19 40768 8192000000 10091853728 10533527552 1084326976 11.689 95 27 49 0 1147645904 58284528 5034400

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 25-02-2023 10:39:10 to 25-02-2023 10:39:26.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 115 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 25-02-2023 10:39:27 to 25-02-2023 10:39:29.

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          213872
MON$OLDEST_ACTIVE               219387
MON$OLDEST_SNAPSHOT             219387
MON$NEXT_TRANSACTION            219387
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                       2024640
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             774
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2903 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2903 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2903 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2903 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 ---
:239: 
:240: mscs-test-161.budzdorov.ru    Sat Feb 25 10:37:28 2023
:241:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:242: 
:243: 
:244: mscs-test-161.budzdorov.ru    Sat Feb 25 10:37:29 2023
:245:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:246: 
:247: 
:248: mscs-test-161.budzdorov.ru    Sat Feb 25 10:37:31 2023
:249:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:250: 
--- end of diff output ---

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

25.02.23 10:39:30. 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-25 10:39:33 No severe PSQL-related problems occured
25.02.23 10:39:34. End of report.