Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.12.2020 10:28
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 16828284 kB
MemAvailable 31439396 kB
Buffers 0 kB
Cached 14790416 kB
SwapCached 3696 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-12-21 10:26:56
DTS_END                         2020-12-21 10:26:56.8040
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/45746
TRN_ID                          227477
ATT_ID                          652
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: 8751.90 23862 1050228
customer order: creation 161.07 399 19328
customer order: refuse 13.29 318 1595
order to supplier: creation 42.58 1338 5109
order to supplier: removal 3.22 117376 386
invoice (draft): creation 43.33 3770 5200
invoice (draft): removal 50.76 916 6091
invoice accept: apply 39.42 18713 4731
invoice accept: cancel 45.27 869 5432
customer reserve: creation 3634.50 182 436140
customer reserve: removal 4446.00 67 533520
realization accept: apply 77.34 171 9281
realization accept: cancel 76.60 114 9192
payment from customer: creation 54.47 97 6536
payment from customer: removal 5.30 87 636
payment to supplier: creation 45.08 123 5410
payment to supplier: removal 3.33 52 399
service: total inventory turnovers 3.60 3415 432
service: total monetary turnovers 5.83 527 699
service: aggregate perf. data 0.88 14792 105
service: refresh index statistics 0.05 313910 6

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-12-2020 10:29:08 to 21-12-2020 10:29:10.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2699
TEST_TIME 2 2690
TEST_TIME 3 3507
TEST_TIME 4 4344
TEST_TIME 5 4304
TEST_TIME 6 4671
TEST_TIME 7 5240
TEST_TIME 8 5523
TEST_TIME 9 6433
TEST_TIME 10 6461
TEST_TIME 11 6946
TEST_TIME 12 7121
TEST_TIME 13 7313
TEST_TIME 14 7464
TEST_TIME 15 7732
TEST_TIME 16 7758
TEST_TIME 17 8340
TEST_TIME 18 8568
TEST_TIME 19 8589
TEST_TIME 20 8970
TEST_TIME 21 8942
TEST_TIME 22 9150
TEST_TIME 23 9312
TEST_TIME 24 9165
TEST_TIME 25 9618
TEST_TIME 26 9828
TEST_TIME 27 10920
TEST_TIME 28 11608
TEST_TIME 29 11452
TEST_TIME 30 11073
TEST_TIME 31 11152
TEST_TIME 32 10910
TEST_TIME 33 10803
TEST_TIME 34 10726
TEST_TIME 35 10543
TEST_TIME 36 10322
TEST_TIME 37 10181
TEST_TIME 38 10168
TEST_TIME 39 10129
TEST_TIME 40 9981
TEST_TIME 41 9844
TEST_TIME 42 9733
TEST_TIME 43 9767
TEST_TIME 44 9687
TEST_TIME 45 9605
TEST_TIME 46 9504
TEST_TIME 47 9421
TEST_TIME 48 9409
TEST_TIME 49 9355
TEST_TIME 50 9194
TEST_TIME 51 9213
TEST_TIME 52 9084
TEST_TIME 53 9135
TEST_TIME 54 9098
TEST_TIME 55 9103
TEST_TIME 56 9186
TEST_TIME 57 9173
TEST_TIME 58 9144
TEST_TIME 59 9134
TEST_TIME 60 9207
TEST_TIME 61 9118
TEST_TIME 62 9197
TEST_TIME 63 9221
TEST_TIME 64 9180
TEST_TIME 65 9100
TEST_TIME 66 9021
TEST_TIME 67 8985
TEST_TIME 68 8895
TEST_TIME 69 8942
TEST_TIME 70 8913
TEST_TIME 71 8971
TEST_TIME 72 8953
TEST_TIME 73 8948
TEST_TIME 74 8899
TEST_TIME 75 8902
TEST_TIME 76 8966
TEST_TIME 77 8902
TEST_TIME 78 9066
TEST_TIME 79 9089
TEST_TIME 80 9022
TEST_TIME 81 9059
TEST_TIME 82 9101
TEST_TIME 83 9116
TEST_TIME 84 9028
TEST_TIME 85 8992
TEST_TIME 86 8929
TEST_TIME 87 8876
TEST_TIME 88 8867
TEST_TIME 89 8816
TEST_TIME 90 8776
TEST_TIME 91 8849
TEST_TIME 92 8851
TEST_TIME 93 8851
TEST_TIME 94 8862
TEST_TIME 95 8830
TEST_TIME 96 8876
TEST_TIME 97 8839
TEST_TIME 98 8901
TEST_TIME 99 8975
TEST_TIME 100 8934
TEST_TIME 101 8862
TEST_TIME 102 8853
TEST_TIME 103 8835
TEST_TIME 104 8824
TEST_TIME 105 8812
TEST_TIME 106 8786
TEST_TIME 107 8780
TEST_TIME 108 8750
TEST_TIME 109 8747
TEST_TIME 110 8803
TEST_TIME 111 8847
TEST_TIME 112 8808
TEST_TIME 113 8874
TEST_TIME 114 8882
TEST_TIME 115 8907
TEST_TIME 116 8894
TEST_TIME 117 8867
TEST_TIME 118 8828
TEST_TIME 119 8797
TEST_TIME 120 8757

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 21-12-2020 10:29:12 to 21-12-2020 10:29:15.

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 1454195 1454195 0 0.00 0 13286 6 0 0
doc_list_biud 1454195 1454195 0 0.00 0 48981 31 0 0
sp_add_invoice_to_stock 4824 4731 93 1.92 43 185077 18714 0 93
sp_cancel_adding_invoice 5432 5432 0 0.00 5 64140 870 0 0
sp_cancel_client_order 1595 1595 0 0.00 5 6405 318 0 0
sp_cancel_customer_reserve 533520 533520 0 0.00 1 26887 68 0 0
sp_cancel_pay_from_customer 636 636 0 0.00 3 4555 87 0 0
sp_cancel_pay_to_supplier 399 399 0 0.00 3 1896 52 0 0
sp_cancel_supplier_invoice 6101 6091 10 0.16 1 23157 916 0 10
sp_cancel_supplier_order 386 386 0 0.00 7 1043362 117377 0 0
sp_cancel_write_off 9192 9192 0 0.00 1 14131 115 0 0
sp_client_order 19328 19328 0 0.00 4 55493 400 0 0
sp_customer_reserve 436140 436140 0 0.00 0 36472 183 0 0
sp_fill_shopping_cart 29626 29626 0 0.00 1 22722 44 0 0
sp_fill_shopping_cart_clo_res 436134 436134 0 0.00 0 26148 20 0 0
sp_get_clo_for_invoice 4720 4720 0 0.00 0 37779 484 0 0
sp_kill_cost_storno 15659 15659 0 0.00 0 12015 55 0 0
sp_kill_qstorno_ret_qs2qd 1082709 1082709 0 0.00 0 26854 36 0 0
sp_kill_qty_storno 570214 570214 0 0.00 0 48980 79 0 0
sp_lock_selected_doc 19386 19386 0 0.00 0 6959 6 0 0
sp_make_cost_storno 25944 25944 0 0.00 0 13213 53 0 0
sp_multiply_rows_for_pdistr 25944 25944 0 0.00 0 9594 26 0 0
sp_multiply_rows_for_qdistr 19328 19328 0 0.00 0 18705 196 0 0
sp_pay_from_customer 6536 6536 0 0.00 4 11257 98 0 0
sp_pay_to_supplier 5410 5410 0 0.00 4 12215 124 0 0
sp_payment_common 11946 11946 0 0.00 1 11247 20 0 0
sp_qd_handle_on_cancel_clo 1595 1595 0 0.00 0 6391 289 0 0
sp_qd_handle_on_invoice_upd_sts 10149 10149 0 0.00 0 48980 397 0 0
sp_qd_handle_on_reserve_upd_sts 9281 9281 0 0.00 0 29871 51 0 0
sp_reserve_write_off 9281 9281 0 0.00 7 29918 171 0 0
sp_supplier_invoice 5200 5200 0 0.00 7 98762 3770 0 0
sp_supplier_order 5109 5109 0 0.00 6 84103 1339 0 0
srv_aggregate_perf_data 105 105 0 0.00 2831 139259 14792 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1627 134597 47244 0 0
srv_find_qd_qs_mism 1005722 1005722 0 0.00 0 11905 5 0 0
srv_make_invnt_saldo 432 432 0 0.00 45 93585 3415 0 0
srv_make_money_saldo 699 699 0 0.00 0 17511 527 0 0
srv_recalc_idx_stat 6 6 0 0.00 160984 449240 313911 0 0
t$perf-abend:sp_add_invoice_to_stock 93 93 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4731 4731 0 0.00 0 51664 190 0 0
t$perf-norm:sp_cancel_adding_invoice 408 408 0 0.00 0 2843 77 0 0
t$perf-norm:sp_cancel_client_order 1595 1595 0 0.00 0 2174 12 0 0
t$perf-norm:sp_cancel_customer_reserve 641 641 0 0.00 0 5420 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 636 636 0 0.00 0 988 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 399 399 0 0.00 0 26637 70 0 0
t$perf-norm:sp_cancel_supplier_invoice 410 410 0 0.00 0 1122 20 0 0
t$perf-norm:sp_cancel_supplier_order 386 386 0 0.00 0 16640 1662 0 0
t$perf-norm:sp_cancel_write_off 913 913 0 0.00 0 10203 19 0 0
t$perf-norm:sp_client_order 19328 19328 0 0.00 0 10167 17 0 0
t$perf-norm:sp_customer_reserve 919 919 0 0.00 0 1839 5 0 0
t$perf-norm:sp_pay_from_customer 6536 6536 0 0.00 0 4245 9 0 0
t$perf-norm:sp_pay_to_supplier 5410 5410 0 0.00 0 9103 9 0 0
t$perf-norm:sp_reserve_write_off 9281 9281 0 0.00 0 11986 15 0 0
t$perf-norm:sp_supplier_invoice 5200 5200 0 0.00 0 7212 17 0 0
t$perf-norm:sp_supplier_order 5109 5109 0 0.00 0 10428 20 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 373 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 78 2 0 0
t$perf-norm:srv_make_invnt_saldo 432 432 0 0.00 0 2259 14 0 0
t$perf-norm:srv_make_money_saldo 699 699 0 0.00 0 1904 7 0 0
t$perf-norm:srv_recalc_idx_stat 6 6 0 0.00 1 51 12 0 0
v_add_invoice_to_stock 9620 9434 186 1.93 0 3703 17 0 186
v_all_customers 35314 35314 0 0.00 0 251 1 0 0
v_all_suppliers 28678 28678 0 0.00 0 109 1 0 0
v_all_wares 38654 38654 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 816 816 0 0.00 0 4476 18 0 0
v_cancel_client_order 3190 3190 0 0.00 0 3683 4 0 0
v_cancel_customer_prepayment 1272 1272 0 0.00 0 3617 12 0 0
v_cancel_customer_reserve 1282 1282 0 0.00 0 3287 6 0 0
v_cancel_payment_to_supplier 798 798 0 0.00 0 1865 5 0 0
v_cancel_supplier_invoice 840 820 20 2.38 0 3416 26 0 20
v_cancel_supplier_order 772 772 0 0.00 0 2121 7 0 0
v_cancel_write_off 1826 1826 0 0.00 0 2235 17 0 0
v_max_id_clo_ord 5109 5109 0 0.00 0 285 1 0 0
v_max_id_clo_res 919 919 0 0.00 0 4109 14 0 0
v_max_id_ord_sup 5190 5190 0 0.00 0 4922 4 0 0
v_max_non_paid_invoice 1370 1370 0 0.00 0 64 2 0 0
v_max_non_paid_realizn 3455 3455 0 0.00 0 2642 4 0 0
v_min_id_clo_ord 5109 5109 0 0.00 0 2261 5 0 0
v_min_id_clo_res 919 919 0 0.00 0 3156 6 0 0
v_min_id_ord_sup 5190 5190 0 0.00 0 9186 6 0 0
v_min_non_paid_invoice 5410 5410 0 0.00 0 7580 12 0 0
v_min_non_paid_realizn 6536 6536 0 0.00 0 6593 12 0 0
v_our_firm 9502 9502 0 0.00 0 16 1 0 0
v_reserve_write_off 18562 18562 0 0.00 0 4970 7 0 0
x_lock_depdocs_on_canc_invoice 11113 11113 0 0.00 0 14976 114 0 0
x_lock_depdocs_on_canc_sup_ord 386 386 0 0.00 0 7571 346 0 0
x_make_qstorno_1000_1200 5109 5109 0 0.00 2 84084 1265 0 0
x_make_qstorno_1200_2000 5200 5200 0 0.00 3 98749 3597 0 0
x_make_qstorno_2100_3300 436140 436140 0 0.00 0 36469 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-12-2020 10:29:16 to 21-12-2020 10:29:20.

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:57:00 2 8192000000 8796637808 8807108608 3167696 0.038 1 1 2 0 3265392 61840 35856
07:58:01 28029 8192000000 9724564560 10020687872 807560512 8.973 100 90 180 0 836947280 7951216 21435552
07:59:39 19769 8192000000 9757222400 10062364672 832528208 9.225 100 89 178 0 866398640 12929696 20940736
08:01:07 7538 8192000000 9794903408 10109558784 862559616 9.526 100 92 184 0 906647744 23163776 20924352
08:02:20 9706 8192000000 9854699984 10184224768 902465760 9.923 100 71 142 2 956937712 39211200 15260752
08:03:34 10053 8192000000 9921968640 10266218496 960120496 10.490 100 76 152 0 1027485136 50964016 16400624
08:04:48 5951 8192000000 9972748160 10327965696 997222912 10.852 100 76 151 1 1082843616 70725456 14895248
08:05:58 4293 8192000000 9999430112 10363916288 1019874624 11.071 100 82 164 1 1111649200 75838448 15936128
08:07:04 1192 8192000000 10008207968 10373898240 1044615760 11.309 100 88 175 2 1117032976 53747760 18669456
08:08:07 1597 8192000000 10051134032 10424541184 1060637824 11.463 100 91 181 0 1165103424 85786080 18679520
08:09:09 13011 8192000000 9999085024 10382065664 1063062160 11.486 100 33 65 2 1117672576 47839936 6770480
08:10:23 6351 8192000000 9991431152 10383020032 1073425760 11.585 100 39 79 2 1121008528 40246656 7336112
08:11:33 6794 8192000000 9980661872 10390827008 1081020736 11.657 100 21 42 1 1117194960 31215824 4958400
08:12:41 67017 8192000000 9995137664 10393993216 1092735200 11.769 100 37 71 4 1119314000 18567248 8011552
08:14:50 6426 8192000000 10016435056 10408460288 1084047280 11.686 99 29 58 2 1115784432 26188384 5548768
08:15:58 7396 8192000000 10014531776 10415329280 1082491584 11.671 99 37 76 1 1120925520 29592144 8841792
08:17:10 6160 8192000000 9995675072 10380996608 1051714448 11.377 97 43 81 4 1065861216 4668144 9478624
08:18:19 18054 8192000000 9956720272 10356625408 989995200 10.781 92 18 34 3 1019081168 25060336 4025632
08:19:39 4023 8192000000 9949448016 10331103232 1021668912 11.088 98 50 100 4 1051518416 18539072 11310432
08:20:48 6828 8192000000 9934348288 10320416768 995233904 10.832 98 40 79 2 1013225312 9711520 8279888
08:21:55 63041 8192000000 9966132688 10339422208 1027351072 11.143 99 55 110 1 1057619216 18856960 11411184
08:23:59 12502 8192000000 9950106688 10350170112 1038059008 11.246 99 35 71 0 1069782416 23892784 7830624
08:25:13 6068 8192000000 9975779856 10365775872 1060051808 11.457 100 30 60 1 1085189008 18541136 6596064
08:26:19 7175 8192000000 9992465600 10383560704 1068172864 11.535 100 33 64 4 1099035472 24379184 6483424
08:27:27 74435 8192000000 10014885680 10406469632 1087482448 11.719 100 48 90 5 1121214544 24240528 9491568
08:29:50 24092 8192000000 10064826240 10478022656 1111382592 11.946 100 32 64 1 1193654336 75529920 6741824
08:31:22 10012 8192000000 10119593056 10518794240 1139441200 12.210 100 54 108 1 1217658256 66804160 11412896
08:32:35 6539 8192000000 10146030208 10551009280 1147879952 12.290 100 58 114 0 1251545632 90720048 12945632
08:33:45 2498 8192000000 10163721712 10562863104 1147986256 12.291 100 58 115 4 1269545232 109178464 12380512
08:34:52 1625 8192000000 10237443312 10637897728 1150910224 12.318 99 85 169 1 1335951424 167353232 17687968
08:36:01 3135 8192000000 10199260912 10616365056 1155463488 12.361 99 64 124 3 1293280960 124064624 13752848
08:37:10 12387 8192000000 10250847648 10659143680 1166412592 12.463 100 63 123 1 1353287952 172556816 14318544
08:38:26 3476 8192000000 10225157856 10626850816 1149517168 12.305 100 77 153 0 1326767536 160818912 16431456
08:39:35 3709 8192000000 10282428288 10689019904 1165107472 12.451 100 72 143 0 1383262208 201571312 16583424
08:40:45 10995 8192000000 10303970016 10718638080 1159622016 12.400 100 70 140 0 1415172512 238556592 16993904
08:42:02 3722 8192000000 10288431856 10699780096 1145233168 12.265 100 79 158 0 1395108688 231734864 18140656
08:43:11 2666 8192000000 10326064192 10753359872 1150313744 12.312 100 77 153 0 1432048768 263409792 18325232
08:44:19 1728 8192000000 10338019584 10740215808 1158249584 12.387 100 78 154 3 1433931104 257720720 17960800
08:45:24 5148 8192000000 10322895264 10734206976 1145007920 12.263 99 54 109 3 1418939744 260007936 13923888
08:46:38 5541 8192000000 10347398064 10776219648 1154734816 12.354 100 49 96 2 1456712480 289539872 12437792
08:47:53 6086 8192000000 10403439008 10821967872 1156490976 12.370 100 58 116 1 1504980352 333593104 14896272
08:49:23 17731 8192000000 10392487344 10818052096 1143665840 12.250 100 30 60 0 1479633152 328223504 7743808
08:50:43 18902 8192000000 10425080848 10877759488 1112720544 11.958 99 25 50 2 1519728416 399772704 7235168
08:52:03 14074 8192000000 10383317808 10835230720 1106553248 11.900 100 22 44 0 1479097056 365959056 6584752
08:53:19 6038 8192000000 10327036656 10794864640 1104472368 11.880 100 40 80 0 1456940880 341967072 10501440
08:54:54 37135 8192000000 10095437472 10535518208 1099488992 11.833 99 18 36 0 1179184960 75646976 4048992
08:57:14 54838 8192000000 10085064608 10582822912 1112908176 11.960 100 22 44 1 1219847312 102021440 4917696
08:59:10 50762 8192000000 10069694432 10551152640 1115124960 11.981 100 15 30 1 1209993344 91642512 3225872
09:01:07 69232 8192000000 10091985808 10530967552 1128408112 12.106 100 14 28 0 1187522256 56293728 2820416
09:03:16 29247 8192000000 10137576192 10572324864 1132045472 12.141 100 53 105 1 1229857712 85672416 12139824
09:04:55 105401 8192000000 10083330464 10519404544 1110772784 11.940 98 36 72 0 1161880960 43173568 7934608
09:07:43 27624 8192000000 10092793760 10547933184 1133666112 12.156 100 25 51 0 1201624592 63066624 4891856
09:09:18 65609 8192000000 10094013600 10577174528 1129169184 12.114 99 14 28 1 1185673008 53570576 2933248
09:11:24 32802 8192000000 10071446032 10520104960 1130566896 12.127 99 10 20 1 1174895856 42391344 1937616
09:13:18 82389 8192000000 10050197008 10540949504 1127360880 12.096 99 20 40 1 1181097680 49488288 4248512
09:16:02 72130 8192000000 10108336432 10570633216 1133614384 12.155 99 14 28 1 1193632192 56970640 3047168
09:18:45 62810 8192000000 10093325376 10573651968 1133031296 12.150 99 20 40 1 1184528352 46531552 4965504
09:20:51 42585 8192000000 10114232160 10559537152 1135925120 12.177 99 21 42 1 1208198128 67199920 5073088
09:22:55 75440 8192000000 10073624720 10540728320 1109610432 11.929 96 21 42 1 1155183360 41471744 4101184
09:25:22 53540 8192000000 10127258688 10566033408 1149726256 12.307 100 26 52 1 1226358544 71064256 5568032
09:27:17 56514 8192000000 10095076080 10558603264 1116997952 11.999 97 31 62 2 1184165248 60815360 6351936
09:29:19 57145 8192000000 10056582000 10513158144 1099112192 11.829 97 24 48 0 1153853728 50148784 4592752
09:31:22 93210 8192000000 10126792640 10579517440 1131651968 12.137 99 36 72 0 1209692448 70680816 7359664
09:34:37 50151 8192000000 10094513936 10574684160 1127430480 12.097 99 4 8 0 1174213616 46173648 609488
09:36:28 99689 8192000000 10079281360 10580738048 1131575632 12.136 99 12 24 1 1218028960 83841424 2611904
09:39:08 30599 8192000000 10075559008 10511642624 1114132640 11.972 98 15 30 1 1136716704 19742144 2841920
09:40:47 70756 8192000000 10125399696 10579308544 1098627488 11.825 96 26 52 0 1156246016 51977248 5641280
09:43:39 38750 8192000000 10122018608 10586501120 1092882928 11.770 97 33 66 2 1199961184 100328944 6749312
09:45:18 65410 8192000000 10063079664 10513387520 1078563040 11.634 97 24 48 1 1147268224 63848368 4856816
09:47:26 111922 8192000000 10048333040 10525552640 1069172080 11.544 96 18 36 0 1147798432 75365792 3260560
09:50:18 115655 8192000000 10030788528 10492461056 1099831232 11.836 99 20 40 1 1149914240 45998656 4084352
09:53:16 64424 8192000000 10048792384 10498064384 1096487600 11.804 99 6 12 0 1126680528 29137088 1055840
09:55:26 85129 8192000000 10085647520 10516762624 1069473440 11.547 96 18 38 0 1144197008 71132048 3591520
09:57:55 78674 8192000000 10082976768 10525159424 1118953584 12.017 100 21 41 2 1161699904 37767696 4978624
10:00:19 51123 8192000000 10068231024 10510950400 1119273568 12.020 99 22 44 0 1153406880 29870992 4262320
10:02:11 38234 8192000000 10059176240 10494865408 1091900400 11.761 98 22 44 1 1134802720 37945968 4956352
10:04:00 54096 8192000000 10137528400 10628235264 1124190064 12.067 100 27 54 1 1237995216 108566672 5238480
10:05:56 104255 8192000000 10081438352 10530713600 1114514864 11.975 98 22 44 0 1165962000 47013648 4433488
10:09:04 52507 8192000000 10109261600 10596196352 1134505040 12.164 100 8 16 1 1199441408 62999296 1937072
10:11:58 24352 8192000000 10078828256 10512453632 1142810000 12.242 100 31 62 3 1179592160 30069664 6712496
10:13:24 48962 8192000000 10115979968 10572181504 1134646576 12.165 99 15 30 0 1182787968 45058160 3083232
10:15:16 105796 8192000000 10116519568 10623004672 1117854528 12.007 98 22 44 2 1245223088 122380000 4988560
10:18:47 67947 8192000000 10114764992 10628386816 1114842336 11.978 98 27 54 3 1194767472 74158368 5766768
10:21:05 85774 8192000000 10096121072 10526195712 1127541008 12.098 99 15 30 1 1166890896 36489456 2860432
10:23:37 134597 8192000000 10071988816 10549747712 1129790512 12.119 99 25 50 0 1185526064 50589968 5145584

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 21-12-2020 10:29:22 to 21-12-2020 10:29:36.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 93 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-12-2020 10:29:37 to 21-12-2020 10:29:39.

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          226901
MON$OLDEST_ACTIVE               227585
MON$OLDEST_SNAPSHOT             227585
MON$NEXT_TRANSACTION            227585
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                       2096448
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             778
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2300 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2300 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2300 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.2300 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 ---
:131: 
:132: mscs-test-161.budzdorov.ru    Mon Dec 21 10:27:06 2020
:133:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:134: 
:135: 
:136: mscs-test-161.budzdorov.ru    Mon Dec 21 10:27:06 2020
:137:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:138: 
--- end of diff output ---

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

21.12.20 10:29:40. 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-12-21 10:29:43 No severe PSQL-related problems occured
21.12.20 10:29:44. End of report.