Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.11.2021 01:39
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 16761052 kB
MemAvailable 31609952 kB
Buffers 0 kB
Cached 14939916 kB
SwapCached 0 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 3.0.8
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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 2021-11-20 01:37:57
DTS_END                         2021-11-20 01:37:57.6650
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/58332
TRN_ID                          241026
ATT_ID                          658
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: 9520.67 18726 1142481
customer order: creation 179.40 335 21528
customer order: refuse 15.42 373 1850
order to supplier: creation 48.57 1178 5828
order to supplier: removal 3.40 110430 408
invoice (draft): creation 48.82 3277 5858
invoice (draft): removal 52.88 781 6345
invoice accept: apply 43.26 17677 5191
invoice accept: cancel 47.50 792 5700
customer reserve: creation 4087.98 172 490558
customer reserve: removal 4679.45 64 561534
realization accept: apply 88.09 183 10571
realization accept: cancel 89.23 102 10707
payment from customer: creation 62.48 123 7497
payment from customer: removal 5.98 94 718
payment to supplier: creation 51.11 151 6133
payment to supplier: removal 3.90 117 468
service: total inventory turnovers 5.19 918 623
service: total monetary turnovers 7.04 21 845
service: aggregate perf. data 0.96 16405 115
service: refresh index statistics 0.03 221332 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-11-2021 01:39:43 to 20-11-2021 01:39:45.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3381
TEST_TIME 2 3563
TEST_TIME 3 4028
TEST_TIME 4 4548
TEST_TIME 5 5234
TEST_TIME 6 5747
TEST_TIME 7 6344
TEST_TIME 8 6626
TEST_TIME 9 6511
TEST_TIME 10 6264
TEST_TIME 11 6252
TEST_TIME 12 6258
TEST_TIME 13 6371
TEST_TIME 14 6490
TEST_TIME 15 6637
TEST_TIME 16 6696
TEST_TIME 17 6597
TEST_TIME 18 6597
TEST_TIME 19 6879
TEST_TIME 20 7387
TEST_TIME 21 7739
TEST_TIME 22 7740
TEST_TIME 23 7802
TEST_TIME 24 8098
TEST_TIME 25 8215
TEST_TIME 26 8521
TEST_TIME 27 8883
TEST_TIME 28 9624
TEST_TIME 29 9583
TEST_TIME 30 10122
TEST_TIME 31 10694
TEST_TIME 32 10501
TEST_TIME 33 10558
TEST_TIME 34 10833
TEST_TIME 35 10825
TEST_TIME 36 10880
TEST_TIME 37 10692
TEST_TIME 38 10808
TEST_TIME 39 10621
TEST_TIME 40 10570
TEST_TIME 41 10418
TEST_TIME 42 10384
TEST_TIME 43 10358
TEST_TIME 44 10331
TEST_TIME 45 10266
TEST_TIME 46 10225
TEST_TIME 47 10196
TEST_TIME 48 10166
TEST_TIME 49 10216
TEST_TIME 50 10261
TEST_TIME 51 10156
TEST_TIME 52 10102
TEST_TIME 53 9992
TEST_TIME 54 9839
TEST_TIME 55 9923
TEST_TIME 56 9937
TEST_TIME 57 9954
TEST_TIME 58 9927
TEST_TIME 59 9893
TEST_TIME 60 9995
TEST_TIME 61 9987
TEST_TIME 62 9996
TEST_TIME 63 10004
TEST_TIME 64 10082
TEST_TIME 65 9981
TEST_TIME 66 10125
TEST_TIME 67 10130
TEST_TIME 68 10143
TEST_TIME 69 10194
TEST_TIME 70 10147
TEST_TIME 71 10137
TEST_TIME 72 10034
TEST_TIME 73 9978
TEST_TIME 74 9934
TEST_TIME 75 9869
TEST_TIME 76 9867
TEST_TIME 77 9817
TEST_TIME 78 9782
TEST_TIME 79 9783
TEST_TIME 80 9803
TEST_TIME 81 9741
TEST_TIME 82 9778
TEST_TIME 83 9766
TEST_TIME 84 9764
TEST_TIME 85 9771
TEST_TIME 86 9775
TEST_TIME 87 9784
TEST_TIME 88 9823
TEST_TIME 89 9855
TEST_TIME 90 9825
TEST_TIME 91 9873
TEST_TIME 92 9855
TEST_TIME 93 9881
TEST_TIME 94 9872
TEST_TIME 95 9797
TEST_TIME 96 9786
TEST_TIME 97 9760
TEST_TIME 98 9724
TEST_TIME 99 9663
TEST_TIME 100 9610
TEST_TIME 101 9579
TEST_TIME 102 9525
TEST_TIME 103 9554
TEST_TIME 104 9527
TEST_TIME 105 9558
TEST_TIME 106 9525
TEST_TIME 107 9537
TEST_TIME 108 9517
TEST_TIME 109 9497
TEST_TIME 110 9539
TEST_TIME 111 9571
TEST_TIME 112 9593
TEST_TIME 113 9597
TEST_TIME 114 9637
TEST_TIME 115 9613
TEST_TIME 116 9619
TEST_TIME 117 9612
TEST_TIME 118 9568
TEST_TIME 119 9548
TEST_TIME 120 9523

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 20-11-2021 01:39:47 to 20-11-2021 01:39:50.

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 1595704 1595704 0 0.00 0 20297 6 0 0
doc_list_biud 1595703 1595703 0 0.00 0 28054 28 0 0
sp_add_invoice_to_stock 5338 5191 147 2.75 18 241932 17678 0 147
sp_cancel_adding_invoice 5700 5700 0 0.00 6 77393 793 0 0
sp_cancel_client_order 1850 1850 0 0.00 4 21311 374 0 0
sp_cancel_customer_reserve 561534 561534 0 0.00 1 20766 64 0 0
sp_cancel_pay_from_customer 718 718 0 0.00 3 17082 94 0 0
sp_cancel_pay_to_supplier 468 468 0 0.00 2 8793 118 0 0
sp_cancel_supplier_invoice 6354 6345 9 0.14 2 18954 781 0 9
sp_cancel_supplier_order 408 408 0 0.00 5 1232907 110431 0 0
sp_cancel_write_off 10707 10707 0 0.00 1 14540 102 0 0
sp_client_order 21528 21528 0 0.00 3 18124 336 0 0
sp_customer_reserve 490558 490558 0 0.00 0 85284 172 0 0
sp_fill_shopping_cart 33207 33207 0 0.00 1 11931 35 0 0
sp_fill_shopping_cart_clo_res 490556 490556 0 0.00 0 17069 12 0 0
sp_get_clo_for_invoice 5189 5189 0 0.00 0 21352 287 0 0
sp_kill_cost_storno 17593 17593 0 0.00 0 17077 56 0 0
sp_kill_qstorno_ret_qs2qd 1140528 1140528 0 0.00 0 20759 34 0 0
sp_kill_qty_storno 602304 602304 0 0.00 0 28053 73 0 0
sp_lock_selected_doc 21832 21832 0 0.00 0 11307 7 0 0
sp_make_cost_storno 29390 29390 0 0.00 0 20261 54 0 0
sp_multiply_rows_for_pdistr 29390 29390 0 0.00 0 19034 30 0 0
sp_multiply_rows_for_qdistr 21528 21528 0 0.00 0 18115 147 0 0
sp_pay_from_customer 7497 7497 0 0.00 4 17008 124 0 0
sp_pay_to_supplier 6133 6133 0 0.00 4 20310 152 0 0
sp_payment_common 13630 13630 0 0.00 1 16582 43 0 0
sp_qd_handle_on_cancel_clo 1850 1850 0 0.00 0 21293 338 0 0
sp_qd_handle_on_invoice_upd_sts 10889 10889 0 0.00 0 28050 317 0 0
sp_qd_handle_on_reserve_upd_sts 10571 10571 0 0.00 0 12631 56 0 0
sp_reserve_write_off 10571 10571 0 0.00 6 19628 183 0 0
sp_supplier_invoice 5858 5858 0 0.00 6 95263 3278 0 0
sp_supplier_order 5828 5828 0 0.00 6 22565 1178 0 0
srv_aggregate_perf_data 115 115 0 0.00 2098 386581 16405 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 2576 121457 41028 0 0
srv_find_qd_qs_mism 1091599 1091599 0 0.00 0 8934 5 0 0
srv_make_invnt_saldo 623 623 0 0.00 22 67857 919 0 0
srv_make_money_saldo 845 845 0 0.00 1 1444 21 0 0
srv_recalc_idx_stat 4 4 0 0.00 132815 342147 221333 0 0
t$perf-abend:sp_add_invoice_to_stock 147 147 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5191 5191 0 0.00 0 16774 176 0 0
t$perf-norm:sp_cancel_adding_invoice 437 437 0 0.00 1 2080 71 0 0
t$perf-norm:sp_cancel_client_order 1850 1850 0 0.00 0 3184 19 0 0
t$perf-norm:sp_cancel_customer_reserve 713 713 0 0.00 0 1209 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 718 718 0 0.00 0 2343 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 468 468 0 0.00 0 8316 45 0 0
t$perf-norm:sp_cancel_supplier_invoice 456 456 0 0.00 0 2288 17 0 0
t$perf-norm:sp_cancel_supplier_order 408 408 0 0.00 0 18602 1756 0 0
t$perf-norm:sp_cancel_write_off 1022 1022 0 0.00 0 7580 21 0 0
t$perf-norm:sp_client_order 21528 21528 0 0.00 0 12603 18 0 0
t$perf-norm:sp_customer_reserve 1056 1056 0 0.00 0 3239 10 0 0
t$perf-norm:sp_pay_from_customer 7497 7497 0 0.00 0 6751 9 0 0
t$perf-norm:sp_pay_to_supplier 6133 6133 0 0.00 0 6759 10 0 0
t$perf-norm:sp_reserve_write_off 10571 10571 0 0.00 0 14787 15 0 0
t$perf-norm:sp_supplier_invoice 5858 5858 0 0.00 0 5844 17 0 0
t$perf-norm:sp_supplier_order 5828 5828 0 0.00 0 8050 16 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 186 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 1756 28 0 0
t$perf-norm:srv_make_invnt_saldo 623 623 0 0.00 0 1950 28 0 0
t$perf-norm:srv_make_money_saldo 845 845 0 0.00 0 7178 28 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 11 5 0 0
v_add_invoice_to_stock 10672 10378 294 2.75 0 6407 19 0 294
v_all_customers 39832 39832 0 0.00 0 151 0 0 0
v_all_suppliers 32298 32298 0 0.00 0 13 1 0 0
v_all_wares 43056 43056 0 0.00 0 151 1 0 0
v_cancel_adding_invoice 874 874 0 0.00 0 2318 10 0 0
v_cancel_client_order 3700 3700 0 0.00 0 4141 7 0 0
v_cancel_customer_prepayment 1436 1436 0 0.00 0 3550 7 0 0
v_cancel_customer_reserve 1426 1426 0 0.00 0 1186 4 0 0
v_cancel_payment_to_supplier 936 936 0 0.00 0 1757 7 0 0
v_cancel_supplier_invoice 930 912 18 1.93 0 7191 23 0 18
v_cancel_supplier_order 816 816 0 0.00 0 310 3 0 0
v_cancel_write_off 2044 2044 0 0.00 0 1434 12 0 0
v_max_id_clo_ord 5828 5828 0 0.00 0 1414 1 0 0
v_max_id_clo_res 1056 1056 0 0.00 0 3345 20 0 0
v_max_id_ord_sup 5851 5851 0 0.00 0 4367 6 0 0
v_max_non_paid_invoice 1663 1663 0 0.00 0 3043 5 0 0
v_max_non_paid_realizn 3738 3738 0 0.00 0 4432 7 0 0
v_min_id_clo_ord 5828 5828 0 0.00 0 7198 10 0 0
v_min_id_clo_res 1056 1056 0 0.00 0 3523 11 0 0
v_min_id_ord_sup 5851 5851 0 0.00 0 3884 7 0 0
v_min_non_paid_invoice 6133 6133 0 0.00 0 14337 36 0 0
v_min_non_paid_realizn 7497 7497 0 0.00 0 15263 32 0 0
v_our_firm 10742 10742 0 0.00 0 12 1 0 0
v_reserve_write_off 21142 21142 0 0.00 0 6368 6 0 0
x_lock_depdocs_on_canc_invoice 11589 11589 0 0.00 0 25500 112 0 0
x_lock_depdocs_on_canc_sup_ord 408 408 0 0.00 0 20782 414 0 0
x_make_qstorno_1000_1200 5828 5828 0 0.00 1 22558 1102 0 0
x_make_qstorno_1200_2000 5858 5858 0 0.00 2 95115 3157 0 0
x_make_qstorno_2100_3300 490558 490558 0 0.00 0 85283 157 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-11-2021 01:39:51 to 20-11-2021 01:39:55.

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
23:08:01 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:02 32058 8192000000 9907136400 10220478464 984115584 10.724 100 89 178 0 1013700160 6050080 23534496
23:10:40 33415 8192000000 9963410784 10288803840 1041213488 11.276 100 86 171 2 1071641760 8699328 21728944
23:12:19 23580 8192000000 9997400752 10335948800 1083471392 11.681 100 76 152 0 1112305168 10195760 18638016
23:13:52 7128 8192000000 10051339376 10404347904 1137474800 12.192 100 77 154 1 1166094192 11329376 17290016
23:15:05 9102 8192000000 10091810528 10454781952 1181287728 12.602 100 80 162 1 1212163744 12409456 18466560
23:16:17 5639 8192000000 10124403904 10496663552 1216932016 12.933 100 77 153 0 1245520192 12271648 16316528
23:17:25 4185 8192000000 10163233408 10537533440 1247523552 13.215 100 88 175 2 1280777840 13534256 19720032
23:18:31 2259 8192000000 10190980192 10575998976 1282647360 13.537 100 86 172 0 1315218256 12487200 20083696
23:19:35 2170 8192000000 10210246288 10593832960 1296054112 13.659 100 91 183 2 1329651152 12522784 21074256
23:20:41 9804 8192000000 10200576000 10602156032 1302333104 13.716 100 51 103 0 1322764000 8844352 11586544
23:21:53 13534 8192000000 10200931856 10613297152 1309734592 13.784 100 14 27 2 1317970672 5670720 2565360
23:23:08 7339 8192000000 10201187920 10623307776 1320274016 13.879 100 18 36 1 1329452320 5323888 3854416
23:24:16 58145 8192000000 10215415088 10638700544 1327541360 13.945 100 30 60 0 1340209008 5609568 7058080
23:26:20 5005 8192000000 10231812928 10646134784 1331944272 13.985 100 24 48 0 1343121488 5453888 5723328
23:27:27 10552 8192000000 10215114400 10637533184 1319504592 13.872 99 16 32 0 1328596752 5024720 4067440
23:28:40 12325 8192000000 10210701840 10633523200 1305856768 13.748 98 9 18 0 1312881472 5183248 1841456
23:29:54 57403 8192000000 10174491408 10579062784 1199850656 12.775 91 32 62 2 1212443920 5099408 7493856
23:31:54 5369 8192000000 10161406320 10566098944 1257630208 13.308 99 22 44 0 1268721952 5633824 5457920
23:33:01 9371 8192000000 10129243760 10526879744 1226133408 13.018 99 15 30 0 1234735072 5330512 3271152
23:34:11 10312 8192000000 10142259808 10549612544 1248175984 13.221 99 19 38 2 1257821824 5075440 4570400
23:35:21 42470 8192000000 10179254784 10582659072 1279593424 13.509 100 49 97 4 1296629072 5121376 11914272
23:37:08 6684 8192000000 10177750464 10592464896 1289668704 13.601 100 24 46 1 1301901760 6680800 5552256
23:38:19 16423 8192000000 10217156272 10624856064 1314387568 13.826 100 46 92 0 1332478480 7354240 10736672
23:39:39 14497 8192000000 10243671856 10652098560 1335595792 14.018 100 56 111 1 1358083424 9559312 12928320
23:40:56 18804 8192000000 10256130304 10668916736 1346767056 14.118 100 48 96 1 1368869376 11039120 11063200
23:42:16 20787 8192000000 10261889136 10679918592 1352566832 14.171 100 46 92 1 1374218848 11290288 10361728
23:43:41 14373 8192000000 10288218256 10698174464 1362989712 14.264 100 63 126 0 1390007312 12804688 14212912
23:45:02 14713 8192000000 10287728128 10707431424 1373850944 14.362 100 62 122 1 1400473456 12714304 13908208
23:46:22 3300 8192000000 10298927072 10718789632 1369797936 14.325 99 55 110 1 1397475344 15217104 12460304
23:47:28 2576 8192000000 10307964992 10726993920 1385413872 14.465 100 57 113 1 1417964768 18666800 13884096
23:48:35 12769 8192000000 10305328432 10734403584 1392296512 14.526 100 41 82 2 1420916176 18753728 9865936
23:49:58 3072 8192000000 10337891264 10758320128 1408374240 14.669 100 68 135 0 1447756416 22749120 16633056
23:51:08 5555 8192000000 10346762704 10762235904 1406029088 14.649 100 72 144 2 1453627568 29807904 17790576
23:52:20 4602 8192000000 10367256208 10788323328 1421662304 14.787 100 75 149 2 1475435200 34560064 19212832
23:53:33 18437 8192000000 10369268640 10796232704 1432590336 14.884 100 54 107 0 1481627376 35081376 13955664
23:54:59 5237 8192000000 10358825616 10775674880 1395950320 14.559 97 64 128 1 1453564064 41071936 16541808
23:56:08 3801 8192000000 10379271728 10799669248 1417901728 14.754 100 63 127 3 1485649264 50733792 17013744
23:57:15 6602 8192000000 10375411680 10795778048 1415077824 14.729 99 68 135 3 1478898736 46228656 17592256
23:58:33 5107 8192000000 10358438592 10782498816 1402378096 14.616 99 50 98 2 1455472960 40070864 13024000
23:59:43 6925 8192000000 10360438720 10791550976 1406841408 14.656 99 37 71 1 1459767728 42584288 10342032
00:00:53 5974 8192000000 10366247872 10792296448 1410532912 14.689 100 44 86 0 1469670304 47563360 11574032
00:02:03 8657 8192000000 10357363248 10785058816 1396849920 14.567 100 46 93 1 1465111952 55865808 12396224
00:03:15 10366 8192000000 10358098768 10785435648 1391029696 14.515 100 44 88 0 1463330448 59642176 12658576
00:04:30 16073 8192000000 10361259056 10792161280 1393463136 14.537 100 37 74 1 1464396880 59776528 11157216
00:05:58 34302 8192000000 10318563344 10765053952 1364192128 14.275 98 23 44 1 1405470480 34598688 6679664
00:07:43 36140 8192000000 10260745088 10730143744 1331517440 13.981 98 11 22 2 1379313888 44770112 3026336
00:09:20 53064 8192000000 10306099632 10737610752 1357836496 14.218 99 19 37 0 1371240320 9604112 3799712
00:11:16 70725 8192000000 10263098112 10713960448 1308682848 13.774 96 5 10 1 1322802736 13414640 705248
00:13:28 54479 8192000000 10282791216 10711707648 1307589536 13.764 95 16 32 0 1323088784 11950704 3548544
00:15:26 31814 8192000000 10283546256 10722226176 1339888784 14.056 98 26 52 0 1359945536 13934608 6122144
00:17:15 99399 8192000000 10271601376 10716954624 1339868048 14.056 98 28 56 0 1360511808 14261296 6382464
00:20:40 71047 8192000000 10294046928 10748141568 1364194288 14.275 99 27 53 1 1386272960 15998624 6080048
00:23:02 26616 8192000000 10289252624 10740920320 1381001632 14.426 100 17 33 2 1396460272 11554992 3903648
00:24:35 32238 8192000000 10317383408 10762903552 1396247680 14.562 100 33 66 1 1416312224 12141536 7923008
00:26:11 52628 8192000000 10303064272 10769801216 1401747040 14.611 100 17 34 1 1422912624 17298496 3867088
00:28:05 37830 8192000000 10292223424 10754465792 1380532096 14.421 99 8 16 0 1390527968 8626128 1369744
00:29:50 46691 8192000000 10296127184 10755878912 1392921920 14.532 100 19 38 1 1409459616 12142672 4395024
00:31:36 52344 8192000000 10315919568 10773348352 1403714320 14.628 100 25 50 0 1423173808 13733376 5726112
00:33:30 55221 8192000000 10315409072 10774564864 1392377152 14.527 99 16 32 1 1408528368 12815312 3335904
00:35:31 28030 8192000000 10309916304 10772205568 1379825120 14.415 98 18 36 0 1391569840 7720128 4024592
00:37:34 44802 8192000000 10313785296 10775089152 1384317104 14.455 99 8 16 0 1397301952 11320912 1663936
00:39:19 28556 8192000000 10300274080 10750197760 1381094496 14.426 99 21 42 0 1396468016 11084032 4289488
00:40:49 89163 8192000000 10276805584 10728124416 1279502208 13.508 91 15 30 0 1293668256 10881664 3284384
00:43:22 51776 8192000000 10306797600 10770759680 1392293344 14.526 100 17 34 0 1412102832 16069024 3740464
00:46:26 68118 8192000000 10290014560 10732871680 1331336608 13.979 96 26 52 0 1347678176 11049904 5291664
00:48:38 121457 8192000000 10256464464 10708041728 1338501264 14.044 98 29 57 1 1354202592 9073888 6627440
00:51:41 30511 8192000000 10254054864 10708193280 1335199008 14.014 98 16 32 0 1344965424 6147728 3618688
00:53:39 59917 8192000000 10241223712 10692386816 1322709584 13.901 97 14 28 0 1338586448 12708512 3168352
00:55:58 63220 8192000000 10271366032 10725163008 1356937152 14.210 99 24 46 2 1373226816 9942992 6346672
00:58:08 68730 8192000000 10265242352 10732355584 1337370720 14.034 98 14 28 2 1354124320 13706288 3047312
01:00:19 61541 8192000000 10268407568 10724450304 1331901888 13.984 97 20 40 0 1350391008 13893840 4595280
01:02:23 33351 8192000000 10275389328 10717159424 1352637152 14.171 99 14 28 0 1363597408 7947648 3012608
01:04:00 32259 8192000000 10265150624 10708410368 1318440064 13.863 97 21 42 0 1336499584 13315024 4744496
01:05:46 73137 8192000000 10257618640 10714411008 1329747024 13.965 98 11 22 0 1345838960 13543424 2548512
01:07:59 94828 8192000000 10304875120 10748981248 1354703088 14.190 98 24 46 1 1369706080 10181296 4821696
01:10:36 25697 8192000000 10298711792 10746277888 1377481904 14.394 99 13 26 0 1386759200 6725248 2552048
01:12:03 107043 8192000000 10294097008 10754609152 1390185888 14.508 100 26 52 1 1406367568 10165536 6016144
01:15:10 57165 8192000000 10277571120 10745475072 1385636000 14.467 100 9 18 0 1395958608 8179072 2143536
01:17:07 65670 8192000000 10265164752 10720509952 1351178544 14.158 97 20 38 0 1364445264 8181808 5084912
01:19:13 18564 8192000000 10290902256 10750152704 1362146944 14.257 98 20 40 0 1384027168 17237040 4643184
01:21:47 84944 8192000000 10273663616 10741186560 1336025120 14.022 97 13 26 0 1349981216 10451408 3504688
01:24:12 34200 8192000000 10282262576 10738704384 1368230208 14.311 99 29 59 0 1384068464 8431840 7406416
01:25:48 51306 8192000000 10288337232 10740101120 1357762368 14.217 98 26 52 2 1375658832 12168784 5727680
01:27:49 63670 8192000000 10278122128 10725457920 1337909568 14.039 97 17 34 0 1353858560 12007568 3941424
01:29:54 96692 8192000000 10278590352 10739986432 1357139664 14.212 99 17 33 1 1373584272 12624352 3820256
01:32:43 36765 8192000000 10272796128 10715639808 1325722176 13.928 97 15 30 1 1336733232 7868304 3142752
01:34:30 60577 8192000000 10258426704 10715111424 1312891408 13.812 96 33 66 0 1330059184 9668928 7498848
01:36:32 82913 8192000000 10248942624 10711187456 1328858304 13.957 98 21 42 0 1345796192 12598496 4339392

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 20-11-2021 01:39:57 to 20-11-2021 01:40:12.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 147 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 20-11-2021 01:40:13 to 20-11-2021 01:40:15.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          240695
MON$OLDEST_ACTIVE               241882
MON$OLDEST_SNAPSHOT             241882
MON$NEXT_TRANSACTION            241882
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-03 16:41:55.3470
MON$PAGES                       2033312
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33540 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33540 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33540 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33540 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:41: 
:42: mscs-test-161.budzdorov.ru    Sat Nov 20 01:38:02 2021
:43:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:44: 
:45: 
:46: mscs-test-161.budzdorov.ru    Sat Nov 20 01:38:13 2021
:47:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:48: 
--- end of diff output ---

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

20.11.21 01:40:16. There are 3 logs in /var/tmp/logs-oltp30 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
2021-11-20 01:40:18 No severe PSQL-related problems occured
20.11.21 01:40:19. End of report.