Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.10.2020 19:28
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 17420412 kB
MemAvailable 31558668 kB
Buffers 0 kB
Cached 14310460 kB
SwapCached 3064 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 3.0.7
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-28 19:26:57
DTS_END                         2020-10-28 19:26:57.4480
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(56:17)
IP                              ::1/40450
TRN_ID                          240821
ATT_ID                          681
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: 9468.09 21824 1136171
customer order: creation 179.53 329 21543
customer order: refuse 14.81 364 1777
order to supplier: creation 48.03 1251 5763
order to supplier: removal 3.48 88684 418
invoice (draft): creation 48.51 3243 5821
invoice (draft): removal 53.83 717 6460
invoice accept: apply 43.40 17231 5208
invoice accept: cancel 48.39 760 5807
customer reserve: creation 3984.64 171 478157
customer reserve: removal 4736.06 51 568327
realization accept: apply 86.86 177 10423
realization accept: cancel 84.37 107 10124
payment from customer: creation 62.52 132 7502
payment from customer: removal 6.03 92 723
payment to supplier: creation 51.88 151 6226
payment to supplier: removal 3.60 60 432
service: total inventory turnovers 4.75 1159 570
service: total monetary turnovers 6.57 26 788
service: aggregate perf. data 0.82 21398 98
service: refresh index statistics 0.03 300368 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-10-2020 19:28:53 to 28-10-2020 19:28:55.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3880
TEST_TIME 2 3934
TEST_TIME 3 4332
TEST_TIME 4 4327
TEST_TIME 5 4467
TEST_TIME 6 4669
TEST_TIME 7 4459
TEST_TIME 8 4701
TEST_TIME 9 4714
TEST_TIME 10 5155
TEST_TIME 11 5359
TEST_TIME 12 5780
TEST_TIME 13 6290
TEST_TIME 14 6660
TEST_TIME 15 7918
TEST_TIME 16 7693
TEST_TIME 17 8174
TEST_TIME 18 8801
TEST_TIME 19 8916
TEST_TIME 20 8992
TEST_TIME 21 9478
TEST_TIME 22 9539
TEST_TIME 23 9362
TEST_TIME 24 9444
TEST_TIME 25 9765
TEST_TIME 26 9833
TEST_TIME 27 10100
TEST_TIME 28 10623
TEST_TIME 29 11123
TEST_TIME 30 11125
TEST_TIME 31 11056
TEST_TIME 32 10918
TEST_TIME 33 10796
TEST_TIME 34 10687
TEST_TIME 35 10886
TEST_TIME 36 10667
TEST_TIME 37 10549
TEST_TIME 38 10625
TEST_TIME 39 10553
TEST_TIME 40 10380
TEST_TIME 41 10371
TEST_TIME 42 10341
TEST_TIME 43 10247
TEST_TIME 44 10142
TEST_TIME 45 10041
TEST_TIME 46 10010
TEST_TIME 47 10019
TEST_TIME 48 10001
TEST_TIME 49 10031
TEST_TIME 50 9943
TEST_TIME 51 9918
TEST_TIME 52 9843
TEST_TIME 53 9760
TEST_TIME 54 9622
TEST_TIME 55 9654
TEST_TIME 56 9576
TEST_TIME 57 9654
TEST_TIME 58 9621
TEST_TIME 59 9619
TEST_TIME 60 9639
TEST_TIME 61 9693
TEST_TIME 62 9714
TEST_TIME 63 9682
TEST_TIME 64 9702
TEST_TIME 65 9832
TEST_TIME 66 9876
TEST_TIME 67 9869
TEST_TIME 68 9922
TEST_TIME 69 9878
TEST_TIME 70 10030
TEST_TIME 71 9938
TEST_TIME 72 9868
TEST_TIME 73 9815
TEST_TIME 74 9774
TEST_TIME 75 9752
TEST_TIME 76 9707
TEST_TIME 77 9659
TEST_TIME 78 9643
TEST_TIME 79 9604
TEST_TIME 80 9518
TEST_TIME 81 9692
TEST_TIME 82 9639
TEST_TIME 83 9610
TEST_TIME 84 9724
TEST_TIME 85 9670
TEST_TIME 86 9693
TEST_TIME 87 9747
TEST_TIME 88 9693
TEST_TIME 89 9697
TEST_TIME 90 9776
TEST_TIME 91 9817
TEST_TIME 92 9804
TEST_TIME 93 9755
TEST_TIME 94 9682
TEST_TIME 95 9675
TEST_TIME 96 9633
TEST_TIME 97 9563
TEST_TIME 98 9563
TEST_TIME 99 9573
TEST_TIME 100 9508
TEST_TIME 101 9530
TEST_TIME 102 9496
TEST_TIME 103 9500
TEST_TIME 104 9489
TEST_TIME 105 9471
TEST_TIME 106 9498
TEST_TIME 107 9494
TEST_TIME 108 9507
TEST_TIME 109 9528
TEST_TIME 110 9588
TEST_TIME 111 9574
TEST_TIME 112 9572
TEST_TIME 113 9551
TEST_TIME 114 9634
TEST_TIME 115 9647
TEST_TIME 116 9569
TEST_TIME 117 9534
TEST_TIME 118 9495
TEST_TIME 119 9518
TEST_TIME 120 9478

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 28-10-2020 19:28:56 to 28-10-2020 19:29:00.

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 1577977 1577977 0 0.00 0 20228 6 0 0
doc_list_biud 1577973 1577973 0 0.00 0 27520 24 0 0
sp_add_invoice_to_stock 5344 5208 136 2.54 32 150792 17232 0 136
sp_cancel_adding_invoice 5807 5807 0 0.00 5 64899 760 0 0
sp_cancel_client_order 1777 1777 0 0.00 4 11178 364 0 0
sp_cancel_customer_reserve 568327 568327 0 0.00 1 27522 51 0 0
sp_cancel_pay_from_customer 723 723 0 0.00 2 13656 92 0 0
sp_cancel_pay_to_supplier 432 432 0 0.00 3 4378 60 0 0
sp_cancel_supplier_invoice 6469 6460 9 0.13 1 21597 718 0 9
sp_cancel_supplier_order 418 418 0 0.00 6 981427 88684 0 0
sp_cancel_write_off 10124 10124 0 0.00 1 24029 107 0 0
sp_client_order 21543 21543 0 0.00 3 17442 330 0 0
sp_customer_reserve 478157 478157 0 0.00 0 34316 171 0 0
sp_fill_shopping_cart 33114 33114 0 0.00 1 20017 38 0 0
sp_fill_shopping_cart_clo_res 478143 478143 0 0.00 0 14483 14 0 0
sp_get_clo_for_invoice 5191 5191 0 0.00 0 27053 333 0 0
sp_kill_cost_storno 17086 17086 0 0.00 0 18632 52 0 0
sp_kill_qstorno_ret_qs2qd 1153656 1153656 0 0.00 0 27516 28 0 0
sp_kill_qty_storno 608526 608526 0 0.00 0 27520 60 0 0
sp_lock_selected_doc 21608 21608 0 0.00 0 10807 6 0 0
sp_make_cost_storno 29341 29341 0 0.00 0 19272 62 0 0
sp_multiply_rows_for_pdistr 29341 29341 0 0.00 0 15148 31 0 0
sp_multiply_rows_for_qdistr 21543 21543 0 0.00 0 16292 155 0 0
sp_pay_from_customer 7502 7502 0 0.00 4 19480 132 0 0
sp_pay_to_supplier 6226 6226 0 0.00 4 20394 151 0 0
sp_payment_common 13728 13728 0 0.00 1 18477 35 0 0
sp_qd_handle_on_cancel_clo 1777 1777 0 0.00 0 11171 325 0 0
sp_qd_handle_on_invoice_upd_sts 10997 10997 0 0.00 0 27180 283 0 0
sp_qd_handle_on_reserve_upd_sts 10423 10423 0 0.00 0 15272 56 0 0
sp_reserve_write_off 10423 10423 0 0.00 7 18509 177 0 0
sp_supplier_invoice 5821 5821 0 0.00 6 101440 3243 0 0
sp_supplier_order 5763 5763 0 0.00 6 57214 1252 0 0
srv_aggregate_perf_data 98 98 0 0.00 2781 146536 21399 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1087 128735 46802 0 0
srv_find_qd_qs_mism 1085821 1085821 0 0.00 0 16880 5 0 0
srv_make_invnt_saldo 570 570 0 0.00 30 58673 1160 0 0
srv_make_money_saldo 788 788 0 0.00 0 2724 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 216062 401173 300369 0 0
t$perf-abend:sp_add_invoice_to_stock 136 136 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 5209 5209 0 0.00 0 24346 158 0 0
t$perf-norm:sp_cancel_adding_invoice 467 467 0 0.00 0 6499 88 0 0
t$perf-norm:sp_cancel_client_order 1777 1777 0 0.00 0 5866 16 0 0
t$perf-norm:sp_cancel_customer_reserve 717 717 0 0.00 0 9351 34 0 0
t$perf-norm:sp_cancel_pay_from_customer 723 723 0 0.00 0 287 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 432 432 0 0.00 0 332 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 456 456 0 0.00 0 3546 19 0 0
t$perf-norm:sp_cancel_supplier_order 418 418 0 0.00 0 20240 1628 0 0
t$perf-norm:sp_cancel_write_off 1005 1005 0 0.00 0 2300 5 0 0
t$perf-norm:sp_client_order 21543 21543 0 0.00 0 7472 17 0 0
t$perf-norm:sp_customer_reserve 1020 1020 0 0.00 0 1741 4 0 0
t$perf-norm:sp_pay_from_customer 7502 7502 0 0.00 0 4585 10 0 0
t$perf-norm:sp_pay_to_supplier 6226 6226 0 0.00 0 5669 9 0 0
t$perf-norm:sp_reserve_write_off 10423 10423 0 0.00 0 9761 13 0 0
t$perf-norm:sp_supplier_invoice 5821 5821 0 0.00 0 9311 19 0 0
t$perf-norm:sp_supplier_order 5763 5763 0 0.00 0 5597 14 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 658 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 473 18 0 0
t$perf-norm:srv_make_invnt_saldo 570 570 0 0.00 0 2029 23 0 0
t$perf-norm:srv_make_money_saldo 788 788 0 0.00 0 677 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 8 5 0 0
v_add_invoice_to_stock 10652 10380 272 2.55 0 4784 19 0 272
v_all_customers 39494 39494 0 0.00 0 25 1 0 0
v_all_suppliers 32038 32038 0 0.00 0 17 1 0 0
v_all_wares 43082 43082 0 0.00 0 94 1 0 0
v_cancel_adding_invoice 934 934 0 0.00 0 3079 10 0 0
v_cancel_client_order 3554 3554 0 0.00 0 4503 9 0 0
v_cancel_customer_prepayment 1446 1446 0 0.00 0 1849 6 0 0
v_cancel_customer_reserve 1434 1434 0 0.00 0 2938 13 0 0
v_cancel_payment_to_supplier 864 864 0 0.00 0 154 3 0 0
v_cancel_supplier_invoice 930 912 18 1.93 0 1839 18 0 18
v_cancel_supplier_order 836 836 0 0.00 0 4197 11 0 0
v_cancel_write_off 2010 2010 0 0.00 0 6637 25 0 0
v_max_id_clo_ord 5757 5757 0 0.00 0 1369 2 0 0
v_max_id_clo_res 1020 1020 0 0.00 0 4722 19 0 0
v_max_id_ord_sup 5816 5816 0 0.00 0 3649 3 0 0
v_max_non_paid_invoice 1780 1780 0 0.00 0 904 3 0 0
v_max_non_paid_realizn 3938 3938 0 0.00 0 1007 3 0 0
v_min_id_clo_ord 5757 5757 0 0.00 0 4230 8 0 0
v_min_id_clo_res 1020 1020 0 0.00 0 6923 25 0 0
v_min_id_ord_sup 5816 5816 0 0.00 0 4165 5 0 0
v_min_non_paid_invoice 6226 6226 0 0.00 0 8749 24 0 0
v_min_non_paid_realizn 7502 7502 0 0.00 0 9231 23 0 0
v_our_firm 10716 10716 0 0.00 0 24 1 0 0
v_reserve_write_off 20846 20846 0 0.00 0 4861 5 0 0
x_lock_depdocs_on_canc_invoice 11811 11811 0 0.00 0 28997 112 0 0
x_lock_depdocs_on_canc_sup_ord 418 418 0 0.00 0 31016 491 0 0
x_make_qstorno_1000_1200 5763 5763 0 0.00 2 57173 1168 0 0
x_make_qstorno_1200_2000 5821 5821 0 0.00 3 101409 3113 0 0
x_make_qstorno_2100_3300 478157 478157 0 0.00 0 34314 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-10-2020 19:29:01 to 28-10-2020 19:29:05.

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
16:57:01 2 8192000000 8798422528 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
16:58:02 21063 8192000000 10348737472 10670600192 1404575344 14.636 100 86 173 0 1439036672 5867136 28594192
16:59:33 18406 8192000000 10426708112 10758709248 1479914144 15.301 100 84 168 0 1514508208 7606880 26987184
17:00:54 19119 8192000000 10484139776 10824802304 1529624976 15.734 100 81 163 0 1563269104 8430176 25213952
17:02:17 9964 8192000000 10533567120 10894827520 1602117184 16.357 100 69 136 1 1632144912 9417280 20610448
17:03:31 10248 8192000000 10608903120 10982842368 1679104656 17.010 100 72 143 1 1710538512 10270720 21163136
17:04:45 6572 8192000000 10705834048 11084447744 1764119024 17.718 100 79 158 0 1799165712 13218720 21827968
17:05:54 5189 8192000000 10746932944 11131842560 1807360976 18.074 100 77 152 2 1841558032 12934144 21262912
17:07:01 3195 8192000000 10795226240 11184984064 1849816288 18.421 100 81 162 0 1887124736 14014928 23293520
17:08:07 1848 8192000000 10815458384 11208663040 1876340272 18.636 100 87 172 1 1912711408 12673904 23697232
17:09:11 1766 8192000000 10827309424 11227848704 1890485088 18.750 100 78 158 1 1924959104 12538528 21935488
17:10:14 1461 8192000000 10833857920 11238625280 1898010944 18.810 100 85 170 1 1933774912 11070560 24693408
17:11:16 7936 8192000000 10801605120 11231424512 1893413040 18.773 100 20 38 1 1904769744 6254208 5102496
17:12:25 55746 8192000000 10851636464 11252113408 1903077728 18.851 100 66 132 2 1928258624 5882368 19298528
17:14:20 7226 8192000000 10820056816 11252228096 1901486352 18.838 100 25 50 0 1914957472 6571056 6900064
17:15:31 53279 8192000000 10821008624 11261550592 1917098320 18.964 100 41 80 1 1934903056 5814224 11990512
17:17:27 6167 8192000000 10825675216 11253141504 1886515344 18.718 99 30 59 3 1900727568 5589968 8622256
17:18:34 4597 8192000000 10750484656 11181228032 1832054000 18.276 99 23 46 1 1850250032 11470672 6725360
17:19:39 22390 8192000000 10718215216 11149103104 1814501296 18.133 99 18 36 0 1825730112 5790064 5438752
17:21:32 6957 8192000000 10733836240 11159113728 1825148432 18.220 100 30 61 1 1841546016 7539776 8857808
17:22:40 15975 8192000000 10714709616 11124416512 1749655424 17.599 97 20 40 1 1760784000 5004080 6124496
17:23:59 7022 8192000000 10746137184 11169673216 1833378768 18.287 100 20 40 2 1849323216 10220256 5724192
17:25:10 46614 8192000000 10763863296 11188760576 1852503200 18.442 100 35 70 1 1868410560 6007872 9899488
17:26:59 5216 8192000000 10799592288 11212636160 1868479504 18.572 100 34 67 1 1883886128 5919728 9486896
17:28:06 29596 8192000000 10844400416 11263541248 1905279152 18.869 100 55 109 2 1932455936 11854784 15322000
17:29:43 29584 8192000000 10861340688 11292024832 1928931072 19.058 100 39 76 2 1954450176 14592368 10926736
17:31:21 12070 8192000000 10886324720 11315187712 1949270592 19.221 100 44 89 3 1982851488 20015712 13565184
17:32:38 35941 8192000000 10899370896 11340460032 1958316288 19.293 100 34 68 3 1991820448 22732160 10772000
17:34:31 22419 8192000000 10913344064 11352494080 1958418048 19.293 99 39 78 2 1997354560 27492560 11443952
17:36:05 3108 8192000000 10952727968 11379785728 1993548080 19.572 100 67 134 2 2046201616 32627248 20026288
17:37:19 5261 8192000000 10964452208 11394146304 2004268144 19.656 100 63 121 3 2053749200 31834832 17646224
17:38:29 3231 8192000000 10978273968 11398860800 2004666704 19.660 100 74 148 1 2062568256 35733424 22168128
17:39:36 2309 8192000000 10982184064 11404034048 2009643232 19.699 100 81 159 2 2072522800 38418832 24460736
17:40:43 2024 8192000000 10953004672 11363602432 1970916176 19.393 99 76 152 2 2034906592 41592416 22398000
17:41:47 12155 8192000000 10914263696 11354394624 1934181968 19.100 98 38 74 1 1980288048 34239744 11866336
17:43:07 3552 8192000000 10969613808 11386826752 1982046096 19.481 100 67 134 1 2045437136 42187552 21203488
17:44:13 1084 8192000000 10958480128 11368787968 1971171664 19.395 100 76 148 1 2035469392 40402032 23895696
17:45:16 15913 8192000000 10881089680 11313807360 1908164112 18.892 98 37 72 5 1954350224 33793184 12392928
17:46:37 8462 8192000000 10899112496 11342553088 1934198304 19.100 99 39 78 2 1979119920 32047776 12873840
17:47:50 19467 8192000000 10936370496 11370594304 1955921952 19.274 99 54 105 2 2001930960 29982528 16026480
17:49:12 12898 8192000000 10884288624 11328864256 1926775296 19.041 98 34 68 1 1968373824 31114000 10484528
17:50:30 17799 8192000000 10906896192 11346309120 1942170512 19.164 100 26 52 1 1986448080 35372640 8904928
17:51:59 16425 8192000000 10897322736 11352854528 1943855264 19.178 100 19 38 2 1986607232 36084160 6667808
17:53:18 35722 8192000000 10890236592 11344576512 1899152880 18.819 98 22 44 1 1949906832 43090816 7663136
17:54:55 20897 8192000000 10886543824 11344404480 1910101776 18.907 99 15 30 1 1951635344 36371584 5161984
17:56:17 105792 8192000000 10875201616 11327541248 1898852608 18.817 98 27 54 1 1926977088 20413408 7711072
17:59:04 46910 8192000000 10893838800 11344064512 1929650400 19.064 99 17 34 0 1947487056 13424272 4412384
18:01:05 86177 8192000000 10904079744 11382210560 1946625936 19.200 99 17 34 1 1976471872 25806896 4039040
18:03:35 14560 8192000000 10911482224 11373576192 1968650176 19.375 100 19 39 0 1990270320 16112256 5507888
18:04:51 67908 8192000000 10903345760 11361755136 1958468576 19.294 99 17 34 1 1975127920 11494848 5164496
18:07:10 34798 8192000000 10890135440 11367129088 1943860592 19.178 98 8 16 0 1955956432 9872960 2222880
18:08:54 31785 8192000000 10900996304 11374743552 1974878800 19.424 100 15 30 1 1989871712 10195648 4797264
18:10:27 62724 8192000000 10921562256 11383287808 1969088160 19.378 99 14 28 1 1985692560 12560352 4044048
18:12:30 103337 8192000000 10919768368 11389939712 1988697936 19.534 100 24 48 1 2019129296 22950272 7481088
18:15:31 59517 8192000000 10908692096 11357356032 1905693104 18.872 96 32 62 1 1921196336 6587456 8915776
18:17:32 62049 8192000000 10878954992 11357585408 1913254560 18.933 97 10 20 1 1927036032 11017472 2764000
18:19:34 65389 8192000000 10865449984 11346964480 1950243152 19.228 99 4 8 2 1957902048 6815008 843888
18:21:56 83204 8192000000 10874406624 11350732800 1843081648 18.366 93 12 24 2 1853690336 7409568 3199120
18:24:19 55477 8192000000 10880277712 11354107904 1926027536 19.035 98 20 40 1 1941619104 9859168 5732400
18:26:19 29447 8192000000 10868013104 11327082496 1910577664 18.911 98 26 51 1 1927585312 9833344 7174304
18:27:50 51508 8192000000 10864858896 11321704448 1863237792 18.530 95 22 44 0 1878111440 9092096 5781552
18:29:42 30154 8192000000 10900669248 11361271808 1945692240 19.192 99 33 66 1 1966985648 12360304 8933104
18:31:13 52561 8192000000 10882062080 11341352960 1884146144 18.699 96 22 44 0 1917125920 26841552 6138224
18:33:07 31451 8192000000 10858662944 11308322816 1861207680 18.513 96 22 44 1 1878240160 10229008 6803472
18:34:40 45498 8192000000 10898052896 11345850368 1926939056 19.042 99 40 78 2 1951255888 12742400 11574432
18:36:26 109830 8192000000 10859555760 11333459968 1902578400 18.847 98 13 25 1 1920954768 14738848 3637520
18:39:16 58463 8192000000 10860345488 11323641856 1866392368 18.555 96 23 46 2 1881897168 9040816 6463984
18:41:18 70947 8192000000 10850081792 11313664000 1860892720 18.511 96 23 45 2 1879724480 12319680 6512080
18:43:31 62412 8192000000 10854525168 11322277888 1914250576 18.941 99 17 34 0 1934802272 15355680 5196016
18:45:34 84044 8192000000 10874742320 11339694080 1918996480 18.979 99 12 24 0 1941210416 18581760 3632176
18:48:02 53829 8192000000 10880953136 11339706368 1931140896 19.076 99 22 44 2 1949925520 12152704 6631920
18:49:56 56367 8192000000 10875821728 11352645632 1948867472 19.217 100 34 68 2 1976238208 17298960 10071776
18:51:56 61766 8192000000 10891897024 11365068800 1936482016 19.119 98 27 54 2 1958083408 13984640 7616752
18:54:04 26794 8192000000 10920982544 11364605952 1962799376 19.328 100 38 76 2 1987862128 13751728 11311024
18:55:34 96479 8192000000 10917582208 11376128000 1980022368 19.465 100 21 42 0 2002261840 16029744 6209728
18:58:16 55127 8192000000 10899644320 11366105088 1908856176 18.897 96 17 34 2 1920241200 6999776 4385248
19:00:24 68163 8192000000 10895507904 11364134912 1962050864 19.322 99 25 50 0 1976954944 8110656 6793424
19:03:07 66328 8192000000 10894621440 11373154304 1963732784 19.336 99 10 20 0 1977576848 10772160 3071904
19:05:26 77864 8192000000 10899778720 11373219840 1960916096 19.313 99 22 44 2 1976507856 9344480 6247280
19:07:44 73529 8192000000 10895375280 11374342144 1954435072 19.262 99 11 22 1 1966600112 9169168 2995872
19:10:08 39201 8192000000 10897647584 11370053632 1969298304 19.380 100 9 18 1 1978611664 6971360 2342000
19:11:48 94034 8192000000 10887413248 11362545664 1897205184 18.804 96 16 32 1 1912485024 10927552 4352288
19:14:22 128735 8192000000 10867945648 11339059200 1826622032 18.232 93 23 46 0 1851464384 18661984 6180368
19:18:52 63119 8192000000 10894283616 11348226048 1935692128 19.112 100 15 30 1 1956680720 16771360 4217232
19:20:56 64525 8192000000 10881617824 11367030784 1932300608 19.085 99 5 10 0 1956831424 23485088 1045728
19:23:01 82124 8192000000 10856538304 11321335808 1859781872 18.502 95 16 32 0 1873954432 9743904 4428656
19:25:25 66231 8192000000 10851476896 11334418432 1893213664 18.772 97 10 20 0 1904544928 8640400 2690864

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 28-10-2020 19:29:07 to 28-10-2020 19:29:21.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 136 v_add_invoice_to_stock 335544517
user_exc 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-10-2020 19:29:22 to 28-10-2020 19:29:24.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          242432
MON$OLDEST_ACTIVE               242433
MON$OLDEST_SNAPSHOT             242433
MON$NEXT_TRANSACTION            242433
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-03 16:41:55.3470
MON$PAGES                       2037856
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.7.33380 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.7.33380 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.7.33380 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.7.33380 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:230: 
:231: mscs-test-161.budzdorov.ru    Wed Oct 28 19:27:24 2020
:232:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:233: 
--- end of diff output ---

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

28.10.20 19:29:25. There are 3 logs in /var/tmp/logs-oltp30 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-28 19:29:28 No severe PSQL-related problems occured
28.10.20 19:29:29. End of report.