Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.08.2022 07:48
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 16160580 kB
MemAvailable 30960560 kB
Buffers 0 kB
Cached 15574896 kB
SwapCached 2512 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.2
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-08-09 07:47:21
DTS_END                         2022-08-09 07:47:21.6110
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/54212
TRN_ID                          211335
ATT_ID                          635
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: 8442.56 26548 1013107
customer order: creation 158.21 509 18985
customer order: refuse 13.67 397 1640
order to supplier: creation 43.15 1429 5178
order to supplier: removal 3.23 144905 387
invoice (draft): creation 43.38 3277 5205
invoice (draft): removal 45.68 945 5481
invoice accept: apply 39.47 27164 4736
invoice accept: cancel 41.46 1167 4975
customer reserve: creation 3700.90 257 444108
customer reserve: removal 4078.74 91 489449
realization accept: apply 77.46 286 9295
realization accept: cancel 76.19 149 9143
payment from customer: creation 54.90 179 6588
payment from customer: removal 5.09 148 611
payment to supplier: creation 45.55 215 5466
payment to supplier: removal 3.18 155 382
service: total inventory turnovers 4.17 3459 500
service: total monetary turnovers 7.14 351 857
service: aggregate perf. data 0.98 14202 117
service: refresh index statistics 0.03 331680 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 09-08-2022 07:48:42 to 09-08-2022 07:48:45.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5933
TEST_TIME 2 6441
TEST_TIME 3 7095
TEST_TIME 4 7157
TEST_TIME 5 6813
TEST_TIME 6 6446
TEST_TIME 7 6326
TEST_TIME 8 6174
TEST_TIME 9 6262
TEST_TIME 10 6494
TEST_TIME 11 6619
TEST_TIME 12 6562
TEST_TIME 13 6851
TEST_TIME 14 7067
TEST_TIME 15 7374
TEST_TIME 16 7489
TEST_TIME 17 7595
TEST_TIME 18 7797
TEST_TIME 19 8137
TEST_TIME 20 8281
TEST_TIME 21 8830
TEST_TIME 22 8812
TEST_TIME 23 8954
TEST_TIME 24 9044
TEST_TIME 25 9106
TEST_TIME 26 9307
TEST_TIME 27 9776
TEST_TIME 28 10139
TEST_TIME 29 10521
TEST_TIME 30 10614
TEST_TIME 31 10507
TEST_TIME 32 10456
TEST_TIME 33 10339
TEST_TIME 34 10156
TEST_TIME 35 10036
TEST_TIME 36 9979
TEST_TIME 37 9854
TEST_TIME 38 9728
TEST_TIME 39 9529
TEST_TIME 40 9463
TEST_TIME 41 9260
TEST_TIME 42 9373
TEST_TIME 43 9354
TEST_TIME 44 9298
TEST_TIME 45 9170
TEST_TIME 46 9081
TEST_TIME 47 8954
TEST_TIME 48 8997
TEST_TIME 49 8933
TEST_TIME 50 8824
TEST_TIME 51 8821
TEST_TIME 52 8770
TEST_TIME 53 8769
TEST_TIME 54 8726
TEST_TIME 55 8739
TEST_TIME 56 8867
TEST_TIME 57 8895
TEST_TIME 58 8991
TEST_TIME 59 9013
TEST_TIME 60 8938
TEST_TIME 61 9118
TEST_TIME 62 9191
TEST_TIME 63 9065
TEST_TIME 64 8947
TEST_TIME 65 8941
TEST_TIME 66 8899
TEST_TIME 67 8819
TEST_TIME 68 8806
TEST_TIME 69 8827
TEST_TIME 70 8743
TEST_TIME 71 8712
TEST_TIME 72 8704
TEST_TIME 73 8707
TEST_TIME 74 8709
TEST_TIME 75 8764
TEST_TIME 76 8734
TEST_TIME 77 8802
TEST_TIME 78 8745
TEST_TIME 79 8748
TEST_TIME 80 8762
TEST_TIME 81 8765
TEST_TIME 82 8929
TEST_TIME 83 8920
TEST_TIME 84 8910
TEST_TIME 85 8932
TEST_TIME 86 8886
TEST_TIME 87 8809
TEST_TIME 88 8826
TEST_TIME 89 8763
TEST_TIME 90 8730
TEST_TIME 91 8662
TEST_TIME 92 8592
TEST_TIME 93 8607
TEST_TIME 94 8626
TEST_TIME 95 8579
TEST_TIME 96 8560
TEST_TIME 97 8597
TEST_TIME 98 8598
TEST_TIME 99 8559
TEST_TIME 100 8556
TEST_TIME 101 8559
TEST_TIME 102 8611
TEST_TIME 103 8584
TEST_TIME 104 8684
TEST_TIME 105 8709
TEST_TIME 106 8673
TEST_TIME 107 8716
TEST_TIME 108 8722
TEST_TIME 109 8673
TEST_TIME 110 8646
TEST_TIME 111 8638
TEST_TIME 112 8614
TEST_TIME 113 8565
TEST_TIME 114 8544
TEST_TIME 115 8507
TEST_TIME 116 8485
TEST_TIME 117 8527
TEST_TIME 118 8517
TEST_TIME 119 8515
TEST_TIME 120 8467

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 09-08-2022 07:48:46 to 09-08-2022 07:48:49.

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 1420979 1420979 0 0.00 0 19504 13 0 0
doc_list_biud 1420961 1420961 0 0.00 0 35259 37 0 0
sp_add_invoice_to_stock 4824 4736 88 1.82 38 256332 27165 0 88
sp_cancel_adding_invoice 4975 4975 0 0.00 7 119415 1168 0 0
sp_cancel_client_order 1640 1640 0 0.00 5 9329 397 0 0
sp_cancel_customer_reserve 489449 489449 0 0.00 1 35261 91 0 0
sp_cancel_pay_from_customer 611 611 0 0.00 4 8451 148 0 0
sp_cancel_pay_to_supplier 382 382 0 0.00 3 9304 156 0 0
sp_cancel_supplier_invoice 5486 5481 5 0.09 3 18761 945 0 5
sp_cancel_supplier_order 387 387 0 0.00 12 1129248 144905 0 0
sp_cancel_write_off 9143 9143 0 0.00 2 7726 150 0 0
sp_client_order 18985 18985 0 0.00 5 28886 510 0 0
sp_customer_reserve 444108 444108 0 0.00 0 72220 258 0 0
sp_fill_shopping_cart 29365 29365 0 0.00 1 26538 84 0 0
sp_fill_shopping_cart_clo_res 444078 444078 0 0.00 0 19263 21 0 0
sp_get_clo_for_invoice 4681 4681 0 0.00 0 26148 375 0 0
sp_kill_cost_storno 15111 15111 0 0.00 0 7859 65 0 0
sp_kill_qstorno_ret_qs2qd 993909 993909 0 0.00 0 35256 41 0 0
sp_kill_qty_storno 525049 525049 0 0.00 0 35258 93 0 0
sp_lock_selected_doc 19401 19401 0 0.00 0 7694 6 0 0
sp_make_cost_storno 26030 26030 0 0.00 0 19468 80 0 0
sp_multiply_rows_for_pdistr 26028 26028 0 0.00 0 10436 37 0 0
sp_multiply_rows_for_qdistr 18985 18985 0 0.00 0 11058 193 0 0
sp_pay_from_customer 6588 6588 0 0.00 5 16603 180 0 0
sp_pay_to_supplier 5466 5466 0 0.00 7 23701 215 0 0
sp_payment_common 12053 12053 0 0.00 2 6282 42 0 0
sp_qd_handle_on_cancel_clo 1640 1640 0 0.00 0 9247 331 0 0
sp_qd_handle_on_invoice_upd_sts 9654 9654 0 0.00 0 14140 472 0 0
sp_qd_handle_on_reserve_upd_sts 9295 9295 0 0.00 0 9897 70 0 0
sp_reserve_write_off 9295 9295 0 0.00 8 23707 287 0 0
sp_supplier_invoice 5205 5205 0 0.00 8 84936 3277 0 0
sp_supplier_order 5178 5178 0 0.00 7 32460 1429 0 0
srv_aggregate_perf_data 117 117 0 0.00 2636 203849 14202 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 712 121590 40739 0 0
srv_find_qd_qs_mism 966499 966499 0 0.00 0 11006 10 0 0
srv_make_invnt_saldo 500 500 0 0.00 85 131340 3459 0 0
srv_make_money_saldo 857 857 0 0.00 1 16006 352 0 0
srv_recalc_idx_stat 4 4 0 0.00 276775 440109 331680 0 0
t$perf-abend:sp_add_invoice_to_stock 88 88 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4738 4738 0 0.00 1 14201 857 0 0
t$perf-norm:sp_cancel_adding_invoice 379 379 0 0.00 0 8887 474 0 0
t$perf-norm:sp_cancel_client_order 1640 1640 0 0.00 0 5966 34 0 0
t$perf-norm:sp_cancel_customer_reserve 709 709 0 0.00 0 781 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 611 611 0 0.00 0 984 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 382 382 0 0.00 0 2097 22 0 0
t$perf-norm:sp_cancel_supplier_invoice 367 367 0 0.00 0 3519 47 0 0
t$perf-norm:sp_cancel_supplier_order 387 387 0 0.00 0 117856 6296 0 0
t$perf-norm:sp_cancel_write_off 953 953 0 0.00 0 1646 21 0 0
t$perf-norm:sp_client_order 18985 18985 0 0.00 0 22467 35 0 0
t$perf-norm:sp_customer_reserve 925 925 0 0.00 0 5013 17 0 0
t$perf-norm:sp_pay_from_customer 6588 6588 0 0.00 0 25039 27 0 0
t$perf-norm:sp_pay_to_supplier 5466 5466 0 0.00 0 6938 21 0 0
t$perf-norm:sp_reserve_write_off 9295 9295 0 0.00 0 9542 21 0 0
t$perf-norm:sp_supplier_invoice 5205 5205 0 0.00 0 6407 30 0 0
t$perf-norm:sp_supplier_order 5178 5178 0 0.00 0 2388 27 0 0
t$perf-norm:srv_aggregate_perf_data 117 117 0 0.00 0 386 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 177 7 0 0
t$perf-norm:srv_make_invnt_saldo 500 500 0 0.00 0 954 12 0 0
t$perf-norm:srv_make_money_saldo 857 857 0 0.00 0 984 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 166 58 0 0
v_add_invoice_to_stock 9530 9354 176 1.84 0 23002 31 0 176
v_all_customers 35089 35089 0 0.00 0 64 3 0 0
v_all_suppliers 28810 28810 0 0.00 0 73 4 0 0
v_all_wares 37970 37970 0 0.00 0 110 3 0 0
v_cancel_adding_invoice 758 758 0 0.00 0 1263 17 0 0
v_cancel_client_order 3280 3280 0 0.00 0 1290 10 0 0
v_cancel_customer_prepayment 1222 1222 0 0.00 0 8440 28 0 0
v_cancel_customer_reserve 1418 1418 0 0.00 0 21853 26 0 0
v_cancel_payment_to_supplier 764 764 0 0.00 0 5857 24 0 0
v_cancel_supplier_invoice 744 734 10 1.34 0 3575 28 0 10
v_cancel_supplier_order 774 774 0 0.00 0 709 14 0 0
v_cancel_write_off 1906 1906 0 0.00 0 662 21 0 0
v_max_id_clo_ord 5176 5176 0 0.00 0 8625 6 0 0
v_max_id_clo_res 925 925 0 0.00 0 3782 16 0 0
v_max_id_ord_sup 5203 5203 0 0.00 0 6060 7 0 0
v_max_non_paid_invoice 1439 1439 0 0.00 0 2582 10 0 0
v_max_non_paid_realizn 3344 3344 0 0.00 0 473 7 0 0
v_min_id_clo_ord 5176 5176 0 0.00 0 3801 10 0 0
v_min_id_clo_res 925 925 0 0.00 0 11198 19 0 0
v_min_id_ord_sup 5203 5203 0 0.00 0 7939 9 0 0
v_min_non_paid_invoice 5466 5466 0 0.00 0 5681 17 0 0
v_min_non_paid_realizn 6586 6586 0 0.00 0 4955 16 0 0
v_our_firm 9362 9362 0 0.00 0 60 4 0 0
v_reserve_write_off 18586 18586 0 0.00 0 23169 17 0 0
x_lock_depdocs_on_canc_invoice 10089 10089 0 0.00 0 12499 124 0 0
x_lock_depdocs_on_canc_sup_ord 387 387 0 0.00 0 22868 371 0 0
x_make_qstorno_1000_1200 5178 5178 0 0.00 2 32451 1276 0 0
x_make_qstorno_1200_2000 5205 5205 0 0.00 3 59255 3022 0 0
x_make_qstorno_2100_3300 444107 444107 0 0.00 0 72208 225 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-08-2022 07:48:50 to 09-08-2022 07:48:54.

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:17:25 1 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
05:18:26 22915 8192000000 9718571280 10012315648 787084960 8.765 100 90 180 0 817935376 7933680 22916736
05:19:58 16392 8192000000 9758204864 10064207872 833002576 9.229 100 88 176 0 865586208 11330160 21253472
05:21:21 14915 8192000000 9791983536 10106974208 860181120 9.502 100 86 172 0 899738480 19370864 20186496
05:22:38 11078 8192000000 9850407952 10181214208 908143344 9.979 100 72 139 0 957941136 34933904 14863888
05:23:51 6690 8192000000 9913001728 10255466496 956995168 10.460 100 84 163 1 1025801776 51994128 16812480
05:25:01 3348 8192000000 9969671088 10319941632 996463456 10.844 100 91 176 0 1078841088 65099552 17278080
05:26:08 993 8192000000 10000602800 10362073088 1017307104 11.046 100 93 184 0 1118390080 83121744 17961232
05:27:11 1054 8192000000 10022018624 10391568384 1037949184 11.245 100 98 187 0 1143083648 86855136 18279328
05:28:14 1219 8192000000 10019461712 10393853952 1061134592 11.467 100 96 190 1 1147253248 67408592 18710064
05:29:17 821 8192000000 10030477552 10408271872 1069624176 11.548 100 87 165 2 1149936736 63171376 17141184
05:30:19 1037 8192000000 10052468240 10438950912 1074945312 11.599 100 87 168 5 1171480592 79930208 16605072
05:31:20 6136 8192000000 10046821184 10456211456 1080228704 11.650 100 62 119 1 1180287632 87546464 12512464
05:32:30 1428 8192000000 10048968304 10440859648 1083793504 11.684 100 72 140 1 1167053392 69360416 13899472
05:33:32 1288 8192000000 10060011728 10447548416 1091255888 11.755 100 96 180 3 1179635328 69405632 18973808
05:34:33 1097 8192000000 10077368976 10472210432 1092182368 11.763 100 85 160 2 1197141824 87759632 17199824
05:35:35 2490 8192000000 10039353648 10436620288 1098178160 11.820 100 62 122 0 1159203456 48654400 12370896
05:36:39 1010 8192000000 10061528832 10449334272 1103346512 11.869 100 92 177 1 1185835216 64551088 17937616
05:37:41 1116 8192000000 10045101440 10434191360 1091829696 11.760 99 94 184 0 1166668352 55595456 19243200
05:38:43 1566 8192000000 10028678768 10413473792 1081252560 11.659 99 85 166 2 1148233088 50831024 16149504
05:39:46 978 8192000000 10048497856 10440953856 1095822384 11.798 100 83 163 1 1171242816 59591296 15829136
05:40:48 1185 8192000000 10034955920 10419146752 1086000912 11.705 100 91 179 2 1153296304 49530944 17764448
05:41:51 941 8192000000 10023175184 10407968768 1078513808 11.633 100 93 170 0 1142727216 47513776 16699632
05:42:53 4934 8192000000 10009615264 10390241280 1046311088 11.325 99 74 139 1 1124853872 65495376 13047408
05:43:58 3328 8192000000 9970352112 10344550400 1034017664 11.207 100 80 154 0 1093972464 44173824 15780976
05:45:03 1245 8192000000 10000429888 10370125824 1030083088 11.169 100 97 183 1 1120269504 71853904 18332512
05:46:05 1191 8192000000 10006266688 10380144640 1046954480 11.331 100 93 172 1 1126968592 61737136 18276976
05:47:07 1102 8192000000 10005070704 10380279808 1045247392 11.315 100 95 177 5 1126469344 63099616 18122336
05:48:17 4876 8192000000 10017994048 10399674368 1062278336 11.478 100 71 140 0 1135065472 58820720 13966416
05:49:27 2238 8192000000 10028569936 10411905024 1069479312 11.547 100 76 145 3 1155405632 71145552 14780768
05:50:32 847 8192000000 10066858592 10447704064 1076438880 11.614 100 90 163 0 1175529120 83502736 15587504
05:51:39 2096 8192000000 10090770576 10477752320 1084109328 11.687 100 78 144 1 1208708064 109471056 15127680
05:52:45 12204 8192000000 10105760400 10500038656 1093402128 11.775 100 56 106 0 1214942992 110308432 11232432
05:54:02 7538 8192000000 10149506096 10549645312 1116046448 11.990 100 70 137 1 1271350480 140415152 14888880
05:55:19 6635 8192000000 10195262720 10594226176 1130492128 12.126 100 67 130 0 1318598368 173846704 14259536
05:56:35 1904 8192000000 10213913808 10625392640 1140762544 12.223 100 75 150 0 1329911024 172374928 16773552
05:57:45 838 8192000000 10262008656 10653589504 1166266416 12.462 100 96 185 0 1373055040 186886720 19901904
05:58:50 1006 8192000000 10306799504 10703933440 1170127216 12.498 100 93 183 1 1417382128 227493296 19761616
05:59:58 1855 8192000000 10351076128 10758955008 1179334704 12.584 100 95 179 0 1461098448 261467184 20296560
06:01:04 702 8192000000 10371775136 10772647936 1175115296 12.545 99 98 194 0 1483145488 286022704 22007488
06:02:08 1794 8192000000 10363204848 10799575040 1178808928 12.579 100 89 175 0 1473147728 274083984 20254816
06:03:13 1419 8192000000 10378244576 10806640640 1195822016 12.738 100 88 168 0 1482796032 267865296 19108720
06:04:19 791 8192000000 10417111296 10840088576 1203428896 12.808 100 96 184 0 1520450288 296400416 20620976
06:05:22 1152 8192000000 10423923600 10877177856 1203871072 12.812 100 94 183 0 1527308160 302348048 21089040
06:06:26 952 8192000000 10414649248 10857099264 1202682960 12.801 100 97 189 2 1520772016 295529360 22559696
06:07:29 3655 8192000000 10402483136 10865868800 1197442928 12.753 100 66 123 0 1514403152 301542160 15418064
06:08:36 1942 8192000000 10382244704 10809733120 1193494592 12.716 100 85 153 0 1486838256 274302560 19041104
06:09:40 6473 8192000000 10369128848 10838224896 1185720784 12.644 100 49 98 0 1481179264 283011120 12447360
06:10:58 16534 8192000000 10406019728 10850672640 1191683104 12.699 100 55 108 2 1528185760 322775664 13726992
06:12:20 27013 8192000000 10393516784 10846720000 1160969936 12.412 99 42 82 0 1495576064 324041632 10564496
06:13:53 20441 8192000000 10307734384 10811838464 1149553216 12.305 99 24 48 0 1451201776 294913264 6735296
06:15:24 65689 8192000000 10192018272 10682671104 1125282304 12.077 98 24 47 1 1370480880 239457888 5740688
06:17:32 45665 8192000000 10090702976 10530430976 1121495072 12.041 99 38 74 2 1214019216 82306288 10217856
06:19:23 5521 8192000000 10111604864 10530201600 1119441408 12.022 99 65 128 1 1211232656 78052640 13738608
06:20:37 82347 8192000000 10100772464 10575564800 1127562528 12.098 100 10 20 0 1223564336 93762800 2239008
06:23:13 53014 8192000000 10046316816 10488131584 1112659408 11.958 99 31 54 0 1149558896 31993264 4906224
06:25:20 81947 8192000000 10056694576 10497675264 1102798336 11.864 98 41 81 0 1138937280 27515680 8623264
06:27:42 74540 8192000000 10141562288 10559008768 1093616496 11.777 96 27 53 0 1181120720 81873968 5630256
06:29:57 35864 8192000000 10076164864 10572828672 1105287712 11.888 98 12 24 0 1194976912 87434448 2254752
06:31:33 67730 8192000000 10091321696 10537820160 1124189216 12.067 99 15 29 0 1171489136 43979424 3320496
06:34:37 56792 8192000000 10075097504 10576478208 1132780656 12.148 100 42 82 1 1199067680 57353024 8934000
06:36:38 44636 8192000000 10060269264 10515963904 1111929776 11.951 99 16 29 1 1134947536 20146704 2871056
06:38:24 57609 8192000000 10105098800 10554716160 1112306784 11.954 98 22 44 0 1185557488 68392992 4857712
06:40:31 92709 8192000000 10181394704 10629206016 1121404320 12.040 98 32 64 0 1243958192 115450288 7103584
06:43:17 75761 8192000000 10146169024 10569592832 1103135584 11.867 97 36 70 1 1199161728 87971536 8054608
06:45:35 21152 8192000000 10103006112 10552201216 1119538960 12.023 99 37 74 0 1191673824 64238496 7896368
06:48:03 87783 8192000000 10051993648 10545864704 1075701760 11.606 95 19 37 0 1188467648 108958288 3807600
06:50:35 96049 8192000000 10085560592 10519183360 1108530400 11.919 99 26 52 0 1147682960 33982208 5170352
06:53:25 34711 8192000000 10064761136 10516803584 1130491744 12.126 100 12 24 0 1185387536 52701600 2194192
06:55:00 93393 8192000000 10078692416 10586730496 1131063024 12.131 100 8 16 0 1181720256 48685984 1971248
06:57:35 121590 8192000000 10056555296 10553372672 1116018752 11.989 98 11 22 0 1164630224 46378560 2232912
07:00:38 66186 8192000000 10091340784 10563489792 1144269936 12.256 100 14 28 0 1196549728 49512592 2767200
07:02:45 35571 8192000000 10076484112 10510655488 1117646640 12.005 98 35 70 0 1172011968 46232592 8132736
07:04:21 43861 8192000000 10108405904 10586603520 1127703344 12.100 99 20 40 0 1203229904 71304448 4222112
07:06:08 100232 8192000000 10144773232 10607173632 1110206160 11.934 97 26 52 0 1215445808 99701680 5537968
07:09:00 42279 8192000000 10106622032 10557280256 1131269008 12.133 100 13 26 0 1204516944 70472224 2775712
07:10:47 20624 8192000000 10091035056 10514378752 1112287936 11.954 98 50 91 0 1169354448 47011072 10055440
07:12:09 21994 8192000000 10111485360 10531811328 1124902704 12.073 100 49 94 1 1203009344 68450880 9655760
07:13:36 96423 8192000000 10067688272 10519937024 1127146816 12.094 100 39 70 1 1183596480 49093264 7356400
07:16:14 82564 8192000000 10068671120 10517970944 1104051760 11.876 99 12 24 0 1143823664 37267568 2504336
07:19:10 79660 8192000000 10085151536 10555215872 1096994544 11.809 98 18 36 0 1188901584 88106000 3801040
07:21:44 30644 8192000000 10037977408 10474344448 1116702352 11.996 100 24 42 1 1144153376 21974096 5476928
07:23:16 41111 8192000000 10092746288 10538418176 1088109600 11.725 97 28 55 0 1154670112 60391744 6168768
07:25:16 56829 8192000000 10096565952 10554527744 1081404880 11.661 96 29 58 0 1161757584 73976896 6375808
07:27:13 63183 8192000000 10128901648 10577260544 1062256624 11.478 94 35 69 1 1167803184 97463840 8082720
07:29:17 60939 8192000000 10116771728 10571567104 1095914272 11.799 98 19 38 0 1199180208 99444768 3821168
07:31:19 56939 8192000000 10104846960 10572460032 1112991392 11.961 99 33 66 0 1177091984 57331904 6768688
07:33:16 24793 8192000000 10124709552 10539188224 1112230160 11.954 98 54 108 0 1189490896 66962880 10297856
07:34:41 37505 8192000000 10116002112 10554413056 1131395216 12.135 100 43 86 0 1221134848 81214320 8525312
07:36:20 107973 8192000000 10057624736 10513362944 1097855824 11.817 97 27 54 0 1156230864 53084432 5290608
07:39:09 59139 8192000000 10062478976 10527776768 1115356528 11.983 98 18 34 0 1163363552 44671440 3335584
07:41:14 92233 8192000000 10085877248 10547609600 1110571648 11.938 98 15 28 0 1170757616 56665392 3520576
07:43:47 57335 8192000000 10081981696 10588270592 1128348560 12.106 99 11 20 0 1217615760 86843792 2423408
07:45:45 6280 8192000000 10098397392 10531397632 1147834752 12.289 100 72 127 3 1197972880 36598480 13539648
07:46:52 25628 8192000000 10130955184 10575577088 1127058208 12.094 98 27 53 0 1216347872 83539984 5749680

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 09-08-2022 07:48:56 to 09-08-2022 07:49:12.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 88 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-08-2022 07:49:13 to 09-08-2022 07:49:15.

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          207186
MON$OLDEST_ACTIVE               211663
MON$OLDEST_SNAPSHOT             211663
MON$NEXT_TRANSACTION            211663
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                       2010880
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             760
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2816 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2816 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2816 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2816 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Tue Aug  9 07:47:27 2022
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Tue Aug  9 07:47:30 2022
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
--- end of diff output ---

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

09.08.22 07:49:16. 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-08-09 07:49:18 No severe PSQL-related problems occured
09.08.22 07:49:20. End of report.