Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.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 17433116 kB
MemAvailable 31567756 kB
Buffers 0 kB
Cached 14240324 kB
SwapCached 5432 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-18 01:26:56
DTS_END                         2020-10-18 01:26:56.6600
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_AGGREGATE_PERF_DATA(83:5) ==> TMP_AGGREGATE_PERF_DATA_AUTOGEN(751:13) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(56:17)
IP                              ::1/44346
TRN_ID                          245922
ATT_ID                          723
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: 9510.92 24998 1141311
customer order: creation 172.54 344 20705
customer order: refuse 14.19 320 1703
order to supplier: creation 45.76 1201 5491
order to supplier: removal 3.73 80382 447
invoice (draft): creation 45.73 3202 5488
invoice (draft): removal 55.33 716 6639
invoice accept: apply 41.97 16614 5036
invoice accept: cancel 50.09 634 6011
customer reserve: creation 3891.28 164 466953
customer reserve: removal 4884.56 47 586147
realization accept: apply 84.57 188 10148
realization accept: cancel 92.14 92 11057
payment from customer: creation 58.33 126 7000
payment from customer: removal 5.37 82 644
payment to supplier: creation 49.37 154 5924
payment to supplier: removal 3.79 79 455
service: total inventory turnovers 4.65 1095 558
service: total monetary turnovers 6.72 21 806
service: aggregate perf. data 0.79 20577 95
service: refresh index statistics 0.03 373918 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-10-2020 01:28:15 to 18-10-2020 01:28:17.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3539
TEST_TIME 2 4046
TEST_TIME 3 5081
TEST_TIME 4 4951
TEST_TIME 5 5518
TEST_TIME 6 6492
TEST_TIME 7 6622
TEST_TIME 8 6755
TEST_TIME 9 6742
TEST_TIME 10 7009
TEST_TIME 11 7218
TEST_TIME 12 7414
TEST_TIME 13 7643
TEST_TIME 14 7875
TEST_TIME 15 7925
TEST_TIME 16 8509
TEST_TIME 17 9079
TEST_TIME 18 9245
TEST_TIME 19 9417
TEST_TIME 20 9220
TEST_TIME 21 9390
TEST_TIME 22 9444
TEST_TIME 23 10359
TEST_TIME 24 10558
TEST_TIME 25 11800
TEST_TIME 26 11762
TEST_TIME 27 11889
TEST_TIME 28 11862
TEST_TIME 29 11473
TEST_TIME 30 11171
TEST_TIME 31 11129
TEST_TIME 32 11105
TEST_TIME 33 11186
TEST_TIME 34 10971
TEST_TIME 35 10812
TEST_TIME 36 10789
TEST_TIME 37 10731
TEST_TIME 38 10618
TEST_TIME 39 10463
TEST_TIME 40 10491
TEST_TIME 41 10445
TEST_TIME 42 10550
TEST_TIME 43 10429
TEST_TIME 44 10365
TEST_TIME 45 10364
TEST_TIME 46 10371
TEST_TIME 47 10396
TEST_TIME 48 10389
TEST_TIME 49 10351
TEST_TIME 50 10265
TEST_TIME 51 10276
TEST_TIME 52 10438
TEST_TIME 53 10437
TEST_TIME 54 10305
TEST_TIME 55 10202
TEST_TIME 56 10170
TEST_TIME 57 10105
TEST_TIME 58 10057
TEST_TIME 59 10023
TEST_TIME 60 9997
TEST_TIME 61 9901
TEST_TIME 62 9819
TEST_TIME 63 9756
TEST_TIME 64 9845
TEST_TIME 65 9798
TEST_TIME 66 9974
TEST_TIME 67 9939
TEST_TIME 68 10012
TEST_TIME 69 10017
TEST_TIME 70 10051
TEST_TIME 71 10027
TEST_TIME 72 10070
TEST_TIME 73 10086
TEST_TIME 74 10110
TEST_TIME 75 10146
TEST_TIME 76 10112
TEST_TIME 77 10127
TEST_TIME 78 10165
TEST_TIME 79 10072
TEST_TIME 80 10024
TEST_TIME 81 10035
TEST_TIME 82 9972
TEST_TIME 83 9925
TEST_TIME 84 9903
TEST_TIME 85 9856
TEST_TIME 86 9784
TEST_TIME 87 9721
TEST_TIME 88 9759
TEST_TIME 89 9697
TEST_TIME 90 9682
TEST_TIME 91 9675
TEST_TIME 92 9680
TEST_TIME 93 9660
TEST_TIME 94 9721
TEST_TIME 95 9788
TEST_TIME 96 9817
TEST_TIME 97 9840
TEST_TIME 98 9794
TEST_TIME 99 9812
TEST_TIME 100 9797
TEST_TIME 101 9833
TEST_TIME 102 9877
TEST_TIME 103 9855
TEST_TIME 104 9827
TEST_TIME 105 9802
TEST_TIME 106 9818
TEST_TIME 107 9811
TEST_TIME 108 9798
TEST_TIME 109 9739
TEST_TIME 110 9699
TEST_TIME 111 9709
TEST_TIME 112 9679
TEST_TIME 113 9636
TEST_TIME 114 9628
TEST_TIME 115 9596
TEST_TIME 116 9589
TEST_TIME 117 9544
TEST_TIME 118 9539
TEST_TIME 119 9528
TEST_TIME 120 9515

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 18-10-2020 01:28:18 to 18-10-2020 01:28:22.

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 1574135 1574135 0 0.00 0 20183 6 0 0
doc_list_biud 1574135 1574135 0 0.00 0 78193 23 0 0
sp_add_invoice_to_stock 5134 5036 98 1.90 38 189672 16615 0 98
sp_cancel_adding_invoice 6011 6011 0 0.00 4 49503 634 0 0
sp_cancel_client_order 1703 1703 0 0.00 4 13162 321 0 0
sp_cancel_customer_reserve 586147 586147 0 0.00 1 47458 48 0 0
sp_cancel_pay_from_customer 644 644 0 0.00 2 5864 83 0 0
sp_cancel_pay_to_supplier 455 455 0 0.00 3 8161 80 0 0
sp_cancel_supplier_invoice 6647 6639 8 0.12 1 78204 717 0 8
sp_cancel_supplier_order 447 447 0 0.00 7 932897 80383 0 0
sp_cancel_write_off 11057 11057 0 0.00 1 9970 92 0 0
sp_client_order 20705 20705 0 0.00 3 17616 344 0 0
sp_customer_reserve 466953 466953 0 0.00 0 66650 164 0 0
sp_fill_shopping_cart 31682 31682 0 0.00 1 14850 40 0 0
sp_fill_shopping_cart_clo_res 466949 466949 0 0.00 0 14966 13 0 0
sp_get_clo_for_invoice 5030 5030 0 0.00 0 30214 305 0 0
sp_kill_cost_storno 18167 18167 0 0.00 0 18406 51 0 0
sp_kill_qstorno_ret_qs2qd 1190437 1190437 0 0.00 0 78187 26 0 0
sp_kill_qty_storno 627182 627182 0 0.00 0 78192 57 0 0
sp_lock_selected_doc 20956 20956 0 0.00 0 6731 4 0 0
sp_make_cost_storno 28102 28102 0 0.00 0 20181 62 0 0
sp_multiply_rows_for_pdistr 28102 28102 0 0.00 0 10687 26 0 0
sp_multiply_rows_for_qdistr 20705 20705 0 0.00 0 13153 157 0 0
sp_pay_from_customer 7000 7000 0 0.00 4 22532 127 0 0
sp_pay_to_supplier 5924 5924 0 0.00 4 25029 154 0 0
sp_payment_common 12924 12924 0 0.00 1 11130 39 0 0
sp_qd_handle_on_cancel_clo 1703 1703 0 0.00 0 13155 291 0 0
sp_qd_handle_on_invoice_upd_sts 11041 11041 0 0.00 0 14386 276 0 0
sp_qd_handle_on_reserve_upd_sts 10148 10148 0 0.00 0 14579 59 0 0
sp_reserve_write_off 10148 10148 0 0.00 6 16516 188 0 0
sp_supplier_invoice 5488 5488 0 0.00 5 79743 3202 0 0
sp_supplier_order 5491 5491 0 0.00 5 41729 1201 0 0
srv_aggregate_perf_data 95 95 0 0.00 2494 433580 20578 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1372 154897 50264 0 0
srv_find_qd_qs_mism 1093649 1093649 0 0.00 0 17769 5 0 0
srv_make_invnt_saldo 558 558 0 0.00 39 56561 1096 0 0
srv_make_money_saldo 806 806 0 0.00 1 1623 21 0 0
srv_recalc_idx_stat 4 4 0 0.00 225640 565585 373918 0 0
t$perf-abend:sp_add_invoice_to_stock 98 98 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5037 5037 0 0.00 0 11787 141 0 0
t$perf-norm:sp_cancel_adding_invoice 429 429 0 0.00 0 8675 99 0 0
t$perf-norm:sp_cancel_client_order 1703 1703 0 0.00 0 7192 12 0 0
t$perf-norm:sp_cancel_customer_reserve 697 697 0 0.00 0 4530 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 644 644 0 0.00 0 2297 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 455 455 0 0.00 0 5269 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 422 422 0 0.00 0 1641 18 0 0
t$perf-norm:sp_cancel_supplier_order 447 447 0 0.00 0 25616 1399 0 0
t$perf-norm:sp_cancel_write_off 981 981 0 0.00 0 3694 9 0 0
t$perf-norm:sp_client_order 20705 20705 0 0.00 0 7433 16 0 0
t$perf-norm:sp_customer_reserve 1028 1028 0 0.00 0 2896 11 0 0
t$perf-norm:sp_pay_from_customer 7000 7000 0 0.00 0 6315 8 0 0
t$perf-norm:sp_pay_to_supplier 5924 5924 0 0.00 0 9384 10 0 0
t$perf-norm:sp_reserve_write_off 10148 10148 0 0.00 0 9246 13 0 0
t$perf-norm:sp_supplier_invoice 5488 5488 0 0.00 0 12721 22 0 0
t$perf-norm:sp_supplier_order 5491 5491 0 0.00 0 3586 11 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 724 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 293 7 0 0
t$perf-norm:srv_make_invnt_saldo 558 558 0 0.00 0 973 14 0 0
t$perf-norm:srv_make_money_saldo 806 806 0 0.00 0 4984 24 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 16 5 0 0
v_add_invoice_to_stock 10256 10060 196 1.91 0 13488 18 0 196
v_all_customers 37844 37844 0 0.00 0 16 1 0 0
v_all_suppliers 30250 30250 0 0.00 0 14 1 0 0
v_all_wares 41408 41408 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 858 858 0 0.00 0 516 6 0 0
v_cancel_client_order 3406 3406 0 0.00 0 6537 9 0 0
v_cancel_customer_prepayment 1288 1288 0 0.00 0 2457 6 0 0
v_cancel_customer_reserve 1394 1394 0 0.00 0 3931 11 0 0
v_cancel_payment_to_supplier 910 910 0 0.00 0 2601 6 0 0
v_cancel_supplier_invoice 860 844 16 1.86 0 2289 16 0 16
v_cancel_supplier_order 894 894 0 0.00 0 936 6 0 0
v_cancel_write_off 1962 1962 0 0.00 0 6293 19 0 0
v_max_id_clo_ord 5491 5491 0 0.00 0 589 1 0 0
v_max_id_clo_res 1028 1028 0 0.00 0 2956 17 0 0
v_max_id_ord_sup 5486 5486 0 0.00 0 5156 3 0 0
v_max_non_paid_invoice 1776 1776 0 0.00 0 5774 10 0 0
v_max_non_paid_realizn 3710 3710 0 0.00 0 3109 4 0 0
v_min_id_clo_ord 5491 5491 0 0.00 0 3584 7 0 0
v_min_id_clo_res 1028 1028 0 0.00 0 5905 11 0 0
v_min_id_ord_sup 5486 5486 0 0.00 0 8811 9 0 0
v_min_non_paid_invoice 5924 5924 0 0.00 0 10396 28 0 0
v_min_non_paid_realizn 7000 7000 0 0.00 0 11125 28 0 0
v_our_firm 10144 10144 0 0.00 0 14 1 0 0
v_reserve_write_off 20296 20296 0 0.00 0 11803 9 0 0
x_lock_depdocs_on_canc_invoice 12228 12228 0 0.00 0 12526 95 0 0
x_lock_depdocs_on_canc_sup_ord 447 447 0 0.00 0 10632 258 0 0
x_make_qstorno_1000_1200 5491 5491 0 0.00 2 41721 1125 0 0
x_make_qstorno_1200_2000 5488 5488 0 0.00 2 79732 3056 0 0
x_make_qstorno_2100_3300 466953 466953 0 0.00 0 66646 149 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 18-10-2020 01:28:22 to 18-10-2020 01:28:27.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
22:57:00 1 8192000000 8798422528 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
22:58:00 27374 8192000000 10361433344 10680700928 1405060160 14.640 100 89 178 0 1440478816 6063200 29355456
22:59:48 21294 8192000000 10429738512 10761687040 1485379248 15.348 100 90 180 0 1523796880 9242752 29174880
23:01:17 16706 8192000000 10509594288 10857463808 1565513600 16.044 100 76 152 1 1599540240 10851088 23175552
23:02:45 12539 8192000000 10591036832 10954072064 1646972992 16.739 100 81 162 2 1683957936 13054176 23930768
23:04:06 5992 8192000000 10664481696 11040423936 1722167136 17.370 100 78 156 0 1757525280 13106096 22252048
23:05:16 6603 8192000000 10734227840 11119259648 1795063616 17.973 100 79 159 0 1830334816 13650928 21620272
23:06:24 1181 8192000000 10791961168 11177394176 1850893424 18.429 100 91 180 1 1889660128 13472096 25294608
23:07:27 1896 8192000000 10799354512 11197263872 1865708432 18.550 100 66 132 0 1896794816 12034112 19052272
23:08:31 4254 8192000000 10789711040 11202506752 1867591552 18.565 100 36 74 3 1886228592 7712752 10924288
23:09:36 20259 8192000000 10779782480 11210711040 1868935648 18.576 100 29 59 2 1883832320 6486816 8409856
23:10:58 10656 8192000000 10798238352 11225923584 1880030944 18.665 100 15 30 3 1890588432 5742976 4814512
23:12:11 10703 8192000000 10815874400 11243646976 1896840336 18.801 100 24 49 3 1909652944 5792656 7019952
23:13:26 39208 8192000000 10852173248 11265875968 1911826336 18.921 100 67 135 3 1937457600 5591232 20040032
23:15:07 11390 8192000000 10809053744 11243245568 1882015744 18.681 99 26 49 0 1896110512 6247568 7847200
23:16:20 11834 8192000000 10781189888 11207675904 1856756048 18.477 99 22 42 2 1867919296 5146704 6016544
23:17:32 9590 8192000000 10723330912 11146006528 1733626512 17.466 94 24 48 2 1746195088 5370336 7198240
23:18:54 9149 8192000000 10732241376 11148050432 1780269616 17.852 98 29 55 4 1795039472 6499408 8270448
23:20:05 5802 8192000000 10680994912 11097903104 1744739984 17.558 98 25 51 2 1757485840 5939344 6806512
23:21:11 5898 8192000000 10743119648 11159654400 1816097936 18.146 99 25 50 1 1830609680 6224448 8287296
23:22:18 9427 8192000000 10768307728 11186667520 1847142624 18.399 100 20 40 3 1867229248 14875968 5210656
23:23:28 33690 8192000000 10772353264 11201474560 1859985856 18.503 100 28 56 2 1873997040 5993648 8017536
23:25:02 7519 8192000000 10786231296 11220533248 1875055344 18.625 100 28 54 1 1889409152 6391904 7961904
23:26:10 9843 8192000000 10797020592 11225759744 1882210800 18.683 100 17 34 1 1892720928 5361504 5148624
23:27:24 8900 8192000000 10840501984 11260207104 1911462784 18.918 100 45 90 0 1931204304 7244704 12496816
23:28:42 89317 8192000000 10858608304 11303690240 1942155568 19.164 100 48 96 2 1965434608 9195040 14084000
23:31:16 1370 8192000000 10909159216 11327483904 1961607424 19.319 100 63 126 3 1992930912 11253856 20069632
23:33:02 7911 8192000000 10916731856 11351662592 1982702688 19.486 100 58 116 0 2012377520 12326384 17348448
23:34:12 2714 8192000000 10938713200 11367870464 1991299424 19.554 100 75 148 2 2031486688 19059360 21127904
23:35:17 5189 8192000000 10927837376 11348865024 1973494736 19.413 100 67 135 1 2008940480 15830848 19614896
23:36:26 2852 8192000000 10911635664 11339030528 1944973408 19.186 99 58 116 1 1989623872 27172464 17478000
23:37:31 3534 8192000000 10881920144 11302731776 1904547776 18.863 98 78 151 3 1952397616 24596448 23253392
23:38:38 14770 8192000000 10885585344 11304808448 1893857424 18.777 98 60 114 2 1940756464 29527616 17371424
23:39:57 4297 8192000000 10917415088 11335757824 1935986944 19.115 99 68 136 1 1989231200 32107488 21136768
23:41:05 2396 8192000000 10884701904 11292540928 1900545168 18.831 99 78 150 1 1956684272 34168768 21970336
23:42:12 1502 8192000000 10936906144 11342508032 1948244864 19.212 100 70 140 0 2014131920 43393360 22493696
23:43:17 10648 8192000000 10870108560 11305390080 1910141616 18.908 100 42 84 1 1963966656 39330928 14494112
23:44:36 3482 8192000000 10933363568 11353018368 1947634704 19.208 100 68 136 2 2017776848 48669312 21472832
23:45:46 8240 8192000000 10900584048 11335053312 1929320816 19.061 99 38 75 1 1986256624 44255792 12680016
23:47:07 17642 8192000000 10910533088 11356925952 1945254768 19.189 100 32 64 0 2002042208 46135168 10652272
23:48:28 22050 8192000000 10903105024 11352436736 1914747120 18.945 99 25 50 2 1978310928 53920336 9643472
23:50:04 33077 8192000000 10884996896 11332341760 1907210288 18.884 100 19 38 0 1958398592 44126464 7061840
23:51:44 57184 8192000000 10872807648 11328274432 1918366272 18.974 99 14 29 1 1939292256 16649568 4276416
23:53:46 68661 8192000000 10874328848 11346165760 1896303712 18.797 97 10 20 1 1916620512 17547232 2769568
23:55:55 36786 8192000000 10896128688 11341389824 1952474912 19.246 100 37 74 2 1978575936 14265456 11835568
23:57:39 61759 8192000000 10893060976 11362471936 1956177280 19.276 99 32 64 2 1977524656 12245792 9101584
23:59:54 48021 8192000000 10900417376 11373211648 1966666928 19.359 100 6 12 0 1980450528 12443024 1340576
00:01:43 55858 8192000000 10892858784 11370045440 1940112016 19.148 98 15 30 1 1966327488 22268752 3946720
00:03:43 60636 8192000000 10887742352 11361828864 1959383616 19.301 99 15 30 0 1977390880 13500416 4506848
00:06:54 60573 8192000000 10914861872 11387236352 1984315200 19.499 100 17 34 1 2000839152 11886304 4637648
00:08:58 32721 8192000000 10908495696 11379458048 1986527248 19.516 100 13 25 0 1998008464 7125120 4356096
00:10:31 61303 8192000000 10922982608 11401117696 1998426672 19.610 100 22 44 1 2014203808 9682096 6095040
00:12:34 154895 8192000000 10918621440 11395444736 1937696208 19.128 97 11 23 1 1953405792 12837808 2871776
00:16:16 86323 8192000000 10924806992 11393449984 1968642688 19.375 99 22 45 1 1987167088 12382976 6141424
00:18:43 77186 8192000000 10909493392 11354550272 1904284384 18.861 96 14 28 1 1925190976 16548032 4358560
00:21:06 79791 8192000000 10881554240 11343888384 1900619120 18.831 96 26 51 1 1914967232 7974432 6373680
00:23:29 31002 8192000000 10841246256 11297615872 1892296704 18.764 98 25 49 1 1913176240 14007824 6871712
00:25:02 94789 8192000000 10811430208 11277451264 1778325888 17.836 92 13 26 0 1793134640 11106672 3702080
00:27:38 40503 8192000000 10838741936 11303387136 1871203184 18.594 97 15 30 1 1891563008 16046256 4313568
00:29:18 58338 8192000000 10845617888 11331072000 1922669216 19.008 100 16 33 1 1945588096 18046864 4872016
00:31:22 67445 8192000000 10841066544 11305881600 1825441632 18.222 95 13 26 0 1843705808 14794336 3469840
00:33:37 56157 8192000000 10833231232 11304464384 1834722864 18.298 95 16 32 1 1849536384 10710128 4103392
00:35:53 94067 8192000000 10858112032 11335651328 1879261760 18.659 97 14 28 1 1899559680 15952800 4345120
00:38:30 63457 8192000000 10866900608 11334008832 1893202192 18.772 98 19 37 3 1915312080 17064304 5045584
00:40:34 57571 8192000000 10849802848 11315990528 1904837696 18.865 98 16 32 0 1921438256 11733104 4867456
00:42:31 34850 8192000000 10887042560 11329204224 1918319952 18.973 99 32 62 2 1937885712 11162976 8402784
00:44:11 54098 8192000000 10898795680 11352645632 1932778544 19.089 99 29 58 1 1952993520 12595712 7619264
00:46:29 90151 8192000000 10856219264 11342548992 1897852832 18.809 97 14 28 1 1916519952 13861872 4805248
00:49:05 44019 8192000000 10872226320 11351863296 1957696080 19.288 100 10 22 1 1967608304 6838368 3073856
00:50:50 69287 8192000000 10894969040 11368165376 1969180448 19.379 100 11 21 1 1983217264 11500880 2535936
00:53:01 68366 8192000000 10895541056 11377360896 1958561424 19.295 99 11 22 2 1978146160 15380912 4203824
00:55:14 62917 8192000000 10903455104 11388272640 1981806640 19.479 100 11 23 1 1993473840 8328880 3338320
00:57:17 58675 8192000000 10939954832 11408842752 1995660288 19.588 100 13 26 0 2014028336 14674160 3693888
00:59:20 75544 8192000000 10926793104 11401019392 1966149984 19.355 98 17 34 1 1984701088 13617776 4933328
01:01:42 81382 8192000000 10921323616 11400667136 1957606528 19.287 98 13 25 1 1970534816 9641664 3286624
01:04:04 47892 8192000000 10918372704 11371544576 1919845168 18.986 96 27 52 0 1935911280 8266736 7799376
01:05:52 20826 8192000000 10908801728 11361390592 1962404576 19.325 100 44 88 3 1986325424 11720704 12200144
01:07:17 55403 8192000000 10882216128 11361832960 1938495840 19.135 99 21 42 0 1956997872 12586272 5915760
01:09:17 32937 8192000000 10913860352 11369504768 1974915632 19.424 100 37 73 1 1996555776 11307680 10332464
01:10:51 93594 8192000000 10911336352 11388084224 1951793008 19.241 99 28 56 1 1981845584 22344880 7707696
01:13:26 94671 8192000000 10868326080 11358535680 1917081936 18.963 97 13 26 0 1944822752 24219312 3521504
01:16:02 122341 8192000000 10846222912 11313565696 1820288128 18.180 93 6 11 2 1834845408 12732976 1824304
01:19:05 56323 8192000000 10839635328 11306983424 1860585456 18.508 96 27 54 0 1875709216 7030080 8093680
01:22:20 84030 8192000000 10818185168 11298877440 1848688656 18.411 96 12 24 0 1864531216 12660688 3181872
01:24:48 73810 8192000000 10841505632 11314499584 1902293200 18.845 99 13 26 0 1918151552 12203296 3655056

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 98 v_add_invoice_to_stock 335544517
user_exc 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-10-2020 01:28:44 to 18-10-2020 01:28:46.

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          244040
MON$OLDEST_ACTIVE               246266
MON$OLDEST_SNAPSHOT             246266
MON$NEXT_TRANSACTION            246266
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                       2038688
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.33375 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.7.33375 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.7.33375 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.7.33375 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 ---
:45: 
:46: mscs-test-161.budzdorov.ru    Sun Oct 18 01:26:59 2020
:47:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:48: 
:49: 
:50: mscs-test-161.budzdorov.ru    Sun Oct 18 01:27:01 2020
:51:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:52: 
--- end of diff output ---

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

18.10.20 01:28:47. 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-18 01:28:49 No severe PSQL-related problems occured
18.10.20 01:28:50. End of report.