Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.10.2020 01: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 17490448 kB
MemAvailable 31554896 kB
Buffers 0 kB
Cached 14147172 kB
SwapCached 5424 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-14 01:26:59
DTS_END                         2020-10-14 01:26:58.9990
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(56:17)
IP                              ::1/36124
TRN_ID                          250247
ATT_ID                          706
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: 9697.92 23153 1163750
customer order: creation 175.73 344 21087
customer order: refuse 14.63 291 1755
order to supplier: creation 47.88 1180 5745
order to supplier: removal 3.57 86798 428
invoice (draft): creation 46.16 2809 5539
invoice (draft): removal 55.99 676 6719
invoice accept: apply 43.05 15661 5166
invoice accept: cancel 50.95 646 6114
customer reserve: creation 3969.52 156 476342
customer reserve: removal 4987.18 48 598462
realization accept: apply 85.16 152 10219
realization accept: cancel 86.27 95 10352
payment from customer: creation 59.94 117 7193
payment from customer: removal 5.94 44 713
payment to supplier: creation 50.25 106 6030
payment to supplier: removal 3.39 62 407
service: total inventory turnovers 4.83 1140 579
service: total monetary turnovers 6.52 28 782
service: aggregate perf. data 0.95 20260 114
service: refresh index statistics 0.03 332437 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-10-2020 01:27:57 to 14-10-2020 01:27:59.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2049
TEST_TIME 2 2617
TEST_TIME 3 4042
TEST_TIME 4 4688
TEST_TIME 5 5679
TEST_TIME 6 5462
TEST_TIME 7 5325
TEST_TIME 8 5612
TEST_TIME 9 5621
TEST_TIME 10 6270
TEST_TIME 11 6605
TEST_TIME 12 7039
TEST_TIME 13 7601
TEST_TIME 14 8053
TEST_TIME 15 8218
TEST_TIME 16 8761
TEST_TIME 17 8668
TEST_TIME 18 8848
TEST_TIME 19 9021
TEST_TIME 20 8813
TEST_TIME 21 9433
TEST_TIME 22 10509
TEST_TIME 23 10561
TEST_TIME 24 11385
TEST_TIME 25 11963
TEST_TIME 26 12183
TEST_TIME 27 12068
TEST_TIME 28 11648
TEST_TIME 29 11362
TEST_TIME 30 11252
TEST_TIME 31 11155
TEST_TIME 32 10992
TEST_TIME 33 10786
TEST_TIME 34 10804
TEST_TIME 35 10691
TEST_TIME 36 10598
TEST_TIME 37 10445
TEST_TIME 38 10224
TEST_TIME 39 10280
TEST_TIME 40 10421
TEST_TIME 41 10357
TEST_TIME 42 10235
TEST_TIME 43 10297
TEST_TIME 44 10337
TEST_TIME 45 10210
TEST_TIME 46 10244
TEST_TIME 47 10284
TEST_TIME 48 10220
TEST_TIME 49 10192
TEST_TIME 50 10141
TEST_TIME 51 10168
TEST_TIME 52 10351
TEST_TIME 53 10314
TEST_TIME 54 10353
TEST_TIME 55 10177
TEST_TIME 56 10098
TEST_TIME 57 10136
TEST_TIME 58 10097
TEST_TIME 59 10047
TEST_TIME 60 10004
TEST_TIME 61 9952
TEST_TIME 62 9890
TEST_TIME 63 9808
TEST_TIME 64 9792
TEST_TIME 65 9758
TEST_TIME 66 9794
TEST_TIME 67 9804
TEST_TIME 68 9814
TEST_TIME 69 9901
TEST_TIME 70 9909
TEST_TIME 71 9930
TEST_TIME 72 9979
TEST_TIME 73 10031
TEST_TIME 74 10018
TEST_TIME 75 10020
TEST_TIME 76 10065
TEST_TIME 77 10109
TEST_TIME 78 10140
TEST_TIME 79 10190
TEST_TIME 80 10101
TEST_TIME 81 10045
TEST_TIME 82 9979
TEST_TIME 83 9908
TEST_TIME 84 9993
TEST_TIME 85 9979
TEST_TIME 86 9926
TEST_TIME 87 9927
TEST_TIME 88 9876
TEST_TIME 89 9804
TEST_TIME 90 9735
TEST_TIME 91 9750
TEST_TIME 92 9741
TEST_TIME 93 9736
TEST_TIME 94 9719
TEST_TIME 95 9712
TEST_TIME 96 9761
TEST_TIME 97 9725
TEST_TIME 98 9800
TEST_TIME 99 9762
TEST_TIME 100 9787
TEST_TIME 101 9818
TEST_TIME 102 9811
TEST_TIME 103 9868
TEST_TIME 104 9875
TEST_TIME 105 9891
TEST_TIME 106 9903
TEST_TIME 107 9862
TEST_TIME 108 9821
TEST_TIME 109 9772
TEST_TIME 110 9756
TEST_TIME 111 9725
TEST_TIME 112 9713
TEST_TIME 113 9669
TEST_TIME 114 9640
TEST_TIME 115 9640
TEST_TIME 116 9617
TEST_TIME 117 9626
TEST_TIME 118 9637
TEST_TIME 119 9693
TEST_TIME 120 9701

