Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.10.2022 07: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 15219496 kB
MemAvailable 30575632 kB
Buffers 0 kB
Cached 16517572 kB
SwapCached 2092 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.3
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-10-07 07:37:20
DTS_END                         2022-10-07 07:37:20.0690
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/55190
TRN_ID                          220639
ATT_ID                          629
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: 9083.73 21244 1090047
customer order: creation 173.14 499 20777
customer order: refuse 14.63 370 1756
order to supplier: creation 46.18 1427 5541
order to supplier: removal 3.38 163836 405
invoice (draft): creation 45.92 3130 5510
invoice (draft): removal 51.07 956 6128
invoice accept: apply 40.67 25804 4880
invoice accept: cancel 46.14 1116 5537
customer reserve: creation 3815.33 243 457839
customer reserve: removal 4559.14 95 547097
realization accept: apply 81.40 264 9768
realization accept: cancel 78.03 166 9363
payment from customer: creation 58.76 162 7051
payment from customer: removal 6.34 137 761
payment to supplier: creation 47.84 180 5741
payment to supplier: removal 3.37 118 404
service: total inventory turnovers 3.83 3884 460
service: total monetary turnovers 7.56 360 907
service: aggregate perf. data 0.98 16003 118
service: refresh index statistics 0.03 206123 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-10-2022 07:39:07 to 07-10-2022 07:39:09.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9973
TEST_TIME 2 6899
TEST_TIME 3 6763
TEST_TIME 4 6258
TEST_TIME 5 6249
TEST_TIME 6 6174
TEST_TIME 7 6290
TEST_TIME 8 6329
TEST_TIME 9 6813
TEST_TIME 10 6946
TEST_TIME 11 7009
TEST_TIME 12 7288
TEST_TIME 13 7534
TEST_TIME 14 7580
TEST_TIME 15 7674
TEST_TIME 16 7752
TEST_TIME 17 7762
TEST_TIME 18 7735
TEST_TIME 19 7790
TEST_TIME 20 7902
TEST_TIME 21 7942
TEST_TIME 22 8252
TEST_TIME 23 8309
TEST_TIME 24 8427
TEST_TIME 25 8792
TEST_TIME 26 9111
TEST_TIME 27 9219
TEST_TIME 28 9396
TEST_TIME 29 9537
TEST_TIME 30 9898
TEST_TIME 31 9911
TEST_TIME 32 10254
TEST_TIME 33 10641
TEST_TIME 34 11325
TEST_TIME 35 11360
TEST_TIME 36 11300
TEST_TIME 37 11137
TEST_TIME 38 11151
TEST_TIME 39 11036
TEST_TIME 40 11006
TEST_TIME 41 10814
TEST_TIME 42 10660
TEST_TIME 43 10588
TEST_TIME 44 10397
TEST_TIME 45 10285
TEST_TIME 46 10405
TEST_TIME 47 10300
TEST_TIME 48 10333
TEST_TIME 49 10352
TEST_TIME 50 10274
TEST_TIME 51 10186
TEST_TIME 52 10170
TEST_TIME 53 10155
TEST_TIME 54 10004
TEST_TIME 55 9917
TEST_TIME 56 9838
TEST_TIME 57 9946
TEST_TIME 58 9887
TEST_TIME 59 9899
TEST_TIME 60 9862
TEST_TIME 61 9808
TEST_TIME 62 9768
TEST_TIME 63 9787
TEST_TIME 64 9926
TEST_TIME 65 9980
TEST_TIME 66 10055
TEST_TIME 67 10046
TEST_TIME 68 10031
TEST_TIME 69 10067
TEST_TIME 70 10021
TEST_TIME 71 10121
TEST_TIME 72 10000
TEST_TIME 73 9971
TEST_TIME 74 9880
TEST_TIME 75 9831
TEST_TIME 76 9795
TEST_TIME 77 9746
TEST_TIME 78 9697
TEST_TIME 79 9691
TEST_TIME 80 9623
TEST_TIME 81 9586
TEST_TIME 82 9592
TEST_TIME 83 9589
TEST_TIME 84 9569
TEST_TIME 85 9554
TEST_TIME 86 9515
TEST_TIME 87 9545
TEST_TIME 88 9575
TEST_TIME 89 9510
TEST_TIME 90 9577
TEST_TIME 91 9672
TEST_TIME 92 9623
TEST_TIME 93 9654
TEST_TIME 94 9629
TEST_TIME 95 9608
TEST_TIME 96 9543
TEST_TIME 97 9531
TEST_TIME 98 9481
TEST_TIME 99 9448
TEST_TIME 100 9375
TEST_TIME 101 9317
TEST_TIME 102 9306
TEST_TIME 103 9299
TEST_TIME 104 9321
TEST_TIME 105 9337
TEST_TIME 106 9317
TEST_TIME 107 9336
TEST_TIME 108 9340
TEST_TIME 109 9340
TEST_TIME 110 9384
TEST_TIME 111 9373
TEST_TIME 112 9350
TEST_TIME 113 9361
TEST_TIME 114 9290
TEST_TIME 115 9310
TEST_TIME 116 9285
TEST_TIME 117 9249
TEST_TIME 118 9209
TEST_TIME 119 9166
TEST_TIME 120 9112

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 07-10-2022 07:39:11 to 07-10-2022 07:39:14.

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 1513453 1513453 0 0.00 0 21677 13 0 0
doc_list_biud 1513441 1513441 0 0.00 0 25797 40 0 0
sp_add_invoice_to_stock 5011 4880 131 2.61 33 219169 25805 0 131
sp_cancel_adding_invoice 5537 5537 0 0.00 6 76493 1117 0 0
sp_cancel_client_order 1756 1756 0 0.00 5 10663 371 0 0
sp_cancel_customer_reserve 547097 547097 0 0.00 1 19211 95 0 0
sp_cancel_pay_from_customer 761 761 0 0.00 4 10228 138 0 0
sp_cancel_pay_to_supplier 404 404 0 0.00 4 3837 118 0 0
sp_cancel_supplier_invoice 6134 6128 6 0.09 3 25803 957 0 6
sp_cancel_supplier_order 405 405 0 0.00 9 1390724 163836 0 0
sp_cancel_write_off 9363 9363 0 0.00 2 11301 167 0 0
sp_client_order 20777 20777 0 0.00 6 21492 499 0 0
sp_customer_reserve 457839 457839 0 0.00 1 64805 243 0 0
sp_fill_shopping_cart 31817 31817 0 0.00 1 19170 80 0 0
sp_fill_shopping_cart_clo_res 457804 457804 0 0.00 0 14513 18 0 0
sp_get_clo_for_invoice 4827 4827 0 0.00 0 33180 307 0 0
sp_kill_cost_storno 16065 16065 0 0.00 0 21661 71 0 0
sp_kill_qstorno_ret_qs2qd 1110090 1110090 0 0.00 0 25796 41 0 0
sp_kill_qty_storno 584880 584880 0 0.00 0 25797 94 0 0
sp_lock_selected_doc 20335 20335 0 0.00 0 9541 9 0 0
sp_make_cost_storno 27387 27387 0 0.00 0 13177 74 0 0
sp_multiply_rows_for_pdistr 27387 27387 0 0.00 0 10778 37 0 0
sp_multiply_rows_for_qdistr 20777 20777 0 0.00 0 16194 203 0 0
sp_pay_from_customer 7051 7051 0 0.00 5 10334 163 0 0
sp_pay_to_supplier 5741 5741 0 0.00 6 7848 180 0 0
sp_payment_common 12790 12790 0 0.00 2 5191 39 0 0
sp_qd_handle_on_cancel_clo 1756 1756 0 0.00 0 10644 301 0 0
sp_qd_handle_on_invoice_upd_sts 10364 10364 0 0.00 0 19062 491 0 0
sp_qd_handle_on_reserve_upd_sts 9767 9767 0 0.00 0 15661 59 0 0
sp_reserve_write_off 9768 9768 0 0.00 8 15828 264 0 0
sp_supplier_invoice 5510 5510 0 0.00 9 80226 3131 0 0
sp_supplier_order 5541 5541 0 0.00 8 32377 1428 0 0
srv_aggregate_perf_data 118 118 0 0.00 2091 96340 16003 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 914 101000 37004 0 0
srv_find_qd_qs_mism 1042204 1042204 0 0.00 0 20820 10 0 0
srv_make_invnt_saldo 460 460 0 0.00 44 89381 3885 0 0
srv_make_money_saldo 907 907 0 0.00 1 14949 361 0 0
srv_recalc_idx_stat 4 4 0 0.00 44018 273316 206124 0 0
t$perf-abend:sp_add_invoice_to_stock 131 131 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4885 4885 0 0.00 1 11500 909 0 0
t$perf-norm:sp_cancel_adding_invoice 400 400 0 0.00 1 7719 387 0 0
t$perf-norm:sp_cancel_client_order 1756 1756 0 0.00 0 3286 29 0 0
t$perf-norm:sp_cancel_customer_reserve 663 663 0 0.00 0 1196 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 761 761 0 0.00 0 1485 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 404 404 0 0.00 0 363 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 386 386 0 0.00 0 722 38 0 0
t$perf-norm:sp_cancel_supplier_order 405 405 0 0.00 0 93897 7515 0 0
t$perf-norm:sp_cancel_write_off 973 973 0 0.00 0 9252 30 0 0
t$perf-norm:sp_client_order 20778 20778 0 0.00 0 5859 38 0 0
t$perf-norm:sp_customer_reserve 992 992 0 0.00 0 8318 26 0 0
t$perf-norm:sp_pay_from_customer 7052 7052 0 0.00 0 7077 21 0 0
t$perf-norm:sp_pay_to_supplier 5741 5741 0 0.00 0 7377 21 0 0
t$perf-norm:sp_reserve_write_off 9769 9769 0 0.00 0 10503 25 0 0
t$perf-norm:sp_supplier_invoice 5510 5510 0 0.00 0 6104 32 0 0
t$perf-norm:sp_supplier_order 5541 5541 0 0.00 0 5683 34 0 0
t$perf-norm:srv_aggregate_perf_data 118 118 0 0.00 0 393 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 135 5 0 0
t$perf-norm:srv_make_invnt_saldo 460 460 0 0.00 0 845 14 0 0
t$perf-norm:srv_make_money_saldo 907 907 0 0.00 0 3430 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 13 199 71 0 0
v_add_invoice_to_stock 9904 9642 262 2.64 0 6858 27 0 262
v_all_customers 37890 37890 0 0.00 0 79 4 0 0
v_all_suppliers 30816 30816 0 0.00 0 70 4 0 0
v_all_wares 41550 41550 0 0.00 0 167 4 0 0
v_cancel_adding_invoice 800 800 0 0.00 0 1004 15 0 0
v_cancel_client_order 3512 3512 0 0.00 0 3792 11 0 0
v_cancel_customer_prepayment 1522 1522 0 0.00 0 4848 18 0 0
v_cancel_customer_reserve 1326 1326 0 0.00 0 773 10 0 0
v_cancel_payment_to_supplier 808 808 0 0.00 0 3756 20 0 0
v_cancel_supplier_invoice 784 772 12 1.53 0 2893 29 0 12
v_cancel_supplier_order 810 810 0 0.00 0 554 10 0 0
v_cancel_write_off 1946 1946 0 0.00 0 8487 26 0 0
v_max_id_clo_ord 5538 5538 0 0.00 0 1158 5 0 0
v_max_id_clo_res 992 992 0 0.00 0 1809 13 0 0
v_max_id_ord_sup 5503 5503 0 0.00 0 7095 6 0 0
v_max_non_paid_invoice 1372 1372 0 0.00 0 120 8 0 0
v_max_non_paid_realizn 3756 3756 0 0.00 0 405 7 0 0
v_min_id_clo_ord 5538 5538 0 0.00 0 1595 8 0 0
v_min_id_clo_res 992 992 0 0.00 0 1450 8 0 0
v_min_id_ord_sup 5503 5503 0 0.00 0 8413 15 0 0
v_min_non_paid_invoice 5739 5739 0 0.00 0 3063 10 0 0
v_min_non_paid_realizn 7051 7051 0 0.00 0 5188 13 0 0
v_our_firm 10248 10248 0 0.00 0 71 5 0 0
v_reserve_write_off 19532 19532 0 0.00 0 7732 11 0 0
x_lock_depdocs_on_canc_invoice 11279 11279 0 0.00 0 9482 113 0 0
x_lock_depdocs_on_canc_sup_ord 405 405 0 0.00 0 11992 401 0 0
x_make_qstorno_1000_1200 5541 5541 0 0.00 2 32346 1267 0 0
x_make_qstorno_1200_2000 5510 5510 0 0.00 4 80216 2914 0 0
x_make_qstorno_2100_3300 457837 457837 0 0.00 0 64803 214 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-10-2022 07:39:15 to 07-10-2022 07:39:19.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:07:23 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
05:08:24 26868 8192000000 9724287616 10021687296 800870256 8.905 100 86 172 0 831482080 9517392 21094432
05:09:58 16168 8192000000 9765947008 10074988544 841675776 9.317 100 89 178 0 874692576 12018096 20998704
05:11:23 16126 8192000000 9807954512 10126884864 873015536 9.630 100 87 174 0 913859296 20522272 20321488
05:12:40 8297 8192000000 9858041744 10191319040 914467808 10.041 100 81 158 0 972005248 40415680 17121760
05:13:51 4103 8192000000 9913169632 10257883136 950908880 10.400 100 86 169 0 1030236048 61267584 18059584
05:14:57 2213 8192000000 9948813296 10298208256 992275936 10.804 100 94 184 0 1068309936 57068736 18965264
05:16:02 2069 8192000000 10014343616 10375077888 1015266496 11.026 100 92 174 0 1129900432 98253536 16380400
05:17:08 1105 8192000000 10000518464 10370560000 1040940912 11.274 100 94 182 2 1116775840 58088240 17746688
05:18:10 1367 8192000000 10010194176 10388025344 1053921040 11.398 100 93 171 4 1132445712 60337952 18186720
05:19:13 1068 8192000000 10015152464 10386993152 1062931344 11.485 100 98 183 0 1136693424 56190640 17571440
05:20:18 1424 8192000000 10038350112 10419605504 1070299712 11.555 100 93 180 0 1163663712 74074720 19289280
05:21:21 1087 8192000000 10051808784 10433720320 1074735424 11.597 100 98 190 1 1175410400 81585488 19089488
05:22:23 2128 8192000000 10056536960 10438246400 1084611120 11.691 100 87 161 2 1175426880 74426320 16389440
05:23:27 968 8192000000 10052737376 10436644864 1085193952 11.697 100 95 185 1 1174599664 70130928 19274784
05:24:29 1026 8192000000 10066268640 10453893120 1086620576 11.711 100 93 180 0 1191192528 85221040 19350912
05:25:32 897 8192000000 10071190832 10463506432 1092708784 11.768 100 92 176 2 1198488640 87329616 18450240
05:26:34 1223 8192000000 10073170784 10466017280 1094311200 11.784 100 87 170 1 1199979072 88293920 17373952
05:27:36 1152 8192000000 10021428368 10412474368 1083934400 11.685 99 85 151 1 1149736048 51206000 14595648
05:28:38 2100 8192000000 10032527696 10424807424 1084360240 11.689 100 88 167 0 1150229744 48304032 17565472
05:29:41 1153 8192000000 10030240768 10415861760 1077982704 11.628 99 81 146 3 1150837424 59122720 13732000
05:30:44 1133 8192000000 10020933392 10414534656 1072880336 11.580 100 78 143 4 1144330624 57276368 14173920
05:31:46 1984 8192000000 10010553536 10395422720 1053409200 11.393 100 74 142 1 1135364240 66712336 15242704
05:32:48 1137 8192000000 9984958096 10355445760 1032080608 11.188 99 94 174 2 1107685248 56877552 18727088
05:33:50 970 8192000000 9964202272 10332364800 1021465648 11.086 100 92 175 2 1089364208 48908032 18990528
05:34:52 2558 8192000000 9991946576 10369859584 1032840384 11.196 99 80 148 1 1108611552 60578432 15192736
05:35:55 2185 8192000000 10000926816 10379055104 1048901632 11.350 100 74 143 0 1118945376 55861424 14182320
05:36:58 3643 8192000000 10009976000 10390605824 1045239056 11.315 100 61 111 2 1128989488 72300480 11449952
05:38:07 1376 8192000000 10022286016 10404036608 1068311264 11.536 100 82 159 0 1142471664 57845056 16315344
05:39:17 20030 8192000000 10049985184 10447974400 1085952944 11.704 100 72 136 2 1170987792 71522864 13511984
05:40:41 4017 8192000000 10074582256 10460311552 1103942176 11.875 100 77 149 0 1197655040 77624928 16087936
05:41:48 7710 8192000000 10139776704 10532782080 1108114176 11.915 100 67 130 1 1250516336 128050592 14351568
05:42:59 1857 8192000000 10152116576 10554060800 1127363456 12.097 100 79 151 1 1272361792 128064128 16934208
05:44:08 7628 8192000000 10185563184 10587377664 1135986400 12.178 100 69 134 1 1310515744 160238640 14290704
05:45:23 2281 8192000000 10219611472 10628038656 1147585872 12.287 100 86 168 0 1336200144 170448672 18165600
05:46:28 1887 8192000000 10240506000 10658770944 1163565232 12.437 100 92 180 0 1356163712 173343312 19255168
05:47:34 1598 8192000000 10261964240 10663313408 1170322960 12.500 100 97 182 3 1371199632 181131616 19745056
05:48:38 2221 8192000000 10307680528 10711097344 1180379376 12.594 100 86 167 0 1421917280 222767248 18770656
05:49:42 972 8192000000 10349387872 10761084928 1184902976 12.636 100 94 185 1 1462569776 254653920 23012880
05:50:45 2249 8192000000 10335209728 10758811648 1195406224 12.734 100 82 157 1 1447208144 234290208 17511712
05:51:51 2969 8192000000 10367217984 10795462656 1195009856 12.730 100 80 157 1 1489065456 275783840 18271760
05:53:00 1444 8192000000 10394574304 10818060288 1204392816 12.817 100 84 163 2 1508011952 284505600 19113536
05:54:04 1424 8192000000 10413319024 10829017088 1201076512 12.786 100 89 175 0 1525904272 303696784 21130976
05:55:08 2048 8192000000 10469681072 10878611456 1208047856 12.851 100 89 176 0 1571075008 342397328 20629824
05:56:13 1010 8192000000 10481507536 10917904384 1206023296 12.832 100 96 190 0 1591462976 361676256 23763424
05:57:17 1446 8192000000 10525077200 10936664064 1199074704 12.768 100 97 189 1 1631810304 410091264 22644336
05:58:21 898 8192000000 10517161488 10959855616 1190243360 12.686 100 96 187 0 1624291216 412179216 21868640
05:59:24 7688 8192000000 10518781472 10969255936 1190203392 12.685 100 51 99 0 1624562672 421251216 13108064
06:00:37 3567 8192000000 10528375072 10985652224 1186944672 12.655 100 67 123 1 1636907536 433251680 16711184
06:01:54 1914 8192000000 10502751920 10979647488 1177278912 12.565 100 88 159 2 1600852208 404584112 18989184
06:02:57 2422 8192000000 10479326512 10938855424 1172796496 12.523 100 67 134 2 1586292320 397210720 16285104
06:04:15 10034 8192000000 10471727088 10901094400 1186627952 12.652 100 46 90 1 1574037808 376225952 11183904
06:05:28 10238 8192000000 10465457376 10943848448 1198606608 12.763 100 51 102 0 1601554736 390511056 12437072
06:06:40 6856 8192000000 10451670448 10963828736 1176490752 12.557 100 38 76 0 1570642928 384166336 9985840
06:07:54 29410 8192000000 10393941008 10886705152 1152683312 12.335 98 27 54 0 1495539984 335983328 6873344
06:10:12 55276 8192000000 10168441984 10779996160 1146196896 12.274 99 15 30 0 1371610608 221908016 3505696
06:12:16 98853 8192000000 10080588624 10569351168 1137016240 12.187 98 13 24 1 1202628352 63451888 2160224
06:14:56 48659 8192000000 10118274336 10589200384 1146601952 12.278 100 20 40 0 1217549328 66287344 4660032
06:16:46 38513 8192000000 10131060032 10573946880 1152437984 12.332 100 40 75 1 1226022704 65551136 8033584
06:18:29 64239 8192000000 10088552960 10550169600 1151153552 12.320 100 48 87 2 1179466288 19056608 9256128
06:20:41 79481 8192000000 10144472464 10603372544 1151940928 12.328 99 25 50 0 1240087104 82837552 5308624
06:23:05 33449 8192000000 10123526848 10629386240 1149573648 12.305 100 11 22 0 1240722416 88491744 2657024
06:25:03 26986 8192000000 10126202128 10584907776 1140227760 12.218 100 46 90 1 1215955072 66339520 9387792
06:26:56 73139 8192000000 10090700816 10556993536 1116541488 11.994 98 15 30 0 1156661824 36956640 3163696
06:29:17 30954 8192000000 10111387392 10612080640 1138383248 12.200 100 10 20 0 1201088112 60649456 2055408
06:30:48 58844 8192000000 10096783744 10553556992 1121816128 12.044 98 10 20 0 1180236496 56227728 2192640
06:32:48 90863 8192000000 10093108256 10579554304 1115386672 11.983 98 7 14 0 1182048144 65169552 1491920
06:35:27 62113 8192000000 10056347904 10536353792 1097613504 11.815 97 19 38 0 1136686816 34603088 4470224
06:37:35 20393 8192000000 10101903472 10553131008 1126292736 12.086 98 34 68 0 1184459792 50868448 7298608
06:39:17 65165 8192000000 10220433744 10682834944 1164114320 12.442 100 26 52 0 1300762464 131393744 5254400
06:41:23 46989 8192000000 10150158768 10618040320 1124574160 12.070 98 17 34 0 1257888912 129764688 3550064
06:43:24 88524 8192000000 10101503552 10560278528 1094237024 11.783 96 33 66 0 1146724080 45951856 6535200
06:45:53 48014 8192000000 10109205936 10579464192 1117174416 12.000 98 27 53 0 1188872768 66423168 5275184
06:48:07 41723 8192000000 10113021904 10620006400 1131001072 12.131 98 26 52 0 1226466288 90375008 5090208
06:49:49 82990 8192000000 10079539392 10545373184 1123537792 12.060 98 29 58 0 1169628848 39745024 6346032
06:52:52 66040 8192000000 10056412688 10510598144 1094188816 11.782 97 26 52 0 1155241424 55629456 5423152
06:55:06 78307 8192000000 10083344336 10555772928 1108107840 11.914 99 19 37 0 1167033744 54814896 4111008
06:57:28 77670 8192000000 10098488256 10573426688 1110222992 11.935 98 23 42 0 1192564336 77838800 4502544
06:59:48 101000 8192000000 10090117024 10559725568 1093864432 11.779 97 19 38 0 1132576336 34871824 3840080
07:02:39 61491 8192000000 10115658640 10554241024 1071836016 11.570 95 20 40 0 1138301152 62253344 4211792
07:04:43 91096 8192000000 10110458144 10594344960 1090079872 11.743 97 18 36 0 1162483952 68890032 3514048
07:07:14 83230 8192000000 10098659712 10608369664 1127666544 12.099 99 28 56 0 1241941616 108436752 5838320
07:09:43 30646 8192000000 10108376944 10546622464 1113178304 11.962 98 47 90 1 1190505984 68268560 9059120
07:11:17 14667 8192000000 10118109072 10542792704 1127048880 12.094 99 42 84 0 1204391376 69330224 8012272
07:12:32 73000 8192000000 10080514592 10546573312 1114286928 11.973 98 38 76 1 1186385472 64497120 7601424
07:15:15 43956 8192000000 10074111200 10522505216 1142868768 12.243 100 13 23 1 1166807152 21556032 2382352
07:17:12 54557 8192000000 10071456096 10548039680 1138740992 12.204 99 11 22 0 1182807840 41515504 2551344
07:19:15 55312 8192000000 10114175296 10590507008 1142132576 12.236 99 31 62 1 1224374608 75523360 6718672
07:21:11 21044 8192000000 10091117168 10539888640 1134347312 12.162 99 34 64 3 1172290832 30210560 7732960
07:22:47 27396 8192000000 10095907120 10538303488 1130084080 12.122 99 25 50 0 1180112560 44889456 5139024
07:24:16 40602 8192000000 10118109120 10594680832 1140370336 12.219 100 35 70 0 1226198736 78215664 7612736
07:25:57 76926 8192000000 10105782176 10560794624 1096885392 11.808 97 23 46 0 1181330064 79604080 4840592
07:28:14 33741 8192000000 10066628784 10513190912 1119385696 12.021 99 40 80 0 1157284336 29464032 8434608
07:29:55 18097 8192000000 10136490832 10578395136 1122004944 12.046 99 51 102 0 1214490752 82295888 10189920
07:31:19 97691 8192000000 10099668528 10597199872 1128729904 12.109 100 31 60 1 1179869232 44487792 6651536
07:33:58 40624 8192000000 10050285952 10529501184 1119573152 12.023 99 10 20 0 1171822336 50088592 2160592
07:35:38 73530 8192000000 10102326656 10550075392 1134352768 12.162 99 16 33 0 1196083760 58246544 3484448

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 07-10-2022 07:39:21 to 07-10-2022 07:39:38.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 131 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

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

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          217130
MON$OLDEST_ACTIVE               220961
MON$OLDEST_SNAPSHOT             220961
MON$NEXT_TRANSACTION            220961
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                       2071968
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             776
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2855 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2855 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2855 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2855 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 ---
:114: 
:115: mscs-test-161.budzdorov.ru    Fri Oct  7 07:37:21 2022
:116:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:117: 
:118: 
:119: mscs-test-161.budzdorov.ru    Fri Oct  7 07:37:29 2022
:120:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:121: 
:122: 
:123: mscs-test-161.budzdorov.ru    Fri Oct  7 07:37:29 2022
:124:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:125: 
--- end of diff output ---

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

07.10.22 07:39:42. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-10-07 07:39:44 No severe PSQL-related problems occured
07.10.22 07:39:46. End of report.