Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.10.2020 01: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 17488604 kB
MemAvailable 31595280 kB
Buffers 0 kB
Cached 14246760 kB
SwapCached 3048 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-24 01:26:56
DTS_END                         2020-10-24 01:26:56.3140
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/56182
TRN_ID                          249056
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: 10024.66 21144 1202959
customer order: creation 181.28 356 21753
customer order: refuse 15.38 275 1846
order to supplier: creation 48.48 1159 5817
order to supplier: removal 3.55 87083 426
invoice (draft): creation 47.23 2722 5668
invoice (draft): removal 58.12 621 6974
invoice accept: apply 43.98 15780 5277
invoice accept: cancel 52.78 607 6334
customer reserve: creation 4110.05 155 493206
customer reserve: removal 5156.37 48 618764
realization accept: apply 87.10 161 10452
realization accept: cancel 85.99 101 10319
payment from customer: creation 62.34 102 7481
payment from customer: removal 5.83 54 699
payment to supplier: creation 50.68 116 6082
payment to supplier: removal 3.38 72 405
service: total inventory turnovers 5.00 1155 600
service: total monetary turnovers 6.15 29 738
service: aggregate perf. data 0.95 18546 114
service: refresh index statistics 0.03 293747 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-10-2020 01:28:47 to 24-10-2020 01:28:49.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2606
TEST_TIME 2 3882
TEST_TIME 3 4846
TEST_TIME 4 5100
TEST_TIME 5 5576
TEST_TIME 6 5898
TEST_TIME 7 6313
TEST_TIME 8 6301
TEST_TIME 9 6303
TEST_TIME 10 6443
TEST_TIME 11 6598
TEST_TIME 12 6995
TEST_TIME 13 7172
TEST_TIME 14 7894
TEST_TIME 15 8438
TEST_TIME 16 8609
TEST_TIME 17 9385
TEST_TIME 18 10084
TEST_TIME 19 10129
TEST_TIME 20 10473
TEST_TIME 21 11944
TEST_TIME 22 12086
TEST_TIME 23 11989
TEST_TIME 24 11933
TEST_TIME 25 11701
TEST_TIME 26 11328
TEST_TIME 27 11206
TEST_TIME 28 11160
TEST_TIME 29 10956
TEST_TIME 30 11006
TEST_TIME 31 10980
TEST_TIME 32 10821
TEST_TIME 33 10581
TEST_TIME 34 10458
TEST_TIME 35 10327
TEST_TIME 36 10255
TEST_TIME 37 11032
TEST_TIME 38 11022
TEST_TIME 39 11173
TEST_TIME 40 11082
TEST_TIME 41 11203
TEST_TIME 42 11137
TEST_TIME 43 11043
TEST_TIME 44 11189
TEST_TIME 45 11113
TEST_TIME 46 11039
TEST_TIME 47 11238
TEST_TIME 48 11339
TEST_TIME 49 11154
TEST_TIME 50 10941
TEST_TIME 51 11020
TEST_TIME 52 10990
TEST_TIME 53 10874
TEST_TIME 54 10933
TEST_TIME 55 10899
TEST_TIME 56 10882
TEST_TIME 57 10764
TEST_TIME 58 10726
TEST_TIME 59 10636
TEST_TIME 60 10579
TEST_TIME 61 10577
TEST_TIME 62 10495
TEST_TIME 63 10475
TEST_TIME 64 10428
TEST_TIME 65 10424
TEST_TIME 66 10430
TEST_TIME 67 10385
TEST_TIME 68 10509
TEST_TIME 69 10440
TEST_TIME 70 10446
TEST_TIME 71 10467
TEST_TIME 72 10439
TEST_TIME 73 10525
TEST_TIME 74 10520
TEST_TIME 75 10555
TEST_TIME 76 10507
TEST_TIME 77 10410
TEST_TIME 78 10421
TEST_TIME 79 10374
TEST_TIME 80 10314
TEST_TIME 81 10229
TEST_TIME 82 10209
TEST_TIME 83 10255
TEST_TIME 84 10266
TEST_TIME 85 10239
TEST_TIME 86 10246
TEST_TIME 87 10216
TEST_TIME 88 10232
TEST_TIME 89 10249
TEST_TIME 90 10253
TEST_TIME 91 10284
TEST_TIME 92 10259
TEST_TIME 93 10316
TEST_TIME 94 10309
TEST_TIME 95 10270
TEST_TIME 96 10182
TEST_TIME 97 10162
TEST_TIME 98 10128
TEST_TIME 99 10144
TEST_TIME 100 10089
TEST_TIME 101 10081
TEST_TIME 102 10057
TEST_TIME 103 10061
TEST_TIME 104 10034
TEST_TIME 105 10075
TEST_TIME 106 10023
TEST_TIME 107 10018
TEST_TIME 108 10138
TEST_TIME 109 10121
TEST_TIME 110 10137
TEST_TIME 111 10118
TEST_TIME 112 10236
TEST_TIME 113 10240
TEST_TIME 114 10293
TEST_TIME 115 10239
TEST_TIME 116 10186
TEST_TIME 117 10148
TEST_TIME 118 10108
TEST_TIME 119 10053
TEST_TIME 120 10027

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 24-10-2020 01:28:51 to 24-10-2020 01:28:54.

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 1658743 1658743 0 0.00 0 13179 5 0 0
doc_list_biud 1658743 1658743 0 0.00 0 76995 22 0 0
sp_add_invoice_to_stock 5383 5277 106 1.96 25 163335 15780 0 106
sp_cancel_adding_invoice 6334 6334 0 0.00 5 50590 607 0 0
sp_cancel_client_order 1846 1846 0 0.00 4 9653 276 0 0
sp_cancel_customer_reserve 618764 618764 0 0.00 1 36410 48 0 0
sp_cancel_pay_from_customer 699 699 0 0.00 2 3177 54 0 0
sp_cancel_pay_to_supplier 405 405 0 0.00 3 4224 73 0 0
sp_cancel_supplier_invoice 6984 6974 10 0.14 1 77014 622 0 10
sp_cancel_supplier_order 426 426 0 0.00 6 757154 87083 0 0
sp_cancel_write_off 10319 10319 0 0.00 1 11729 102 0 0
sp_client_order 21753 21753 0 0.00 4 33985 356 0 0
sp_customer_reserve 493206 493206 0 0.00 0 64968 155 0 0
sp_fill_shopping_cart 33229 33229 0 0.00 1 9487 30 0 0
sp_fill_shopping_cart_clo_res 493200 493200 0 0.00 0 15638 13 0 0
sp_get_clo_for_invoice 5271 5271 0 0.00 0 36191 277 0 0
sp_kill_cost_storno 17757 17757 0 0.00 0 8139 43 0 0
sp_kill_qstorno_ret_qs2qd 1255247 1255247 0 0.00 0 76989 26 0 0
sp_kill_qty_storno 660386 660386 0 0.00 0 76992 56 0 0
sp_lock_selected_doc 21783 21783 0 0.00 0 10278 6 0 0
sp_make_cost_storno 29286 29286 0 0.00 0 12780 48 0 0
sp_multiply_rows_for_pdistr 29286 29286 0 0.00 0 8907 25 0 0
sp_multiply_rows_for_qdistr 21753 21753 0 0.00 0 11684 177 0 0
sp_pay_from_customer 7481 7481 0 0.00 4 12786 102 0 0
sp_pay_to_supplier 6082 6082 0 0.00 4 11438 116 0 0
sp_payment_common 13563 13563 0 0.00 2 8582 27 0 0
sp_qd_handle_on_cancel_clo 1846 1846 0 0.00 0 9645 245 0 0
sp_qd_handle_on_invoice_upd_sts 11605 11605 0 0.00 0 45323 233 0 0
sp_qd_handle_on_reserve_upd_sts 10452 10452 0 0.00 0 14823 60 0 0
sp_reserve_write_off 10452 10452 0 0.00 5 14868 162 0 0
sp_supplier_invoice 5668 5668 0 0.00 6 97569 2722 0 0
sp_supplier_order 5817 5817 0 0.00 6 26003 1160 0 0
srv_aggregate_perf_data 114 114 0 0.00 2636 477165 18546 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 2773 184939 50665 0 0
srv_find_qd_qs_mism 1152236 1152236 0 0.00 0 13178 5 0 0
srv_make_invnt_saldo 600 600 0 0.00 27 104939 1155 0 0
srv_make_money_saldo 738 738 0 0.00 0 3980 29 0 0
srv_recalc_idx_stat 4 4 0 0.00 176346 580895 293748 0 0
t$perf-abend:sp_add_invoice_to_stock 106 106 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5277 5277 0 0.00 0 30335 148 0 0
t$perf-norm:sp_cancel_adding_invoice 455 455 0 0.00 0 2066 78 0 0
t$perf-norm:sp_cancel_client_order 1846 1846 0 0.00 0 5881 16 0 0
t$perf-norm:sp_cancel_customer_reserve 728 728 0 0.00 0 1226 6 0 0
t$perf-norm:sp_cancel_pay_from_customer 699 699 0 0.00 0 8255 22 0 0
t$perf-norm:sp_cancel_pay_to_supplier 405 405 0 0.00 0 4463 24 0 0
t$perf-norm:sp_cancel_supplier_invoice 456 456 0 0.00 0 6507 30 0 0
t$perf-norm:sp_cancel_supplier_order 426 426 0 0.00 0 30795 1385 0 0
t$perf-norm:sp_cancel_write_off 1045 1045 0 0.00 0 2833 12 0 0
t$perf-norm:sp_client_order 21753 21753 0 0.00 0 16808 15 0 0
t$perf-norm:sp_customer_reserve 1051 1051 0 0.00 0 3981 8 0 0
t$perf-norm:sp_pay_from_customer 7481 7481 0 0.00 0 10194 13 0 0
t$perf-norm:sp_pay_to_supplier 6082 6082 0 0.00 0 6178 15 0 0
t$perf-norm:sp_reserve_write_off 10452 10452 0 0.00 0 7990 13 0 0
t$perf-norm:sp_supplier_invoice 5668 5668 0 0.00 0 7112 19 0 0
t$perf-norm:sp_supplier_order 5817 5817 0 0.00 0 4301 14 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 417 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 7 1 0 0
t$perf-norm:srv_make_invnt_saldo 600 600 0 0.00 0 2527 16 0 0
t$perf-norm:srv_make_money_saldo 738 738 0 0.00 0 1118 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 15 6 0 0
v_add_invoice_to_stock 10754 10542 212 1.97 0 4826 19 0 212
v_all_customers 39698 39698 0 0.00 0 15 0 0 0
v_all_suppliers 32092 32092 0 0.00 0 18 1 0 0
v_all_wares 43506 43506 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 910 910 0 0.00 0 1633 7 0 0
v_cancel_client_order 3692 3692 0 0.00 0 8226 8 0 0
v_cancel_customer_prepayment 1398 1398 0 0.00 0 646 3 0 0
v_cancel_customer_reserve 1456 1456 0 0.00 0 2514 9 0 0
v_cancel_payment_to_supplier 810 810 0 0.00 0 644 4 0 0
v_cancel_supplier_invoice 932 912 20 2.14 0 2951 17 0 20
v_cancel_supplier_order 852 852 0 0.00 0 2417 6 0 0
v_cancel_write_off 2090 2090 0 0.00 0 8651 18 0 0
v_max_id_clo_ord 5817 5817 0 0.00 0 506 1 0 0
v_max_id_clo_res 1051 1051 0 0.00 0 2744 18 0 0
v_max_id_ord_sup 5659 5659 0 0.00 0 4449 5 0 0
v_max_non_paid_invoice 1512 1512 0 0.00 0 2159 3 0 0
v_max_non_paid_realizn 4020 4020 0 0.00 0 3289 5 0 0
v_min_id_clo_ord 5817 5817 0 0.00 0 5904 6 0 0
v_min_id_clo_res 1051 1051 0 0.00 0 464 2 0 0
v_min_id_ord_sup 5659 5659 0 0.00 0 4123 5 0 0
v_min_non_paid_invoice 6082 6082 0 0.00 0 7291 18 0 0
v_min_non_paid_realizn 7481 7481 0 0.00 0 8577 16 0 0
v_our_firm 10730 10730 0 0.00 0 14 1 0 0
v_reserve_write_off 20904 20904 0 0.00 0 8635 6 0 0
x_lock_depdocs_on_canc_invoice 12852 12852 0 0.00 0 16709 103 0 0
x_lock_depdocs_on_canc_sup_ord 426 426 0 0.00 0 13405 312 0 0
x_make_qstorno_1000_1200 5817 5817 0 0.00 2 25992 1098 0 0
x_make_qstorno_1200_2000 5668 5668 0 0.00 3 97538 2615 0 0
x_make_qstorno_2100_3300 493206 493206 0 0.00 0 64967 141 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-10-2020 01:28:55 to 24-10-2020 01:28:59.

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
22:57:00 2 8192000000 8798422528 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
22:58:01 33624 8192000000 10379936256 10699268096 1426615472 14.831 100 90 180 0 1462316336 6350960 29349904
22:59:36 14040 8192000000 10442153632 10771329024 1491927760 15.406 100 96 192 0 1531359248 8964224 30467264
23:01:04 16535 8192000000 10485915792 10828726272 1541977760 15.841 100 88 176 3 1580238544 11008336 27252448
23:02:24 12090 8192000000 10573391456 10930798592 1632596592 16.617 100 78 156 1 1668303072 11978816 23727664
23:03:40 5803 8192000000 10631560832 11007148032 1698888144 17.176 100 77 154 1 1733379104 12124720 22366240
23:04:47 5315 8192000000 10701565184 11086102528 1767886032 17.750 100 76 152 1 1802276912 13618528 20772352
23:05:54 2096 8192000000 10758511728 11145920512 1818763696 18.168 100 88 174 3 1856254640 13045520 24445424
23:06:57 1635 8192000000 10817560288 11206242304 1866418688 18.555 100 89 179 0 1904887952 12899664 25569600
23:08:00 2191 8192000000 10802403840 11206389760 1876732672 18.639 100 68 131 1 1903487472 8373568 18381232
23:09:03 9692 8192000000 10789257152 11214401536 1880267648 18.667 100 24 48 4 1893418864 6339744 6811472
23:10:17 10369 8192000000 10797314192 11228307456 1885933728 18.713 100 22 44 4 1898821248 6395104 6492416
23:11:29 70691 8192000000 10804612976 11243282432 1895445920 18.790 100 26 52 1 1912115584 9273280 7396384
23:13:42 6924 8192000000 10822890976 11257069568 1908020768 18.891 100 35 70 4 1923557456 5840080 9696608
23:14:49 5517 8192000000 10813474592 11251122176 1887575888 18.726 99 22 44 1 1899840992 6178896 6086208
23:15:55 54456 8192000000 10775798000 11207909376 1824998416 18.219 96 31 62 0 1839432352 5231536 9202400
23:17:54 5109 8192000000 10759999152 11182628864 1828290640 18.245 99 27 54 1 1841525728 5088288 8146800
23:19:00 6556 8192000000 10727559232 11145469952 1784355152 17.885 97 22 44 4 1796383760 5489952 6538656
23:20:08 6959 8192000000 10692884464 11111911424 1769185296 17.760 99 12 24 2 1782071104 9478560 3407248
23:21:15 29994 8192000000 10726582800 11149529088 1804395296 18.050 99 40 80 0 1822185744 5988656 11801792
23:22:49 6533 8192000000 10748107632 11177525248 1842929744 18.365 100 33 66 0 1858492656 4962864 10600048
23:23:59 4509 8192000000 10774075232 11204583424 1861003264 18.511 100 28 56 0 1876141488 6686256 8451968
23:25:05 11637 8192000000 10788061856 11213410304 1868044128 18.568 100 14 27 1 1877448832 5494096 3910608
23:26:21 64952 8192000000 10823096448 11247398912 1881267472 18.675 100 25 50 3 1894295936 4924688 8103776
23:28:45 12645 8192000000 10859694144 11292401664 1939434496 19.142 100 36 72 2 1960476368 10861808 10180064
23:30:01 38071 8192000000 10878606736 11318382592 1951976048 19.242 100 31 62 2 1973394704 12726816 8691840
23:31:53 7371 8192000000 10915556672 11344883712 1975065792 19.426 100 61 122 1 2008182480 14761136 18355552
23:33:06 11495 8192000000 10927910784 11373539328 1991365376 19.555 100 49 98 0 2024449856 18258688 14825792
23:34:22 10957 8192000000 10944515664 11376185344 1977607872 19.446 98 53 106 2 2011526704 18234544 15684288
23:35:40 6926 8192000000 10942301504 11367051264 1977019904 19.441 99 59 117 2 2018529520 24253120 17256496
23:36:50 15135 8192000000 10959820880 11393744896 1974183520 19.419 98 55 109 4 2022639552 31496320 16959712
23:38:11 2772 8192000000 10972968176 11388289024 1999830224 19.621 100 86 172 1 2066912592 39622992 27459376
23:39:17 4490 8192000000 10989770576 11409657856 2006567248 19.674 100 77 154 2 2072509120 42285312 23656560
23:40:27 13078 8192000000 10935627408 11382964224 1968709440 19.375 99 41 82 2 2031248096 48893328 13645328
23:41:42 10243 8192000000 10922389888 11356155904 1939667200 19.144 99 48 96 0 2001907168 46665408 15574560
23:42:56 23363 8192000000 10895152064 11332960256 1877363280 18.644 97 28 56 2 1934520672 47599216 9558176
23:44:24 15314 8192000000 10881755456 11330011136 1917537024 18.967 100 30 60 2 1972581728 44719152 10325552
23:45:43 19098 8192000000 10869344720 11320725504 1883757008 18.695 98 22 44 2 1930977760 40151472 7069280
23:47:21 49890 8192000000 10825192832 11275198464 1816476000 18.149 95 16 30 1 1849888544 28248816 5163728
23:49:14 101016 8192000000 10809172832 11258253312 1777705328 17.831 94 9 18 0 1788919360 9161136 2052896
23:52:01 94792 8192000000 10835821680 11280265216 1872144096 18.602 98 32 64 1 1889299920 8297472 8858352
23:54:53 36349 8192000000 10837553552 11300118528 1911829376 18.921 100 5 10 0 1926483632 13202288 1451968
23:56:35 10129 8192000000 10869744192 11311321088 1935153520 19.108 100 60 117 4 1959114864 7537408 16423936
23:58:08 95527 8192000000 10873749376 11327619072 1903983904 18.858 97 17 34 0 1919544512 10377408 5183200
00:00:58 34186 8192000000 10892230928 11341762560 1944410592 19.182 99 23 46 0 1976835408 25235984 7188832
00:02:49 56581 8192000000 10904826304 11403816960 1962803072 19.328 100 20 39 1 2006663024 37612880 6247072
00:05:11 61628 8192000000 10911108256 11383394304 1971005424 19.393 100 13 26 0 1990978560 16685648 3287488
00:07:19 92945 8192000000 10925034560 11381133312 1950068192 19.227 98 15 30 1 1964242688 9948768 4225728
00:09:52 50243 8192000000 10944289824 11399163904 1980179712 19.466 99 38 76 1 2006098944 15077248 10841984
00:11:42 34754 8192000000 10957856640 11407130624 2006951808 19.678 100 27 54 1 2026927296 12633616 7341872
00:13:25 140336 8192000000 10915776880 11397181440 1896630688 18.799 95 8 16 0 1923485856 24542928 2312240
00:17:43 26363 8192000000 10916019520 11386802176 1993025040 19.568 100 40 76 2 2013754160 9966304 10762816
00:19:17 37535 8192000000 10931260912 11388092416 1992440080 19.563 100 43 87 2 2019440128 13723504 13276544
00:21:18 24952 8192000000 10880094976 11354644480 1918624928 18.976 97 13 26 0 1936064272 13958032 3481312
00:22:50 62405 8192000000 10899119152 11375718400 1979635472 19.462 100 14 29 0 1992826624 9162704 4028448
00:24:57 29644 8192000000 10893241616 11363295232 1955056736 19.267 99 17 34 1 1970082912 9044160 5982016
00:26:28 57096 8192000000 10880687920 11357278208 1939731888 19.145 98 24 46 0 1957895824 10248992 7914944
00:28:30 49084 8192000000 10853543936 11331883008 1902619776 18.847 97 7 14 0 1910497584 6322544 1555264
00:30:29 42978 8192000000 10847287824 11310325760 1848188944 18.407 94 29 58 2 1863254656 7235904 7829808
00:32:16 22843 8192000000 10893685840 11342016512 1932524128 19.087 99 38 76 2 1950796512 7473712 10798672
00:33:39 66357 8192000000 10844800656 11314446336 1810912736 18.103 93 13 26 1 1827713824 13169312 3631776
00:35:47 9518 8192000000 10880737088 11311947776 1899948128 18.826 98 68 135 1 1929559744 11162480 18449136
00:37:00 34396 8192000000 10836926240 11276320768 1862367840 18.522 98 36 72 0 1883481968 10724544 10389584
00:38:36 11450 8192000000 10847301232 11280707584 1888296288 18.732 100 62 125 0 1920343408 15252560 16794560
00:39:52 60929 8192000000 10851814224 11311919104 1878893104 18.656 98 17 34 2 1900331216 16425488 5012624
00:41:58 55422 8192000000 10883297600 11334000640 1931388000 19.078 99 38 74 0 1954645792 12346128 10911664
00:43:54 85685 8192000000 10878032720 11360260096 1952175088 19.244 100 16 32 0 1970672560 14100160 4397312
00:46:21 72091 8192000000 10884701440 11358257152 1964774336 19.344 100 10 20 1 1978961248 11867376 2319536
00:49:01 54909 8192000000 10888397328 11361759232 1962295152 19.324 100 14 28 0 1977448784 11069888 4083744
00:50:58 32042 8192000000 10914376272 11366449152 1970633040 19.390 100 28 56 0 1986444912 7927056 7884816
00:52:31 114163 8192000000 10906663712 11378950144 1923831024 19.018 97 24 48 0 1938698080 8260256 6606800
00:55:26 184937 8192000000 10909311120 11370737664 1911030112 18.915 96 15 30 0 1933047760 18343344 3674304
00:59:32 93263 8192000000 10906794704 11377213440 1930512000 19.071 97 9 18 0 1946169824 13208048 2449776
01:02:05 109856 8192000000 10903236528 11381628928 1973179904 19.411 99 33 66 1 1991904576 9330480 9394192
01:05:11 58139 8192000000 10868022752 11358285824 1900939920 18.834 96 8 16 0 1912376304 9320352 2116032
01:08:04 65853 8192000000 10873436816 11345641472 1888732688 18.736 96 17 34 2 1904724448 11023792 4967968
01:10:10 37352 8192000000 10887767568 11347714048 1918781184 18.977 98 15 30 1 1934212816 10107712 5323920
01:11:58 57596 8192000000 10912116624 11380674560 1956453008 19.278 99 15 30 1 1977603824 16940992 4209824
01:13:59 92399 8192000000 10888248400 11367682048 1904081936 18.859 97 11 22 0 1916852928 9781568 2989424
01:16:36 52609 8192000000 10887104912 11332120576 1819398256 18.173 92 30 60 2 1844143328 16675520 8069552
01:18:37 85500 8192000000 10871540464 11348578304 1865234768 18.546 97 9 18 0 1897519696 29602288 2682640
01:21:03 71611 8192000000 10837643792 11313238016 1900826976 18.833 99 10 20 1 1916970240 13430752 2712512
01:23:22 50490 8192000000 10840685920 11312328704 1902142080 18.844 99 25 50 0 1918275376 9334224 6799072
01:25:16 66730 8192000000 10832101680 11302064128 1866235984 18.554 97 11 22 1 1880236144 10884464 3115696

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 24-10-2020 01:29:01 to 24-10-2020 01:29:16.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 106 v_add_invoice_to_stock 335544517
user_exc 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 24-10-2020 01:29:17 to 24-10-2020 01:29:18.

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          247201
MON$OLDEST_ACTIVE               249827
MON$OLDEST_SNAPSHOT             249827
MON$NEXT_TRANSACTION            249827
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                       2028832
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.33379 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.7.33379 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.7.33379 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.7.33379 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 ---
:109: 
:110: mscs-test-161.budzdorov.ru    Sat Oct 24 01:27:07 2020
:111:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:112: 
:113: 
:114: mscs-test-161.budzdorov.ru    Sat Oct 24 01:27:12 2020
:115:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:116: 
:117: 
:118: mscs-test-161.budzdorov.ru    Sat Oct 24 01:27:12 2020
:119:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:120: 
--- end of diff output ---

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

24.10.20 01:29:20. 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-24 01:29:22 No severe PSQL-related problems occured
24.10.20 01:29:23. End of report.