Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.01.2022 04: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 15786772 kB
MemAvailable 31300424 kB
Buffers 20 kB
Cached 15851400 kB
SwapCached 1180 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-09 04:37:12
DTS_END                         2022-01-09 04:37:12.4540
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/37156
TRN_ID                          210199
ATT_ID                          568
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: 8329.38 34591 999526
customer order: creation 157.38 482 18885
customer order: refuse 13.95 493 1674
order to supplier: creation 42.78 1450 5134
order to supplier: removal 3.04 203204 365
invoice (draft): creation 42.79 3914 5135
invoice (draft): removal 45.00 1188 5400
invoice accept: apply 39.13 29003 4696
invoice accept: cancel 40.96 1600 4915
customer reserve: creation 3629.75 272 435570
customer reserve: removal 4043.48 119 485218
realization accept: apply 77.92 314 9350
realization accept: cancel 71.28 197 8554
payment from customer: creation 56.27 194 6752
payment from customer: removal 4.90 139 588
payment to supplier: creation 45.08 210 5410
payment to supplier: removal 3.18 158 381
service: total inventory turnovers 3.73 5177 447
service: total monetary turnovers 7.88 422 946
service: aggregate perf. data 0.84 20336 101
service: refresh index statistics 0.04 422945 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-01-2022 04:38:34 to 09-01-2022 04:38:36.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 11486
TEST_TIME 2 7773
TEST_TIME 3 6701
TEST_TIME 4 7185
TEST_TIME 5 7152
TEST_TIME 6 7113
TEST_TIME 7 6883
TEST_TIME 8 6940
TEST_TIME 9 6786
TEST_TIME 10 6664
TEST_TIME 11 6699
TEST_TIME 12 6795
TEST_TIME 13 6817
TEST_TIME 14 6964
TEST_TIME 15 6988
TEST_TIME 16 7261
TEST_TIME 17 7461
TEST_TIME 18 7469
TEST_TIME 19 7517
TEST_TIME 20 7527
TEST_TIME 21 7564
TEST_TIME 22 7636
TEST_TIME 23 7672
TEST_TIME 24 7836
TEST_TIME 25 8075
TEST_TIME 26 7935
TEST_TIME 27 7864
TEST_TIME 28 8082
TEST_TIME 29 8036
TEST_TIME 30 8069
TEST_TIME 31 8312
TEST_TIME 32 8413
TEST_TIME 33 8399
TEST_TIME 34 8927
TEST_TIME 35 9011
TEST_TIME 36 9322
TEST_TIME 37 9353
TEST_TIME 38 10188
TEST_TIME 39 10425
TEST_TIME 40 10674
TEST_TIME 41 10790
TEST_TIME 42 10536
TEST_TIME 43 10344
TEST_TIME 44 10274
TEST_TIME 45 10220
TEST_TIME 46 10158
TEST_TIME 47 10056
TEST_TIME 48 10044
TEST_TIME 49 9977
TEST_TIME 50 9950
TEST_TIME 51 9817
TEST_TIME 52 9713
TEST_TIME 53 9592
TEST_TIME 54 9696
TEST_TIME 55 9618
TEST_TIME 56 9558
TEST_TIME 57 9421
TEST_TIME 58 9353
TEST_TIME 59 9254
TEST_TIME 60 9177
TEST_TIME 61 9119
TEST_TIME 62 9091
TEST_TIME 63 9019
TEST_TIME 64 8969
TEST_TIME 65 8913
TEST_TIME 66 8870
TEST_TIME 67 8845
TEST_TIME 68 8778
TEST_TIME 69 8669
TEST_TIME 70 8633
TEST_TIME 71 8629
TEST_TIME 72 8568
TEST_TIME 73 8546
TEST_TIME 74 8533
TEST_TIME 75 8522
TEST_TIME 76 8525
TEST_TIME 77 8512
TEST_TIME 78 8592
TEST_TIME 79 8669
TEST_TIME 80 8624
TEST_TIME 81 8611
TEST_TIME 82 8736
TEST_TIME 83 8753
TEST_TIME 84 8764
TEST_TIME 85 8736
TEST_TIME 86 8787
TEST_TIME 87 8743
TEST_TIME 88 8686
TEST_TIME 89 8683
TEST_TIME 90 8610
TEST_TIME 91 8619
TEST_TIME 92 8584
TEST_TIME 93 8555
TEST_TIME 94 8514
TEST_TIME 95 8481
TEST_TIME 96 8528
TEST_TIME 97 8504
TEST_TIME 98 8495
TEST_TIME 99 8479
TEST_TIME 100 8500
TEST_TIME 101 8516
TEST_TIME 102 8521
TEST_TIME 103 8539
TEST_TIME 104 8566
TEST_TIME 105 8570
TEST_TIME 106 8614
TEST_TIME 107 8617
TEST_TIME 108 8633
TEST_TIME 109 8608
TEST_TIME 110 8600
TEST_TIME 111 8547
TEST_TIME 112 8556
TEST_TIME 113 8511
TEST_TIME 114 8490
TEST_TIME 115 8459
TEST_TIME 116 8451
TEST_TIME 117 8422
TEST_TIME 118 8409
TEST_TIME 119 8420
TEST_TIME 120 8357

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 09-01-2022 04:38:38 to 09-01-2022 04:38:41.

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 1400360 1400360 0 0.00 0 18097 15 0 0
doc_list_biud 1400353 1400353 0 0.00 0 46553 48 0 0
sp_add_invoice_to_stock 4801 4696 105 2.18 61 223647 29004 0 105
sp_cancel_adding_invoice 4915 4915 0 0.00 5 113115 1600 0 0
sp_cancel_client_order 1674 1674 0 0.00 5 9525 494 0 0
sp_cancel_customer_reserve 485218 485218 0 0.00 1 20234 120 0 0
sp_cancel_pay_from_customer 588 588 0 0.00 4 7864 140 0 0
sp_cancel_pay_to_supplier 381 381 0 0.00 3 10318 159 0 0
sp_cancel_supplier_invoice 5415 5400 15 0.27 3 46570 1189 0 15
sp_cancel_supplier_order 365 365 0 0.00 15 1644903 203204 0 0
sp_cancel_write_off 8554 8554 0 0.00 2 9644 197 0 0
sp_client_order 18885 18885 0 0.00 6 32375 483 0 0
sp_customer_reserve 435570 435570 0 0.00 1 78865 273 0 0
sp_fill_shopping_cart 29136 29136 0 0.00 2 25432 106 0 0
sp_fill_shopping_cart_clo_res 435535 435535 0 0.00 0 19099 23 0 0
sp_get_clo_for_invoice 4650 4650 0 0.00 0 29468 421 0 0
sp_kill_cost_storno 14438 14438 0 0.00 0 9627 91 0 0
sp_kill_qstorno_ret_qs2qd 984755 984755 0 0.00 0 46550 51 0 0
sp_kill_qty_storno 520123 520123 0 0.00 0 46551 119 0 0
sp_lock_selected_doc 19340 19340 0 0.00 0 4737 7 0 0
sp_make_cost_storno 26162 26162 0 0.00 0 18089 89 0 0
sp_multiply_rows_for_pdistr 26160 26160 0 0.00 0 9768 43 0 0
sp_multiply_rows_for_qdistr 18885 18885 0 0.00 0 32286 185 0 0
sp_pay_from_customer 6752 6752 0 0.00 6 13751 194 0 0
sp_pay_to_supplier 5410 5410 0 0.00 6 13759 211 0 0
sp_payment_common 12162 12162 0 0.00 2 11495 52 0 0
sp_qd_handle_on_cancel_clo 1674 1674 0 0.00 0 9516 420 0 0
sp_qd_handle_on_invoice_upd_sts 9562 9562 0 0.00 0 31881 666 0 0
sp_qd_handle_on_reserve_upd_sts 9350 9350 0 0.00 0 17005 71 0 0
sp_reserve_write_off 9350 9350 0 0.00 8 17802 314 0 0
sp_supplier_invoice 5135 5135 0 0.00 8 105071 3914 0 0
sp_supplier_order 5134 5134 0 0.00 7 49397 1450 0 0
srv_aggregate_perf_data 101 101 0 0.00 2514 219750 20336 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 767 132028 31701 0 0
srv_find_qd_qs_mism 953692 953692 0 0.00 0 13337 11 0 0
srv_make_invnt_saldo 447 447 0 0.00 70 155201 5178 0 0
srv_make_money_saldo 946 946 0 0.00 1 24523 422 0 0
srv_recalc_idx_stat 5 5 0 0.00 198682 872630 422945 0 0
t$perf-abend:sp_add_invoice_to_stock 105 105 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4703 4703 0 0.00 0 35403 1213 0 0
t$perf-norm:sp_cancel_adding_invoice 390 390 0 0.00 2 6727 655 0 0
t$perf-norm:sp_cancel_client_order 1674 1674 0 0.00 0 1615 28 0 0
t$perf-norm:sp_cancel_customer_reserve 649 649 0 0.00 0 1049 32 0 0
t$perf-norm:sp_cancel_pay_from_customer 588 588 0 0.00 0 3934 21 0 0
t$perf-norm:sp_cancel_pay_to_supplier 381 381 0 0.00 0 6372 34 0 0
t$perf-norm:sp_cancel_supplier_invoice 380 380 0 0.00 0 21630 108 0 0
t$perf-norm:sp_cancel_supplier_order 365 365 0 0.00 0 110603 9056 0 0
t$perf-norm:sp_cancel_write_off 923 923 0 0.00 0 2733 24 0 0
t$perf-norm:sp_client_order 18885 18885 0 0.00 0 19371 42 0 0
t$perf-norm:sp_customer_reserve 979 979 0 0.00 0 590 15 0 0
t$perf-norm:sp_pay_from_customer 6752 6752 0 0.00 0 14118 33 0 0
t$perf-norm:sp_pay_to_supplier 5410 5410 0 0.00 0 2847 20 0 0
t$perf-norm:sp_reserve_write_off 9351 9351 0 0.00 0 24782 33 0 0
t$perf-norm:sp_supplier_invoice 5135 5135 0 0.00 0 3353 37 0 0
t$perf-norm:sp_supplier_order 5134 5134 0 0.00 0 11912 41 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 1297 38 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 344 17 0 0
t$perf-norm:srv_make_invnt_saldo 447 447 0 0.00 0 2086 30 0 0
t$perf-norm:srv_make_money_saldo 946 946 0 0.00 0 552 7 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 177 59 0 0
v_add_invoice_to_stock 9488 9278 210 2.21 0 6896 27 0 210
v_all_customers 34812 34812 0 0.00 0 189 4 0 0
v_all_suppliers 28718 28718 0 0.00 0 127 5 0 0
v_all_wares 37768 37768 0 0.00 0 77 4 0 0
v_cancel_adding_invoice 780 780 0 0.00 0 549 17 0 0
v_cancel_client_order 3348 3348 0 0.00 0 899 10 0 0
v_cancel_customer_prepayment 1176 1176 0 0.00 0 1770 14 0 0
v_cancel_customer_reserve 1298 1298 0 0.00 0 5035 21 0 0
v_cancel_payment_to_supplier 762 762 0 0.00 0 1216 14 0 0
v_cancel_supplier_invoice 790 760 30 3.79 0 970 24 0 30
v_cancel_supplier_order 730 730 0 0.00 0 437 13 0 0
v_cancel_write_off 1846 1846 0 0.00 0 1450 26 0 0
v_max_id_clo_ord 5132 5132 0 0.00 0 344 6 0 0
v_max_id_clo_res 979 979 0 0.00 0 1080 12 0 0
v_max_id_ord_sup 5120 5120 0 0.00 0 6263 8 0 0
v_max_non_paid_invoice 1303 1303 0 0.00 0 1062 10 0 0
v_max_non_paid_realizn 3426 3426 0 0.00 0 5994 11 0 0
v_min_id_clo_ord 5132 5132 0 0.00 0 3155 9 0 0
v_min_id_clo_res 979 979 0 0.00 0 4196 17 0 0
v_min_id_ord_sup 5120 5120 0 0.00 0 7602 14 0 0
v_min_non_paid_invoice 5410 5410 0 0.00 0 11473 20 0 0
v_min_non_paid_realizn 6752 6752 0 0.00 0 7153 17 0 0
v_our_firm 9608 9608 0 0.00 0 131 5 0 0
v_reserve_write_off 18700 18700 0 0.00 0 4800 12 0 0
x_lock_depdocs_on_canc_invoice 9935 9935 0 0.00 0 23068 162 0 0
x_lock_depdocs_on_canc_sup_ord 365 365 0 0.00 0 42753 600 0 0
x_make_qstorno_1000_1200 5134 5134 0 0.00 3 49326 1233 0 0
x_make_qstorno_1200_2000 5135 5135 0 0.00 4 105051 3618 0 0
x_make_qstorno_2100_3300 435570 435570 0 0.00 0 78864 235 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-01-2022 04:38:42 to 09-01-2022 04:38:46.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
02:07:16 3 8192000000 8797196320 8807829504 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:16 37442 8192000000 9737061824 10039164928 818917984 9.088 100 94 188 0 848852464 7829088 22105392
02:09:55 22485 8192000000 9781160864 10088513536 851394048 9.414 100 92 184 1 886520752 13548416 21578288
02:11:20 23040 8192000000 9817746928 10137481216 884638752 9.746 100 85 170 1 926393488 22903392 18851344
02:12:46 9497 8192000000 9876808240 10209222656 914917552 10.046 100 82 164 0 984069040 51503904 17647584
02:13:58 4626 8192000000 9926386144 10266374144 939728912 10.290 100 83 166 0 1035798576 78077008 17992656
02:15:06 5025 8192000000 9950730720 10301100032 984303920 10.726 100 83 157 2 1056826656 55370496 17152240
02:16:14 5260 8192000000 9986902736 10351902720 1015390880 11.027 100 79 154 1 1109964080 79369648 15203552
02:17:22 1081 8192000000 10001890816 10366136320 1037960992 11.245 100 93 180 1 1120568832 64763072 17844768
02:18:25 1153 8192000000 10029627232 10402971648 1052009728 11.380 100 81 154 0 1148446368 80765664 15670976
02:19:28 1052 8192000000 10047397840 10419802112 1063703024 11.492 100 97 186 0 1167776800 86056096 18017680
02:20:31 1094 8192000000 10029773760 10409172992 1068066880 11.534 100 91 169 1 1149408160 65062096 16279184
02:21:34 1284 8192000000 10031686656 10411577344 1078338912 11.632 100 98 185 2 1156944496 59760560 18845024
02:22:36 902 8192000000 10048680096 10433646592 1078353712 11.632 100 96 189 1 1172473808 74416576 19703520
02:23:38 848 8192000000 10070457216 10456489984 1084720368 11.692 100 85 166 0 1193280016 91871104 16688544
02:24:40 1950 8192000000 10050807248 10438791168 1084938368 11.695 100 89 171 1 1176530592 73368016 18224208
02:25:43 1585 8192000000 10062631152 10447245312 1093531216 11.776 100 96 184 0 1181894320 69176944 19186160
02:26:46 5269 8192000000 10035240160 10432503808 1093447280 11.775 100 69 136 3 1167329328 60091104 13790944
02:27:53 1129 8192000000 10077704304 10466320384 1095606256 11.796 100 94 181 0 1196943920 83147808 18189856
02:28:54 11113 8192000000 10036372976 10431655936 1092616288 11.768 100 49 92 1 1164098656 62947312 8535056
02:30:07 2233 8192000000 10032275440 10426609664 1086616912 11.710 100 74 141 5 1152564192 50855216 15092064
02:31:10 996 8192000000 10056690064 10443579392 1088949360 11.733 100 96 179 0 1175368240 67194704 19224176
02:32:12 1342 8192000000 10026959504 10403438592 1065992800 11.514 100 94 189 0 1149033168 63679904 19360464
02:33:14 1073 8192000000 10011091408 10380595200 1030813056 11.176 99 92 169 0 1129281280 81355200 17113024
02:34:16 3433 8192000000 9970012576 10346045440 1026705424 11.137 99 71 125 3 1095765728 56633744 12426560
02:35:22 1067 8192000000 9964073696 10334441472 1026477360 11.134 100 91 158 2 1088143760 43742512 17923888
02:36:24 1001 8192000000 9983945648 10354057216 1034232016 11.209 100 83 158 1 1100257904 51342384 14683504
02:37:27 1463 8192000000 10016693440 10389319680 1051881984 11.379 100 92 166 2 1133747552 67239424 14626144
02:38:39 967 8192000000 10020874720 10393800704 1061636320 11.472 100 84 148 1 1135195696 60384560 13174816
02:39:44 35686 8192000000 10024248112 10410819584 1070582480 11.558 100 62 122 1 1149860608 67335360 11942768
02:41:29 1208 8192000000 10081733120 10459889664 1085445616 11.699 100 93 178 0 1198986720 94726512 18814592
02:42:34 2490 8192000000 10094327408 10483101696 1096845984 11.808 100 80 150 1 1216787168 104981152 14960032
02:43:40 958 8192000000 10125212640 10506010624 1104560096 11.881 100 95 183 2 1241466256 117258656 19647504
02:44:46 2585 8192000000 10143801072 10532540416 1107959936 11.913 100 80 151 1 1260221600 135463664 16798000
02:45:50 2632 8192000000 10164153824 10552233984 1119232544 12.020 100 80 156 1 1283904624 148302304 16369776
02:47:01 3314 8192000000 10191974624 10579865600 1137505088 12.192 100 80 154 1 1303028752 147214800 18308864
02:48:10 1285 8192000000 10239253552 10632294400 1141531072 12.230 100 92 175 2 1351528032 190033888 19963072
02:49:15 1558 8192000000 10266837248 10659815424 1154973440 12.356 100 89 178 1 1380471936 205173792 20324704
02:50:20 2485 8192000000 10288530832 10687918080 1154175312 12.349 100 86 171 0 1400750944 226809552 19766080
02:51:25 2609 8192000000 10309299520 10717278208 1158233024 12.387 100 81 149 0 1415839888 240759376 16847488
02:52:36 8492 8192000000 10309063872 10734530560 1164322080 12.444 100 79 157 1 1421055424 238567168 18166176
02:53:58 1215 8192000000 10318264656 10728808448 1171805984 12.514 100 93 181 0 1427105984 235115808 20184192
02:55:05 760 8192000000 10338115296 10735841280 1159675088 12.400 100 92 180 0 1444247232 263863536 20708608
02:56:09 1234 8192000000 10377024832 10775396352 1175427856 12.548 100 94 182 0 1484478416 287807152 21243408
02:57:13 772 8192000000 10425515744 10823753728 1182469600 12.613 100 99 193 2 1529763024 325318864 21974560
02:58:18 866 8192000000 10457471248 10867568640 1192909072 12.710 100 98 194 1 1566209904 350853600 22447232
02:59:23 1066 8192000000 10449581840 10877423616 1180813856 12.598 100 96 185 2 1552024256 349646416 21563984
03:00:28 961 8192000000 10488676880 10922844160 1189685344 12.680 100 94 184 1 1591293984 379778176 21830464
03:01:32 931 8192000000 10477805936 10920734720 1186506368 12.651 100 87 172 0 1581284608 374306176 20472064
03:02:38 5078 8192000000 10487158864 10913054720 1182339296 12.612 100 63 125 0 1595687216 397750496 15597424
03:03:49 849 8192000000 10535611168 10947403776 1192634704 12.708 100 76 148 0 1643346320 432626480 18085136
03:04:58 2670 8192000000 10585719520 11031007232 1194117488 12.722 100 90 174 2 1686411552 470584688 21709376
03:06:04 793 8192000000 10620684048 11026669568 1195042560 12.730 100 94 185 0 1720462768 503828640 21591568
03:07:07 972 8192000000 10591781344 11043430400 1178263488 12.574 99 97 188 0 1694189120 493235856 22689776
03:08:15 1541 8192000000 10637347680 11083284480 1183849488 12.626 100 89 172 2 1744589840 539526944 21213408
03:09:24 5931 8192000000 10621822464 11075846144 1181127168 12.601 100 57 114 0 1720299488 523925680 15246640
03:10:36 12747 8192000000 10562788368 11076235264 1168422240 12.482 99 51 101 0 1681877072 500399488 13055344
03:11:53 11786 8192000000 10535352528 11016642560 1164529120 12.446 99 39 78 0 1631319696 456842304 9948272
03:13:46 31804 8192000000 10461449984 10941599744 1144694528 12.260 100 23 46 0 1563425264 412516960 6213776
03:15:24 25420 8192000000 10269321072 10794258432 1150885280 12.318 100 32 64 0 1413420400 253925296 8609824
03:16:52 30614 8192000000 10167265728 10617761792 1127569872 12.098 98 25 48 2 1273832672 140909664 5353136
03:18:23 65970 8192000000 10107879136 10569908224 1142165200 12.236 100 15 30 1 1210890576 65172384 3552992
03:21:18 12568 8192000000 10161586336 10589986816 1161517248 12.417 100 63 115 3 1251687568 78391232 11779088
03:22:38 120917 8192000000 10186613568 10677026816 1170663232 12.503 100 26 52 0 1291906864 115362784 5880848
03:25:40 61795 8192000000 10153654944 10624266240 1166565440 12.465 100 20 42 0 1268889696 97709232 4615024
03:27:50 41587 8192000000 10145417152 10600501248 1159972672 12.403 100 23 46 1 1243131536 78499904 4658960
03:29:45 62562 8192000000 10184577456 10639675392 1167213872 12.471 100 16 31 0 1268035104 97465456 3355776
03:31:59 84513 8192000000 10089799568 10564157440 1131024992 12.131 98 21 43 0 1170547168 35287968 4234208
03:34:39 16434 8192000000 10083390784 10539802624 1137121856 12.188 98 28 55 0 1171467600 29159792 5185952
03:35:56 45225 8192000000 10113172128 10560360448 1125680640 12.081 98 20 40 1 1203175776 73329392 4165744
03:37:47 132028 8192000000 10108328592 10620100608 1123904352 12.064 98 13 26 0 1186968912 60484304 2580256
03:42:17 29270 8192000000 10071708880 10519478272 1118238608 12.010 99 46 85 3 1164929632 38725552 7965472
03:43:47 58264 8192000000 10057294768 10529624064 1132758752 12.147 100 16 32 0 1181203568 45242736 3202080
03:46:04 74293 8192000000 10084605232 10550800384 1128948768 12.111 99 8 16 1 1170549200 39843296 1757136
03:48:38 51757 8192000000 10050445344 10520981504 1123212224 12.057 99 15 30 0 1165090704 38226640 3651840
03:50:31 29606 8192000000 10086996384 10539745280 1143277744 12.246 100 29 58 0 1181684448 32484384 5922320
03:52:02 28451 8192000000 10095902128 10554630144 1126661632 12.090 98 30 58 1 1180211248 47678608 5871008
03:53:32 42598 8192000000 10114926128 10562060288 1103322672 11.869 96 27 54 0 1184278288 75355280 5600336
03:55:23 52849 8192000000 10110628880 10632757248 1134807264 12.167 99 22 44 1 1237787792 98412656 4567872
03:57:31 34236 8192000000 10153065376 10586226688 1151692704 12.325 100 30 60 0 1232783424 74620768 6469952
03:59:12 62004 8192000000 10119243712 10638991360 1122048192 12.046 98 20 40 0 1226465872 100045904 4371776
04:01:16 34446 8192000000 10082884048 10514972672 1129287104 12.115 99 32 64 0 1176346688 40969760 6089824
04:02:51 11117 8192000000 10133442736 10555944960 1124793024 12.072 99 59 118 0 1205596224 69297216 11505984
04:04:04 103691 8192000000 10079906320 10540003328 1111993120 11.951 98 19 37 2 1166698784 51060064 3645600
04:07:01 53435 8192000000 10067611616 10510573568 1116369408 11.993 100 21 41 1 1163405072 42815904 4219760
04:08:54 53910 8192000000 10055748752 10529996800 1108573728 11.919 99 17 34 0 1170653840 58616336 3463776
04:11:22 59623 8192000000 10090092336 10546171904 1115804176 11.987 99 16 32 1 1207927200 88440032 3682992
04:13:47 68026 8192000000 10051533440 10494029824 1053538224 11.395 94 13 24 3 1112202128 55722464 2941440
04:16:01 41644 8192000000 10105534848 10545115136 1126059168 12.084 100 19 36 2 1184682208 54711264 3911776
04:18:04 32078 8192000000 10106740384 10535800832 1124872064 12.073 99 42 84 2 1198090608 64409200 8809344
04:20:22 91014 8192000000 10097393440 10552569856 1134960608 12.168 100 9 18 0 1201591968 64151456 2479904
04:22:56 46174 8192000000 10071736176 10527367168 1056171184 11.420 93 36 72 1 1142242976 78631872 7439920
04:24:44 83130 8192000000 10138534528 10602590208 1126504992 12.088 99 17 33 0 1203266384 73399792 3361600
04:27:08 94330 8192000000 10063074560 10501967872 1120063328 12.028 98 32 56 2 1161806768 35798592 5944848
04:29:43 44794 8192000000 10069587712 10549202944 1143748384 12.251 100 15 30 2 1178612464 31767008 3097072
04:32:38 71218 8192000000 10071512704 10561806336 1144380608 12.257 100 15 26 1 1197920816 50648512 2891696
04:34:55 21890 8192000000 10073098848 10526310400 1137303792 12.190 99 39 76 2 1171513168 26000176 8209200
04:36:17 27278 8192000000 10097310592 10560724992 1132109216 12.141 99 33 64 2 1205067664 65135856 7822592

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-01-2022 04:38:48 to 09-01-2022 04:39:04.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 105 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-01-2022 04:39:05 to 09-01-2022 04:39:07.

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          206206
MON$OLDEST_ACTIVE               210327
MON$OLDEST_SNAPSHOT             210327
MON$NEXT_TRANSACTION            210327
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                       2118784
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             754
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 ---
:201: 
:202: mscs-test-161.budzdorov.ru    Sun Jan  9 04:37:19 2022
:203:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:204: 
--- end of diff output ---

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

09.01.22 04:39:08. 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-09 04:39:10 No severe PSQL-related problems occured
09.01.22 04:39:12. End of report.