Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.10.2020 22: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 17374520 kB
MemAvailable 31545636 kB
Buffers 0 kB
Cached 14292128 kB
SwapCached 5152 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-20 22:26:57
DTS_END                         2020-10-20 22:26:57.6660
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/34008
TRN_ID                          253966
ATT_ID                          657
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: 9806.43 19358 1176772
customer order: creation 184.72 334 22166
customer order: refuse 15.61 310 1873
order to supplier: creation 48.49 1106 5819
order to supplier: removal 3.83 82575 459
invoice (draft): creation 48.56 2774 5827
invoice (draft): removal 56.53 722 6783
invoice accept: apply 44.75 15863 5370
invoice accept: cancel 50.77 592 6092
customer reserve: creation 4111.06 158 493327
customer reserve: removal 4930.15 50 591618
realization accept: apply 91.48 147 10977
realization accept: cancel 84.29 94 10115
payment from customer: creation 62.02 83 7442
payment from customer: removal 5.95 66 714
payment to supplier: creation 51.91 98 6229
payment to supplier: removal 3.58 49 429
service: total inventory turnovers 5.03 1147 604
service: total monetary turnovers 6.84 30 821
service: aggregate perf. data 0.86 22390 103
service: refresh index statistics 0.03 258577 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-10-2020 22:27:42 to 20-10-2020 22:27:44.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2101
TEST_TIME 2 4783
TEST_TIME 3 5304
TEST_TIME 4 5342
TEST_TIME 5 5774
TEST_TIME 6 5984
TEST_TIME 7 5928
TEST_TIME 8 6103
TEST_TIME 9 6062
TEST_TIME 10 6426
TEST_TIME 11 6781
TEST_TIME 12 7287
TEST_TIME 13 7496
TEST_TIME 14 8199
TEST_TIME 15 8409
TEST_TIME 16 9345
TEST_TIME 17 9629
TEST_TIME 18 9865
TEST_TIME 19 10665
TEST_TIME 20 10567
TEST_TIME 21 10427
TEST_TIME 22 10233
TEST_TIME 23 10106
TEST_TIME 24 10669
TEST_TIME 25 12490
TEST_TIME 26 12443
TEST_TIME 27 12374
TEST_TIME 28 12079
TEST_TIME 29 11707
TEST_TIME 30 11778
TEST_TIME 31 11797
TEST_TIME 32 11752
TEST_TIME 33 11648
TEST_TIME 34 11471
TEST_TIME 35 11315
TEST_TIME 36 11385
TEST_TIME 37 11278
TEST_TIME 38 11112
TEST_TIME 39 11000
TEST_TIME 40 10833
TEST_TIME 41 10914
TEST_TIME 42 10723
TEST_TIME 43 10823
TEST_TIME 44 10761
TEST_TIME 45 10865
TEST_TIME 46 10761
TEST_TIME 47 10756
TEST_TIME 48 10671
TEST_TIME 49 10661
TEST_TIME 50 10664
TEST_TIME 51 10714
TEST_TIME 52 10724
TEST_TIME 53 10757
TEST_TIME 54 10794
TEST_TIME 55 10740
TEST_TIME 56 10745
TEST_TIME 57 10718
TEST_TIME 58 10586
TEST_TIME 59 10460
TEST_TIME 60 10407
TEST_TIME 61 10304
TEST_TIME 62 10273
TEST_TIME 63 10314
TEST_TIME 64 10290
TEST_TIME 65 10201
TEST_TIME 66 10181
TEST_TIME 67 10158
TEST_TIME 68 10219
TEST_TIME 69 10214
TEST_TIME 70 10186
TEST_TIME 71 10145
TEST_TIME 72 10230
TEST_TIME 73 10193
TEST_TIME 74 10210
TEST_TIME 75 10179
TEST_TIME 76 10205
TEST_TIME 77 10235
TEST_TIME 78 10261
TEST_TIME 79 10253
TEST_TIME 80 10179
TEST_TIME 81 10086
TEST_TIME 82 10062
TEST_TIME 83 10078
TEST_TIME 84 10028
TEST_TIME 85 9986
TEST_TIME 86 9937
TEST_TIME 87 9889
TEST_TIME 88 9892
TEST_TIME 89 9866
TEST_TIME 90 9877
TEST_TIME 91 9829
TEST_TIME 92 9903
TEST_TIME 93 9950
TEST_TIME 94 9942
TEST_TIME 95 9930
TEST_TIME 96 10002
TEST_TIME 97 10023
TEST_TIME 98 10026
TEST_TIME 99 9991
TEST_TIME 100 9970
TEST_TIME 101 10044
TEST_TIME 102 9990
TEST_TIME 103 9920
TEST_TIME 104 9882
TEST_TIME 105 9839
TEST_TIME 106 9777
TEST_TIME 107 9731
TEST_TIME 108 9736
TEST_TIME 109 9726
TEST_TIME 110 9698
TEST_TIME 111 9693
TEST_TIME 112 9660
TEST_TIME 113 9663
TEST_TIME 114 9738
TEST_TIME 115 9764
TEST_TIME 116 9728
TEST_TIME 117 9773
TEST_TIME 118 9796
TEST_TIME 119 9858
TEST_TIME 120 9809

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 20-10-2020 22:27:46 to 20-10-2020 22:27:49.

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 1633801 1633801 0 0.00 0 25994 5 0 0
doc_list_biud 1633801 1633801 0 0.00 0 77098 23 0 0
sp_add_invoice_to_stock 5577 5370 207 3.71 38 196817 15863 0 207
sp_cancel_adding_invoice 6092 6092 0 0.00 5 52108 593 0 0
sp_cancel_client_order 1873 1873 0 0.00 4 14420 310 0 0
sp_cancel_customer_reserve 591618 591618 0 0.00 1 31173 51 0 0
sp_cancel_pay_from_customer 714 714 0 0.00 3 4802 66 0 0
sp_cancel_pay_to_supplier 429 429 0 0.00 2 1816 50 0 0
sp_cancel_supplier_invoice 6798 6783 15 0.22 1 77105 723 0 15
sp_cancel_supplier_order 459 459 0 0.00 5 967698 82576 0 0
sp_cancel_write_off 10115 10115 0 0.00 1 13921 95 0 0
sp_client_order 22166 22166 0 0.00 4 37542 335 0 0
sp_customer_reserve 493327 493327 0 0.00 0 72776 159 0 0
sp_fill_shopping_cart 33788 33788 0 0.00 1 13273 34 0 0
sp_fill_shopping_cart_clo_res 493307 493307 0 0.00 0 19710 14 0 0
sp_get_clo_for_invoice 5350 5350 0 0.00 0 17678 299 0 0
sp_kill_cost_storno 17350 17350 0 0.00 0 4874 43 0 0
sp_kill_qstorno_ret_qs2qd 1200593 1200593 0 0.00 0 77094 28 0 0
sp_kill_qty_storno 633267 633267 0 0.00 0 77096 59 0 0
sp_lock_selected_doc 22388 22388 0 0.00 0 7496 5 0 0
sp_make_cost_storno 29998 29998 0 0.00 0 11182 41 0 0
sp_multiply_rows_for_pdistr 29998 29998 0 0.00 0 6885 21 0 0
sp_multiply_rows_for_qdistr 22166 22166 0 0.00 0 11205 166 0 0
sp_pay_from_customer 7442 7442 0 0.00 4 14197 84 0 0
sp_pay_to_supplier 6229 6229 0 0.00 4 11039 99 0 0
sp_payment_common 13671 13671 0 0.00 1 6413 17 0 0
sp_qd_handle_on_cancel_clo 1873 1873 0 0.00 0 11654 258 0 0
sp_qd_handle_on_invoice_upd_sts 11442 11442 0 0.00 0 22980 261 0 0
sp_qd_handle_on_reserve_upd_sts 10977 10977 0 0.00 0 15431 48 0 0
sp_reserve_write_off 10977 10977 0 0.00 6 15444 148 0 0
sp_supplier_invoice 5827 5827 0 0.00 6 100442 2775 0 0
sp_supplier_order 5819 5819 0 0.00 5 26313 1106 0 0
srv_aggregate_perf_data 103 103 0 0.00 2993 454748 22390 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 1688 156837 52338 0 0
srv_find_qd_qs_mism 1126139 1126139 0 0.00 0 25992 5 0 0
srv_make_invnt_saldo 604 604 0 0.00 47 75248 1148 0 0
srv_make_money_saldo 821 821 0 0.00 1 7562 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 153352 393337 258578 0 0
t$perf-abend:sp_add_invoice_to_stock 207 207 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5370 5370 0 0.00 0 11983 155 0 0
t$perf-norm:sp_cancel_adding_invoice 407 407 0 0.00 0 3943 77 0 0
t$perf-norm:sp_cancel_client_order 1873 1873 0 0.00 0 2813 12 0 0
t$perf-norm:sp_cancel_customer_reserve 687 687 0 0.00 0 1118 6 0 0
t$perf-norm:sp_cancel_pay_from_customer 714 714 0 0.00 0 427 1 0 0
t$perf-norm:sp_cancel_pay_to_supplier 429 429 0 0.00 0 723 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 435 435 0 0.00 0 350 6 0 0
t$perf-norm:sp_cancel_supplier_order 459 459 0 0.00 0 28949 1326 0 0
t$perf-norm:sp_cancel_write_off 1057 1057 0 0.00 0 2817 8 0 0
t$perf-norm:sp_client_order 22166 22166 0 0.00 0 8833 15 0 0
t$perf-norm:sp_customer_reserve 1082 1082 0 0.00 0 6199 16 0 0
t$perf-norm:sp_pay_from_customer 7442 7442 0 0.00 0 7564 8 0 0
t$perf-norm:sp_pay_to_supplier 6229 6229 0 0.00 0 10962 12 0 0
t$perf-norm:sp_reserve_write_off 10977 10977 0 0.00 0 4545 8 0 0
t$perf-norm:sp_supplier_invoice 5827 5827 0 0.00 0 10205 22 0 0
t$perf-norm:sp_supplier_order 5819 5819 0 0.00 0 7792 17 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 81 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 30 1 0 0
t$perf-norm:srv_make_invnt_saldo 604 604 0 0.00 0 1245 17 0 0
t$perf-norm:srv_make_money_saldo 821 821 0 0.00 0 1869 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 3 0 0
v_add_invoice_to_stock 11114 10700 414 3.72 0 6319 20 0 414
v_all_customers 39758 39758 0 0.00 0 12 1 0 0
v_all_suppliers 32346 32346 0 0.00 0 46 1 0 0
v_all_wares 44332 44332 0 0.00 0 24 1 0 0
v_cancel_adding_invoice 814 814 0 0.00 0 2371 10 0 0
v_cancel_client_order 3746 3746 0 0.00 0 14126 14 0 0
v_cancel_customer_prepayment 1428 1428 0 0.00 0 1691 7 0 0
v_cancel_customer_reserve 1374 1374 0 0.00 0 1884 7 0 0
v_cancel_payment_to_supplier 858 858 0 0.00 0 1778 6 0 0
v_cancel_supplier_invoice 900 870 30 3.33 0 2251 22 0 30
v_cancel_supplier_order 918 918 0 0.00 0 4041 8 0 0
v_cancel_write_off 2114 2114 0 0.00 0 5658 16 0 0
v_max_id_clo_ord 5819 5819 0 0.00 0 3858 2 0 0
v_max_id_clo_res 1082 1082 0 0.00 0 6758 18 0 0
v_max_id_ord_sup 5803 5803 0 0.00 0 6138 4 0 0
v_max_non_paid_invoice 1678 1678 0 0.00 0 696 3 0 0
v_max_non_paid_realizn 4179 4179 0 0.00 0 1252 3 0 0
v_min_id_clo_ord 5819 5819 0 0.00 0 2349 3 0 0
v_min_id_clo_res 1082 1082 0 0.00 0 2744 11 0 0
v_min_id_ord_sup 5803 5803 0 0.00 0 7262 9 0 0
v_min_non_paid_invoice 6229 6229 0 0.00 0 4718 9 0 0
v_min_non_paid_realizn 7442 7442 0 0.00 0 6410 10 0 0
v_our_firm 11100 11100 0 0.00 0 17 1 0 0
v_reserve_write_off 21954 21954 0 0.00 0 6935 9 0 0
x_lock_depdocs_on_canc_invoice 12440 12440 0 0.00 0 15420 90 0 0
x_lock_depdocs_on_canc_sup_ord 459 459 0 0.00 0 5465 232 0 0
x_make_qstorno_1000_1200 5819 5819 0 0.00 1 26303 1043 0 0
x_make_qstorno_1200_2000 5827 5827 0 0.00 3 94542 2645 0 0
x_make_qstorno_2100_3300 493327 493327 0 0.00 0 72772 142 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-10-2020 22:27:50 to 20-10-2020 22:27:54.

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
19:57:01 1 8192000000 8798422528 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
19:58:02 34568 8192000000 10366998144 10688729088 1417614704 14.752 100 86 172 0 1452514256 6538096 28361456
19:59:39 20607 8192000000 10416620624 10746093568 1469520768 15.210 100 89 178 0 1507040208 8217792 29301648
20:01:09 12584 8192000000 10506970992 10852339712 1551084048 15.919 100 79 156 1 1584826064 9937760 23804256
20:02:24 8690 8192000000 10579731280 10937577472 1636068224 16.646 100 75 148 3 1673499888 15217680 22213984
20:03:34 6989 8192000000 10656195232 11031281664 1717379552 17.330 100 76 153 0 1751281184 12060064 21841568
20:04:43 3452 8192000000 10726137360 11106623488 1784615824 17.887 100 83 166 2 1821965744 13442064 23907856
20:05:49 2297 8192000000 10766036720 11152908288 1829328544 18.254 100 84 168 0 1867942880 14471648 24142688
20:06:52 1456 8192000000 10809031200 11197784064 1866608336 18.557 100 93 186 3 1906624144 13894896 26120912
20:07:55 30212 8192000000 10803910080 11210244096 1873557632 18.613 100 60 120 0 1900006416 7890288 18558496
20:09:25 6587 8192000000 10797191456 11218411520 1879636064 18.662 100 41 83 2 1899061600 7637392 11788144
20:10:34 8140 8192000000 10803951104 11234238464 1890440288 18.749 100 13 25 2 1899469280 5412224 3616768
20:11:46 12316 8192000000 10804730880 11240398848 1901254512 18.836 100 39 80 1 1918214048 5538496 11421040
20:12:58 15543 8192000000 10814319712 11252932608 1904885936 18.866 100 22 44 1 1921118944 10560688 5672320
20:14:17 7839 8192000000 10822169760 11250655232 1896673328 18.800 99 30 61 0 1911351200 5903424 8774448
20:15:25 11689 8192000000 10765361744 11189415936 1786448416 17.903 94 30 59 4 1800356704 5878592 8029696
20:16:37 19664 8192000000 10785174704 11203198976 1820766976 18.184 97 20 40 3 1832904912 6177632 5960304
20:18:22 8535 8192000000 10749405328 11175247872 1813356736 18.123 98 35 71 1 1829985344 6832768 9795840
20:19:32 5355 8192000000 10670881568 11080658944 1736407568 17.489 98 30 58 2 1751746528 5891680 9447280
20:20:43 14128 8192000000 10714903840 11131781120 1775392464 17.812 98 23 45 6 1787605616 5466944 6746208
20:21:58 10259 8192000000 10732928144 11149971456 1813433440 18.124 100 27 54 1 1826837840 5447360 7957040
20:23:10 8841 8192000000 10752696272 11185700864 1838421872 18.328 100 14 28 2 1852225056 10020192 3782992
20:24:21 5931 8192000000 10768973104 11195592704 1852006720 18.438 100 16 32 1 1861831600 5023728 4801152
20:25:29 20422 8192000000 10786336992 11206782976 1865799440 18.550 100 14 29 2 1875301728 5625712 3876576
20:26:53 44436 8192000000 10828514320 11243134976 1893454688 18.774 100 51 101 2 1915155248 6958768 14741792
20:28:45 37418 8192000000 10857019040 11289161728 1932906288 19.090 100 50 100 0 1956522096 9698592 13917216
20:30:32 28344 8192000000 10854826992 11291103232 1921696320 19.000 99 35 70 2 1947374704 15314240 10364144
20:32:06 8539 8192000000 10906536128 11326484480 1941282384 19.157 99 56 114 1 1978884352 21140288 16461680
20:33:18 12931 8192000000 10894338096 11330392064 1937592992 19.128 99 41 82 1 1980215280 29630848 12991440
20:34:37 14330 8192000000 10920341472 11347927040 1965356528 19.349 100 62 125 2 2010779008 27893600 17528880
20:35:59 1688 8192000000 10960341856 11377401856 1989965184 19.544 100 79 157 2 2045263664 31023232 24275248
20:37:05 3232 8192000000 10946591808 11361894400 1961293856 19.316 99 76 151 2 2019282480 34876448 23112176
20:38:13 3367 8192000000 10926756000 11344261120 1953077456 19.251 100 70 140 0 2013152304 38937952 21136896
20:39:20 1870 8192000000 10962787072 11368271872 1972192864 19.403 100 76 151 2 2037966032 42704720 23068448
20:40:25 5380 8192000000 10928333376 11358941184 1944534976 19.183 99 70 136 2 2013527728 48421008 20571744
20:41:42 11289 8192000000 10893623600 11316289536 1900600608 18.831 98 37 72 0 1967820832 54622288 12597936
20:42:58 18335 8192000000 10911737536 11348733952 1926959584 19.043 99 34 68 0 1989018752 50142320 11916848
20:44:18 2773 8192000000 10954605392 11359936512 1943817008 19.177 100 81 161 1 2023743792 54521936 25404848
20:45:24 7417 8192000000 10911833200 11351060480 1944194368 19.180 100 44 88 0 1995618496 37433104 13991024
20:46:41 15202 8192000000 10907276240 11351199744 1932518128 19.087 99 25 50 0 1980686448 40357312 7811008
20:48:18 34872 8192000000 10921146944 11366178816 1945413088 19.190 100 25 50 1 2006941328 53017520 8510720
20:51:07 60399 8192000000 10851517024 11334221824 1865483776 18.548 97 17 34 1 1936902288 65157856 6260656
20:53:08 66504 8192000000 10859485200 11305771008 1874031696 18.617 97 11 23 1 1891028256 13882688 3113872
20:55:19 156836 8192000000 10889259168 11367972864 1909034080 18.899 98 22 44 0 1929152784 13876096 6242608
20:59:02 62850 8192000000 10886945312 11340738560 1917732384 18.969 97 29 57 2 1936782112 11156736 7892992
21:01:20 39779 8192000000 10924136752 11370643456 1976233200 19.435 100 41 82 1 1999028032 11431360 11363472
21:03:04 32127 8192000000 10904509808 11364192256 1972176080 19.403 100 17 34 0 1987188640 10245008 4767552
21:05:00 45263 8192000000 10908520928 11372056576 1958772544 19.296 99 9 18 0 1975710080 14291248 2646288
21:06:55 27198 8192000000 10924002096 11386454016 1976903056 19.440 100 21 39 1 2010763920 28151792 5709072
21:08:24 46153 8192000000 10917128256 11386490880 1983424704 19.492 100 43 86 0 2014169616 17917216 12827696
21:10:29 53279 8192000000 10909332640 11393900544 1968071024 19.370 99 11 22 1 1988828640 17397088 3360528
21:12:23 100072 8192000000 10910159376 11384655872 1953086896 19.251 98 14 28 0 1971044960 14294096 3663968
21:15:23 52598 8192000000 10911920064 11393830912 1975920592 19.432 99 22 44 3 1992384368 10430960 6032816
21:17:19 33162 8192000000 10912510656 11385303040 1958102608 19.291 98 31 62 0 1977771152 10904336 8764208
21:18:53 59585 8192000000 10900052864 11368439808 1909064032 18.899 96 12 24 2 1923709280 10998688 3646560
21:20:58 54941 8192000000 10927018352 11378450432 1982487440 19.484 100 32 64 1 2001861520 10947712 8426368
21:22:56 111466 8192000000 10885301008 11354185728 1916321936 18.957 97 25 50 1 1934422896 11266016 6834944
21:26:27 56673 8192000000 10885418176 11348025344 1906724592 18.880 97 23 46 0 1927958064 14881440 6352032
21:28:24 61855 8192000000 10831165936 11318419456 1875408992 18.628 97 19 38 1 1899522688 18476416 5637280
21:30:33 49803 8192000000 10829419520 11302252544 1884894256 18.705 98 15 31 0 1901386864 12656688 3835920
21:32:24 58441 8192000000 10868784448 11327033344 1913803968 18.937 99 24 48 0 1928708288 8312528 6591792
21:34:26 101867 8192000000 10874439248 11348115456 1889082400 18.738 97 11 22 0 1910034672 17737680 3214592
21:37:11 76583 8192000000 10888890864 11368296448 1945332800 19.189 99 24 48 1 1966386384 14439328 6614256
21:39:28 30619 8192000000 10892883776 11347763200 1925235072 19.029 98 32 64 2 1944717056 10318608 9163376
21:41:00 36228 8192000000 10913621616 11363344384 1951522400 19.239 100 35 70 0 1971000368 9973552 9504416
21:42:37 83101 8192000000 10906839504 11356221440 1930671200 19.072 98 18 36 1 1951829632 16390976 4767456
21:45:03 121193 8192000000 10878286304 11339796480 1888440576 18.733 96 16 32 0 1902848288 9944560 4463152
21:48:06 49874 8192000000 10885688800 11357278208 1962301280 19.324 100 20 40 3 1978016016 9692512 6022224
21:50:38 50396 8192000000 10887676512 11362459648 1968237520 19.371 100 8 16 1 1976892496 6357216 2297760
21:52:31 64142 8192000000 10889459952 11363590144 1972458736 19.405 100 12 24 1 1984572240 8571840 3541664
21:54:44 64719 8192000000 10885904784 11360956416 1902936624 18.850 96 13 26 0 1918609504 11857408 3815472
21:56:51 46871 8192000000 10908414816 11374321664 1954603856 19.263 99 20 40 0 1978091904 17988848 5499200
21:58:38 64853 8192000000 10896136688 11377344512 1941132912 19.156 98 21 43 1 1963417680 16107520 6177248
22:00:44 143181 8192000000 10893897792 11375988736 1870479648 18.588 95 10 20 0 1888462560 15489296 2493616
22:04:10 100083 8192000000 10908002064 11351470080 1871345152 18.595 95 16 32 1 1886707552 10454048 4908352
22:06:51 126974 8192000000 10851132240 11324141568 1810561728 18.100 93 10 20 0 1829255936 16397824 2296384
22:10:09 51873 8192000000 10837264448 11310510080 1896882400 18.801 98 19 38 0 1911852240 9458960 5510880
22:12:07 52929 8192000000 10853987792 11324063744 1902745792 18.848 98 11 22 0 1919688496 13967120 2975584
22:14:29 41649 8192000000 10828552416 11295895552 1895935488 18.794 98 12 23 0 1910424912 10895248 3594176
22:16:11 44044 8192000000 10837468000 11291164672 1834238960 18.294 95 20 40 0 1848717232 8744192 5734080
22:18:32 55554 8192000000 10872733520 11328471040 1928752944 19.057 100 18 36 1 1948843376 15010832 5079600
22:20:32 78924 8192000000 10839450112 11300274176 1802971040 18.038 94 19 38 1 1818935696 10725184 5239472
22:23:04 61027 8192000000 10870994496 11336859648 1933250384 19.093 100 22 44 0 1956249312 16802944 6195984
22:25:13 113084 8192000000 9459351056 9753235456 597970640 6.802 31 4 8 0 603835024 4805584 1058800

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 20-10-2020 22:27:56 to 20-10-2020 22:28:10.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 207 v_add_invoice_to_stock 335544517
user_exc 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 20-10-2020 22:28:11 to 20-10-2020 22:28:13.

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          253965
MON$OLDEST_ACTIVE               254327
MON$OLDEST_SNAPSHOT             254327
MON$NEXT_TRANSACTION            254327
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                       2039008
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.33376 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.7.33376 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.7.33376 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.7.33376 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 ---
:24: 
:25: mscs-test-161.budzdorov.ru    Tue Oct 20 22:27:11 2020
:26:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:27: 
:28: 
:29: mscs-test-161.budzdorov.ru    Tue Oct 20 22:27:11 2020
:30:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:31: 
:32: 
:33: mscs-test-161.budzdorov.ru    Tue Oct 20 22:27:11 2020
:34:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:35: 
:36: 
:37: mscs-test-161.budzdorov.ru    Tue Oct 20 22:27:11 2020
:38:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:39: 
:40: 
:41: mscs-test-161.budzdorov.ru    Tue Oct 20 22:27:11 2020
:42:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:43: 
:44: 
:45: mscs-test-161.budzdorov.ru    Tue Oct 20 22:27:11 2020
:46:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:47: 
:48: 
:49: mscs-test-161.budzdorov.ru    Tue Oct 20 22:27:11 2020
:50:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:51: 
:52: 
:53: mscs-test-161.budzdorov.ru    Tue Oct 20 22:27:11 2020
:54:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:55: 
:56: 
:57: mscs-test-161.budzdorov.ru    Tue Oct 20 22:27:11 2020
:58:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:59: 
:60: 
:61: mscs-test-161.budzdorov.ru    Tue Oct 20 22:27:11 2020
:62:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:63: 
:64: 
:65: mscs-test-161.budzdorov.ru    Tue Oct 20 22:27:11 2020
:66:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:67: 
:68: 
:69: mscs-test-161.budzdorov.ru    Tue Oct 20 22:27:11 2020
:70:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:71: 
:72: 
:73: mscs-test-161.budzdorov.ru    Tue Oct 20 22:27:11 2020
:74:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:75: 
:76: 
:77: mscs-test-161.budzdorov.ru    Tue Oct 20 22:27:11 2020
:78:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:79: 
:80: 
:81: mscs-test-161.budzdorov.ru    Tue Oct 20 22:27:11 2020
:82:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:83: 
:84: 
:85: mscs-test-161.budzdorov.ru    Tue Oct 20 22:27:11 2020
:86:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:87: 
--- end of diff output ---

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

20.10.20 22:28:14. 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-20 22:28:17 No severe PSQL-related problems occured
20.10.20 22:28:18. End of report.