Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.11.2020 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 16968776 kB
MemAvailable 31425224 kB
Buffers 0 kB
Cached 14669964 kB
SwapCached 2844 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 2020-11-05 10:26:54
DTS_END                         2020-11-05 10:26:54.8080
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/49926
TRN_ID                          230004
ATT_ID                          600
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: 8844.63 23192 1061356
customer order: creation 165.69 405 19883
customer order: refuse 14.48 281 1737
order to supplier: creation 42.75 1378 5130
order to supplier: removal 3.18 127098 381
invoice (draft): creation 42.45 3692 5094
invoice (draft): removal 50.81 954 6097
invoice accept: apply 39.85 16135 4782
invoice accept: cancel 46.22 819 5546
customer reserve: creation 3668.51 157 440221
customer reserve: removal 4482.11 72 537853
realization accept: apply 80.64 169 9677
realization accept: cancel 86.20 117 10344
payment from customer: creation 56.69 122 6803
payment from customer: removal 5.68 110 682
payment to supplier: creation 45.68 120 5482
payment to supplier: removal 3.13 85 375
service: total inventory turnovers 3.83 3046 459
service: total monetary turnovers 5.83 573 699
service: aggregate perf. data 0.89 15311 107
service: refresh index statistics 0.03 293186 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 05-11-2020 10:27:40 to 05-11-2020 10:27:43.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3324
TEST_TIME 2 4174
TEST_TIME 3 4647
TEST_TIME 4 4252
TEST_TIME 5 4642
TEST_TIME 6 5100
TEST_TIME 7 5026
TEST_TIME 8 4958
TEST_TIME 9 5020
TEST_TIME 10 5326
TEST_TIME 11 5473
TEST_TIME 12 5706
TEST_TIME 13 5737
TEST_TIME 14 6106
TEST_TIME 15 6298
TEST_TIME 16 6794
TEST_TIME 17 6790
TEST_TIME 18 7318
TEST_TIME 19 7430
TEST_TIME 20 7517
TEST_TIME 21 7755
TEST_TIME 22 8283
TEST_TIME 23 8858
TEST_TIME 24 9063
TEST_TIME 25 9412
TEST_TIME 26 11107
TEST_TIME 27 10795
TEST_TIME 28 10552
TEST_TIME 29 10399
TEST_TIME 30 10292
TEST_TIME 31 10195
TEST_TIME 32 10013
TEST_TIME 33 9918
TEST_TIME 34 9847
TEST_TIME 35 9675
TEST_TIME 36 9444
TEST_TIME 37 9327
TEST_TIME 38 9509
TEST_TIME 39 9499
TEST_TIME 40 9501
TEST_TIME 41 9486
TEST_TIME 42 9370
TEST_TIME 43 9439
TEST_TIME 44 9491
TEST_TIME 45 9539
TEST_TIME 46 9431
TEST_TIME 47 9466
TEST_TIME 48 9629
TEST_TIME 49 9558
TEST_TIME 50 9502
TEST_TIME 51 9340
TEST_TIME 52 9325
TEST_TIME 53 9230
TEST_TIME 54 9155
TEST_TIME 55 9038
TEST_TIME 56 9009
TEST_TIME 57 8960
TEST_TIME 58 8991
TEST_TIME 59 8925
TEST_TIME 60 8993
TEST_TIME 61 9018
TEST_TIME 62 9012
TEST_TIME 63 8976
TEST_TIME 64 9018
TEST_TIME 65 9084
TEST_TIME 66 9069
TEST_TIME 67 9159
TEST_TIME 68 9117
TEST_TIME 69 9113
TEST_TIME 70 9122
TEST_TIME 71 9014
TEST_TIME 72 8948
TEST_TIME 73 8891
TEST_TIME 74 8878
TEST_TIME 75 8826
TEST_TIME 76 8891
TEST_TIME 77 8828
TEST_TIME 78 8784
TEST_TIME 79 8842
TEST_TIME 80 8954
TEST_TIME 81 8952
TEST_TIME 82 9013
TEST_TIME 83 8944
TEST_TIME 84 9048
TEST_TIME 85 9021
TEST_TIME 86 9046
TEST_TIME 87 9027
TEST_TIME 88 8937
TEST_TIME 89 8879
TEST_TIME 90 8828
TEST_TIME 91 8822
TEST_TIME 92 8781
TEST_TIME 93 8863
TEST_TIME 94 8866
TEST_TIME 95 8864
TEST_TIME 96 8862
TEST_TIME 97 8845
TEST_TIME 98 8825
TEST_TIME 99 8916
TEST_TIME 100 8957
TEST_TIME 101 8961
TEST_TIME 102 9025
TEST_TIME 103 9010
TEST_TIME 104 9014
TEST_TIME 105 9051
TEST_TIME 106 8995
TEST_TIME 107 8924
TEST_TIME 108 8889
TEST_TIME 109 8834
TEST_TIME 110 8792
TEST_TIME 111 8738
TEST_TIME 112 8735
TEST_TIME 113 8806
TEST_TIME 114 8797
TEST_TIME 115 8788
TEST_TIME 116 8810
TEST_TIME 117 8816
TEST_TIME 118 8799
TEST_TIME 119 8819
TEST_TIME 120 8850

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 05-11-2020 10:27:44 to 05-11-2020 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 1468659 1468659 0 0.00 0 37427 6 0 0
doc_list_biud 1468659 1468659 0 0.00 0 64037 33 0 0
sp_add_invoice_to_stock 4873 4782 91 1.86 35 217165 16135 0 91
sp_cancel_adding_invoice 5546 5546 0 0.00 4 76619 819 0 0
sp_cancel_client_order 1737 1737 0 0.00 5 12585 282 0 0
sp_cancel_customer_reserve 537853 537853 0 0.00 1 64039 72 0 0
sp_cancel_pay_from_customer 682 682 0 0.00 3 6894 111 0 0
sp_cancel_pay_to_supplier 375 375 0 0.00 3 2974 86 0 0
sp_cancel_supplier_invoice 6098 6097 1 0.01 2 21542 954 0 1
sp_cancel_supplier_order 381 381 0 0.00 8 1226952 127098 0 0
sp_cancel_write_off 10344 10344 0 0.00 1 15276 117 0 0
sp_client_order 19883 19883 0 0.00 4 31649 406 0 0
sp_customer_reserve 440221 440221 0 0.00 0 85106 158 0 0
sp_fill_shopping_cart 30107 30107 0 0.00 1 23033 49 0 0
sp_fill_shopping_cart_clo_res 440211 440211 0 0.00 0 17216 19 0 0
sp_get_clo_for_invoice 4771 4771 0 0.00 0 34952 468 0 0
sp_kill_cost_storno 16947 16947 0 0.00 0 8014 54 0 0
sp_kill_qstorno_ret_qs2qd 1092528 1092528 0 0.00 0 64035 39 0 0
sp_kill_qty_storno 576406 576406 0 0.00 0 64037 83 0 0
sp_lock_selected_doc 19964 19964 0 0.00 0 9999 6 0 0
sp_make_cost_storno 26733 26733 0 0.00 0 37425 51 0 0
sp_multiply_rows_for_pdistr 26733 26733 0 0.00 0 7472 29 0 0
sp_multiply_rows_for_qdistr 19883 19883 0 0.00 0 22919 236 0 0
sp_pay_from_customer 6803 6803 0 0.00 4 16102 123 0 0
sp_pay_to_supplier 5482 5482 0 0.00 5 14197 120 0 0
sp_payment_common 12285 12285 0 0.00 1 6894 18 0 0
sp_qd_handle_on_cancel_clo 1737 1737 0 0.00 0 12578 263 0 0
sp_qd_handle_on_invoice_upd_sts 10317 10317 0 0.00 0 37457 345 0 0
sp_qd_handle_on_reserve_upd_sts 9677 9677 0 0.00 0 29567 55 0 0
sp_reserve_write_off 9677 9677 0 0.00 7 37433 170 0 0
sp_supplier_invoice 5094 5094 0 0.00 8 97886 3692 0 0
sp_supplier_order 5130 5130 0 0.00 6 41827 1379 0 0
srv_aggregate_perf_data 107 107 0 0.00 2668 166484 15311 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 2085 130109 51191 0 0
srv_find_qd_qs_mism 1015865 1015865 0 0.00 0 12812 5 0 0
srv_make_invnt_saldo 459 459 0 0.00 50 110493 3046 0 0
srv_make_money_saldo 699 699 0 0.00 1 17622 573 0 0
srv_recalc_idx_stat 4 4 0 0.00 74346 446651 293187 0 0
t$perf-abend:sp_add_invoice_to_stock 91 91 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4782 4782 0 0.00 0 15759 154 0 0
t$perf-norm:sp_cancel_adding_invoice 394 394 0 0.00 0 7512 94 0 0
t$perf-norm:sp_cancel_client_order 1737 1737 0 0.00 0 3111 14 0 0
t$perf-norm:sp_cancel_customer_reserve 621 621 0 0.00 0 9706 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 682 682 0 0.00 0 359 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 375 375 0 0.00 0 4384 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 389 389 0 0.00 0 4180 23 0 0
t$perf-norm:sp_cancel_supplier_order 381 381 0 0.00 0 23767 1409 0 0
t$perf-norm:sp_cancel_write_off 937 937 0 0.00 0 13288 37 0 0
t$perf-norm:sp_client_order 19883 19883 0 0.00 0 12515 20 0 0
t$perf-norm:sp_customer_reserve 934 934 0 0.00 0 5839 15 0 0
t$perf-norm:sp_pay_from_customer 6803 6803 0 0.00 0 5521 9 0 0
t$perf-norm:sp_pay_to_supplier 5482 5482 0 0.00 0 4377 10 0 0
t$perf-norm:sp_reserve_write_off 9677 9677 0 0.00 0 37596 19 0 0
t$perf-norm:sp_supplier_invoice 5094 5094 0 0.00 0 12048 22 0 0
t$perf-norm:sp_supplier_order 5130 5130 0 0.00 0 10951 18 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 337 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 5 1 0 0
t$perf-norm:srv_make_invnt_saldo 459 459 0 0.00 0 3155 13 0 0
t$perf-norm:srv_make_money_saldo 699 699 0 0.00 0 6773 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 7 3 0 0
v_add_invoice_to_stock 9724 9542 182 1.87 0 3900 14 0 182
v_all_customers 35918 35918 0 0.00 0 22 1 0 0
v_all_suppliers 28430 28430 0 0.00 0 16 1 0 0
v_all_wares 39766 39766 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 788 788 0 0.00 0 3111 14 0 0
v_cancel_client_order 3474 3474 0 0.00 0 2039 4 0 0
v_cancel_customer_prepayment 1364 1364 0 0.00 0 2365 5 0 0
v_cancel_customer_reserve 1242 1242 0 0.00 0 1764 5 0 0
v_cancel_payment_to_supplier 750 750 0 0.00 0 2968 13 0 0
v_cancel_supplier_invoice 780 778 2 0.25 0 2009 16 0 2
v_cancel_supplier_order 762 762 0 0.00 0 5822 14 0 0
v_cancel_write_off 1874 1874 0 0.00 0 2406 12 0 0
v_max_id_clo_ord 5130 5130 0 0.00 0 838 1 0 0
v_max_id_clo_res 934 934 0 0.00 0 2082 12 0 0
v_max_id_ord_sup 5094 5094 0 0.00 0 10723 9 0 0
v_max_non_paid_invoice 1491 1491 0 0.00 0 527 2 0 0
v_max_non_paid_realizn 3682 3682 0 0.00 0 3312 5 0 0
v_min_id_clo_ord 5130 5130 0 0.00 0 1977 5 0 0
v_min_id_clo_res 934 934 0 0.00 0 5644 15 0 0
v_min_id_ord_sup 5094 5094 0 0.00 0 12498 10 0 0
v_min_non_paid_invoice 5482 5482 0 0.00 0 6167 9 0 0
v_min_non_paid_realizn 6803 6803 0 0.00 0 6087 13 0 0
v_our_firm 10090 10090 0 0.00 0 38 1 0 0
v_reserve_write_off 19354 19354 0 0.00 0 3070 6 0 0
x_lock_depdocs_on_canc_invoice 11254 11254 0 0.00 0 27575 113 0 0
x_lock_depdocs_on_canc_sup_ord 381 381 0 0.00 0 11440 394 0 0
x_make_qstorno_1000_1200 5130 5130 0 0.00 2 41818 1307 0 0
x_make_qstorno_1200_2000 5094 5094 0 0.00 4 97877 3484 0 0
x_make_qstorno_2100_3300 440221 440221 0 0.00 0 85104 137 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-11-2020 10:27:48 to 05-11-2020 10:27:52.

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:58 1 8192000000 8796633152 8807174144 3163616 0.038 1 1 2 0 3261312 61840 35856
07:57:59 31848 8192000000 9719497680 10012643328 790371744 8.799 100 84 168 1 818842288 7885024 20585520
07:59:34 18895 8192000000 9763449264 10070319104 835719616 9.257 100 87 173 1 869034784 12819344 20495824
08:00:58 20753 8192000000 9813174928 10132037632 874863152 9.649 100 90 178 2 918769152 23795152 20110848
08:02:26 9193 8192000000 9880576784 10212995072 926039296 10.156 100 78 156 0 994243440 51343136 16861008
08:03:44 7025 8192000000 9923163488 10271531008 965129744 10.539 100 76 152 0 1029348016 48654992 15563280
08:04:54 6132 8192000000 9963949408 10321059840 1000207664 10.881 100 77 154 0 1081106688 65309936 15589088
08:06:03 2040 8192000000 10002475760 10361692160 1029887264 11.167 100 91 181 1 1118725104 71174144 17663696
08:07:06 1331 8192000000 10027179040 10393083904 1048414320 11.345 100 89 179 1 1136289728 69233616 18641792
08:08:09 1333 8192000000 10043989936 10416123904 1067428592 11.528 100 92 186 2 1161413168 74673360 19311216
08:09:11 24257 8192000000 9959192608 10354855936 1067709152 11.530 100 37 74 2 1087082816 10988944 8384720
08:10:36 6931 8192000000 9984176656 10392178688 1077478224 11.623 100 28 56 1 1114048992 30342624 6228144
08:11:44 7718 8192000000 10000202720 10404937728 1087394032 11.718 100 23 46 1 1127307360 35333408 4579920
08:12:52 6857 8192000000 9989838608 10391764992 1094405264 11.785 100 23 46 3 1118359392 18307728 5646400
08:14:00 49314 8192000000 10001576016 10416283648 1097022512 11.809 100 44 88 1 1118017776 11273168 9722096
08:15:53 8472 8192000000 10028232544 10433282048 1101400544 11.851 100 42 85 3 1139118352 28700208 9017600
08:17:02 11506 8192000000 9997574816 10399301632 1085982112 11.704 99 32 64 0 1115153696 21818320 7353264
08:18:21 8165 8192000000 9969387440 10372521984 1039628096 11.261 98 20 40 3 1080678496 36935200 4115200
08:19:30 53015 8192000000 9954683152 10343849984 960774784 10.497 92 34 67 2 987784944 19320560 7689600
08:21:25 2299 8192000000 9955199728 10334076928 1039679088 11.262 100 61 120 1 1075041488 20773776 14588624
08:22:31 9507 8192000000 9953052592 10346803200 1030586048 11.174 99 36 73 0 1066344480 26927376 8831056
08:23:43 12501 8192000000 9985645856 10389495808 1056961200 11.427 100 18 35 1 1080358960 19684144 3713616
08:24:57 24341 8192000000 9974797232 10388942848 1062863056 11.484 100 26 52 0 1105241056 36710480 5667520
08:26:21 6345 8192000000 10012663856 10401878016 1085437744 11.699 100 36 71 2 1112788432 19299760 8050928
08:27:40 16486 8192000000 10055945424 10449276928 1104020992 11.876 100 55 110 0 1157644480 41003968 12619520
08:29:08 20687 8192000000 10084257616 10481934336 1124350224 12.068 100 52 104 0 1188641120 53642112 10648784
08:30:36 28287 8192000000 10117132624 10530885632 1132012608 12.140 100 34 68 1 1221455216 82048256 7394352
08:32:12 5612 8192000000 10162681568 10569895936 1148991200 12.300 100 59 116 0 1271008896 109680544 12337152
08:33:24 19393 8192000000 10204082656 10615128064 1160881360 12.412 100 47 94 1 1305958064 134650736 10425968
08:34:49 9541 8192000000 10238471440 10655199232 1171509072 12.511 100 53 103 2 1342188016 158688288 11990656
08:36:03 9978 8192000000 10274165808 10693292032 1182073200 12.610 100 46 90 2 1368496720 176056288 10367232
08:37:17 5800 8192000000 10308196256 10725900288 1196330928 12.742 100 62 123 0 1413075824 202197968 14546928
08:38:28 3726 8192000000 10333283824 10750087168 1198840000 12.766 100 68 136 0 1439250272 224294048 16116224
08:39:40 3637 8192000000 10393547872 10804256768 1193416048 12.715 99 72 144 1 1495338032 285266224 16655760
08:40:49 2084 8192000000 10387671440 10798358528 1177572960 12.568 100 74 148 3 1494392000 299473440 17345600
08:41:57 2119 8192000000 10430044384 10834157568 1172529696 12.520 100 81 159 0 1531986304 340062944 19393664
08:43:06 6495 8192000000 10423812480 10840870912 1160360192 12.407 99 50 100 2 1516631136 343607472 12663472
08:44:19 11868 8192000000 10426882448 10885828608 1161774192 12.420 100 45 90 1 1565760288 392724608 11261488
08:45:33 5360 8192000000 10440837520 10849988608 1153022384 12.338 100 54 107 0 1538677104 372425424 13229296
08:46:43 12085 8192000000 10462233312 10890584064 1131519440 12.136 98 38 76 2 1570330192 428749296 10061456
08:47:59 6799 8192000000 10473120848 10899062784 1129094496 12.113 99 36 72 1 1578982960 439913008 9975456
08:49:48 22041 8192000000 10385750816 10812702720 1115986560 11.989 100 23 46 0 1476019216 352989504 7043152
08:51:12 13975 8192000000 10371534912 10824105984 1100382080 11.841 99 22 44 1 1488721296 382403440 5935776
08:52:30 24752 8192000000 10017428144 10481971200 1081414976 11.661 99 14 28 0 1242677568 157904640 3357952
08:54:01 46529 8192000000 10055965696 10490195968 1084593600 11.691 98 40 83 4 1142119392 48511552 9014240
08:56:05 49967 8192000000 10055100192 10513932288 1092374976 11.765 98 14 29 1 1150911120 55583936 2952208
08:57:55 127871 8192000000 10079802896 10502762496 1084383968 11.689 97 29 58 2 1144207312 53693088 6130256
09:01:14 121277 8192000000 10104061456 10549018624 1118692704 12.015 100 21 42 2 1211431680 88308096 4430880
09:04:17 87310 8192000000 10090158624 10578948096 1129191152 12.114 100 16 32 0 1222814048 90372016 3250880
09:06:48 52928 8192000000 10071144560 10517004288 1118782848 12.015 98 30 57 1 1169155616 44669840 5702928
09:08:43 39230 8192000000 10127630576 10573393920 1147760704 12.288 100 28 52 0 1232047984 78647392 5639888
09:10:34 80059 8192000000 10056345248 10507218944 1084951360 11.695 95 13 26 1 1126096992 38665808 2479824
09:13:03 72353 8192000000 10124826752 10594603008 1147222560 12.283 100 23 46 2 1231254992 78980048 5052384
09:15:19 72906 8192000000 10087281216 10524819456 1121303056 12.039 98 33 65 2 1143722064 15393584 7025424
09:19:14 64681 8192000000 10065738608 10521444352 1127519312 12.098 99 13 26 1 1147830192 17655632 2655248
09:22:52 50557 8192000000 10082288752 10535292928 1140918768 12.224 100 11 22 0 1176405808 33497040 1990000
09:24:43 86993 8192000000 10079054848 10556207104 1087571968 11.720 95 20 40 2 1150693152 58440672 4680512
09:27:10 58946 8192000000 10059644160 10500571136 1116385440 11.993 98 19 38 0 1159843120 39685856 3771824
09:29:12 39639 8192000000 10096024288 10539282432 1104054128 11.876 97 25 50 0 1137274976 28424544 4796304
09:30:52 75885 8192000000 10096269680 10546429952 1116012992 11.989 98 16 32 0 1166035136 46556016 3466128
09:33:08 51247 8192000000 10085855824 10522316800 1115650912 11.986 98 24 48 1 1164973120 44549344 4772864
09:35:01 13107 8192000000 10055787280 10458312704 1071750512 11.569 97 50 100 0 1119398528 37741376 9906640
09:36:21 103446 8192000000 10050917760 10476498944 1076252880 11.612 97 43 86 1 1147710624 62625296 8832448
09:39:22 45079 8192000000 10070364240 10531778560 1090732224 11.750 99 9 18 0 1158252688 65582144 1938320
09:41:08 80635 8192000000 10117306480 10606972928 1119741056 12.025 100 19 38 1 1220791168 96909360 4140752
09:43:29 71118 8192000000 10150684304 10584956928 1101074352 11.848 97 24 49 0 1236581808 129842256 5665200
09:45:43 62678 8192000000 10095132224 10565222400 1104756544 11.883 99 13 26 0 1158985616 50902816 3326256
09:47:46 70741 8192000000 10047771488 10505658368 1096249792 11.802 98 18 35 0 1174397712 74442352 3705568
09:49:57 120309 8192000000 10052648208 10483580928 1065420064 11.508 95 22 44 0 1117454768 47326304 4708400
09:52:59 112978 8192000000 10057195856 10492186624 1112792624 11.959 100 12 24 1 1157260272 41677536 2790112
09:56:05 96364 8192000000 10090355312 10570768384 1111797552 11.949 98 10 20 0 1196468320 82468592 2202176
09:58:51 86522 8192000000 10099251712 10606006272 1124686176 12.071 99 17 34 1 1218170288 89966864 3517248
10:01:30 78597 8192000000 10070123552 10508476416 1141063888 12.226 100 21 42 3 1182014752 37241280 3709584
10:03:49 54088 8192000000 10135082304 10572730368 1143870240 12.252 99 31 62 2 1211479696 60424816 7184640
10:05:45 130109 8192000000 10151729536 10610401280 1129061552 12.113 98 13 26 1 1218019056 86652224 2305280
10:08:58 66442 8192000000 10092702192 10530893824 1120181696 12.029 98 34 67 1 1177069392 49812400 7075296
10:11:05 67671 8192000000 10082419792 10549374976 1137444544 12.191 99 14 28 0 1188784576 48649168 2690864
10:13:13 77220 8192000000 10062866736 10527969280 1134364672 12.162 99 14 28 0 1166351536 29128960 2857904
10:15:34 47399 8192000000 10057307760 10528174080 1135166832 12.170 99 4 8 0 1160578768 24943680 468256
10:17:21 68199 8192000000 10134376672 10582482944 1138163584 12.198 99 14 28 1 1221142064 79309216 3669264
10:19:30 46249 8192000000 10089431408 10535120896 1143663760 12.250 99 16 32 1 1185808720 38931056 3213904
10:21:17 78814 8192000000 10093599360 10568101888 1116592144 11.995 97 16 32 1 1169207200 49098832 3516224
10:24:26 49709 8192000000 10122801904 10573840384 1133919696 12.158 99 21 40 4 1210414576 72308752 4186128
10:26:29 34822 8192000000 8941229632 9128816640 95853328 1.156 9 2 4 0 105775936 9610224 312384

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 91 v_add_invoice_to_stock 335544517
except 1 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 05-11-2020 10:28:10 to 05-11-2020 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          230296
MON$OLDEST_ACTIVE               230297
MON$OLDEST_SNAPSHOT             230297
MON$NEXT_TRANSACTION            230297
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                       2077600
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             786
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2247 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2247 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2247 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.2247 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 ---
:19: 
:20: mscs-test-161.budzdorov.ru    Thu Nov  5 10:27:05 2020
:21:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:22: 
--- end of diff output ---

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

05.11.20 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
2020-11-05 10:28:15 No severe PSQL-related problems occured
05.11.20 10:28:17. End of report.