Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.10.2020 16: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 17349752 kB
MemAvailable 31517528 kB
Buffers 0 kB
Cached 14321520 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 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-25 16:27:02
DTS_END                         2020-10-25 16:27:02.0840
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> X_MAKE_QSTORNO_1000_1200(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(56:17)
IP                              ::1/54200
TRN_ID                          249409
ATT_ID                          714
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: 9966.87 20340 1196024
customer order: creation 180.34 347 21641
customer order: refuse 15.41 266 1849
order to supplier: creation 47.60 1103 5712
order to supplier: removal 3.73 85720 448
invoice (draft): creation 48.43 2737 5812
invoice (draft): removal 58.33 581 6999
invoice accept: apply 42.83 16407 5139
invoice accept: cancel 53.08 593 6369
customer reserve: creation 3984.72 162 478166
customer reserve: removal 5221.51 49 626581
realization accept: apply 86.94 146 10433
realization accept: cancel 91.54 99 10985
payment from customer: creation 60.33 88 7239
payment from customer: removal 5.72 45 686
payment to supplier: creation 51.08 110 6129
payment to supplier: removal 3.65 53 438
service: total inventory turnovers 4.52 1217 542
service: total monetary turnovers 6.23 23 748
service: aggregate perf. data 0.87 22170 104
service: refresh index statistics 0.03 274879 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 25-10-2020 16:27:49 to 25-10-2020 16:27:51.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3001
TEST_TIME 2 3946
TEST_TIME 3 5964
TEST_TIME 4 5815
TEST_TIME 5 5980
TEST_TIME 6 6538
TEST_TIME 7 6710
TEST_TIME 8 6699
TEST_TIME 9 7016
TEST_TIME 10 7062
TEST_TIME 11 6876
TEST_TIME 12 7589
TEST_TIME 13 7764
TEST_TIME 14 8365
TEST_TIME 15 8988
TEST_TIME 16 9100
TEST_TIME 17 9604
TEST_TIME 18 10895
TEST_TIME 19 11303
TEST_TIME 20 12318
TEST_TIME 21 12790
TEST_TIME 22 12403
TEST_TIME 23 12092
TEST_TIME 24 11815
TEST_TIME 25 12012
TEST_TIME 26 11999
TEST_TIME 27 11781
TEST_TIME 28 11701
TEST_TIME 29 11570
TEST_TIME 30 11343
TEST_TIME 31 11481
TEST_TIME 32 11479
TEST_TIME 33 11238
TEST_TIME 34 11097
TEST_TIME 35 10834
TEST_TIME 36 10903
TEST_TIME 37 10993
TEST_TIME 38 10811
TEST_TIME 39 10667
TEST_TIME 40 10653
TEST_TIME 41 10742
TEST_TIME 42 10796
TEST_TIME 43 10689
TEST_TIME 44 10708
TEST_TIME 45 10923
TEST_TIME 46 10890
TEST_TIME 47 10856
TEST_TIME 48 10857
TEST_TIME 49 10907
TEST_TIME 50 10905
TEST_TIME 51 10854
TEST_TIME 52 10654
TEST_TIME 53 10663
TEST_TIME 54 10560
TEST_TIME 55 10610
TEST_TIME 56 10486
TEST_TIME 57 10393
TEST_TIME 58 10334
TEST_TIME 59 10229
TEST_TIME 60 10293
TEST_TIME 61 10241
TEST_TIME 62 10159
TEST_TIME 63 10180
TEST_TIME 64 10163
TEST_TIME 65 10123
TEST_TIME 66 10235
TEST_TIME 67 10283
TEST_TIME 68 10355
TEST_TIME 69 10301
TEST_TIME 70 10371
TEST_TIME 71 10497
TEST_TIME 72 10507
TEST_TIME 73 10445
TEST_TIME 74 10569
TEST_TIME 75 10472
TEST_TIME 76 10380
TEST_TIME 77 10338
TEST_TIME 78 10352
TEST_TIME 79 10278
TEST_TIME 80 10273
TEST_TIME 81 10215
TEST_TIME 82 10172
TEST_TIME 83 10118
TEST_TIME 84 10081
TEST_TIME 85 10124
TEST_TIME 86 10124
TEST_TIME 87 10088
TEST_TIME 88 10085
TEST_TIME 89 10066
TEST_TIME 90 10200
TEST_TIME 91 10176
TEST_TIME 92 10151
TEST_TIME 93 10195
TEST_TIME 94 10254
TEST_TIME 95 10238
TEST_TIME 96 10251
TEST_TIME 97 10303
TEST_TIME 98 10316
TEST_TIME 99 10268
TEST_TIME 100 10299
TEST_TIME 101 10242
TEST_TIME 102 10183
TEST_TIME 103 10186
TEST_TIME 104 10155
TEST_TIME 105 10156
TEST_TIME 106 10124
TEST_TIME 107 10112
TEST_TIME 108 10127
TEST_TIME 109 10094
TEST_TIME 110 10045
TEST_TIME 111 10019
TEST_TIME 112 10016
TEST_TIME 113 9997
TEST_TIME 114 10000
TEST_TIME 115 10000
TEST_TIME 116 9997
TEST_TIME 117 10028
TEST_TIME 118 10098
TEST_TIME 119 10050
TEST_TIME 120 9967

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 25-10-2020 16:27:52 to 25-10-2020 16:27:56.

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 1640579 1640579 0 0.00 0 11050 5 0 0
doc_list_biud 1640579 1640579 0 0.00 0 32174 23 0 0
sp_add_invoice_to_stock 5231 5139 92 1.75 35 229780 16407 0 92
sp_cancel_adding_invoice 6369 6369 0 0.00 4 65878 594 0 0
sp_cancel_client_order 1849 1849 0 0.00 4 10876 266 0 0
sp_cancel_customer_reserve 626581 626581 0 0.00 1 20504 49 0 0
sp_cancel_pay_from_customer 686 686 0 0.00 3 2321 46 0 0
sp_cancel_pay_to_supplier 438 438 0 0.00 3 2142 54 0 0
sp_cancel_supplier_invoice 7010 6999 11 0.15 2 12366 582 0 11
sp_cancel_supplier_order 448 448 0 0.00 9 882953 85721 0 0
sp_cancel_write_off 10985 10985 0 0.00 1 9691 100 0 0
sp_client_order 21641 21641 0 0.00 3 23777 348 0 0
sp_customer_reserve 478166 478166 0 0.00 0 90263 163 0 0
sp_fill_shopping_cart 33154 33154 0 0.00 1 14456 33 0 0
sp_fill_shopping_cart_clo_res 478166 478166 0 0.00 0 16768 13 0 0
sp_get_clo_for_invoice 5139 5139 0 0.00 0 35126 381 0 0
sp_kill_cost_storno 18478 18478 0 0.00 0 6797 42 0 0
sp_kill_qstorno_ret_qs2qd 1271594 1271594 0 0.00 0 20463 26 0 0
sp_kill_qty_storno 668803 668803 0 0.00 0 32174 56 0 0
sp_lock_selected_doc 21597 21597 0 0.00 0 9625 8 0 0
sp_make_cost_storno 28940 28940 0 0.00 0 10401 42 0 0
sp_multiply_rows_for_pdistr 28940 28940 0 0.00 0 8110 21 0 0
sp_multiply_rows_for_qdistr 21641 21641 0 0.00 0 16345 173 0 0
sp_pay_from_customer 7239 7239 0 0.00 4 13538 88 0 0
sp_pay_to_supplier 6129 6129 0 0.00 5 8920 111 0 0
sp_payment_common 13368 13368 0 0.00 1 8064 30 0 0
sp_qd_handle_on_cancel_clo 1849 1849 0 0.00 0 10871 237 0 0
sp_qd_handle_on_invoice_upd_sts 11508 11508 0 0.00 0 32173 247 0 0
sp_qd_handle_on_reserve_upd_sts 10433 10433 0 0.00 0 19337 50 0 0
sp_reserve_write_off 10433 10433 0 0.00 6 19355 147 0 0
sp_supplier_invoice 5812 5812 0 0.00 5 83096 2738 0 0
sp_supplier_order 5712 5712 0 0.00 5 32755 1103 0 0
srv_aggregate_perf_data 104 104 0 0.00 2498 462615 22170 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 1048 120711 52911 0 0
srv_find_qd_qs_mism 1147449 1147449 0 0.00 0 11050 5 0 0
srv_make_invnt_saldo 542 542 0 0.00 41 97529 1217 0 0
srv_make_money_saldo 748 748 0 0.00 0 5485 23 0 0
srv_recalc_idx_stat 4 4 0 0.00 221608 349505 274880 0 0
t$perf-abend:sp_add_invoice_to_stock 92 92 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 5139 5139 0 0.00 0 11614 132 0 0
t$perf-norm:sp_cancel_adding_invoice 413 413 0 0.00 0 8022 88 0 0
t$perf-norm:sp_cancel_client_order 1849 1849 0 0.00 0 1258 6 0 0
t$perf-norm:sp_cancel_customer_reserve 727 727 0 0.00 0 4751 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 686 686 0 0.00 0 2251 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 438 438 0 0.00 0 1338 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 408 408 0 0.00 0 2459 14 0 0
t$perf-norm:sp_cancel_supplier_order 448 448 0 0.00 0 15398 1284 0 0
t$perf-norm:sp_cancel_write_off 1056 1056 0 0.00 0 3022 6 0 0
t$perf-norm:sp_client_order 21641 21641 0 0.00 0 5884 12 0 0
t$perf-norm:sp_customer_reserve 1055 1055 0 0.00 0 2275 7 0 0
t$perf-norm:sp_pay_from_customer 7239 7239 0 0.00 0 6746 8 0 0
t$perf-norm:sp_pay_to_supplier 6129 6129 0 0.00 0 6320 8 0 0
t$perf-norm:sp_reserve_write_off 10433 10433 0 0.00 0 8469 8 0 0
t$perf-norm:sp_supplier_invoice 5812 5812 0 0.00 0 6863 16 0 0
t$perf-norm:sp_supplier_order 5712 5712 0 0.00 0 5168 10 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 165 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 424 8 0 0
t$perf-norm:srv_make_invnt_saldo 542 542 0 0.00 0 1727 14 0 0
t$perf-norm:srv_make_money_saldo 748 748 0 0.00 0 1154 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 12 4 0 0
v_add_invoice_to_stock 10460 10276 184 1.75 0 4805 18 0 184
v_all_customers 39220 39220 0 0.00 0 132 0 0 0
v_all_suppliers 32204 32204 0 0.00 0 20 1 0 0
v_all_wares 43282 43282 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 826 826 0 0.00 0 3110 14 0 0
v_cancel_client_order 3698 3698 0 0.00 0 2032 5 0 0
v_cancel_customer_prepayment 1372 1372 0 0.00 0 344 3 0 0
v_cancel_customer_reserve 1454 1454 0 0.00 0 2964 5 0 0
v_cancel_payment_to_supplier 876 876 0 0.00 0 2070 7 0 0
v_cancel_supplier_invoice 838 816 22 2.62 0 606 13 0 22
v_cancel_supplier_order 896 896 0 0.00 0 5364 20 0 0
v_cancel_write_off 2112 2112 0 0.00 0 4846 20 0 0
v_max_id_clo_ord 5709 5709 0 0.00 0 305 1 0 0
v_max_id_clo_res 1055 1055 0 0.00 0 3811 25 0 0
v_max_id_ord_sup 5804 5804 0 0.00 0 5183 5 0 0
v_max_non_paid_invoice 1539 1539 0 0.00 0 2916 4 0 0
v_max_non_paid_realizn 3827 3827 0 0.00 0 3478 6 0 0
v_min_id_clo_ord 5709 5709 0 0.00 0 3128 8 0 0
v_min_id_clo_res 1055 1055 0 0.00 0 890 3 0 0
v_min_id_ord_sup 5804 5804 0 0.00 0 3437 4 0 0
v_min_non_paid_invoice 6129 6129 0 0.00 0 6741 21 0 0
v_min_non_paid_realizn 7239 7239 0 0.00 0 5991 19 0 0
v_our_firm 10886 10886 0 0.00 0 153 1 0 0
v_reserve_write_off 20866 20866 0 0.00 0 5216 6 0 0
x_lock_depdocs_on_canc_invoice 12960 12960 0 0.00 0 11065 90 0 0
x_lock_depdocs_on_canc_sup_ord 448 448 0 0.00 0 12545 291 0 0
x_make_qstorno_1000_1200 5712 5712 0 0.00 2 32704 1040 0 0
x_make_qstorno_1200_2000 5812 5812 0 0.00 2 73899 2623 0 0
x_make_qstorno_2100_3300 478166 478166 0 0.00 0 90262 148 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-10-2020 16:27:57 to 25-10-2020 16:28:01.

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
13:57:05 2 8192000000 8798422528 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
13:58:06 30778 8192000000 10368405328 10687660032 1414467648 14.724 100 92 184 1 1450408480 6052192 29888640
13:59:40 26504 8192000000 10450247408 10784366592 1502092304 15.494 100 90 180 0 1539357632 8561648 28703680
14:01:10 17867 8192000000 10504258704 10851520512 1553011808 15.936 100 81 162 1 1588463168 10122560 25328800
14:02:32 12838 8192000000 10584240768 10944942080 1646897776 16.738 100 72 144 0 1678229808 10172000 21160032
14:03:49 8783 8192000000 10667631840 11041423360 1724712304 17.391 100 74 147 1 1757108736 11919840 20476592
14:05:00 4683 8192000000 10720649264 11108462592 1786068736 17.899 100 75 149 0 1819457824 12047184 21341904
14:06:08 4222 8192000000 10769627184 11157737472 1831122304 18.268 100 81 160 1 1867188384 13380320 22685760
14:07:15 1554 8192000000 10812369872 11200688128 1868329680 18.571 100 93 185 2 1908460688 13924464 26206544
14:08:18 6490 8192000000 10823278016 11219521536 1880707696 18.671 100 83 166 2 1918285744 13647856 23930192
14:09:25 5681 8192000000 10800762480 11215912960 1878932224 18.656 100 39 77 2 1898154400 8235088 10987088
14:10:32 13826 8192000000 10797013680 11225198592 1885311104 18.708 100 23 47 1 1898354800 6683808 6359888
14:11:50 6242 8192000000 10803911200 11237228544 1897766544 18.808 100 22 44 0 1910731568 7020448 5944576
14:12:58 49603 8192000000 10809576448 11251593216 1907093776 18.883 100 56 114 2 1930140624 6000912 17045936
14:14:48 5883 8192000000 10841892688 11267891200 1912313072 18.925 100 42 85 0 1935691328 11541680 11836576
14:15:56 7752 8192000000 10824248176 11252301824 1892977744 18.770 99 28 55 1 1906068144 5701984 7388416
14:17:08 51378 8192000000 10776071136 11189350400 1778458352 17.837 95 41 81 0 1794948224 4318560 12171312
14:18:59 7352 8192000000 10731398848 11136929792 1764528976 17.722 97 39 78 0 1786110224 10157552 11423696
14:20:07 7112 8192000000 10678787744 11090436096 1745594480 17.565 97 19 38 2 1757399296 6099008 5705808
14:21:14 8675 8192000000 10717602352 11144589312 1796555936 17.986 99 21 42 1 1808961152 6661600 5743616
14:22:24 39964 8192000000 10748996416 11165433856 1821010832 18.186 100 40 80 2 1837819520 5470976 11337712
14:24:09 5439 8192000000 10768207456 11192418304 1848549008 18.410 100 27 54 4 1865836288 10284608 7002672
14:25:19 13470 8192000000 10775432672 11205672960 1863582176 18.532 100 22 44 0 1876036208 6118720 6335312
14:26:32 28616 8192000000 10772301376 11210551296 1869892208 18.583 100 28 55 1 1883280624 5604976 7783440
14:28:01 31114 8192000000 10830397632 11251073024 1900426880 18.830 100 37 74 1 1917047936 7015920 9605136
14:29:40 13291 8192000000 10870330512 11289686016 1927954848 19.051 100 50 99 0 1954078128 12714064 13409216
14:30:56 10673 8192000000 10873296656 11301728256 1942339408 19.165 100 50 99 1 1971042240 13813888 14888944
14:32:10 10048 8192000000 10913316832 11337674752 1965432064 19.349 100 57 114 2 2003957648 22484304 16041280
14:33:22 18597 8192000000 10926951776 11355090944 1966466688 19.357 100 44 88 1 2007166128 27071760 13627680
14:34:45 19701 8192000000 10949320384 11386470400 1995021440 19.583 100 52 104 0 2045017264 34182912 15812912
14:36:09 3164 8192000000 10963153600 11385688064 1988313408 19.530 100 64 125 0 2044984320 37468656 19202256
14:37:18 3541 8192000000 10978919120 11407290368 2010086224 19.702 100 62 122 3 2069588832 39832752 19669856
14:38:26 5291 8192000000 10988048496 11409567744 2002760176 19.644 100 60 120 0 2065639472 44267392 18611904
14:39:37 3162 8192000000 10962229440 11385114624 1968454496 19.373 99 60 120 1 2036096576 47658896 19983184
14:40:43 6851 8192000000 10955132208 11378716672 1970593456 19.390 100 58 116 0 2038250192 48815952 18840784
14:41:57 7516 8192000000 10951718160 11377393664 1950322960 19.229 99 47 94 0 2022836768 56253616 16260192
14:43:07 6488 8192000000 10956500832 11381927936 1968343392 19.372 100 56 112 0 2039364784 52183984 18837408
14:44:15 8951 8192000000 10916917472 11360456704 1933845792 19.098 99 42 85 1 2003651216 55220112 14585312
14:45:28 13116 8192000000 10920358032 11373387776 1941984064 19.163 100 26 52 0 2006628528 55142672 9501792
14:47:00 22725 8192000000 10845029584 11318022144 1857481120 18.483 97 15 30 1 1902402592 39549856 5371616
14:48:48 103504 8192000000 10806445536 11259158528 1797077760 17.990 94 18 36 1 1816887840 14629872 5180208
14:51:33 27777 8192000000 10863779152 11294244864 1892938112 18.769 99 59 119 0 1921298640 10955632 17404896
14:53:04 102634 8192000000 10833105792 11275485184 1800510032 18.018 94 17 33 1 1819428384 14214160 4704192
14:56:02 30819 8192000000 10824985536 11274862592 1878996480 18.657 98 27 54 0 1895327344 8324256 8006608
14:57:43 63337 8192000000 10846594016 11276492800 1895627920 18.791 99 23 38 2 1910842976 9830208 5384848
14:59:47 89701 8192000000 10863085488 11321651200 1921522000 18.999 99 10 20 0 1932105872 7502928 3080944
15:02:22 88811 8192000000 10891364768 11361648640 1962615200 19.327 100 18 36 1 1980786832 12748368 5423264
15:04:53 56956 8192000000 10876917888 11343241216 1927447344 19.046 98 9 15 1 1947632144 18129520 2055280
15:06:52 61758 8192000000 10912719488 11381571584 1978176416 19.450 100 14 28 1 1994321296 12347328 3797552
15:08:57 80449 8192000000 10942718480 11393441792 1987664144 19.525 100 12 24 0 2005870432 14835888 3370400
15:11:20 99066 8192000000 10929819136 11409571840 1985612400 19.509 99 21 42 2 2014946880 23393968 5940512
15:13:59 35171 8192000000 10927431312 11379814400 1955759584 19.272 98 26 50 0 1972191232 9717056 6714592
15:15:40 107722 8192000000 10920984944 11378573312 1916845760 18.962 96 11 22 1 1945854944 26213280 2795904
15:18:38 115302 8192000000 10910450944 11370377216 1949154704 19.220 99 28 55 2 1973954096 15812224 8987168
15:21:39 1047 8192000000 10921534272 11360411648 1971209168 19.395 100 62 124 0 2002065184 13239120 17616896
15:22:41 40531 8192000000 10863390672 11348451328 1924404592 19.022 98 11 22 1 1936260160 8755728 3099840
15:24:23 80553 8192000000 10862316544 11331420160 1844702896 18.379 94 15 30 1 1861409392 11809344 4897152
15:26:44 49137 8192000000 10866192800 11328475136 1906297216 18.877 98 15 30 1 1922927968 12554688 4076064
15:28:38 60856 8192000000 10854304992 11323547648 1885207184 18.707 97 18 36 1 1897439952 7588992 4643776
15:31:23 39165 8192000000 10854429792 11310403584 1854479744 18.459 95 23 46 2 1876459760 15798576 6181440
15:33:22 92584 8192000000 10874245280 11345022976 1876759008 18.639 96 21 42 0 1896329600 13632880 5937712
15:35:54 63893 8192000000 10884246064 11334774784 1890905824 18.753 97 23 46 0 1911704064 14391360 6406880
15:38:06 112669 8192000000 10888346496 11369119744 1902878320 18.849 97 23 46 1 1930640144 21410208 6351616
15:40:59 111219 8192000000 10904109824 11341705216 1892439936 18.765 97 21 43 0 1913337488 14091984 6805568
15:43:54 80532 8192000000 10875874512 11329671168 1881554912 18.678 96 31 62 0 1905267328 15514912 8197504
15:46:23 31018 8192000000 10860576384 11317501952 1897399696 18.805 98 27 54 2 1913658176 8754672 7503808
15:48:03 49672 8192000000 10846058080 11316056064 1898361360 18.813 98 13 26 0 1909077360 6867760 3848240
15:49:55 73722 8192000000 10868548832 11346165760 1929501808 19.063 99 15 30 1 1947438064 13217200 4719056
15:52:11 61132 8192000000 10880135024 11359293440 1940955952 19.154 99 7 14 0 1954629440 11762432 1911056
15:54:25 19061 8192000000 10889883056 11360083968 1966420832 19.357 100 22 44 1 1983069728 10164592 6484304
15:55:47 69282 8192000000 10923713360 11391504384 1982259024 19.483 100 25 50 0 2006641952 16914176 7468752
15:58:52 33270 8192000000 10935341952 11400560640 2004227360 19.656 100 21 42 2 2019069264 9457056 5384848
16:00:31 65469 8192000000 10913798976 11379367936 1919617632 18.984 96 11 22 1 1937335984 14357600 3360752
16:02:36 55202 8192000000 10932780016 11391250432 1954903888 19.266 98 20 40 1 1973125632 12538928 5682816
16:04:32 49701 8192000000 10919815056 11399786496 1984065872 19.497 100 18 36 1 2007862432 18614736 5181824
16:06:23 105232 8192000000 10884998560 11361599488 1881372992 18.676 95 11 22 0 1902688448 18370464 2944992
16:09:08 70000 8192000000 10890722816 11359371264 1914598448 18.944 97 40 80 0 1938607488 12320608 11688432
16:11:22 27649 8192000000 10888972032 11340783616 1949362864 19.221 99 44 88 4 1974777056 13199648 12214544
16:12:53 82819 8192000000 10883496352 11360436224 1929533008 19.063 98 10 20 1 1952923664 20603168 2787488
16:15:16 47008 8192000000 10861657392 11329687552 1896414400 18.797 97 11 22 0 1909607520 9960080 3233040
16:17:04 81152 8192000000 10886933024 11348905984 1933373952 19.094 99 15 30 0 1945064592 7392784 4297856
16:19:30 82635 8192000000 10870875072 11344879616 1856028400 18.471 95 11 23 0 1866171712 7386144 2757168
16:21:54 70463 8192000000 10850185824 11308310528 1870245568 18.586 96 20 40 1 1892456544 16865136 5345840
16:24:05 120710 8192000000 10869176736 11360333824 1880337984 18.668 96 16 32 0 1896646800 11612512 4696304

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 25-10-2020 16:28:02 to 25-10-2020 16:28:17.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 92 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 25-10-2020 16:28:18 to 25-10-2020 16:28:20.

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          247811
MON$OLDEST_ACTIVE               249542
MON$OLDEST_SNAPSHOT             249542
MON$NEXT_TRANSACTION            249542
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                       2035424
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 ---
:50: 
:51: mscs-test-161.budzdorov.ru    Sun Oct 25 16:27:06 2020
:52:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:53: 
:54: 
:55: mscs-test-161.budzdorov.ru    Sun Oct 25 16:27:08 2020
:56:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:57: 
:58: 
:59: mscs-test-161.budzdorov.ru    Sun Oct 25 16:27:09 2020
:60:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:61: 
--- end of diff output ---

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

25.10.20 16:28:21. 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-25 16:28:23 No severe PSQL-related problems occured
25.10.20 16:28:25. End of report.