Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.10.2020 16:31
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 15785936 kB
MemAvailable 31564384 kB
Buffers 0 kB
Cached 15807824 kB
SwapCached 5464 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-12 16:31:20
DTS_END                         2020-10-12 16:31:20.5920
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(56:17)
IP                              ::1/41688
TRN_ID                          231160
ATT_ID                          642
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: 9100.75 21188 1092090
customer order: creation 164.35 366 19722
customer order: refuse 14.35 343 1722
order to supplier: creation 44.00 1338 5280
order to supplier: removal 3.41 110943 409
invoice (draft): creation 43.80 3720 5256
invoice (draft): removal 53.43 861 6412
invoice accept: apply 39.00 19092 4680
invoice accept: cancel 48.54 891 5825
customer reserve: creation 3653.66 186 438439
customer reserve: removal 4749.03 61 569884
realization accept: apply 79.43 172 9531
realization accept: cancel 85.13 108 10215
payment from customer: creation 55.73 102 6687
payment from customer: removal 5.20 92 624
payment to supplier: creation 46.58 138 5590
payment to supplier: removal 3.38 77 405
service: total inventory turnovers 4.64 1072 557
service: total monetary turnovers 6.30 25 756
service: aggregate perf. data 0.77 24867 92
service: refresh index statistics 0.03 259314 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 12-10-2020 16:32:01 to 12-10-2020 16:32:04.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4689
TEST_TIME 2 3374
TEST_TIME 3 3506
TEST_TIME 4 4268
TEST_TIME 5 4439
TEST_TIME 6 4723
TEST_TIME 7 4555
TEST_TIME 8 4436
TEST_TIME 9 4979
TEST_TIME 10 5228
TEST_TIME 11 5469
TEST_TIME 12 5571
TEST_TIME 13 5491
TEST_TIME 14 5496
TEST_TIME 15 5624
TEST_TIME 16 5801
TEST_TIME 17 6036
TEST_TIME 18 6155
TEST_TIME 19 6348
TEST_TIME 20 6728
TEST_TIME 21 6969
TEST_TIME 22 7258
TEST_TIME 23 7862
TEST_TIME 24 7694
TEST_TIME 25 7871
TEST_TIME 26 8479
TEST_TIME 27 8543
TEST_TIME 28 9070
TEST_TIME 29 9747
TEST_TIME 30 10186
TEST_TIME 31 10081
TEST_TIME 32 9956
TEST_TIME 33 9824
TEST_TIME 34 9914
TEST_TIME 35 9762
TEST_TIME 36 9792
TEST_TIME 37 9667
TEST_TIME 38 9648
TEST_TIME 39 9498
TEST_TIME 40 9460
TEST_TIME 41 9525
TEST_TIME 42 9396
TEST_TIME 43 9251
TEST_TIME 44 9068
TEST_TIME 45 9170
TEST_TIME 46 9110
TEST_TIME 47 9336
TEST_TIME 48 9263
TEST_TIME 49 9266
TEST_TIME 50 9268
TEST_TIME 51 9187
TEST_TIME 52 9091
TEST_TIME 53 9115
TEST_TIME 54 9074
TEST_TIME 55 9038
TEST_TIME 56 9051
TEST_TIME 57 9040
TEST_TIME 58 9052
TEST_TIME 59 9061
TEST_TIME 60 8995
TEST_TIME 61 9122
TEST_TIME 62 9148
TEST_TIME 63 9183
TEST_TIME 64 9221
TEST_TIME 65 9277
TEST_TIME 66 9386
TEST_TIME 67 9420
TEST_TIME 68 9394
TEST_TIME 69 9398
TEST_TIME 70 9445
TEST_TIME 71 9477
TEST_TIME 72 9400
TEST_TIME 73 9313
TEST_TIME 74 9278
TEST_TIME 75 9272
TEST_TIME 76 9238
TEST_TIME 77 9201
TEST_TIME 78 9178
TEST_TIME 79 9139
TEST_TIME 80 9076
TEST_TIME 81 9082
TEST_TIME 82 9097
TEST_TIME 83 9048
TEST_TIME 84 9139
TEST_TIME 85 9104
TEST_TIME 86 9160
TEST_TIME 87 9174
TEST_TIME 88 9133
TEST_TIME 89 9224
TEST_TIME 90 9240
TEST_TIME 91 9221
TEST_TIME 92 9292
TEST_TIME 93 9297
TEST_TIME 94 9283
TEST_TIME 95 9347
TEST_TIME 96 9355
TEST_TIME 97 9327
TEST_TIME 98 9246
TEST_TIME 99 9231
TEST_TIME 100 9239
TEST_TIME 101 9217
TEST_TIME 102 9184
TEST_TIME 103 9163
TEST_TIME 104 9122
TEST_TIME 105 9117
TEST_TIME 106 9113
TEST_TIME 107 9046
TEST_TIME 108 9010
TEST_TIME 109 9036
TEST_TIME 110 9024
TEST_TIME 111 9021
TEST_TIME 112 9038
TEST_TIME 113 9084
TEST_TIME 114 9074
TEST_TIME 115 9077
TEST_TIME 116 9066
TEST_TIME 117 9099
TEST_TIME 118 9137
TEST_TIME 119 9148
TEST_TIME 120 9102

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-10-2020 16:32:05 to 12-10-2020 16:32:08.

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 1499579 1499579 0 0.00 0 24101 6 0 0
doc_list_biud 1499577 1499577 0 0.00 0 74282 30 0 0
sp_add_invoice_to_stock 4739 4680 59 1.24 21 160139 19093 0 59
sp_cancel_adding_invoice 5825 5825 0 0.00 5 76013 892 0 0
sp_cancel_client_order 1722 1722 0 0.00 4 10909 343 0 0
sp_cancel_customer_reserve 569884 569884 0 0.00 1 74286 62 0 0
sp_cancel_pay_from_customer 624 624 0 0.00 3 4141 93 0 0
sp_cancel_pay_to_supplier 405 405 0 0.00 3 3862 77 0 0
sp_cancel_supplier_invoice 6415 6412 3 0.04 1 19830 861 0 3
sp_cancel_supplier_order 409 409 0 0.00 8 1254915 110944 0 0
sp_cancel_write_off 10215 10215 0 0.00 1 8275 108 0 0
sp_client_order 19722 19722 0 0.00 3 34886 367 0 0
sp_customer_reserve 438439 438439 0 0.00 0 65637 187 0 0
sp_fill_shopping_cart 30248 30248 0 0.00 1 19912 41 0 0
sp_fill_shopping_cart_clo_res 438428 438428 0 0.00 0 16449 15 0 0
sp_get_clo_for_invoice 4667 4667 0 0.00 0 16082 314 0 0
sp_kill_cost_storno 17069 17069 0 0.00 0 7222 59 0 0
sp_kill_qstorno_ret_qs2qd 1156804 1156804 0 0.00 0 74278 34 0 0
sp_kill_qty_storno 608665 608665 0 0.00 0 74282 73 0 0
sp_lock_selected_doc 19729 19729 0 0.00 0 7830 6 0 0
sp_make_cost_storno 26475 26475 0 0.00 0 24098 55 0 0
sp_multiply_rows_for_pdistr 26475 26475 0 0.00 0 6164 27 0 0
sp_multiply_rows_for_qdistr 19722 19722 0 0.00 0 34874 166 0 0
sp_pay_from_customer 6687 6687 0 0.00 4 17428 102 0 0
sp_pay_to_supplier 5590 5590 0 0.00 5 24104 139 0 0
sp_payment_common 12277 12277 0 0.00 1 21078 28 0 0
sp_qd_handle_on_cancel_clo 1722 1722 0 0.00 0 10835 312 0 0
sp_qd_handle_on_invoice_upd_sts 10492 10492 0 0.00 0 21530 375 0 0
sp_qd_handle_on_reserve_upd_sts 9531 9531 0 0.00 0 17574 54 0 0
sp_reserve_write_off 9531 9531 0 0.00 6 17606 173 0 0
sp_supplier_invoice 5256 5256 0 0.00 6 91820 3721 0 0
sp_supplier_order 5280 5280 0 0.00 6 30925 1338 0 0
srv_aggregate_perf_data 92 92 0 0.00 2355 427168 24867 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 3136 140385 44721 0 0
srv_find_qd_qs_mism 1047475 1047475 0 0.00 0 22580 5 0 0
srv_make_invnt_saldo 557 557 0 0.00 31 58555 1072 0 0
srv_make_money_saldo 756 756 0 0.00 1 918 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 164478 322493 259315 0 0
t$perf-abend:sp_add_invoice_to_stock 59 59 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4680 4680 0 0.00 0 9910 186 0 0
t$perf-norm:sp_cancel_adding_invoice 394 394 0 0.00 0 7706 122 0 0
t$perf-norm:sp_cancel_client_order 1722 1722 0 0.00 0 3893 19 0 0
t$perf-norm:sp_cancel_customer_reserve 642 642 0 0.00 0 1226 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 624 624 0 0.00 0 853 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 405 405 0 0.00 0 428 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 363 363 0 0.00 0 596 10 0 0
t$perf-norm:sp_cancel_supplier_order 409 409 0 0.00 0 25434 1955 0 0
t$perf-norm:sp_cancel_write_off 972 972 0 0.00 0 2030 11 0 0
t$perf-norm:sp_client_order 19722 19722 0 0.00 0 6981 22 0 0
t$perf-norm:sp_customer_reserve 889 889 0 0.00 0 6874 13 0 0
t$perf-norm:sp_pay_from_customer 6687 6687 0 0.00 0 4522 10 0 0
t$perf-norm:sp_pay_to_supplier 5590 5590 0 0.00 0 7126 11 0 0
t$perf-norm:sp_reserve_write_off 9531 9531 0 0.00 0 6842 17 0 0
t$perf-norm:sp_supplier_invoice 5256 5256 0 0.00 0 5769 21 0 0
t$perf-norm:sp_supplier_order 5280 5280 0 0.00 0 6908 24 0 0
t$perf-norm:srv_aggregate_perf_data 92 92 0 0.00 0 4027 67 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 811 13 0 0
t$perf-norm:srv_make_invnt_saldo 557 557 0 0.00 0 4207 39 0 0
t$perf-norm:srv_make_money_saldo 756 756 0 0.00 0 5545 28 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 3 0 0
v_add_invoice_to_stock 9452 9334 118 1.24 0 9640 23 0 118
v_all_customers 35966 35966 0 0.00 0 110 1 0 0
v_all_suppliers 29224 29224 0 0.00 0 122 1 0 0
v_all_wares 39444 39444 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 788 788 0 0.00 0 1249 9 0 0
v_cancel_client_order 3444 3444 0 0.00 0 6123 7 0 0
v_cancel_customer_prepayment 1248 1248 0 0.00 0 1099 5 0 0
v_cancel_customer_reserve 1284 1284 0 0.00 0 5631 11 0 0
v_cancel_payment_to_supplier 810 810 0 0.00 0 3855 10 0 0
v_cancel_supplier_invoice 732 726 6 0.81 0 623 14 0 6
v_cancel_supplier_order 818 818 0 0.00 0 131 2 0 0
v_cancel_write_off 1944 1944 0 0.00 0 6261 17 0 0
v_max_id_clo_ord 5273 5273 0 0.00 0 6595 3 0 0
v_max_id_clo_res 889 889 0 0.00 0 2021 10 0 0
v_max_id_ord_sup 5253 5253 0 0.00 0 2723 3 0 0
v_max_non_paid_invoice 1488 1488 0 0.00 0 280 2 0 0
v_max_non_paid_realizn 3409 3409 0 0.00 0 4230 4 0 0
v_min_id_clo_ord 5273 5273 0 0.00 0 3474 5 0 0
v_min_id_clo_res 889 889 0 0.00 0 1822 5 0 0
v_min_id_ord_sup 5253 5253 0 0.00 0 6451 20 0 0
v_min_non_paid_invoice 5590 5590 0 0.00 0 21073 22 0 0
v_min_non_paid_realizn 6687 6687 0 0.00 0 4455 13 0 0
v_our_firm 10034 10034 0 0.00 0 23 1 0 0
v_reserve_write_off 19062 19062 0 0.00 0 5668 6 0 0
x_lock_depdocs_on_canc_invoice 11874 11874 0 0.00 0 24837 128 0 0
x_lock_depdocs_on_canc_sup_ord 409 409 0 0.00 0 6817 358 0 0
x_make_qstorno_1000_1200 5280 5280 0 0.00 2 20709 1257 0 0
x_make_qstorno_1200_2000 5256 5256 0 0.00 4 91794 3571 0 0
x_make_qstorno_2100_3300 438439 438439 0 0.00 0 65636 169 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-10-2020 16:32:09 to 12-10-2020 16:32:13.

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
14:01:26 275 8192000000 8978465088 9034125312 152197968 1.823 24 23 25 0 153644960 294000 1152992
14:02:29 32530 8192000000 10359026000 10680389632 1420762272 14.779 100 86 172 0 1454755392 5781616 28211504
14:04:10 31387 8192000000 10414974000 10745683968 1469464016 15.209 100 86 172 0 1504554960 7895104 27195840
14:05:59 10083 8192000000 10457054608 10799284224 1517331568 15.627 100 76 152 0 1549702560 8990832 23380160
14:07:13 8797 8192000000 10537279680 10896920576 1603273504 16.367 100 73 144 0 1635154064 10830336 21050224
14:08:24 7962 8192000000 10612928432 10981244928 1676319280 16.986 100 87 174 0 1714433264 13195520 24918464
14:09:33 3606 8192000000 10675207120 11051945984 1735791984 17.484 100 77 153 1 1772134176 13649584 22692608
14:10:41 5220 8192000000 10727793248 11115749376 1786875312 17.906 100 76 150 2 1820308736 12228800 21204624
14:11:48 1816 8192000000 10789794256 11175505920 1843695392 18.371 100 93 186 0 1883098656 14069424 25333840
14:12:52 36234 8192000000 10793737456 11202330624 1867404352 18.563 100 61 122 1 1894172960 9215696 17552912
14:14:30 5919 8192000000 10796750832 11221409792 1884741168 18.703 100 33 66 1 1900969200 7117568 9110464
14:15:37 5522 8192000000 10809946048 11234885632 1894309792 18.780 100 28 56 1 1908578992 6490336 7778864
14:16:47 13293 8192000000 10800987616 11241533440 1902084288 18.843 100 22 44 0 1914916048 6872496 5959264
14:18:02 41098 8192000000 10857424336 11267579904 1920238000 18.989 100 65 131 1 1945956400 6237856 19480544
14:19:43 7422 8192000000 10835475952 11266838528 1915888192 18.954 100 25 50 2 1930298016 7078384 7331440
14:20:51 7610 8192000000 10833828432 11273744384 1915416320 18.950 100 20 41 0 1930882768 9850560 5615888
14:22:00 58094 8192000000 10766798224 11204239360 1843018464 18.365 97 32 65 2 1858371440 6339984 9012992
14:24:03 3794 8192000000 10750640048 11167715328 1815865616 18.144 99 47 94 0 1836578512 7722448 12990448
14:25:10 4442 8192000000 10719667504 11136192512 1799199024 18.007 100 14 28 0 1808390032 5517344 3673664
14:26:18 16353 8192000000 10727162848 11140165632 1788536272 17.920 99 33 68 4 1804801360 5424608 10840480
14:27:34 19614 8192000000 10730309984 11154534400 1816684272 18.151 100 18 36 1 1827684448 5990736 5009440
14:28:56 9392 8192000000 10734993696 11162923008 1814582128 18.133 99 21 42 2 1825621504 5367408 5671968
14:30:06 8615 8192000000 10774536128 11204243456 1857628032 18.484 100 23 46 0 1870020624 5578240 6814352
14:31:14 103290 8192000000 10819772576 11246735360 1888416880 18.733 100 33 66 2 1903530032 5886736 9226416
14:34:28 29995 8192000000 10853175712 11282075648 1923219472 19.013 100 50 100 3 1949734224 12336720 14178032
14:36:04 13839 8192000000 10877841792 11306500096 1944011760 19.179 100 41 82 0 1968087840 11997072 12079008
14:37:23 24428 8192000000 10894004576 11328806912 1964950912 19.345 100 50 99 1 1992767808 12323184 15493712
14:39:02 7793 8192000000 10922252480 11349708800 1976321968 19.436 100 47 92 0 2006036400 16169328 13545104
14:40:14 29040 8192000000 10924501024 11359252480 1981349520 19.475 100 55 108 1 2024343776 26216224 16778032
14:41:49 4470 8192000000 10950659488 11378184192 1994335776 19.578 100 64 127 1 2038742256 24527152 19879328
14:42:58 10247 8192000000 10963048784 11396431872 2004579152 19.659 100 56 112 2 2052342432 31199696 16563584
14:44:19 6118 8192000000 10975247360 11408420864 2009845344 19.700 100 59 117 1 2062083904 35025376 17213184
14:45:28 3596 8192000000 11003989504 11423809536 2023944352 19.811 100 79 156 2 2085479632 38047072 23488208
14:46:36 9902 8192000000 10994397056 11420733440 2003128000 19.647 99 67 134 3 2067391024 43767408 20495616
14:47:50 3475 8192000000 10982148480 11402153984 1998961136 19.615 100 84 163 1 2068442672 44023504 25458032
14:48:59 5035 8192000000 10995269248 11425591296 2016659536 19.754 100 65 130 3 2082765712 44885808 21220368
14:50:11 3135 8192000000 10988342064 11411005440 2000116576 19.624 100 72 144 3 2072663008 49326320 23220112
14:51:18 3578 8192000000 10971281040 11385675776 1961795584 19.320 99 88 175 1 2043519728 54449088 27275056
14:52:30 10315 8192000000 10943771840 11381760000 1965492864 19.350 100 53 106 0 2035498160 52389360 17615936
14:53:49 5446 8192000000 10956660336 11384025088 1962336064 19.325 100 61 121 1 2039540416 57365280 19839072
14:55:01 5575 8192000000 10963392816 11387932672 1971312528 19.396 100 56 110 1 2032453472 42959008 18181936
14:56:11 7191 8192000000 10955964608 11381202944 1943800624 19.177 99 70 140 1 2021858112 55611824 22445664
14:57:21 36554 8192000000 10881278192 11331334144 1860015328 18.503 96 23 46 1 1920931296 52964448 7951520
14:59:04 36214 8192000000 10872870224 11327848448 1904628672 18.864 100 18 36 0 1963934464 52409136 6896656
15:00:40 56561 8192000000 10851471872 11313541120 1790818944 17.939 94 19 38 0 1841974944 44891536 6264464
15:02:39 14727 8192000000 10870313616 11297464320 1907876432 18.890 99 48 95 2 1933439792 11912704 13650656
15:04:25 31929 8192000000 10858353520 11305521152 1907298224 18.885 99 41 81 1 1935512128 16767200 11446704
15:06:00 30972 8192000000 10850748784 11299475456 1916359888 18.958 99 28 56 1 1933098864 8741008 7997968
15:07:39 78064 8192000000 10867683680 11320823808 1901068016 18.835 98 8 16 0 1911445488 8295056 2082416
15:10:33 78315 8192000000 10893235856 11357351936 1951258016 19.236 100 22 42 3 1973708144 16609376 5840752
15:13:04 31797 8192000000 10882223872 11339497472 1933048096 19.091 98 23 46 2 1956130256 16711040 6371120
15:14:36 103154 8192000000 10899010512 11364847616 1954809888 19.265 100 18 37 1 1986378832 25827696 5741248
15:17:33 42722 8192000000 10875732656 11381309440 1935157872 19.108 99 9 18 0 1974035616 35757424 3120320
15:19:18 35273 8192000000 10868480112 11329064960 1940725600 19.153 100 29 58 0 1961222016 12573040 7923376
15:20:55 37972 8192000000 10880821968 11345555456 1953357440 19.253 100 20 40 1 1972497168 12944592 6195136
15:22:40 77428 8192000000 10902757088 11363647488 1967949456 19.369 100 18 36 1 1985717200 12431312 5336432
15:25:04 52942 8192000000 10896389840 11370102784 1955938864 19.274 99 21 42 1 1977522928 14963840 6620224
15:27:06 67395 8192000000 10887089648 11369484288 1953589184 19.255 99 10 20 1 1969675584 12776352 3310048
15:29:15 74120 8192000000 10906265392 11384360960 1982099456 19.481 100 12 24 0 1994742512 9276784 3366272
15:31:29 67352 8192000000 10911064992 11382468608 1945502752 19.191 97 23 46 0 1962932560 11264192 6165616
15:33:51 48006 8192000000 10907508064 11383709696 1969283632 19.380 99 6 12 0 1979420672 8831568 1305472
15:35:47 77060 8192000000 10915838768 11394658304 1934170064 19.100 97 12 24 1 1957360848 19881392 3309392
15:38:08 68755 8192000000 10901263696 11375427584 1949491008 19.222 99 9 18 1 1966694144 14475712 2727424
15:40:20 89979 8192000000 10879934304 11350933504 1867487696 18.564 95 11 22 2 1882613376 12303232 2822448
15:42:50 61377 8192000000 10903367136 11353403392 1932108800 19.084 98 19 36 0 1947736640 10074256 5553584
15:44:58 67580 8192000000 10876442160 11333840896 1901853568 18.841 97 26 52 1 1916437312 7372768 7210976
15:47:12 20597 8192000000 10849073696 11310739456 1921866864 19.002 99 30 58 1 1940590256 10650016 8073376
15:48:34 53743 8192000000 10845058304 11313729536 1881338656 18.676 97 20 40 2 1894204848 7107024 5759168
15:50:28 37086 8192000000 10856857376 11319025664 1906121104 18.875 98 19 38 2 1919778688 7939376 5718208
15:52:16 85558 8192000000 10846522320 11320107008 1872081488 18.601 96 20 40 1 1892694256 14121056 6491712
15:54:44 14607 8192000000 10872746480 11328450560 1930949200 19.074 100 46 91 3 1961995984 16689152 14357632
15:56:02 38968 8192000000 10846593888 11304804352 1872674032 18.606 97 28 54 1 1889568256 8899232 7994992
15:57:43 51210 8192000000 10836476112 11292577792 1875954416 18.632 98 20 40 2 1891193216 9536544 5702256
15:59:36 56769 8192000000 10841634992 11310616576 1873613024 18.613 97 13 26 0 1893547856 16038016 3896816
16:01:35 61606 8192000000 10872864240 11326980096 1904484480 18.862 98 21 42 1 1920236160 9751888 5999792
16:03:38 67725 8192000000 10898428720 11365535744 1944468112 19.182 99 13 25 1 1958023904 10120128 3435664
16:05:45 18748 8192000000 10921590336 11369697280 1975417824 19.428 100 39 78 1 1998519872 12456352 10645696
16:07:07 140385 8192000000 10850406592 11305832448 1863024800 18.528 94 21 42 1 1880081472 11018656 6038016
16:10:58 56315 8192000000 10880779344 11367989248 1964574208 19.342 100 11 22 0 1982038944 14407488 3057248
16:12:57 58564 8192000000 10893457424 11368620032 1958702112 19.296 99 10 20 2 1974304400 12950736 2651552
16:16:40 80567 8192000000 10882929376 11368939520 1973307984 19.412 100 14 28 1 1987106000 9644752 4153264
16:19:10 86171 8192000000 10889607568 11379752960 1966799344 19.360 99 15 30 1 1983435312 12987984 3647984
16:21:37 35881 8192000000 10881113696 11345047552 1929245968 19.061 98 21 42 3 1944576624 9380096 5950560
16:23:20 53125 8192000000 10888158816 11354054656 1935706032 19.112 98 13 26 0 1952423120 12553056 4164032
16:25:20 77995 8192000000 10892462464 11371106304 1907011936 18.883 96 18 36 0 1922207952 10329568 4866448
16:27:40 18388 8192000000 10900323776 11352711168 1932028288 19.083 98 44 88 0 1955549120 11347440 12173392
16:29:00 95379 8192000000 10897386016 11384950784 1892584256 18.767 96 13 26 1 1917604704 21298064 3722384

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 59 v_add_invoice_to_stock 335544517
user_exc 3 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 12-10-2020 16:32:30 to 12-10-2020 16:32:31.

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          230575
MON$OLDEST_ACTIVE               231301
MON$OLDEST_SNAPSHOT             231301
MON$NEXT_TRANSACTION            231301
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                       2020160
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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Mon Oct 12 16:31:24 2020
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Mon Oct 12 16:31:27 2020
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Mon Oct 12 16:31:32 2020
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:17: 
:18: 
:19: mscs-test-161.budzdorov.ru    Mon Oct 12 16:31:32 2020
:20:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:21: 
--- end of diff output ---

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

12.10.20 16:32:32. 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-12 16:32:35 No severe PSQL-related problems occured
12.10.20 16:32:36. End of report.