Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.11.2020 04:29
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 16820148 kB
MemAvailable 31426416 kB
Buffers 20 kB
Cached 14818116 kB
SwapCached 2924 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-11 04:26:53
DTS_END                         2020-11-11 04:26:53.5110
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/55476
TRN_ID                          239684
ATT_ID                          618
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: 9048.17 19466 1085780
customer order: creation 174.39 431 20927
customer order: refuse 14.38 314 1726
order to supplier: creation 46.86 1368 5623
order to supplier: removal 3.32 124843 398
invoice (draft): creation 46.33 3576 5560
invoice (draft): removal 50.26 1026 6031
invoice accept: apply 42.34 17023 5081
invoice accept: cancel 45.48 843 5458
customer reserve: creation 3876.70 168 465204
customer reserve: removal 4455.76 73 534691
realization accept: apply 83.78 178 10053
realization accept: cancel 76.98 143 9238
payment from customer: creation 61.32 111 7358
payment from customer: removal 6.13 81 735
payment to supplier: creation 49.30 122 5916
payment to supplier: removal 3.45 130 414
service: total inventory turnovers 4.22 2872 507
service: total monetary turnovers 6.23 563 748
service: aggregate perf. data 0.90 14429 108
service: refresh index statistics 0.03 221025 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 11-11-2020 04:29:53 to 11-11-2020 04:29:56.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4200
TEST_TIME 2 3972
TEST_TIME 3 4971
TEST_TIME 4 5621
TEST_TIME 5 4997
TEST_TIME 6 5265
TEST_TIME 7 5255
TEST_TIME 8 5511
TEST_TIME 9 5594
TEST_TIME 10 5562
TEST_TIME 11 5669
TEST_TIME 12 5954
TEST_TIME 13 5865
TEST_TIME 14 5964
TEST_TIME 15 5966
TEST_TIME 16 6253
TEST_TIME 17 6531
TEST_TIME 18 7119
TEST_TIME 19 7177
TEST_TIME 20 7538
TEST_TIME 21 8189
TEST_TIME 22 8809
TEST_TIME 23 9318
TEST_TIME 24 9174
TEST_TIME 25 9594
TEST_TIME 26 11043
TEST_TIME 27 11077
TEST_TIME 28 10923
TEST_TIME 29 10684
TEST_TIME 30 10527
TEST_TIME 31 10298
TEST_TIME 32 10125
TEST_TIME 33 10052
TEST_TIME 34 10094
TEST_TIME 35 9982
TEST_TIME 36 9823
TEST_TIME 37 9918
TEST_TIME 38 9832
TEST_TIME 39 9740
TEST_TIME 40 9896
TEST_TIME 41 9947
TEST_TIME 42 9935
TEST_TIME 43 9868
TEST_TIME 44 9884
TEST_TIME 45 9957
TEST_TIME 46 10037
TEST_TIME 47 9988
TEST_TIME 48 9905
TEST_TIME 49 10072
TEST_TIME 50 10012
TEST_TIME 51 9822
TEST_TIME 52 9670
TEST_TIME 53 9612
TEST_TIME 54 9528
TEST_TIME 55 9558
TEST_TIME 56 9512
TEST_TIME 57 9514
TEST_TIME 58 9471
TEST_TIME 59 9447
TEST_TIME 60 9398
TEST_TIME 61 9402
TEST_TIME 62 9641
TEST_TIME 63 9625
TEST_TIME 64 9658
TEST_TIME 65 9655
TEST_TIME 66 9578
TEST_TIME 67 9594
TEST_TIME 68 9591
TEST_TIME 69 9528
TEST_TIME 70 9414
TEST_TIME 71 9398
TEST_TIME 72 9300
TEST_TIME 73 9232
TEST_TIME 74 9166
TEST_TIME 75 9233
TEST_TIME 76 9185
TEST_TIME 77 9163
TEST_TIME 78 9183
TEST_TIME 79 9201
TEST_TIME 80 9226
TEST_TIME 81 9238
TEST_TIME 82 9292
TEST_TIME 83 9283
TEST_TIME 84 9249
TEST_TIME 85 9265
TEST_TIME 86 9223
TEST_TIME 87 9158
TEST_TIME 88 9097
TEST_TIME 89 9077
TEST_TIME 90 9108
TEST_TIME 91 9095
TEST_TIME 92 9119
TEST_TIME 93 9092
TEST_TIME 94 9065
TEST_TIME 95 9080
TEST_TIME 96 9119
TEST_TIME 97 9134
TEST_TIME 98 9164
TEST_TIME 99 9233
TEST_TIME 100 9234
TEST_TIME 101 9246
TEST_TIME 102 9266
TEST_TIME 103 9232
TEST_TIME 104 9149
TEST_TIME 105 9137
TEST_TIME 106 9091
TEST_TIME 107 9054
TEST_TIME 108 9007
TEST_TIME 109 9069
TEST_TIME 110 9043
TEST_TIME 111 9055
TEST_TIME 112 9025
TEST_TIME 113 9032
TEST_TIME 114 9051
TEST_TIME 115 9047
TEST_TIME 116 9087
TEST_TIME 117 9145
TEST_TIME 118 9157
TEST_TIME 119 9117
TEST_TIME 120 9049

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 11-11-2020 04:29:57 to 11-11-2020 04:30:01.

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 1517435 1517435 0 0.00 0 17372 7 0 0
doc_list_biud 1517432 1517432 0 0.00 0 67516 32 0 0
sp_add_invoice_to_stock 5226 5081 145 2.77 26 263195 17024 0 145
sp_cancel_adding_invoice 5458 5458 0 0.00 5 58723 844 0 0
sp_cancel_client_order 1726 1726 0 0.00 4 12877 314 0 0
sp_cancel_customer_reserve 534691 534691 0 0.00 1 23851 74 0 0
sp_cancel_pay_from_customer 735 735 0 0.00 3 5192 81 0 0
sp_cancel_pay_to_supplier 414 414 0 0.00 3 8026 130 0 0
sp_cancel_supplier_invoice 6040 6031 9 0.14 2 67522 1027 0 9
sp_cancel_supplier_order 398 398 0 0.00 8 1283227 124844 0 0
sp_cancel_write_off 9238 9238 0 0.00 1 13184 144 0 0
sp_client_order 20927 20927 0 0.00 4 19141 431 0 0
sp_customer_reserve 465204 465204 0 0.00 0 186310 168 0 0
sp_fill_shopping_cart 32107 32107 0 0.00 1 20934 44 0 0
sp_fill_shopping_cart_clo_res 465202 465202 0 0.00 0 20852 18 0 0
sp_get_clo_for_invoice 5079 5079 0 0.00 0 41759 447 0 0
sp_kill_cost_storno 15845 15845 0 0.00 0 17357 61 0 0
sp_kill_qstorno_ret_qs2qd 1085049 1085049 0 0.00 0 67514 40 0 0
sp_kill_qty_storno 572673 572673 0 0.00 0 67516 85 0 0
sp_lock_selected_doc 20969 20969 0 0.00 0 9343 9 0 0
sp_make_cost_storno 28406 28406 0 0.00 0 15944 53 0 0
sp_multiply_rows_for_pdistr 28404 28404 0 0.00 0 8474 31 0 0
sp_multiply_rows_for_qdistr 20927 20927 0 0.00 0 19134 245 0 0
sp_pay_from_customer 7358 7358 0 0.00 4 13870 112 0 0
sp_pay_to_supplier 5916 5916 0 0.00 4 20005 122 0 0
sp_payment_common 13273 13273 0 0.00 1 8710 20 0 0
sp_qd_handle_on_cancel_clo 1726 1726 0 0.00 0 12816 281 0 0
sp_qd_handle_on_invoice_upd_sts 10536 10536 0 0.00 0 43980 349 0 0
sp_qd_handle_on_reserve_upd_sts 10053 10053 0 0.00 0 15965 46 0 0
sp_reserve_write_off 10053 10053 0 0.00 6 18315 178 0 0
sp_supplier_invoice 5560 5560 0 0.00 7 108610 3577 0 0
sp_supplier_order 5623 5623 0 0.00 5 25306 1368 0 0
srv_aggregate_perf_data 108 108 0 0.00 2854 209269 14430 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1309 165174 49201 0 0
srv_find_qd_qs_mism 1037844 1037844 0 0.00 0 17290 6 0 0
srv_make_invnt_saldo 507 507 0 0.00 40 106333 2872 0 0
srv_make_money_saldo 748 748 0 0.00 1 15267 564 0 0
srv_recalc_idx_stat 4 4 0 0.00 75720 277507 221026 0 0
t$perf-abend:sp_add_invoice_to_stock 145 145 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5081 5081 0 0.00 0 6945 130 0 0
t$perf-norm:sp_cancel_adding_invoice 400 400 0 0.00 0 3847 77 0 0
t$perf-norm:sp_cancel_client_order 1726 1726 0 0.00 0 2759 11 0 0
t$perf-norm:sp_cancel_customer_reserve 700 700 0 0.00 0 588 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 735 735 0 0.00 0 2408 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 414 414 0 0.00 0 2081 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 397 397 0 0.00 0 1721 21 0 0
t$perf-norm:sp_cancel_supplier_order 398 398 0 0.00 0 17451 1483 0 0
t$perf-norm:sp_cancel_write_off 1068 1068 0 0.00 0 6361 12 0 0
t$perf-norm:sp_client_order 20927 20927 0 0.00 0 5107 13 0 0
t$perf-norm:sp_customer_reserve 993 993 0 0.00 0 1167 8 0 0
t$perf-norm:sp_pay_from_customer 7358 7358 0 0.00 0 4293 8 0 0
t$perf-norm:sp_pay_to_supplier 5916 5916 0 0.00 0 3610 8 0 0
t$perf-norm:sp_reserve_write_off 10053 10053 0 0.00 0 3516 9 0 0
t$perf-norm:sp_supplier_invoice 5560 5560 0 0.00 0 3785 13 0 0
t$perf-norm:sp_supplier_order 5623 5623 0 0.00 0 4933 14 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 244 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 11 1 0 0
t$perf-norm:srv_make_invnt_saldo 507 507 0 0.00 0 339 4 0 0
t$perf-norm:srv_make_money_saldo 748 748 0 0.00 0 358 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 10 4 0 0
v_add_invoice_to_stock 10446 10156 290 2.77 0 10192 18 0 290
v_all_customers 38084 38084 0 0.00 0 65 1 0 0
v_all_suppliers 30868 30868 0 0.00 0 15 1 0 0
v_all_wares 41854 41854 0 0.00 0 42 1 0 0
v_cancel_adding_invoice 800 800 0 0.00 0 2698 10 0 0
v_cancel_client_order 3452 3452 0 0.00 0 3370 6 0 0
v_cancel_customer_prepayment 1470 1470 0 0.00 0 1337 6 0 0
v_cancel_customer_reserve 1400 1400 0 0.00 0 701 4 0 0
v_cancel_payment_to_supplier 828 828 0 0.00 0 1880 10 0 0
v_cancel_supplier_invoice 812 794 18 2.21 0 6502 31 0 18
v_cancel_supplier_order 796 796 0 0.00 0 3492 10 0 0
v_cancel_write_off 2136 2136 0 0.00 0 4401 19 0 0
v_max_id_clo_ord 5622 5622 0 0.00 0 369 1 0 0
v_max_id_clo_res 993 993 0 0.00 0 3035 20 0 0
v_max_id_ord_sup 5558 5558 0 0.00 0 3398 5 0 0
v_max_non_paid_invoice 1662 1662 0 0.00 0 1360 3 0 0
v_max_non_paid_realizn 3918 3918 0 0.00 0 1106 3 0 0
v_min_id_clo_ord 5622 5622 0 0.00 0 6514 7 0 0
v_min_id_clo_res 993 993 0 0.00 0 4170 15 0 0
v_min_id_ord_sup 5558 5558 0 0.00 0 12140 8 0 0
v_min_non_paid_invoice 5916 5916 0 0.00 0 4785 9 0 0
v_min_non_paid_realizn 7357 7357 0 0.00 0 8703 16 0 0
v_our_firm 10648 10648 0 0.00 0 20 1 0 0
v_reserve_write_off 20106 20106 0 0.00 0 3824 7 0 0
x_lock_depdocs_on_canc_invoice 11092 11092 0 0.00 0 11957 106 0 0
x_lock_depdocs_on_canc_sup_ord 398 398 0 0.00 0 14562 324 0 0
x_make_qstorno_1000_1200 5623 5623 0 0.00 2 25297 1294 0 0
x_make_qstorno_1200_2000 5560 5560 0 0.00 4 108598 3402 0 0
x_make_qstorno_2100_3300 465204 465204 0 0.00 0 186309 148 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-11-2020 04:30:02 to 11-11-2020 04:30:06.

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
01:56:57 1 8192000000 8796633152 8807174144 3163616 0.038 1 1 2 0 3261312 61840 35856
01:57:58 29898 8192000000 9731743872 10030325760 806230768 8.959 100 88 174 1 835442528 8157456 21054304
01:59:36 21746 8192000000 9766921440 10075066368 840804768 9.308 100 85 170 0 877664768 16701120 20158880
02:01:05 16443 8192000000 9807690576 10131599360 869754528 9.598 100 77 154 1 912149280 24896416 17498336
02:02:34 11406 8192000000 9890767632 10226323456 932099392 10.215 100 82 163 3 997143008 46835712 18207904
02:03:52 9681 8192000000 9946135104 10293108736 975266304 10.638 100 82 163 1 1053860192 61427696 17166192
02:05:05 4451 8192000000 9976558816 10340982784 1007999152 10.956 100 78 154 1 1102318784 78951904 15367728
02:06:11 2235 8192000000 10027158176 10390814720 1036387344 11.230 100 87 171 2 1143223680 89491696 17344640
02:07:15 1430 8192000000 10035672128 10405265408 1057624000 11.434 100 97 194 1 1149535616 70407568 21504048
02:08:17 5912 8192000000 9989339264 10384461824 1062028848 11.476 100 36 71 1 1107888912 38680848 7179216
02:09:24 3021 8192000000 10001392048 10401370112 1072839568 11.579 100 40 78 1 1120065840 39336784 7889488
02:10:29 5545 8192000000 10003758352 10391777280 1078184592 11.630 100 35 70 1 1118633744 32990112 7459040
02:11:37 64174 8192000000 9988953216 10385367040 1082567792 11.672 100 45 90 0 1105844848 12647952 10629104
02:13:42 3649 8192000000 10004680400 10407292928 1087516208 11.719 100 42 84 0 1128333888 32073984 8743696
02:14:48 5653 8192000000 10012606144 10407534592 1090076000 11.743 100 42 84 4 1121822784 23161984 8584800
02:15:54 4167 8192000000 9993091792 10384490496 1070832576 11.560 100 40 78 3 1097198112 17920096 8445440
02:16:59 27216 8192000000 10000143312 10403540992 1071054416 11.562 100 42 83 0 1102062848 22805872 8202560
02:18:27 6112 8192000000 9940463968 10325815296 1011654304 10.991 97 34 66 1 1035034560 15941744 7438512
02:19:36 7740 8192000000 9915586448 10295824384 969833456 10.585 94 46 92 2 1015292656 35431072 10028128
02:20:49 5163 8192000000 9910848576 10285047808 990515520 10.786 98 36 69 2 1016675024 19687168 6472336
02:21:55 19372 8192000000 9937231024 10330611712 1012796112 11.002 99 31 62 2 1048710592 28456176 7458304
02:23:17 9016 8192000000 9967390448 10353479680 1049006656 11.351 100 47 93 0 1073652608 13665232 10980720
02:24:28 7110 8192000000 9993037552 10379911168 1064289216 11.498 100 42 86 3 1097591696 24200400 9102080
02:25:37 64123 8192000000 9988243904 10393657344 1075943440 11.609 100 28 56 2 1101231824 19121424 6166960
02:27:49 42838 8192000000 10064044368 10459090944 1106188016 11.896 100 44 88 3 1159026592 43690720 9147856
02:29:35 15398 8192000000 10094946960 10493861888 1125260112 12.077 100 60 118 2 1208493616 71041280 12192224
02:30:52 50251 8192000000 10153017920 10563801088 1144830464 12.261 100 36 72 0 1249358800 96488816 8039520
02:32:46 5444 8192000000 10190983424 10593878016 1151227648 12.321 100 61 121 3 1288998288 124966576 12804064
02:33:57 13131 8192000000 10216112192 10625433600 1160048256 12.404 100 54 105 0 1315651888 143685312 11918320
02:35:14 5898 8192000000 10234676736 10643779584 1156684640 12.372 99 53 106 1 1334938656 166870288 11383728
02:36:26 6921 8192000000 10297662640 10705162240 1168818736 12.486 99 63 126 1 1392193040 209064960 14309344
02:37:36 4404 8192000000 10310154992 10718593024 1184598448 12.633 100 57 114 1 1417613024 218991200 14023376
02:38:45 2056 8192000000 10356585872 10773217280 1189666128 12.680 100 75 146 0 1457936608 250504992 17765488
02:39:51 2430 8192000000 10400859136 10805010432 1188927600 12.673 100 73 145 0 1498705168 293000864 16776704
02:40:57 6750 8192000000 10449837248 10857136128 1193784832 12.719 100 74 148 0 1550408752 339017552 17606368
02:42:10 1890 8192000000 10474856864 10879954944 1166579440 12.465 99 79 158 2 1573345632 386399584 20366608
02:43:17 2973 8192000000 10480313456 10887094272 1155254064 12.359 99 87 175 4 1580324560 403613600 21456896
02:44:25 6185 8192000000 10442829696 10850693120 1163134208 12.433 100 50 96 1 1550021904 374726592 12161104
02:45:35 6295 8192000000 10486946576 10898702336 1152941440 12.337 100 58 114 2 1585727424 417715152 15070832
02:46:45 1308 8192000000 10495892960 10897010688 1155621104 12.362 100 88 172 2 1592819248 416958448 20239696
02:47:50 4754 8192000000 10445310192 10864422912 1154043536 12.347 100 56 114 1 1549148704 380702864 14402304
02:48:57 21163 8192000000 10360639072 10834616320 1138045664 12.197 99 30 59 2 1488280784 342323360 7911760
02:50:22 32035 8192000000 10368023248 10815197184 1117261008 12.001 99 19 38 0 1467844976 344965328 5618640
02:52:09 55752 8192000000 10061365744 10566307840 1092715376 11.768 98 13 26 1 1164526640 68646928 3164336
02:54:23 21759 8192000000 10077890160 10520006656 1111947760 11.951 100 21 42 2 1154738416 37959328 4831328
02:55:50 130484 8192000000 10042520112 10477338624 1075978304 11.609 96 18 36 0 1127397360 47550032 3869024
02:59:08 50086 8192000000 10097644320 10538057728 1118873408 12.016 100 39 78 1 1198906896 71918496 8114992
03:00:58 165159 8192000000 10087055888 10572132352 1095469840 11.795 97 21 43 1 1158107744 57663504 4974400
03:04:48 73751 8192000000 10158535392 10602409984 1144209088 12.255 100 30 60 1 1233149168 82679424 6260656
03:07:04 49357 8192000000 10107458672 10560118784 1138959856 12.206 100 13 26 0 1228372816 86674240 2738720
03:09:12 34647 8192000000 10103497328 10543898624 1146179920 12.274 100 33 66 0 1205966992 52885792 6901280
03:10:59 113611 8192000000 10113481968 10592194560 1150076000 12.310 100 9 18 0 1200962592 49204608 1681984
03:14:20 52025 8192000000 10161367216 10584051712 1147050512 12.282 99 30 59 1 1219133744 65766896 6316336
03:16:13 123669 8192000000 10103000336 10565693440 1111294016 11.945 96 11 22 0 1168332704 54876784 2161904
03:19:35 59166 8192000000 10087433632 10571247616 1130738736 12.128 99 10 19 1 1189770336 56435920 2595680
03:22:02 43147 8192000000 10063874992 10486902784 1116058768 11.990 98 32 68 1 1143608592 20886224 6663600
03:23:52 52890 8192000000 10077796256 10521096192 1106496928 11.899 97 17 34 2 1163893968 53807616 3589424
03:25:52 38383 8192000000 10126539808 10557652992 1121543648 12.042 98 30 60 0 1198609312 70912512 6153152
03:27:31 86844 8192000000 10115559648 10658250752 1125245888 12.077 99 20 40 2 1266456240 136786032 4424320
03:30:15 146618 8192000000 10048768528 10493124608 983602848 10.719 87 8 16 0 1019261808 34034432 1624528
03:33:51 55776 8192000000 10082513632 10502836224 1094789120 11.788 98 33 66 2 1158164528 55981648 7393760
03:35:48 117922 8192000000 10003721504 10463621120 1057278176 11.430 96 22 44 0 1124659696 62780448 4601072
03:38:48 35412 8192000000 10018783312 10468880384 1095515120 11.795 100 5 10 0 1135430592 39045952 869520
03:40:23 64741 8192000000 10080804720 10528985088 1085491344 11.700 98 8 16 0 1142575168 55369376 1714448
03:42:28 31303 8192000000 10067384352 10500542464 1088053312 11.724 98 27 54 0 1132541072 38852848 5634912
03:44:11 53399 8192000000 10101468368 10531991552 1116058032 11.990 99 26 52 1 1180498864 58846880 5593952
03:46:31 26574 8192000000 10062532368 10484338688 1100920720 11.846 98 24 48 1 1137137584 31003136 5213728
03:48:05 58406 8192000000 10052763328 10512650240 1091050496 11.753 98 10 20 1 1148392080 55416848 1924736
03:50:04 22065 8192000000 10079640688 10492743680 1127909520 12.102 100 62 122 1 1164940384 24049648 12981216
03:51:28 24140 8192000000 10109024192 10558734336 1122933008 12.055 99 36 72 3 1217208256 87120112 7155136
03:52:52 63135 8192000000 10022911280 10464210944 1091647648 11.758 97 23 46 1 1124512304 27887936 4976720
03:55:00 60425 8192000000 10085023920 10553683968 1106569728 11.900 99 7 14 1 1146974832 38892352 1512752
03:57:09 82206 8192000000 10110978224 10564247552 1140315408 12.218 100 9 18 0 1198452368 56383488 1753472
03:59:32 64000 8192000000 10110469760 10557689856 1138349904 12.200 99 24 45 2 1189769872 46423968 4996000
04:01:36 49825 8192000000 10131937104 10578202624 1154592512 12.353 100 22 44 1 1227584480 67835008 5156960
04:03:26 82510 8192000000 10136623136 10625622016 1116192624 11.991 97 14 28 0 1213800464 94776720 2831120
04:05:49 64184 8192000000 10075413232 10531500032 1110574688 11.938 97 32 64 0 1155105248 38058960 6471600
04:07:54 36463 8192000000 10112128288 10556575744 1144878144 12.261 100 33 67 3 1182851616 31362320 6611152
04:09:33 115748 8192000000 10083469712 10553257984 1136920608 12.187 99 30 60 0 1187481984 44392448 6168928
04:12:40 30642 8192000000 10074638848 10543071232 1136428848 12.182 99 5 10 0 1174485504 37017120 1039536
04:14:57 80346 8192000000 10071988432 10566053888 1097032480 11.809 97 18 36 0 1149509904 48323408 4154016
04:17:28 30774 8192000000 10047587664 10499534848 1091327232 11.755 98 12 24 1 1130615344 36572272 2715840
04:19:02 51363 8192000000 10119071232 10562760704 1081873600 11.665 96 29 58 3 1165906608 77294880 6738128
04:21:05 64188 8192000000 10127154032 10566107136 1091344848 11.755 98 19 38 1 1170652560 75284704 4023008
04:23:10 44184 8192000000 10085576752 10551943168 1093429088 11.775 98 31 60 2 1189375920 90024032 5922800
04:24:56 111203 8192000000 10036606336 10471886848 1045210944 11.315 94 13 26 0 1074425568 26628928 2585696

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 11-11-2020 04:30:08 to 11-11-2020 04:30:22.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-11-2020 04:30:23 to 11-11-2020 04:30:25.

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          239325
MON$OLDEST_ACTIVE               239970
MON$OLDEST_SNAPSHOT             239970
MON$NEXT_TRANSACTION            239970
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                       2092352
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             811
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2249 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2249 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2249 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.2249 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'

11.11.20 04:30:26. 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-11 04:30:29 No severe PSQL-related problems occured
11.11.20 04:30:30. End of report.