Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.01.2021 10:27
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 e0e7b46250ba4f9795ceb55e0face73f
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 2020-09-25 13:42
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.00
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 16678144 kB
MemAvailable 31217236 kB
Buffers 0 kB
Cached 14875692 kB
SwapCached 4864 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.0
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 2021-01-15 10:26:55
DTS_END                         2021-01-15 10:26:55.8460
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/37496
TRN_ID                          229664
ATT_ID                          642
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: 8834.12 22947 1060094
customer order: creation 164.05 434 19686
customer order: refuse 14.18 322 1701
order to supplier: creation 42.88 1366 5146
order to supplier: removal 3.27 113668 392
invoice (draft): creation 42.14 3807 5057
invoice (draft): removal 50.02 881 6002
invoice accept: apply 39.90 17638 4788
invoice accept: cancel 45.37 982 5444
customer reserve: creation 3717.14 169 446057
customer reserve: removal 4437.08 68 532449
realization accept: apply 76.93 178 9231
realization accept: cancel 80.41 121 9649
payment from customer: creation 54.88 118 6586
payment from customer: removal 5.22 67 626
payment to supplier: creation 46.64 157 5597
payment to supplier: removal 3.34 123 401
service: total inventory turnovers 3.81 2937 457
service: total monetary turnovers 5.93 500 712
service: aggregate perf. data 0.90 17372 108
service: refresh index statistics 0.04 298039 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 15-01-2021 10:27:40 to 15-01-2021 10:27:43.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2959
TEST_TIME 2 2684
TEST_TIME 3 4280
TEST_TIME 4 4975
TEST_TIME 5 4767
TEST_TIME 6 4872
TEST_TIME 7 4968
TEST_TIME 8 5209
TEST_TIME 9 5101
TEST_TIME 10 5069
TEST_TIME 11 5456
TEST_TIME 12 5899
TEST_TIME 13 5839
TEST_TIME 14 5854
TEST_TIME 15 6189
TEST_TIME 16 6351
TEST_TIME 17 6736
TEST_TIME 18 6963
TEST_TIME 19 7411
TEST_TIME 20 7991
TEST_TIME 21 8635
TEST_TIME 22 8798
TEST_TIME 23 9217
TEST_TIME 24 9143
TEST_TIME 25 9548
TEST_TIME 26 10364
TEST_TIME 27 10564
TEST_TIME 28 10298
TEST_TIME 29 10307
TEST_TIME 30 10044
TEST_TIME 31 9882
TEST_TIME 32 9694
TEST_TIME 33 9548
TEST_TIME 34 9457
TEST_TIME 35 9282
TEST_TIME 36 9433
TEST_TIME 37 9437
TEST_TIME 38 9260
TEST_TIME 39 9146
TEST_TIME 40 9109
TEST_TIME 41 9143
TEST_TIME 42 9053
TEST_TIME 43 9133
TEST_TIME 44 9033
TEST_TIME 45 9183
TEST_TIME 46 9311
TEST_TIME 47 9255
TEST_TIME 48 9201
TEST_TIME 49 9320
TEST_TIME 50 9276
TEST_TIME 51 9276
TEST_TIME 52 9334
TEST_TIME 53 9408
TEST_TIME 54 9279
TEST_TIME 55 9121
TEST_TIME 56 9117
TEST_TIME 57 9040
TEST_TIME 58 8979
TEST_TIME 59 8950
TEST_TIME 60 8916
TEST_TIME 61 8854
TEST_TIME 62 8876
TEST_TIME 63 8829
TEST_TIME 64 8969
TEST_TIME 65 9135
TEST_TIME 66 9148
TEST_TIME 67 9085
TEST_TIME 68 9142
TEST_TIME 69 9166
TEST_TIME 70 9153
TEST_TIME 71 9158
TEST_TIME 72 9168
TEST_TIME 73 9049
TEST_TIME 74 8981
TEST_TIME 75 8938
TEST_TIME 76 8882
TEST_TIME 77 8845
TEST_TIME 78 8800
TEST_TIME 79 8798
TEST_TIME 80 8806
TEST_TIME 81 8773
TEST_TIME 82 8754
TEST_TIME 83 8754
TEST_TIME 84 8795
TEST_TIME 85 8757
TEST_TIME 86 8869
TEST_TIME 87 8975
TEST_TIME 88 9018
TEST_TIME 89 9061
TEST_TIME 90 8993
TEST_TIME 91 8901
TEST_TIME 92 8884
TEST_TIME 93 8855
TEST_TIME 94 8802
TEST_TIME 95 8820
TEST_TIME 96 8798
TEST_TIME 97 8813
TEST_TIME 98 8802
TEST_TIME 99 8782
TEST_TIME 100 8779
TEST_TIME 101 8815
TEST_TIME 102 8776
TEST_TIME 103 8785
TEST_TIME 104 8825
TEST_TIME 105 8995
TEST_TIME 106 9000
TEST_TIME 107 8957
TEST_TIME 108 9009
TEST_TIME 109 8939
TEST_TIME 110 8889
TEST_TIME 111 8835
TEST_TIME 112 8777
TEST_TIME 113 8783
TEST_TIME 114 8788
TEST_TIME 115 8760
TEST_TIME 116 8785
TEST_TIME 117 8828
TEST_TIME 118 8830
TEST_TIME 119 8822
TEST_TIME 120 8841

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 15-01-2021 10:27:44 to 15-01-2021 10:27:47.

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 1472105 1472105 0 0.00 0 16889 6 0 0
doc_list_biud 1472105 1472105 0 0.00 0 78569 31 0 0
sp_add_invoice_to_stock 4884 4788 96 1.96 33 194825 17639 0 96
sp_cancel_adding_invoice 5444 5444 0 0.00 4 79388 983 0 0
sp_cancel_client_order 1701 1701 0 0.00 5 14330 323 0 0
sp_cancel_customer_reserve 532449 532449 0 0.00 1 78572 68 0 0
sp_cancel_pay_from_customer 626 626 0 0.00 3 5533 67 0 0
sp_cancel_pay_to_supplier 401 401 0 0.00 3 11501 124 0 0
sp_cancel_supplier_invoice 6010 6002 8 0.13 2 75622 881 0 8
sp_cancel_supplier_order 392 392 0 0.00 7 1149030 113668 0 0
sp_cancel_write_off 9649 9649 0 0.00 1 10049 121 0 0
sp_client_order 19686 19686 0 0.00 4 106845 434 0 0
sp_customer_reserve 446057 446057 0 0.00 0 50870 170 0 0
sp_fill_shopping_cart 29887 29887 0 0.00 1 29778 50 0 0
sp_fill_shopping_cart_clo_res 446048 446048 0 0.00 0 32390 19 0 0
sp_get_clo_for_invoice 4779 4779 0 0.00 0 41796 494 0 0
sp_kill_cost_storno 16120 16120 0 0.00 0 10750 59 0 0
sp_kill_qstorno_ret_qs2qd 1080941 1080941 0 0.00 0 78565 37 0 0
sp_kill_qty_storno 569646 569646 0 0.00 0 78568 79 0 0
sp_lock_selected_doc 19417 19417 0 0.00 0 7222 6 0 0
sp_make_cost_storno 26192 26192 0 0.00 0 14857 64 0 0
sp_multiply_rows_for_pdistr 26192 26192 0 0.00 0 11064 35 0 0
sp_multiply_rows_for_qdistr 19686 19686 0 0.00 0 106836 248 0 0
sp_pay_from_customer 6586 6586 0 0.00 4 14634 119 0 0
sp_pay_to_supplier 5597 5597 0 0.00 5 14860 157 0 0
sp_payment_common 12183 12183 0 0.00 1 10118 28 0 0
sp_qd_handle_on_cancel_clo 1701 1701 0 0.00 0 14323 293 0 0
sp_qd_handle_on_invoice_upd_sts 10222 10222 0 0.00 0 50882 379 0 0
sp_qd_handle_on_reserve_upd_sts 9231 9231 0 0.00 0 10169 43 0 0
sp_reserve_write_off 9231 9231 0 0.00 6 15171 179 0 0
sp_supplier_invoice 5057 5057 0 0.00 7 87055 3808 0 0
sp_supplier_order 5146 5146 0 0.00 5 83332 1366 0 0
srv_aggregate_perf_data 108 108 0 0.00 2789 463043 17372 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 987 157885 51285 0 0
srv_find_qd_qs_mism 1014272 1014272 0 0.00 0 16888 5 0 0
srv_make_invnt_saldo 457 457 0 0.00 44 103224 2938 0 0
srv_make_money_saldo 712 712 0 0.00 1 14628 501 0 0
srv_recalc_idx_stat 5 5 0 0.00 67679 476022 298039 0 0
t$perf-abend:sp_add_invoice_to_stock 96 96 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4789 4789 0 0.00 0 41532 180 0 0
t$perf-norm:sp_cancel_adding_invoice 384 384 0 0.00 1 6229 144 0 0
t$perf-norm:sp_cancel_client_order 1701 1701 0 0.00 0 665 9 0 0
t$perf-norm:sp_cancel_customer_reserve 574 574 0 0.00 0 2207 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 626 626 0 0.00 0 2744 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 401 401 0 0.00 0 1368 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 373 373 0 0.00 0 3955 21 0 0
t$perf-norm:sp_cancel_supplier_order 392 392 0 0.00 0 16381 1431 0 0
t$perf-norm:sp_cancel_write_off 957 957 0 0.00 0 624 5 0 0
t$perf-norm:sp_client_order 19686 19686 0 0.00 0 7056 17 0 0
t$perf-norm:sp_customer_reserve 950 950 0 0.00 0 2920 10 0 0
t$perf-norm:sp_pay_from_customer 6586 6586 0 0.00 0 8195 9 0 0
t$perf-norm:sp_pay_to_supplier 5597 5597 0 0.00 0 6182 10 0 0
t$perf-norm:sp_reserve_write_off 9231 9231 0 0.00 0 8216 13 0 0
t$perf-norm:sp_supplier_invoice 5057 5057 0 0.00 0 6744 16 0 0
t$perf-norm:sp_supplier_order 5146 5146 0 0.00 0 3708 15 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 470 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 171 3 0 0
t$perf-norm:srv_make_invnt_saldo 457 457 0 0.00 0 2908 18 0 0
t$perf-norm:srv_make_money_saldo 712 712 0 0.00 0 2612 14 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 9748 9556 192 1.96 0 6955 18 0 192
v_all_customers 35922 35922 0 0.00 0 19 1 0 0
v_all_suppliers 28426 28426 0 0.00 0 140 1 0 0
v_all_wares 39372 39372 0 0.00 0 66 1 0 0
v_cancel_adding_invoice 768 768 0 0.00 0 2332 10 0 0
v_cancel_client_order 3402 3402 0 0.00 0 2446 5 0 0
v_cancel_customer_prepayment 1252 1252 0 0.00 0 392 3 0 0
v_cancel_customer_reserve 1148 1148 0 0.00 0 909 4 0 0
v_cancel_payment_to_supplier 802 802 0 0.00 0 1568 5 0 0
v_cancel_supplier_invoice 762 746 16 2.09 0 8407 32 0 16
v_cancel_supplier_order 784 784 0 0.00 0 1298 5 0 0
v_cancel_write_off 1914 1914 0 0.00 0 6050 16 0 0
v_max_id_clo_ord 5146 5146 0 0.00 0 404 1 0 0
v_max_id_clo_res 950 950 0 0.00 0 4381 21 0 0
v_max_id_ord_sup 5054 5054 0 0.00 0 5510 7 0 0
v_max_non_paid_invoice 1584 1584 0 0.00 0 391 2 0 0
v_max_non_paid_realizn 3467 3467 0 0.00 0 4061 5 0 0
v_min_id_clo_ord 5146 5146 0 0.00 0 2740 6 0 0
v_min_id_clo_res 950 950 0 0.00 0 8657 30 0 0
v_min_id_ord_sup 5054 5054 0 0.00 0 7820 12 0 0
v_min_non_paid_invoice 5597 5597 0 0.00 0 10112 24 0 0
v_min_non_paid_realizn 6586 6586 0 0.00 0 9947 17 0 0
v_our_firm 9688 9688 0 0.00 0 21 1 0 0
v_reserve_write_off 18462 18462 0 0.00 0 2350 5 0 0
x_lock_depdocs_on_canc_invoice 11073 11073 0 0.00 0 23671 108 0 0
x_lock_depdocs_on_canc_sup_ord 392 392 0 0.00 0 19837 398 0 0
x_make_qstorno_1000_1200 5146 5146 0 0.00 2 83322 1286 0 0
x_make_qstorno_1200_2000 5057 5057 0 0.00 4 87046 3603 0 0
x_make_qstorno_2100_3300 446057 446057 0 0.00 0 50869 148 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 15-01-2021 10:27:48 to 15-01-2021 10:27:53.

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
07:56:59 9 8192000000 8796638048 8807108608 3168016 0.038 1 1 2 0 3265632 61840 35776
07:58:00 31536 8192000000 9721824688 10016866304 795817888 8.854 100 91 181 1 825877440 7967472 22092080
07:59:35 21415 8192000000 9761025728 10065920000 841441952 9.314 100 92 184 0 875357344 12402864 21512528
08:01:02 18814 8192000000 9809277392 10128248832 877654960 9.676 100 86 171 2 918838816 21860160 19323696
08:02:28 13474 8192000000 9867355776 10195910656 916527472 10.062 100 78 156 1 972390000 39366384 16496144
08:03:47 6544 8192000000 9928235184 10274443264 960691184 10.496 100 81 160 1 1045463152 67831312 16940656
08:04:56 6875 8192000000 9965275856 10319892480 998527520 10.864 100 81 162 3 1080451968 65380256 16544192
08:06:06 2588 8192000000 10007346208 10369204224 1024299440 11.113 100 92 182 2 1122942944 80452784 18190720
08:07:09 1238 8192000000 10021488032 10387611648 1053016560 11.390 100 90 179 1 1132597536 61165600 18415376
08:08:11 19540 8192000000 9995352800 10379812864 1066128720 11.515 100 51 100 2 1107313504 30622672 10562112
08:09:32 5471 8192000000 10003670416 10388680704 1072834848 11.579 100 45 91 1 1128150624 45523696 9792080
08:10:39 4563 8192000000 9999319296 10386948096 1081131520 11.658 100 32 64 1 1109394560 21041184 7221856
08:11:44 6604 8192000000 10004069424 10399293440 1086215760 11.707 100 46 90 3 1115606992 19223664 10167568
08:12:53 71747 8192000000 10011544944 10422276096 1079551760 11.643 99 39 78 1 1116124288 28463776 8108752
08:15:09 3188 8192000000 10012397312 10409263104 1083353104 11.679 99 53 103 2 1126891600 31417088 12121408
08:16:12 5665 8192000000 10008820480 10407583744 1088955008 11.733 99 47 92 4 1117623632 18487072 10181552
08:17:19 6019 8192000000 9981983392 10372341760 1057552176 11.433 98 37 75 2 1079450352 13840704 8057472
08:18:27 25678 8192000000 9951880160 10360709120 990629328 10.788 93 19 37 2 1012080208 17074240 4376640
08:19:53 5143 8192000000 9943855296 10322382848 1000034576 10.879 97 46 92 2 1032680528 22237200 10408752
08:21:02 6600 8192000000 9945916224 10335846400 1018287120 11.055 99 54 104 1 1047767584 17262784 12217680
08:22:11 52357 8192000000 9938169440 10322456576 998104672 10.860 97 48 95 2 1020608432 12330224 10173536
08:24:09 6691 8192000000 9967721344 10359640064 1044245216 11.305 100 42 85 3 1083010432 28419104 10346112
08:25:17 12323 8192000000 9978277744 10382151680 1063516880 11.490 100 30 60 1 1082295104 11217488 7560736
08:26:32 6901 8192000000 9997775136 10397315072 1087450848 11.718 100 28 57 0 1115713504 22228656 6034000
08:27:41 63355 8192000000 10032739360 10430177280 1091447248 11.756 99 44 86 0 1136632976 36231008 8954720
08:29:50 6183 8192000000 10065356016 10475180032 1111175920 11.944 100 40 80 3 1183634560 64622016 7836624
08:31:04 28245 8192000000 10085527792 10491514880 1133372000 12.153 100 42 83 1 1184316320 42228592 8715728
08:32:37 15540 8192000000 10130553936 10538127360 1144143568 12.254 100 45 88 2 1234175376 80281232 9750576
08:33:55 18536 8192000000 10172767888 10596651008 1156477744 12.370 100 51 99 1 1291406960 124004016 10925200
08:35:26 23737 8192000000 10223411824 10639196160 1172746128 12.522 100 45 88 1 1324775408 142148224 9881056
08:36:57 8649 8192000000 10287665632 10700845056 1183756160 12.625 100 58 116 0 1390234048 193493776 12984112
08:38:08 6701 8192000000 10297798784 10718810112 1191495712 12.697 100 54 108 3 1407194240 203327008 12371520
08:39:19 6375 8192000000 10294496480 10707431424 1183049552 12.619 99 66 132 1 1398525168 201188528 14287088
08:40:30 5965 8192000000 10349095120 10760478720 1180261712 12.593 100 71 143 1 1450182496 253305184 16615600
08:41:42 4440 8192000000 10372436320 10778423296 1168736816 12.485 99 80 159 2 1462580368 274624560 19218992
08:42:53 3589 8192000000 10401673520 10812530688 1172986000 12.525 100 79 157 1 1499670416 308082320 18602096
08:44:02 986 8192000000 10413575824 10815668224 1159166816 12.395 100 79 158 1 1511151040 332808048 19176176
08:45:11 4398 8192000000 10402102016 10818732032 1144494560 12.258 100 84 166 0 1497254176 333770768 18988848
08:46:20 11662 8192000000 10361560912 10791657472 1135631088 12.174 99 40 80 2 1456401664 310517664 10252912
08:47:39 11749 8192000000 10335736384 10779049984 1150748912 12.317 100 36 72 1 1456755264 296380752 9625600
08:48:58 15592 8192000000 10299948096 10735484928 1131032544 12.131 99 32 64 1 1396291072 256481056 8777472
08:50:21 29800 8192000000 10301827600 10747129856 1147395040 12.285 100 18 36 0 1392910224 240721152 4794032
08:52:00 55515 8192000000 10129894000 10619592704 1075344912 11.603 96 13 26 2 1274968448 195789952 3833584
08:53:59 58288 8192000000 10074269824 10503835648 1098020256 11.819 99 29 59 1 1161989344 57822400 6146688
08:56:31 62330 8192000000 10043246080 10471112704 1089737280 11.740 98 29 59 0 1114915072 18765392 6412400
08:58:46 86314 8192000000 10130205040 10587488256 1126184864 12.085 99 16 32 1 1217893408 87964496 3744048
09:01:12 36469 8192000000 10150449520 10587820032 1144734672 12.260 100 33 65 1 1252955520 100347408 7873440
09:02:49 97567 8192000000 10062400608 10548985856 1123604336 12.061 98 21 42 0 1203909712 75882592 4422784
09:05:44 33946 8192000000 10080989184 10510209024 1115859584 11.988 98 25 50 0 1176351616 55378512 5113520
09:07:35 71838 8192000000 10079395424 10523680768 1129152432 12.113 99 9 17 1 1187158640 55955312 2050896
09:10:17 20652 8192000000 10137947456 10595758080 1149277376 12.303 100 49 98 0 1231078832 70821680 10979776
09:11:40 85103 8192000000 10105951984 10625576960 1146975568 12.281 100 16 32 1 1209696112 59077088 3643456
09:14:08 57289 8192000000 10136813328 10576502784 1131931600 12.140 99 26 52 1 1169583888 32309776 5342512
09:16:09 24613 8192000000 10096968640 10530103296 1153869760 12.346 100 33 66 2 1183279904 23625024 5785120
09:17:36 48769 8192000000 10158220304 10604879872 1138263392 12.199 98 16 32 0 1231439360 90040288 3135680
09:19:26 157751 8192000000 10067105376 10520977408 1132695776 12.147 98 15 29 0 1182683872 46850544 3137552
09:23:10 48294 8192000000 10066531872 10542661632 1115942688 11.989 97 5 10 0 1140188784 23293840 952256
09:25:05 43985 8192000000 10067629824 10538364928 1127231184 12.095 98 9 18 0 1155052576 25826608 1994784
09:26:49 35796 8192000000 10139379904 10582937600 1166318624 12.462 100 17 34 0 1217289712 47203280 3767808
09:28:25 97263 8192000000 10224167328 10685190144 1155523920 12.361 98 22 44 0 1279965152 119555712 4885520
09:31:04 72569 8192000000 10124098832 10652192768 1145572848 12.268 99 11 22 0 1249745072 102065408 2106816
09:33:17 34435 8192000000 10096962176 10517528576 1117841520 12.007 98 48 95 1 1159106288 31592080 9672688
09:34:53 142726 8192000000 10096341856 10548527104 1018432544 11.057 90 15 30 0 1050255744 28922064 2901136
09:38:24 89456 8192000000 10033259920 10503307264 1087880992 11.723 97 8 16 0 1116528032 26688704 1958336
09:41:11 38940 8192000000 10055719920 10519257088 1108845568 11.921 99 9 17 0 1148574528 38178320 1550640
09:42:51 43769 8192000000 10045000064 10519453696 1106819952 11.902 99 4 8 0 1130629728 23176912 632864
09:44:35 62553 8192000000 10072598640 10521378816 1106759712 11.902 98 25 47 3 1151017024 38699856 5557456
09:46:39 43793 8192000000 10071728480 10501492736 1098164400 11.820 98 21 42 1 1162969040 60643520 4161120
09:48:29 80838 8192000000 10170973536 10606743552 1086888720 11.713 96 27 54 2 1213424048 120842912 5692416
09:50:57 39448 8192000000 10170732448 10608533504 1115186912 11.981 99 24 48 0 1238583552 118337008 5059632
09:52:44 74907 8192000000 10107010512 10609709056 1104953520 11.885 99 9 18 1 1177679840 70704560 2021760
09:55:05 90021 8192000000 10075573952 10548330496 1076065776 11.610 96 11 22 0 1125629504 47785088 1778640
09:57:36 157883 8192000000 10057113168 10526265344 1110148224 11.934 98 13 26 1 1165867728 52648976 3070528
10:01:58 58246 8192000000 10061053152 10491224064 1090362416 11.746 96 16 32 0 1129884912 36329136 3193360
10:04:05 23361 8192000000 10068177008 10491834368 1125655952 12.080 100 18 36 1 1153044912 23741184 3647776
10:05:30 69584 8192000000 10105384128 10537357312 1116095120 11.990 98 24 48 0 1188181136 66969664 5116352
10:07:42 79611 8192000000 10159913808 10604892160 1123727600 12.062 98 21 41 1 1217249488 88851376 4670512
10:10:24 99555 8192000000 10103430640 10685743104 1108581920 11.919 96 10 20 0 1229534960 118775936 2177104
10:13:06 36554 8192000000 10113532160 10550415360 1146698704 12.278 100 22 44 1 1198133984 47081392 4353888
10:14:44 130401 8192000000 10109203152 10601160704 1136671808 12.184 99 28 57 0 1201434368 58520912 6241648
10:18:09 68428 8192000000 10151099152 10603810816 1157061680 12.376 100 11 21 0 1265092704 105935728 2095296
10:20:18 59743 8192000000 10116203920 10583166976 1118734416 12.015 97 9 18 0 1184638000 64200336 1703248
10:22:23 70610 8192000000 10096580960 10652053504 1118071760 12.009 98 23 47 1 1253614080 129756864 5785456
10:24:42 23056 8192000000 10063328256 10487488512 1106925728 11.903 98 34 68 0 1135374400 20947840 7500832
10:26:10 50493 8192000000 9224555424 9520041984 359311424 4.201 32 9 18 0 383799632 22758704 1729504

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 15-01-2021 10:27:54 to 15-01-2021 10:28:09.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 96 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-01-2021 10:28:10 to 15-01-2021 10:28:12.

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          230134
MON$OLDEST_ACTIVE               230135
MON$OLDEST_SNAPSHOT             230135
MON$NEXT_TRANSACTION            230135
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                       2086624
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                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             791
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2325 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2325 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2325 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2325 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16: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 ---
:96: 
:97: mscs-test-161.budzdorov.ru    Fri Jan 15 10:27:02 2021
:98:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:99: 
:100: 
:101: mscs-test-161.budzdorov.ru    Fri Jan 15 10:27:03 2021
:102:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:103: 
:104: 
:105: mscs-test-161.budzdorov.ru    Fri Jan 15 10:27:07 2021
:106:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:107: 
--- end of diff output ---

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

15.01.21 10:28:13. 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
2021-01-15 10:28:16 No severe PSQL-related problems occured
15.01.21 10:28:17. End of report.