Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.10.2020 07:27
Common Performance Final Results
  1. Hardware and OS info
  2. DB and 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, full
  3. Ratio "Versions / Records" for tables
  4. Database Validation Results
  5. New in firebird.log while test was run
  6. 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 16888904 kB
MemAvailable 31510188 kB
Buffers 0 kB
Cached 14763312 kB
SwapCached 3044 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-10-26 07:26:51
DTS_END                         2020-10-26 07:26:51.2820
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(134:18) ==> SP_CANCEL_SUPPLIER_INVOICE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(67:17)
IP                              ::1/40926
TRN_ID                          236539
ATT_ID                          658
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 8808.23 23514 1056987
customer order: creation 172.66 432 20719
customer order: refuse 14.32 338 1718
order to supplier: creation 44.90 1450 5388
order to supplier: removal 3.25 128314 390
invoice (draft): creation 44.44 3566 5333
invoice (draft): removal 49.42 1067 5930
invoice accept: apply 41.05 17334 4926
invoice accept: cancel 44.27 960 5312
customer reserve: creation 3755.76 170 450691
customer reserve: removal 4355.87 76 522704
realization accept: apply 81.95 192 9834
realization accept: cancel 76.05 125 9126
payment from customer: creation 57.48 116 6897
payment from customer: removal 5.55 91 666
payment to supplier: creation 47.24 122 5669
payment to supplier: removal 3.28 100 393
service: total inventory turnovers 3.90 3314 468
service: total monetary turnovers 5.99 560 719
service: aggregate perf. data 0.83 14907 100
service: refresh index statistics 0.03 297041 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 26-10-2020 07:27:44 to 26-10-2020 07:27:46.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4475
TEST_TIME 2 3771
TEST_TIME 3 4573
TEST_TIME 4 5092
TEST_TIME 5 5343
TEST_TIME 6 5080
TEST_TIME 7 5071
TEST_TIME 8 5087
TEST_TIME 9 5306
TEST_TIME 10 5436
TEST_TIME 11 5746
TEST_TIME 12 5977
TEST_TIME 13 6134
TEST_TIME 14 6044
TEST_TIME 15 6433
TEST_TIME 16 6744
TEST_TIME 17 7122
TEST_TIME 18 7288
TEST_TIME 19 7769
TEST_TIME 20 8314
TEST_TIME 21 8193
TEST_TIME 22 8466
TEST_TIME 23 8778
TEST_TIME 24 8892
TEST_TIME 25 9017
TEST_TIME 26 9257
TEST_TIME 27 9594
TEST_TIME 28 11614
TEST_TIME 29 11357
TEST_TIME 30 11011
TEST_TIME 31 10840
TEST_TIME 32 10718
TEST_TIME 33 10580
TEST_TIME 34 10481
TEST_TIME 35 10372
TEST_TIME 36 10242
TEST_TIME 37 10134
TEST_TIME 38 10033
TEST_TIME 39 9798
TEST_TIME 40 9776
TEST_TIME 41 9831
TEST_TIME 42 9723
TEST_TIME 43 9760
TEST_TIME 44 9728
TEST_TIME 45 9630
TEST_TIME 46 9710
TEST_TIME 47 9647
TEST_TIME 48 9817
TEST_TIME 49 9765
TEST_TIME 50 9780
TEST_TIME 51 9744
TEST_TIME 52 9755
TEST_TIME 53 9696
TEST_TIME 54 9562
TEST_TIME 55 9513
TEST_TIME 56 9421
TEST_TIME 57 9352
TEST_TIME 58 9259
TEST_TIME 59 9263
TEST_TIME 60 9200
TEST_TIME 61 9200
TEST_TIME 62 9155
TEST_TIME 63 9171
TEST_TIME 64 9142
TEST_TIME 65 9239
TEST_TIME 66 9185
TEST_TIME 67 9183
TEST_TIME 68 9203
TEST_TIME 69 9390
TEST_TIME 70 9350
TEST_TIME 71 9446
TEST_TIME 72 9370
TEST_TIME 73 9262
TEST_TIME 74 9248
TEST_TIME 75 9206
TEST_TIME 76 9189
TEST_TIME 77 9148
TEST_TIME 78 9121
TEST_TIME 79 9130
TEST_TIME 80 9142
TEST_TIME 81 9105
TEST_TIME 82 9050
TEST_TIME 83 9128
TEST_TIME 84 9114
TEST_TIME 85 9172
TEST_TIME 86 9152
TEST_TIME 87 9231
TEST_TIME 88 9227
TEST_TIME 89 9220
TEST_TIME 90 9129
TEST_TIME 91 9082
TEST_TIME 92 9037
TEST_TIME 93 8997
TEST_TIME 94 8958
TEST_TIME 95 8955
TEST_TIME 96 8924
TEST_TIME 97 8912
TEST_TIME 98 8904
TEST_TIME 99 8915
TEST_TIME 100 8902
TEST_TIME 101 8978
TEST_TIME 102 8956
TEST_TIME 103 8956
TEST_TIME 104 8967
TEST_TIME 105 8967
TEST_TIME 106 8996
TEST_TIME 107 8973
TEST_TIME 108 8979
TEST_TIME 109 8936
TEST_TIME 110 8949
TEST_TIME 111 8884
TEST_TIME 112 8840
TEST_TIME 113 8805
TEST_TIME 114 8786
TEST_TIME 115 8760
TEST_TIME 116 8756
TEST_TIME 117 8751
TEST_TIME 118 8780
TEST_TIME 119 8781
TEST_TIME 120 8809

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 26-10-2020 07:27:48 to 26-10-2020 07:27:51.

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 1474833 1474833 0 0.00 0 19338 7 0 0
doc_list_biud 1474833 1474833 0 0.00 0 70889 34 0 0
sp_add_invoice_to_stock 5039 4926 113 2.24 29 177531 17335 0 113
sp_cancel_adding_invoice 5312 5312 0 0.00 5 69879 960 0 0
sp_cancel_client_order 1718 1718 0 0.00 4 22527 338 0 0
sp_cancel_customer_reserve 522704 522704 0 0.00 1 70890 76 0 0
sp_cancel_pay_from_customer 666 666 0 0.00 3 5763 92 0 0
sp_cancel_pay_to_supplier 393 393 0 0.00 3 11895 100 0 0
sp_cancel_supplier_invoice 5941 5930 11 0.18 2 40625 1067 0 11
sp_cancel_supplier_order 390 390 0 0.00 7 1129119 128314 0 0
sp_cancel_write_off 9126 9126 0 0.00 1 10102 126 0 0
sp_client_order 20719 20719 0 0.00 4 22601 432 0 0
sp_customer_reserve 450691 450691 0 0.00 0 48099 171 0 0
sp_fill_shopping_cart 31434 31434 0 0.00 1 19448 48 0 0
sp_fill_shopping_cart_clo_res 450687 450687 0 0.00 0 28601 19 0 0
sp_get_clo_for_invoice 4922 4922 0 0.00 0 26269 416 0 0
sp_kill_cost_storno 15497 15497 0 0.00 0 7321 62 0 0
sp_kill_qstorno_ret_qs2qd 1060854 1060854 0 0.00 0 70874 41 0 0
sp_kill_qty_storno 559936 559936 0 0.00 0 70888 89 0 0
sp_lock_selected_doc 20374 20374 0 0.00 0 7637 5 0 0
sp_make_cost_storno 27322 27322 0 0.00 0 19336 58 0 0
sp_multiply_rows_for_pdistr 27322 27322 0 0.00 0 8039 32 0 0
sp_multiply_rows_for_qdistr 20719 20719 0 0.00 0 17859 231 0 0
sp_pay_from_customer 6897 6897 0 0.00 4 10615 116 0 0
sp_pay_to_supplier 5669 5669 0 0.00 5 12047 122 0 0
sp_payment_common 12566 12566 0 0.00 1 8428 25 0 0
sp_qd_handle_on_cancel_clo 1718 1718 0 0.00 0 22515 311 0 0
sp_qd_handle_on_invoice_upd_sts 10234 10234 0 0.00 0 34426 391 0 0
sp_qd_handle_on_reserve_upd_sts 9834 9834 0 0.00 0 22956 64 0 0
sp_reserve_write_off 9834 9834 0 0.00 7 22993 192 0 0
sp_supplier_invoice 5333 5333 0 0.00 7 95563 3566 0 0
sp_supplier_order 5388 5388 0 0.00 5 34618 1451 0 0
srv_aggregate_perf_data 100 100 0 0.00 2591 166141 14907 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 2055 144443 47615 0 0
srv_find_qd_qs_mism 1010577 1010577 0 0.00 0 11229 6 0 0
srv_make_invnt_saldo 468 468 0 0.00 42 105550 3314 0 0
srv_make_money_saldo 719 719 0 0.00 1 15643 560 0 0
srv_recalc_idx_stat 4 4 0 0.00 203374 386201 297042 0 0
t$perf-abend:sp_add_invoice_to_stock 113 113 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4926 4926 0 0.00 0 15076 144 0 0
t$perf-norm:sp_cancel_adding_invoice 408 408 0 0.00 0 25881 202 0 0
t$perf-norm:sp_cancel_client_order 1718 1718 0 0.00 0 3438 10 0 0
t$perf-norm:sp_cancel_customer_reserve 643 643 0 0.00 0 603 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 666 666 0 0.00 0 5016 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 393 393 0 0.00 0 898 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 400 400 0 0.00 0 37391 105 0 0
t$perf-norm:sp_cancel_supplier_order 390 390 0 0.00 0 16609 1648 0 0
t$perf-norm:sp_cancel_write_off 1001 1001 0 0.00 0 2408 7 0 0
t$perf-norm:sp_client_order 20719 20719 0 0.00 0 30757 17 0 0
t$perf-norm:sp_customer_reserve 935 935 0 0.00 0 6438 13 0 0
t$perf-norm:sp_pay_from_customer 6897 6897 0 0.00 0 2756 6 0 0
t$perf-norm:sp_pay_to_supplier 5669 5669 0 0.00 0 5472 8 0 0
t$perf-norm:sp_reserve_write_off 9834 9834 0 0.00 0 10900 11 0 0
t$perf-norm:sp_supplier_invoice 5333 5333 0 0.00 0 2554 14 0 0
t$perf-norm:sp_supplier_order 5388 5388 0 0.00 0 5078 15 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 542 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 5 0 0 0
t$perf-norm:srv_make_invnt_saldo 468 468 0 0.00 0 4026 14 0 0
t$perf-norm:srv_make_money_saldo 719 719 0 0.00 0 1856 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 2 2 0 0
v_add_invoice_to_stock 10068 9842 226 2.24 0 7856 17 0 226
v_all_customers 37502 37502 0 0.00 0 23 1 0 0
v_all_suppliers 29458 29458 0 0.00 0 31 1 0 0
v_all_wares 41436 41436 0 0.00 0 13 1 0 0
v_cancel_adding_invoice 816 816 0 0.00 0 2353 9 0 0
v_cancel_client_order 3436 3436 0 0.00 0 1975 4 0 0
v_cancel_customer_prepayment 1332 1332 0 0.00 0 1979 8 0 0
v_cancel_customer_reserve 1286 1286 0 0.00 0 495 3 0 0
v_cancel_payment_to_supplier 786 786 0 0.00 0 4671 9 0 0
v_cancel_supplier_invoice 822 800 22 2.67 0 2355 22 0 22
v_cancel_supplier_order 780 780 0 0.00 0 1129 7 0 0
v_cancel_write_off 2002 2002 0 0.00 0 8518 23 0 0
v_max_id_clo_ord 5388 5388 0 0.00 0 2654 2 0 0
v_max_id_clo_res 935 935 0 0.00 0 3497 15 0 0
v_max_id_ord_sup 5327 5327 0 0.00 0 5059 5 0 0
v_max_non_paid_invoice 1655 1655 0 0.00 0 219 2 0 0
v_max_non_paid_realizn 3726 3726 0 0.00 0 4536 5 0 0
v_min_id_clo_ord 5388 5388 0 0.00 0 4738 10 0 0
v_min_id_clo_res 935 935 0 0.00 0 6029 21 0 0
v_min_id_ord_sup 5327 5327 0 0.00 0 10702 15 0 0
v_min_non_paid_invoice 5669 5669 0 0.00 0 6424 15 0 0
v_min_non_paid_realizn 6897 6897 0 0.00 0 8425 18 0 0
v_our_firm 10276 10276 0 0.00 0 12 1 0 0
v_reserve_write_off 19666 19666 0 0.00 0 4427 5 0 0
x_lock_depdocs_on_canc_invoice 10842 10842 0 0.00 0 15172 114 0 0
x_lock_depdocs_on_canc_sup_ord 390 390 0 0.00 0 18361 406 0 0
x_make_qstorno_1000_1200 5388 5388 0 0.00 2 34604 1374 0 0
x_make_qstorno_1200_2000 5333 5333 0 0.00 3 95529 3367 0 0
x_make_qstorno_2100_3300 450691 450691 0 0.00 0 45945 150 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-10-2020 07:27:52 to 26-10-2020 07:27:56.

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
04:56:54 2 8192000000 8796633056 8807174144 3163600 0.038 1 1 2 0 3261296 61840 35856
04:57:55 29752 8192000000 9719227488 10013376512 797457888 8.871 100 93 186 0 828333872 7715328 23160656
04:59:30 22008 8192000000 9763016032 10068049920 831914784 9.219 100 92 184 0 866132752 12648592 21569376
05:01:03 18580 8192000000 9802951232 10123227136 865328400 9.553 100 75 150 0 905086928 22476704 17281824
05:02:27 12837 8192000000 9878087488 10216898560 928793392 10.183 100 84 166 3 988182656 41235120 18154144
05:03:47 5181 8192000000 9936937776 10285965312 968008256 10.567 100 77 152 1 1055936064 71506816 16420992
05:04:54 3844 8192000000 9985804672 10344837120 1005124352 10.928 100 87 174 4 1102187712 79345072 17718288
05:06:00 2064 8192000000 10017513888 10380177408 1036354640 11.230 100 91 182 3 1127835504 73344192 18136672
05:07:03 1277 8192000000 10038795344 10406207488 1052318560 11.383 100 95 190 1 1145854112 73973120 19562432
05:08:05 7248 8192000000 9991774416 10381996032 1058548864 11.443 100 42 83 2 1114557200 47290496 8717840
05:09:15 6882 8192000000 9988357120 10376851456 1066193376 11.516 100 26 51 1 1095389344 23748032 5447936
05:10:23 5600 8192000000 10000095408 10395979776 1082100176 11.667 100 44 86 0 1114457088 22725968 9630944
05:11:31 53288 8192000000 10002192288 10410803200 1085091776 11.696 100 35 66 2 1105374752 13022560 7260416
05:13:25 5553 8192000000 10016508064 10416218112 1095134192 11.791 100 34 68 0 1134016512 32188400 6693920
05:14:34 5961 8192000000 10017938016 10420862976 1099606480 11.834 100 42 85 1 1137140576 28899776 8634320
05:15:44 6662 8192000000 10003028960 10409115648 1091384208 11.756 99 16 31 2 1119845328 25198800 3262320
05:16:51 55092 8192000000 9979252928 10386677760 978074864 10.665 90 23 41 0 995736096 12605552 5055680
05:18:48 7130 8192000000 9947264704 10334547968 1031787616 11.186 99 39 77 3 1054743072 14474288 8481168
05:19:56 6196 8192000000 9935675104 10318176256 1009062928 10.966 98 48 97 2 1040686800 20093776 11530096
05:21:06 8529 8192000000 9930537904 10315329536 999330784 10.872 98 31 62 1 1024661632 18402368 6928480
05:22:20 39032 8192000000 9968653088 10366066688 1026987376 11.139 99 34 67 3 1060766160 26894560 6884224
05:23:59 7712 8192000000 9983730304 10365435904 1055703920 11.415 100 49 97 2 1091683760 24743600 11236240
05:25:09 9732 8192000000 9996387520 10394099712 1065544608 11.510 100 35 69 1 1089360032 16306832 7508592
05:26:20 9460 8192000000 9993682512 10391388160 1081454512 11.661 100 16 31 1 1106643264 22105968 3082784
05:27:30 59277 8192000000 10031757904 10430021632 1095766848 11.797 100 44 86 1 1139905856 34397888 9741120
05:29:34 17806 8192000000 10056729600 10457952256 1114672288 11.977 100 54 106 4 1168414432 42322176 11419968
05:31:00 7387 8192000000 10113116288 10508075008 1129562672 12.117 100 73 145 0 1228728752 83768688 15397392
05:32:13 27521 8192000000 10142176864 10546221056 1142231600 12.237 100 56 112 0 1264187392 110190048 11765744
05:33:51 7443 8192000000 10200142176 10604904448 1157759840 12.382 100 53 106 0 1304746416 135478704 11507872
05:35:02 3398 8192000000 10247476080 10651557888 1162054272 12.423 100 71 142 0 1354822368 177800832 14967264
05:36:11 21903 8192000000 10264035232 10687500288 1157362560 12.379 99 65 130 2 1383594336 211467264 14764512
05:37:41 3074 8192000000 10273046960 10676731904 1172570768 12.521 100 77 153 2 1373974768 184663824 16740176
05:38:53 2054 8192000000 10357917472 10754187264 1163787664 12.439 100 83 166 1 1456682368 274576080 18318624
05:40:00 6517 8192000000 10350396784 10751373312 1136660144 12.184 100 66 132 3 1445359728 293519024 15180560
05:41:18 7612 8192000000 10379052832 10806112256 1116989296 11.999 97 64 127 1 1456008960 323042944 15976720
05:42:29 12466 8192000000 10373620480 10789253120 1142017168 12.235 100 52 103 3 1474779808 319250624 13512016
05:43:45 2995 8192000000 10393827312 10807701504 1142087760 12.235 99 72 144 2 1486578976 327000992 17490224
05:44:52 3025 8192000000 10379343648 10794889216 1124467520 12.069 98 74 145 1 1472662944 330587600 17607824
05:46:00 10672 8192000000 10408107216 10819203072 1152328032 12.331 100 58 113 1 1504149664 337488352 14333280
05:47:15 16250 8192000000 10398212672 10813558784 1129867264 12.120 98 41 82 1 1480941184 340437808 10636112
05:48:34 11812 8192000000 10445533904 10879623168 1154503152 12.352 100 37 74 0 1551160928 386845552 9812224
05:49:52 17487 8192000000 10457733520 10891776000 1161811760 12.420 100 39 78 4 1550106912 377629808 10665344
05:51:27 35422 8192000000 10497464304 10960658432 1142087264 12.235 99 31 62 1 1588787888 438391456 8309168
05:53:33 36624 8192000000 10341230000 10835095552 1095584304 11.796 98 24 48 0 1539279296 436720176 6974816
05:55:15 91046 8192000000 10036248192 10454740992 1115935936 11.989 100 32 63 2 1138261024 15479728 6845360
05:57:46 67830 8192000000 10068972128 10513240064 1117112272 12.000 99 24 49 0 1176443168 54104688 5226208
06:00:22 65259 8192000000 10066604400 10537492480 1118003888 12.008 99 9 18 1 1170921984 51086144 1831952
06:02:27 51535 8192000000 10104365856 10536366080 1138575552 12.202 100 24 49 0 1211657088 67842800 5238736
06:04:24 75688 8192000000 10090203536 10528153600 1122915376 12.055 99 16 32 0 1193297104 66941552 3440176
06:07:40 52780 8192000000 10087622928 10531872768 1120114080 12.028 99 10 20 0 1157909760 35816736 1978944
06:09:40 60255 8192000000 10067041696 10520117248 1120325200 12.030 98 20 40 4 1169867200 45170816 4371184
06:11:42 55682 8192000000 10103228992 10584952832 1134213136 12.161 99 38 74 1 1235181152 92625920 8342096
06:13:59 68645 8192000000 10064002432 10505060352 1145589360 12.268 100 10 20 0 1176388400 29004928 1794112
06:16:14 49466 8192000000 10094742048 10513702912 1150553088 12.315 100 21 42 1 1191424960 36596688 4275184
06:18:05 56218 8192000000 10091760528 10545766400 1146258960 12.274 99 16 30 1 1188451632 38865232 3327440
06:20:03 92487 8192000000 10102766656 10568253440 1141789568 12.232 99 23 46 1 1202793040 55448032 5555440
06:22:40 46097 8192000000 10078517776 10525085696 1152365360 12.332 100 10 20 0 1190750000 36454224 1930416
06:25:08 85508 8192000000 10082234160 10541686784 1139465472 12.211 99 6 12 0 1175070880 34070736 1534672
06:27:42 43962 8192000000 10084437200 10509299712 1108566464 11.919 97 24 49 0 1129899776 16505536 4827776
06:29:33 53263 8192000000 10096217936 10525192192 1104871360 11.884 97 19 38 2 1151696176 42681456 4143360
06:31:31 35815 8192000000 10032678096 10444824576 1061651120 11.472 95 29 58 2 1095639392 28154816 5833456
06:33:09 47486 8192000000 10143504112 10585886720 1128175024 12.104 100 27 54 0 1224496240 90730816 5590400
06:34:57 74037 8192000000 10081630048 10568777728 1086250400 11.707 96 15 30 0 1223602112 134009792 3341920
06:37:17 50451 8192000000 10059508208 10496135168 1085501600 11.700 96 11 20 2 1126071552 38482688 2087264
06:39:10 144443 8192000000 10087262224 10551201792 1099368304 11.832 98 19 37 1 1193992624 90148768 4475552
06:42:35 32867 8192000000 10056702096 10577920000 1103292240 11.869 99 3 6 0 1154504400 50820080 392080
06:44:24 48943 8192000000 10062375408 10498535424 1099782512 11.836 99 24 48 2 1148806048 43956848 5066688
06:46:34 52390 8192000000 10065459744 10481127424 1093423392 11.775 97 21 42 1 1126130704 28159728 4547584
06:48:28 33476 8192000000 10112471152 10537160704 1093572128 11.777 98 40 79 0 1162860480 61029984 8258368
06:50:13 72557 8192000000 10080008736 10537250816 1092862784 11.770 98 16 32 0 1159184352 63220736 3100832
06:52:26 27594 8192000000 10071241872 10485112832 1103809232 11.874 99 45 87 5 1143379056 31594384 7975440
06:54:10 50543 8192000000 10078857008 10521927680 1104202352 11.877 98 18 36 2 1141658272 33403792 4052128
06:56:09 123376 8192000000 10036840752 10507071488 1109466080 11.927 99 10 20 1 1157477232 45623328 2387824
06:59:13 92180 8192000000 10081350800 10536882176 1098914352 11.827 97 12 22 0 1147581696 46378816 2288528
07:01:50 83895 8192000000 10072327840 10534899712 1117909120 12.007 99 10 20 2 1162323264 42435904 1978240
07:04:18 43192 8192000000 10095038336 10538123264 1119686016 12.024 99 22 44 0 1159374976 35542848 4146112
07:06:06 37027 8192000000 10108318656 10553925632 1130374208 12.125 99 32 62 3 1190744688 53308512 7061968
07:07:52 63098 8192000000 10079121920 10522906624 1127889520 12.101 99 25 50 0 1177978384 44608176 5480688
07:09:59 56867 8192000000 10086164512 10532102144 1113767616 11.968 98 31 62 1 1171975872 51955072 6253184
07:11:56 56322 8192000000 10092145680 10547523584 1099002464 11.828 97 30 60 1 1158836672 53751488 6082720
07:14:05 67632 8192000000 10080800016 10514743296 1080990992 11.657 95 19 38 1 1117647424 32918032 3738400
07:16:17 129377 8192000000 10060192544 10541858816 1100776016 11.845 98 24 48 1 1165580592 59434544 5370032
07:20:02 70231 8192000000 10096264560 10554499072 1109401248 11.927 98 9 18 1 1168259664 57077648 1780768
07:22:37 46334 8192000000 10115362208 10555150336 1100229232 11.840 97 21 43 1 1187559792 82259984 5070576
07:24:32 55321 8192000000 10076627216 10536706048 1091352000 11.756 97 16 31 0 1145443152 50959520 3131632
07:26:31 30167 8192000000 9024746352 9270104064 178295200 2.130 17 1 2 0 188111920 9746192 70528

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 26-10-2020 07:27:58 to 26-10-2020 07:28:13.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 113 v_add_invoice_to_stock 335544517
user_exc 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 26-10-2020 07:28:14 to 26-10-2020 07:28:16.

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          237458
MON$OLDEST_ACTIVE               237459
MON$OLDEST_SNAPSHOT             237459
MON$NEXT_TRANSACTION            237459
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                       2100544
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             788
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2235 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2235 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2235 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.2235 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 ---
--- end of diff output ---

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

26.10.20 07:28:17. 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-10-26 07:28:19 No severe PSQL-related problems occured
26.10.20 07:28:21. End of report.