Performance per minute, chart


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

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 1605822 1605822 0 0.00 0 27332 5 0 0
doc_list_biud 1605822 1605822 0 0.00 0 25626 23 0 0
sp_add_invoice_to_stock 5229 5166 63 1.20 52 165027 15662 0 63
sp_cancel_adding_invoice 6114 6114 0 0.00 4 46303 646 0 0
sp_cancel_client_order 1755 1755 0 0.00 4 13365 292 0 0
sp_cancel_customer_reserve 598462 598462 0 0.00 1 20056 49 0 0
sp_cancel_pay_from_customer 713 713 0 0.00 2 2293 44 0 0
sp_cancel_pay_to_supplier 407 407 0 0.00 3 2618 62 0 0
sp_cancel_supplier_invoice 6726 6719 7 0.10 1 25690 677 0 7
sp_cancel_supplier_order 428 428 0 0.00 6 1042999 86798 0 0
sp_cancel_write_off 10352 10352 0 0.00 1 6730 96 0 0
sp_client_order 21087 21087 0 0.00 3 28668 345 0 0
sp_customer_reserve 476342 476342 0 0.00 0 157089 157 0 0
sp_fill_shopping_cart 32362 32362 0 0.00 1 10084 36 0 0
sp_fill_shopping_cart_clo_res 476339 476339 0 0.00 0 13876 10 0 0
sp_get_clo_for_invoice 5160 5160 0 0.00 0 23566 286 0 0
sp_kill_cost_storno 17586 17586 0 0.00 0 6194 42 0 0
sp_kill_qstorno_ret_qs2qd 1214423 1214423 0 0.00 0 25624 26 0 0
sp_kill_qty_storno 639209 639209 0 0.00 0 25624 57 0 0
sp_lock_selected_doc 21158 21158 0 0.00 0 7132 3 0 0
sp_make_cost_storno 28602 28602 0 0.00 0 25376 46 0 0
sp_multiply_rows_for_pdistr 28602 28602 0 0.00 0 7942 22 0 0
sp_multiply_rows_for_qdistr 21087 21087 0 0.00 0 24220 153 0 0
sp_pay_from_customer 7193 7193 0 0.00 4 46568 117 0 0
sp_pay_to_supplier 6030 6030 0 0.00 5 15438 107 0 0
sp_payment_common 13223 13223 0 0.00 1 46563 28 0 0
sp_qd_handle_on_cancel_clo 1755 1755 0 0.00 0 13286 270 0 0
sp_qd_handle_on_invoice_upd_sts 11274 11274 0 0.00 0 17246 267 0 0
sp_qd_handle_on_reserve_upd_sts 10219 10219 0 0.00 0 17901 52 0 0
sp_reserve_write_off 10219 10219 0 0.00 6 26365 152 0 0
sp_supplier_invoice 5539 5539 0 0.00 6 68135 2809 0 0
sp_supplier_order 5745 5745 0 0.00 5 38145 1181 0 0
srv_aggregate_perf_data 114 114 0 0.00 2125 313010 20260 0 0
srv_fill_mon_cache_memory 59 59 0 0.00 2356 200163 54344 0 0
srv_find_qd_qs_mism 1115350 1115350 0 0.00 0 27307 4 0 0
srv_make_invnt_saldo 579 579 0 0.00 25 54609 1141 0 0
srv_make_money_saldo 782 782 0 0.00 1 4916 29 0 0
srv_recalc_idx_stat 4 4 0 0.00 172642 499457 332438 0 0
t$perf-abend:sp_add_invoice_to_stock 63 63 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5166 5166 0 0.00 0 25520 162 0 0
t$perf-norm:sp_cancel_adding_invoice 398 398 0 0.00 0 3199 87 0 0
t$perf-norm:sp_cancel_client_order 1755 1755 0 0.00 0 657 6 0 0
t$perf-norm:sp_cancel_customer_reserve 701 701 0 0.00 0 1535 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 713 713 0 0.00 0 3460 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 407 407 0 0.00 0 295 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 375 375 0 0.00 0 1368 13 0 0
t$perf-norm:sp_cancel_supplier_order 428 428 0 0.00 0 31598 1353 0 0
t$perf-norm:sp_cancel_write_off 1003 1003 0 0.00 0 2697 10 0 0
t$perf-norm:sp_client_order 21087 21087 0 0.00 0 11895 14 0 0
t$perf-norm:sp_customer_reserve 999 999 0 0.00 0 2346 6 0 0
t$perf-norm:sp_pay_from_customer 7193 7193 0 0.00 0 20606 13 0 0
t$perf-norm:sp_pay_to_supplier 6030 6030 0 0.00 0 4420 9 0 0
t$perf-norm:sp_reserve_write_off 10219 10219 0 0.00 0 4958 11 0 0
t$perf-norm:sp_supplier_invoice 5539 5539 0 0.00 0 5946 17 0 0
t$perf-norm:sp_supplier_order 5745 5745 0 0.00 0 5810 13 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 387 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 59 59 0 0.00 0 587 10 0 0
t$perf-norm:srv_make_invnt_saldo 579 579 0 0.00 0 1982 16 0 0
t$perf-norm:srv_make_money_saldo 782 782 0 0.00 0 395 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 2 0 0
v_add_invoice_to_stock 10444 10318 126 1.20 0 3397 14 0 126
v_all_customers 38496 38496 0 0.00 0 164 1 0 0
v_all_suppliers 31350 31350 0 0.00 0 24 1 0 0
v_all_wares 42174 42174 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 796 796 0 0.00 0 5880 15 0 0
v_cancel_client_order 3510 3510 0 0.00 0 1864 4 0 0
v_cancel_customer_prepayment 1426 1426 0 0.00 0 527 3 0 0
v_cancel_customer_reserve 1402 1402 0 0.00 0 1160 5 0 0
v_cancel_payment_to_supplier 814 814 0 0.00 0 2269 7 0 0
v_cancel_supplier_invoice 764 750 14 1.83 0 588 12 0 14
v_cancel_supplier_order 856 856 0 0.00 0 742 3 0 0
v_cancel_write_off 2006 2006 0 0.00 0 2417 12 0 0
v_max_id_clo_ord 5745 5745 0 0.00 0 183 1 0 0
v_max_id_clo_res 999 999 0 0.00 0 9707 26 0 0
v_max_id_ord_sup 5530 5530 0 0.00 0 4793 4 0 0
v_max_non_paid_invoice 1630 1630 0 0.00 0 814 3 0 0
v_max_non_paid_realizn 3778 3778 0 0.00 0 845 3 0 0
v_min_id_clo_ord 5745 5745 0 0.00 0 4192 4 0 0
v_min_id_clo_res 999 999 0 0.00 0 2849 9 0 0
v_min_id_ord_sup 5530 5530 0 0.00 0 4406 6 0 0
v_min_non_paid_invoice 6030 6030 0 0.00 0 10873 15 0 0
v_min_non_paid_realizn 7193 7193 0 0.00 0 6637 12 0 0
v_our_firm 10508 10508 0 0.00 0 12 1 0 0
v_reserve_write_off 20438 20438 0 0.00 0 5403 6 0 0
x_lock_depdocs_on_canc_invoice 12458 12458 0 0.00 0 36983 117 0 0
x_lock_depdocs_on_canc_sup_ord 428 428 0 0.00 0 2350 283 0 0
x_make_qstorno_1000_1200 5745 5745 0 0.00 1 38006 1117 0 0
x_make_qstorno_1200_2000 5539 5539 0 0.00 3 68126 2673 0 0
x_make_qstorno_2100_3300 476342 476342 0 0.00 0 157088 144 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-10-2020 01:28:04 to 14-10-2020 01:28:08.

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:02 2 8192000000 8798422528 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
22:58:03 23119 8192000000 10375758544 10700689408 1431268384 14.872 100 89 178 1 1466119824 6060768 28790672
22:59:40 22476 8192000000 10459584464 10793648128 1511501056 15.576 100 94 188 1 1550342208 8971568 29869584
23:01:10 14812 8192000000 10498752720 10849030144 1562237056 16.015 100 84 168 2 1599149760 10036368 26876336
23:02:26 15444 8192000000 10577798560 10941702144 1638621104 16.668 100 77 154 1 1672002832 10259264 23122464
23:03:46 4342 8192000000 10662886368 11035144192 1729436080 17.431 100 83 166 2 1765363856 12596480 23331296
23:04:51 3667 8192000000 10723782816 11106504704 1788175136 17.917 100 89 178 1 1827572592 14369264 25028192
23:05:57 4927 8192000000 10780073824 11169046528 1834513520 18.296 100 77 155 1 1869330832 13355664 21461648
23:07:02 1580 8192000000 10807879488 11201392640 1869706464 18.582 100 81 162 4 1904193472 11524928 22962080
23:08:05 7623 8192000000 10791359712 11213578240 1875340336 18.627 100 31 62 0 1890925376 7039776 8545264
23:09:16 26565 8192000000 10827483056 11235987456 1890705904 18.751 100 61 122 0 1917986944 9728144 17552896
23:10:43 4434 8192000000 10821663616 11248500736 1892733152 18.768 100 29 58 0 1912040448 11408432 7898864
23:11:48 5614 8192000000 10832337632 11256668160 1903438064 18.854 100 29 58 1 1918345712 6636784 8270864
23:12:59 59448 8192000000 10832899024 11267231744 1915708048 18.952 100 33 66 0 1931310496 5870528 9731920
23:15:01 5588 8192000000 10826582864 11245629440 1881821664 18.680 99 43 87 0 1900898544 7002384 12074496
23:16:08 6819 8192000000 10776266512 11197390848 1801513440 18.026 95 37 74 1 1819079360 6465584 11100336
23:17:18 71290 8192000000 10703357728 11131092992 1622891168 16.534 88 31 62 3 1636723600 4278288 9554144
23:19:34 8566 8192000000 10724739184 11144617984 1768813200 17.757 97 31 62 1 1787479696 10293744 8372752
23:20:43 7614 8192000000 10723766848 11144028160 1770745712 17.773 97 27 54 1 1784515120 5991040 7778368
23:21:52 74210 8192000000 10752373136 11187838976 1850855040 18.429 100 28 56 1 1865202576 5649520 8698016
23:24:07 9282 8192000000 10784880240 11213271040 1866991040 18.560 100 29 58 1 1881081776 6170624 7920112
23:25:21 6714 8192000000 10789180416 11223699456 1873874352 18.616 100 25 50 0 1886730384 4915136 7940896
23:26:28 13950 8192000000 10803412176 11233423360 1886532528 18.718 100 24 48 1 1899217568 5706832 6978208
23:28:13 25728 8192000000 10821050336 11249360896 1897186032 18.804 99 45 90 0 1919038768 8275920 13576816
23:29:46 5249 8192000000 10876347904 11300249600 1942014192 19.163 100 55 110 1 1970328240 12721712 15592336
23:30:56 14357 8192000000 10881796208 11316097024 1953977136 19.258 100 52 104 3 1985610912 15760032 15873744
23:32:15 7945 8192000000 10898357280 11326590976 1961281328 19.316 100 44 88 2 1988035312 13730464 13023520
23:33:39 22864 8192000000 10915859184 11348639744 1961916912 19.321 99 47 94 0 1995222752 19417744 13888096
23:35:05 13539 8192000000 10904511776 11330195456 1942666624 19.168 99 53 104 2 1980478016 21438144 16373248
23:36:21 2872 8192000000 10913424464 11332108288 1956703424 19.280 100 61 122 0 2001512544 26374064 18435056
23:37:29 4268 8192000000 10884847488 11300868096 1925197520 19.028 98 69 138 2 1974973152 28997360 20778272
23:38:37 5030 8192000000 10914108656 11330576384 1936359792 19.118 99 75 150 0 1990876512 31650816 22865904
23:39:44 3193 8192000000 10890335616 11305353216 1918983168 18.979 99 56 108 1 1964402928 29072496 16347264
23:40:51 2355 8192000000 10897246256 11311640576 1931665376 19.080 100 68 136 2 1987117136 34646656 20805104
23:42:00 2951 8192000000 10939933840 11345395712 1951089360 19.235 100 87 175 0 2018065200 40043536 26932304
23:43:07 9413 8192000000 10912742096 11339497472 1930960112 19.075 99 47 95 3 1982376096 35996128 15419856
23:44:25 3706 8192000000 10948036848 11364646912 1958169696 19.291 100 71 142 0 2028052064 48033808 21848560
23:45:34 14146 8192000000 10945766288 11375620096 1956006128 19.274 100 44 86 1 2027266048 56519040 14740880
23:46:50 23706 8192000000 10948584112 11392761856 1961872576 19.321 100 39 78 1 2035934720 59528320 14533824
23:48:30 21109 8192000000 10928230064 11390312448 1959645824 19.303 100 22 44 3 2014323760 47194032 7483904
23:49:59 26226 8192000000 10892651008 11361730560 1917186576 18.964 99 16 32 0 1978189968 54944656 6058736
23:52:34 102624 8192000000 10866839376 11335401472 1918712432 18.977 99 8 17 0 1939904784 18855536 2336816
23:55:18 37206 8192000000 10886183184 11340718080 1952349232 19.245 100 34 68 0 1972981440 11328448 9303760
23:56:59 172621 8192000000 10866338096 11327295488 1881668000 18.679 95 10 20 0 1890825504 6971808 2185696
00:00:52 200163 8192000000 10899452240 11363151872 1896511376 18.798 96 12 22 2 1906400592 7032096 2857120
00:05:40 87648 8192000000 10917968672 11391008768 1969690944 19.383 99 7 14 1 1985306144 13464096 2151104
00:08:10 45575 8192000000 10942991456 11404197888 2003139872 19.647 100 27 53 0 2023632688 12212800 8280016
00:09:56 79284 8192000000 10931196736 11381317632 1960533312 19.310 98 14 28 0 1975664864 11231984 3899568
00:12:24 94635 8192000000 10904970256 11387023360 1915006768 18.947 96 8 16 1 1927639344 10380544 2252032
00:15:08 82452 8192000000 10924030720 11391426560 1950038288 19.227 98 19 38 0 1972959536 17472128 5449120
00:17:37 55947 8192000000 10883663328 11364761600 1950748160 19.232 99 8 16 0 1968295216 14872720 2674336
00:19:33 125401 8192000000 10914673696 11381043200 1966276960 19.356 99 18 36 1 1986890000 15468992 5144048
00:22:44 93706 8192000000 10882825376 11357298688 1888612560 18.735 95 37 74 1 1912728112 13651824 10463728
00:25:35 53550 8192000000 10842703664 11310194688 1867427392 18.563 95 11 22 0 1879306752 8725120 3154240
00:27:34 47581 8192000000 10874425920 11339202560 1892425520 18.765 96 13 26 0 1907783872 11731696 3626656
00:29:22 71169 8192000000 10843530112 11317481472 1907740080 18.889 98 15 30 1 1929232736 16797488 4695168
00:31:42 60101 8192000000 10851105920 11327561728 1899012352 18.818 98 8 16 0 1912243072 11119840 2110880
00:33:43 11733 8192000000 10860571600 11310051328 1926640752 19.040 100 35 70 3 1947539248 11070240 9828256
00:34:55 76166 8192000000 10889939216 11334770688 1901663456 18.840 97 21 42 1 1924160912 16334896 6162560
00:37:14 54009 8192000000 10876644832 11330867200 1891959456 18.762 97 22 44 1 1908859152 10776832 6122864
00:39:14 55541 8192000000 10869124832 11335876608 1916460032 18.958 99 18 36 1 1939455408 17828080 5167296
00:41:15 32118 8192000000 10901875584 11357036544 1953718752 19.256 100 29 58 0 1974480784 12646928 8115104
00:42:57 70165 8192000000 10884999440 11348979712 1925230544 19.029 99 15 30 0 1949310880 20106672 3973664
00:45:07 108382 8192000000 10889524848 11368235008 1922842992 19.010 98 11 22 0 1944435840 18739808 2853040
00:48:21 83233 8192000000 10899558176 11366076416 1966516384 19.358 100 26 52 2 1986070768 11774144 7780240
00:50:50 72745 8192000000 10879348624 11360980992 1944026816 19.179 99 7 14 1 1959806704 14096640 1683248
00:53:08 47683 8192000000 10875558624 11352981504 1906355296 18.877 97 5 10 1 1914909536 7261056 1293184
00:55:03 79346 8192000000 10898760624 11380375552 1982276704 19.483 100 15 30 0 1999097216 12079632 4740880
00:57:28 82241 8192000000 10885277456 11365908480 1918408208 18.974 97 7 13 2 1931228480 11314096 1506176
00:59:51 57289 8192000000 10890489808 11361263616 1919806432 18.985 97 10 20 0 1928897104 6386272 2704400
01:01:50 52257 8192000000 10887880560 11366461440 1965855280 19.353 100 17 34 1 1985057728 14339984 4862464
01:03:48 58527 8192000000 10879302000 11360772096 1938435248 19.134 98 26 52 0 1958962144 12472880 8054016
01:05:56 120288 8192000000 10888913760 11371196416 1878148224 18.650 95 18 36 0 1900248016 16820560 5279232
01:08:57 61966 8192000000 10874794576 11352068096 1916482384 18.959 98 18 36 1 1939678464 18355888 4840192
01:10:59 57689 8192000000 10870288128 11339530240 1893786304 18.776 97 23 46 0 1914817456 14864496 6166656
01:12:57 114077 8192000000 10848643232 11319930880 1906213024 18.876 98 21 42 1 1926852416 14400720 6238672
01:15:54 32985 8192000000 10831865552 11308969984 1887381424 18.725 98 9 17 0 1898214464 8507424 2325616
01:17:34 54839 8192000000 10846038544 11331284992 1929476848 19.063 100 9 17 2 1942489616 10234080 2778688
01:19:58 52183 8192000000 10844687104 11320569856 1858453456 18.491 95 26 47 5 1876610256 10775552 7381248
01:21:53 66711 8192000000 10843174880 11302440960 1817235632 18.155 94 14 28 0 1831720048 9689600 4794816
01:24:03 41395 8192000000 10857731504 11323179008 1912796496 18.929 99 26 52 1 1931720304 10961024 7962784
01:26:20 44312 8192000000 9671825472 10012786688 799549168 8.892 42 11 22 1 809601296 7033808 3018320

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 63 v_add_invoice_to_stock 335544517
user_exc 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-10-2020 01:28:25 to 14-10-2020 01:28:27.

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          249695
MON$OLDEST_ACTIVE               250678
MON$OLDEST_SNAPSHOT             250678
MON$NEXT_TRANSACTION            250678
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                       2028064
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.33374 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.7.33374 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.7.33374 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.7.33374 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 ---
:121: 
:122: mscs-test-161.budzdorov.ru    Wed Oct 14 01:27:06 2020
:123:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:124: 
:125: 
:126: mscs-test-161.budzdorov.ru    Wed Oct 14 01:27:07 2020
:127:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:128: 
--- end of diff output ---

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

14.10.20 01:28:28. 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-14 01:28:31 No severe PSQL-related problems occured
14.10.20 01:28:32. End of report.