Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.01.2022 13: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 15978084 kB
MemAvailable 31267584 kB
Buffers 0 kB
Cached 15670388 kB
SwapCached 1176 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.1
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-01-11 13:37:12
DTS_END                         2022-01-11 13:37:12.3900
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/45330
TRN_ID                          229049
ATT_ID                          674
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: 9296.08 22217 1115530
customer order: creation 178.12 486 21374
customer order: refuse 15.07 407 1808
order to supplier: creation 47.64 1458 5717
order to supplier: removal 3.35 152069 402
invoice (draft): creation 47.91 3197 5749
invoice (draft): removal 51.80 861 6216
invoice accept: apply 43.50 24299 5220
invoice accept: cancel 47.08 1188 5649
customer reserve: creation 3974.81 232 476977
customer reserve: removal 4577.72 87 549326
realization accept: apply 87.47 289 10496
realization accept: cancel 85.68 161 10282
payment from customer: creation 61.59 187 7391
payment from customer: removal 5.93 153 712
payment to supplier: creation 51.79 216 6215
payment to supplier: removal 3.57 110 428
service: total inventory turnovers 3.71 4378 445
service: total monetary turnovers 8.45 353 1014
service: aggregate perf. data 0.87 19040 104
service: refresh index statistics 0.04 235168 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-01-2022 13:38:28 to 11-01-2022 13:38:30.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7128
TEST_TIME 2 6615
TEST_TIME 3 5791
TEST_TIME 4 5540
TEST_TIME 5 6251
TEST_TIME 6 6329
TEST_TIME 7 6408
TEST_TIME 8 6607
TEST_TIME 9 6532
TEST_TIME 10 7156
TEST_TIME 11 7485
TEST_TIME 12 7721
TEST_TIME 13 7999
TEST_TIME 14 8312
TEST_TIME 15 8409
TEST_TIME 16 8263
TEST_TIME 17 8300
TEST_TIME 18 8178
TEST_TIME 19 8327
TEST_TIME 20 8523
TEST_TIME 21 8800
TEST_TIME 22 8773
TEST_TIME 23 8870
TEST_TIME 24 8864
TEST_TIME 25 8884
TEST_TIME 26 9005
TEST_TIME 27 9295
TEST_TIME 28 9739
TEST_TIME 29 9939
TEST_TIME 30 9960
TEST_TIME 31 10318
TEST_TIME 32 10228
TEST_TIME 33 10884
TEST_TIME 34 11166
TEST_TIME 35 11369
TEST_TIME 36 11249
TEST_TIME 37 11115
TEST_TIME 38 11047
TEST_TIME 39 10960
TEST_TIME 40 10884
TEST_TIME 41 10809
TEST_TIME 42 10683
TEST_TIME 43 10804
TEST_TIME 44 10786
TEST_TIME 45 10899
TEST_TIME 46 10844
TEST_TIME 47 10687
TEST_TIME 48 10519
TEST_TIME 49 10431
TEST_TIME 50 10374
TEST_TIME 51 10310
TEST_TIME 52 10182
TEST_TIME 53 10187
TEST_TIME 54 10165
TEST_TIME 55 10074
TEST_TIME 56 10082
TEST_TIME 57 10050
TEST_TIME 58 10013
TEST_TIME 59 9926
TEST_TIME 60 9887
TEST_TIME 61 10021
TEST_TIME 62 9942
TEST_TIME 63 10015
TEST_TIME 64 10009
TEST_TIME 65 9923
TEST_TIME 66 9868
TEST_TIME 67 9917
TEST_TIME 68 9924
TEST_TIME 69 9952
TEST_TIME 70 10037
TEST_TIME 71 9950
TEST_TIME 72 10034
TEST_TIME 73 10016
TEST_TIME 74 9997
TEST_TIME 75 10089
TEST_TIME 76 10075
TEST_TIME 77 9979
TEST_TIME 78 9920
TEST_TIME 79 9870
TEST_TIME 80 9797
TEST_TIME 81 9739
TEST_TIME 82 9721
TEST_TIME 83 9698
TEST_TIME 84 9689
TEST_TIME 85 9658
TEST_TIME 86 9611
TEST_TIME 87 9554
TEST_TIME 88 9482
TEST_TIME 89 9561
TEST_TIME 90 9639
TEST_TIME 91 9661
TEST_TIME 92 9660
TEST_TIME 93 9661
TEST_TIME 94 9625
TEST_TIME 95 9618
TEST_TIME 96 9746
TEST_TIME 97 9769
TEST_TIME 98 9751
TEST_TIME 99 9770
TEST_TIME 100 9756
TEST_TIME 101 9748
TEST_TIME 102 9736
TEST_TIME 103 9723
TEST_TIME 104 9702
TEST_TIME 105 9714
TEST_TIME 106 9662
TEST_TIME 107 9630
TEST_TIME 108 9649
TEST_TIME 109 9609
TEST_TIME 110 9581
TEST_TIME 111 9518
TEST_TIME 112 9485
TEST_TIME 113 9463
TEST_TIME 114 9414
TEST_TIME 115 9378
TEST_TIME 116 9396
TEST_TIME 117 9374
TEST_TIME 118 9412
TEST_TIME 119 9394
TEST_TIME 120 9321

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 11-01-2022 13:38:31 to 11-01-2022 13:38:35.

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 1554088 1554088 0 0.00 0 24123 13 0 0
doc_list_biud 1554066 1554066 0 0.00 0 94544 37 0 0
sp_add_invoice_to_stock 5395 5220 175 3.24 78 194522 24299 0 175
sp_cancel_adding_invoice 5649 5649 0 0.00 7 71189 1189 0 0
sp_cancel_client_order 1808 1808 0 0.00 6 10404 407 0 0
sp_cancel_customer_reserve 549326 549326 0 0.00 1 33119 88 0 0
sp_cancel_pay_from_customer 712 712 0 0.00 3 7601 154 0 0
sp_cancel_pay_to_supplier 428 428 0 0.00 4 1361 110 0 0
sp_cancel_supplier_invoice 6223 6216 7 0.11 3 94616 862 0 7
sp_cancel_supplier_order 402 402 0 0.00 23 1650183 152070 0 0
sp_cancel_write_off 10282 10282 0 0.00 2 23117 162 0 0
sp_client_order 21374 21374 0 0.00 6 27867 487 0 0
sp_customer_reserve 476977 476977 0 0.00 1 44434 232 0 0
sp_fill_shopping_cart 32823 32823 0 0.00 1 15766 89 0 0
sp_fill_shopping_cart_clo_res 476940 476940 0 0.00 0 15996 18 0 0
sp_get_clo_for_invoice 5173 5173 0 0.00 0 18921 242 0 0
sp_kill_cost_storno 17071 17071 0 0.00 0 9884 70 0 0
sp_kill_qstorno_ret_qs2qd 1115552 1115552 0 0.00 0 94540 37 0 0
sp_kill_qty_storno 589349 589349 0 0.00 0 94543 88 0 0
sp_lock_selected_doc 21586 21586 0 0.00 0 7695 8 0 0
sp_make_cost_storno 29273 29273 0 0.00 0 13855 90 0 0
sp_multiply_rows_for_pdistr 29271 29271 0 0.00 0 16227 39 0 0
sp_multiply_rows_for_qdistr 21373 21373 0 0.00 0 11162 167 0 0
sp_pay_from_customer 7391 7391 0 0.00 6 13385 188 0 0
sp_pay_to_supplier 6215 6215 0 0.00 6 16253 216 0 0
sp_payment_common 13604 13604 0 0.00 2 10026 52 0 0
sp_qd_handle_on_cancel_clo 1808 1808 0 0.00 0 10396 320 0 0
sp_qd_handle_on_invoice_upd_sts 10819 10819 0 0.00 0 21167 510 0 0
sp_qd_handle_on_reserve_upd_sts 10496 10496 0 0.00 0 16521 65 0 0
sp_reserve_write_off 10496 10496 0 0.00 9 16557 290 0 0
sp_supplier_invoice 5749 5749 0 0.00 9 105671 3198 0 0
sp_supplier_order 5717 5717 0 0.00 8 29669 1459 0 0
srv_aggregate_perf_data 104 104 0 0.00 2825 211964 19041 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 787 121156 39240 0 0
srv_find_qd_qs_mism 1064362 1064362 0 0.00 0 24115 9 0 0
srv_make_invnt_saldo 445 445 0 0.00 62 58535 4378 0 0
srv_make_money_saldo 1014 1014 0 0.00 1 16139 353 0 0
srv_recalc_idx_stat 5 5 0 0.00 61659 441524 235168 0 0
t$perf-abend:sp_add_invoice_to_stock 175 175 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5229 5229 0 0.00 1 14225 1063 0 0
t$perf-norm:sp_cancel_adding_invoice 416 416 0 0.00 1 14433 554 0 0
t$perf-norm:sp_cancel_client_order 1808 1808 0 0.00 0 6336 32 0 0
t$perf-norm:sp_cancel_customer_reserve 716 716 0 0.00 0 870 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 712 712 0 0.00 0 1441 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 428 428 0 0.00 0 4085 21 0 0
t$perf-norm:sp_cancel_supplier_invoice 453 453 0 0.00 0 5917 56 0 0
t$perf-norm:sp_cancel_supplier_order 402 402 0 0.00 0 91356 6823 0 0
t$perf-norm:sp_cancel_write_off 988 988 0 0.00 0 4002 24 0 0
t$perf-norm:sp_client_order 21375 21375 0 0.00 0 8992 41 0 0
t$perf-norm:sp_customer_reserve 1054 1054 0 0.00 0 2125 23 0 0
t$perf-norm:sp_pay_from_customer 7391 7391 0 0.00 0 5003 19 0 0
t$perf-norm:sp_pay_to_supplier 6215 6215 0 0.00 0 5697 22 0 0
t$perf-norm:sp_reserve_write_off 10497 10497 0 0.00 0 20629 29 0 0
t$perf-norm:sp_supplier_invoice 5749 5749 0 0.00 0 9006 37 0 0
t$perf-norm:sp_supplier_order 5717 5717 0 0.00 0 4390 35 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 785 22 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 627 13 0 0
t$perf-norm:srv_make_invnt_saldo 445 445 0 0.00 0 2225 16 0 0
t$perf-norm:srv_make_money_saldo 1014 1014 0 0.00 0 13629 20 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 5 217 83 0 0
v_add_invoice_to_stock 10682 10332 350 3.27 0 4453 25 0 350
v_all_customers 38856 38856 0 0.00 0 106 4 0 0
v_all_suppliers 32168 32168 0 0.00 0 68 5 0 0
v_all_wares 42742 42742 0 0.00 0 58 4 0 0
v_cancel_adding_invoice 832 832 0 0.00 0 1018 14 0 0
v_cancel_client_order 3616 3616 0 0.00 0 3765 13 0 0
v_cancel_customer_prepayment 1424 1424 0 0.00 0 5074 20 0 0
v_cancel_customer_reserve 1432 1432 0 0.00 1 2792 11 0 0
v_cancel_payment_to_supplier 856 856 0 0.00 0 881 12 0 0
v_cancel_supplier_invoice 920 906 14 1.52 0 1841 27 0 14
v_cancel_supplier_order 804 804 0 0.00 0 282 10 0 0
v_cancel_write_off 1976 1976 0 0.00 0 2919 26 0 0
v_max_id_clo_ord 5713 5713 0 0.00 0 832 5 0 0
v_max_id_clo_res 1054 1054 0 0.00 0 3532 23 0 0
v_max_id_ord_sup 5736 5736 0 0.00 0 3475 7 0 0
v_max_non_paid_invoice 1578 1578 0 0.00 0 58 8 0 0
v_max_non_paid_realizn 4027 4027 0 0.00 0 2740 8 0 0
v_min_id_clo_ord 5713 5713 0 0.00 0 4792 10 0 0
v_min_id_clo_res 1054 1054 0 0.00 0 7249 20 0 0
v_min_id_ord_sup 5736 5736 0 0.00 0 5688 12 0 0
v_min_non_paid_invoice 6213 6213 0 0.00 0 6886 19 0 0
v_min_non_paid_realizn 7391 7391 0 0.00 0 10007 21 0 0
v_our_firm 10614 10614 0 0.00 0 60 5 0 0
v_reserve_write_off 20992 20992 0 0.00 0 6618 12 0 0
x_lock_depdocs_on_canc_invoice 11412 11412 0 0.00 0 17397 120 0 0
x_lock_depdocs_on_canc_sup_ord 402 402 0 0.00 0 8739 347 0 0
x_make_qstorno_1000_1200 5717 5717 0 0.00 3 29635 1274 0 0
x_make_qstorno_1200_2000 5749 5749 0 0.00 3 98044 2969 0 0
x_make_qstorno_2100_3300 476976 476976 0 0.00 0 38935 201 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-01-2022 13:38:36 to 11-01-2022 13:38:40.

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
11:07:16 2 8192000000 8797325056 8808026112 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:16 29910 8192000000 9720632800 10017656832 792568304 8.821 100 90 181 0 822306064 7979136 21758624
11:09:50 30621 8192000000 9771016912 10079797248 844061488 9.341 100 91 180 1 882122800 16555472 21505840
11:11:27 19202 8192000000 9820597536 10142240768 887376832 9.773 100 91 182 0 932914560 24546432 20991296
11:12:54 7949 8192000000 9889545392 10225614848 930951360 10.204 100 87 166 2 1000461616 51747968 17762288
11:14:06 4866 8192000000 9931009728 10273992704 969223888 10.579 100 86 172 0 1042388080 54822928 18341264
11:15:13 2288 8192000000 9973760272 10328915968 1002815760 10.906 100 87 170 1 1092730016 72777392 17136864
11:16:18 1704 8192000000 10021297920 10389680128 1025906192 11.129 100 89 175 0 1147826544 104483040 17437312
11:17:22 841 8192000000 10016178000 10383831040 1044174640 11.305 100 95 183 0 1137348384 75056992 18116752
11:18:23 1168 8192000000 10022006816 10394988544 1055895616 11.417 100 96 187 1 1141892432 67768816 18228000
11:19:27 1178 8192000000 10052524416 10427846656 1067195376 11.525 100 99 186 1 1170120272 84084288 18840608
11:20:29 1326 8192000000 10034025072 10408071168 1075483520 11.604 100 86 162 3 1151622448 60221568 15917360
11:21:31 1110 8192000000 10065424000 10448109568 1085799136 11.703 100 98 182 1 1181748288 77577888 18371264
11:22:33 1344 8192000000 10070113936 10457182208 1093930256 11.780 100 92 175 1 1191582944 79600928 18051760
11:23:35 1127 8192000000 10069669760 10454585344 1098582640 11.824 100 96 185 2 1193025024 75565072 18877312
11:24:42 1144 8192000000 10055585696 10444398592 1102337552 11.860 100 92 182 1 1179476752 59249984 17889216
11:25:45 898 8192000000 10068875936 10456473600 1102514624 11.861 100 94 175 0 1190020928 68707696 18798608
11:26:49 2108 8192000000 10046173744 10443866112 1103799936 11.874 100 72 119 5 1162664336 46600288 12264112
11:27:51 1193 8192000000 10041012016 10428510208 1097098800 11.810 100 82 150 3 1159995792 48218944 14678048
11:28:54 1928 8192000000 10044276688 10442629120 1100648272 11.844 100 71 140 2 1167430864 52270624 14511968
11:30:00 793 8192000000 10050858016 10434228224 1091028576 11.752 100 95 177 1 1166908416 56687456 19192384
11:31:02 1261 8192000000 10036400144 10420207616 1081810992 11.665 100 82 145 0 1155760896 60064016 13885888
11:32:04 1250 8192000000 10009706992 10389016576 1056231408 11.420 99 96 182 2 1131972944 54859216 20882320
11:33:06 1336 8192000000 10009168176 10382295040 1037444064 11.240 99 95 183 2 1126976000 71051824 18480112
11:34:08 4848 8192000000 9974491232 10355007488 1032744544 11.195 99 58 109 0 1092771888 48628320 11399024
11:35:15 914 8192000000 9996541584 10370985984 1042457136 11.288 100 84 156 3 1116397952 58083584 15857232
11:36:17 1765 8192000000 9997914080 10374004736 1047053680 11.332 100 89 164 2 1123384096 59725392 16605024
11:37:22 52074 8192000000 9988052192 10368913408 1057145568 11.429 100 57 113 1 1113031744 44789776 11096400
11:39:20 7241 8192000000 10022934272 10401263616 1068988080 11.542 100 76 138 2 1145234256 61565088 14681088
11:40:32 8242 8192000000 10047579088 10429997056 1080967408 11.657 99 75 140 1 1167946384 73083440 13895536
11:41:47 1183 8192000000 10124957840 10510254080 1112395712 11.955 100 93 174 2 1248178176 115409472 20372992
11:42:53 3859 8192000000 10148631520 10535763968 1124222304 12.067 100 88 172 0 1266605664 123634672 18748688
11:44:06 776 8192000000 10177867888 10571776000 1136833552 12.186 100 84 164 1 1294008032 138374416 18800064
11:45:15 5803 8192000000 10213830544 10611646464 1143353056 12.247 100 91 176 3 1329679632 167293200 19033376
11:46:25 3123 8192000000 10232513856 10632613888 1148798176 12.298 100 81 155 0 1346977472 181032368 17146928
11:47:33 970 8192000000 10247016320 10649313280 1161493520 12.417 100 100 193 3 1355735648 173710256 20531872
11:48:37 1474 8192000000 10260253360 10659684352 1161537376 12.418 100 87 169 0 1366533440 186885488 18110576
11:49:42 1198 8192000000 10264236800 10666033152 1165309200 12.453 100 90 174 0 1372586880 188207136 19070544
11:50:45 2664 8192000000 10271131552 10692448256 1169862560 12.496 100 86 167 2 1382519744 193704032 18953152
11:51:51 873 8192000000 10292060288 10699161600 1173819888 12.533 100 95 186 1 1405534448 212240976 19473584
11:52:55 2392 8192000000 10285868592 10694656000 1172728656 12.522 100 85 160 1 1393144208 202804192 17611360
11:54:04 1104 8192000000 10317472240 10719866880 1166669184 12.466 100 94 175 0 1425474288 237647248 21157856
11:55:08 1004 8192000000 10359731952 10757599232 1179765104 12.588 100 96 191 2 1459851632 259271136 20815392
11:56:12 951 8192000000 10378005904 10793353216 1173569744 12.530 100 96 184 0 1483274336 288543296 21161296
11:57:16 1275 8192000000 10365774928 10801258496 1186717152 12.653 100 83 162 2 1477758112 271803536 19237424
11:58:19 2721 8192000000 10415308672 10843750400 1179244224 12.583 100 94 184 2 1520698352 320215824 21238304
11:59:25 863 8192000000 10447260880 10853437440 1174760224 12.541 100 93 179 1 1552843696 355780768 22302704
12:00:29 2963 8192000000 10453147968 10881560576 1172846832 12.523 100 92 178 1 1557818224 364086128 20885264
12:01:33 1271 8192000000 10502952192 10912280576 1178736544 12.578 100 94 184 0 1610648720 408289216 23622960
12:02:38 3141 8192000000 10504576944 10932011008 1169676368 12.494 100 76 148 0 1608177152 420213040 18287744
12:03:50 2368 8192000000 10484471136 10934145024 1161045088 12.413 100 64 125 1 1591572544 414616992 15910464
12:04:55 22438 8192000000 10491482400 10947858432 1162629104 12.428 100 41 82 0 1595736736 421760928 11346704
12:06:28 24213 8192000000 10410089968 10891075584 1151618368 12.325 99 28 56 0 1491904288 332765952 7519968
12:08:05 35885 8192000000 10305005456 10814693376 1139126192 12.207 100 18 37 1 1438943440 294967792 4849456
12:10:05 13689 8192000000 10204236928 10727206912 1111854752 11.950 98 24 46 2 1335810544 218196976 5758816
12:11:29 30767 8192000000 10102602272 10525437952 1122982384 12.055 100 52 101 1 1200028896 64351840 12694672
12:13:02 80844 8192000000 10068187120 10526208000 1119387360 12.021 98 23 45 0 1156304336 32227392 4689584
12:15:23 76826 8192000000 10055105968 10514161664 1104805776 11.883 97 26 49 2 1142205728 31707952 5692000
12:17:40 101676 8192000000 10147298496 10602958848 1129670960 12.118 99 17 34 0 1208444144 74936992 3836192
12:20:22 113795 8192000000 10088122528 10579845120 1132376272 12.144 99 12 24 0 1213205088 77894608 2934208
12:23:17 94857 8192000000 10082014096 10530500608 1124396224 12.069 98 11 22 0 1142401264 15992128 2012912
12:26:06 81305 8192000000 10078235280 10533216256 1130395824 12.125 98 33 64 2 1157790544 19891408 7503312
12:28:29 54722 8192000000 10080989712 10532970496 1138071184 12.197 99 38 71 1 1189978736 44924880 6982672
12:30:31 3679 8192000000 10120436336 10548027392 1147057920 12.282 100 60 115 1 1214033472 54990784 11984768
12:31:38 37116 8192000000 10119543792 10568065024 1132799632 12.148 98 22 44 2 1210093808 72420880 4873296
12:33:16 29238 8192000000 10157247440 10628538368 1148850400 12.299 99 31 60 1 1262706896 107033488 6823008
12:34:46 52621 8192000000 10186229632 10657591296 1157618544 12.381 100 19 36 3 1272027584 110252912 4156128
12:36:40 60601 8192000000 10175236000 10649972736 1140042560 12.216 99 15 31 0 1275710528 131996880 3671088
12:38:41 73996 8192000000 10136764608 10611556352 1149030768 12.300 99 18 36 1 1216163696 63835824 3297104
12:41:39 97605 8192000000 10114542352 10556735488 1093993424 11.781 94 20 40 2 1147164448 48429056 4741968
12:44:17 53830 8192000000 10123751680 10566475776 1103395696 11.870 97 19 38 0 1186230848 78860672 3974480
12:46:11 33118 8192000000 10110748592 10536501248 1113236080 11.963 98 39 78 0 1178821488 56742640 8842768
12:47:45 57586 8192000000 10114084816 10565910528 1139261552 12.209 100 28 50 2 1218372880 73815664 5295664
12:49:45 42144 8192000000 10102734704 10517045248 1102613280 11.862 96 47 87 1 1163440096 52080112 8746704
12:51:28 76514 8192000000 10085170832 10583109632 1109724528 11.930 97 20 40 1 1180915888 67165920 4025440
12:53:48 56860 8192000000 10075948432 10515640320 1124378528 12.068 99 44 80 1 1168233344 35007840 8846976
12:55:45 64120 8192000000 10047833552 10469199872 1061636080 11.472 94 24 46 2 1083173072 16423120 5113872
12:57:52 26583 8192000000 10069362576 10494898176 1114453232 11.975 99 48 94 1 1159132640 35530752 9148656
12:59:19 40794 8192000000 10069647328 10507386880 1093300384 11.774 98 34 68 0 1167620688 67708480 6611824
13:01:01 85102 8192000000 10097988880 10574368768 1126805504 12.091 100 19 39 0 1186844208 55531552 4507152
13:04:17 87046 8192000000 10175862496 10630348800 1125939264 12.083 99 13 26 0 1231856048 103104464 2812320
13:06:46 84163 8192000000 10071706912 10550415360 1097893824 11.818 97 20 39 0 1193938832 91752048 4292960
13:09:11 54467 8192000000 10082796448 10509205504 1119124032 12.019 99 35 68 2 1167268112 41227984 6916096
13:11:08 41938 8192000000 10140657360 10583162880 1145355312 12.266 100 23 44 1 1217709360 68048080 4305968
13:12:51 70411 8192000000 10123157600 10655870976 1146028752 12.272 100 22 44 0 1251608752 100999232 4580768
13:15:04 29368 8192000000 10113652192 10550620160 1149939680 12.309 100 30 60 1 1211984224 55743776 6300768
13:16:34 52778 8192000000 10109648160 10565419008 1143059696 12.244 99 25 50 0 1192570768 44820528 4690544
13:18:30 64404 8192000000 10154478784 10605809664 1105174480 11.887 96 29 58 1 1215189968 104242368 5773120
13:20:35 121156 8192000000 10061782560 10530910208 1134701104 12.166 100 32 64 1 1230230192 88874352 6654736
13:23:39 43580 8192000000 10109820864 10572894208 1135332256 12.172 99 27 54 3 1213783104 72811920 5638928
13:25:31 36877 8192000000 10083530160 10589585408 1145737632 12.269 100 13 26 0 1191479552 43068912 2673008
13:27:09 89002 8192000000 10078117264 10545037312 1125635440 12.080 98 20 40 0 1172366528 42232848 4498240
13:29:39 68640 8192000000 10076320000 10555588608 1131083504 12.132 99 13 26 1 1190916480 57178816 2654160
13:31:48 59885 8192000000 10106838128 10573471744 1123178720 12.057 98 14 28 0 1203236208 77502256 2555232
13:34:07 24348 8192000000 10105435456 10556215296 1111393920 11.946 98 21 42 0 1181834176 65915696 4524560
13:35:35 47141 8192000000 10052268896 10505678848 1074952320 11.599 95 14 26 1 1122409856 44454432 3003104

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 11-01-2022 13:38:42 to 11-01-2022 13:38:58.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 175 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-01-2022 13:38:58 to 11-01-2022 13:39:00.

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          226774
MON$OLDEST_ACTIVE               229306
MON$OLDEST_SNAPSHOT             229306
MON$NEXT_TRANSACTION            229306
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                       2087264
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             785
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2704 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2704 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2704 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2704 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 ---
:328: 
:329: mscs-test-161.budzdorov.ru    Tue Jan 11 13:37:15 2022
:330:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:331: 
:332: 
:333: mscs-test-161.budzdorov.ru    Tue Jan 11 13:37:19 2022
:334:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:335: 
--- end of diff output ---

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

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