Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.10.2020 01:28
Common Performance Final Results
  1. Hardware and OS info
  2. DB and test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database Statistics, full
  3. Ratio "Versions / Records" for tables
  4. Database Validation Results
  5. New in firebird.log while test was run
  6. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID e0e7b46250ba4f9795ceb55e0face73f
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2020-09-25 13:42
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.00
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 17664832 kB
MemAvailable 31592360 kB
Buffers 0 kB
Cached 14063848 kB
SwapCached 3816 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-23 01:26:58
DTS_END                         2020-10-23 01:26:58.4440
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_AGGREGATE_PERF_DATA(83:5) ==> TMP_AGGREGATE_PERF_DATA_AUTOGEN(301:13) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(56:17)
IP                              ::1/39984
TRN_ID                          248959
ATT_ID                          695
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: 9605.79 17940 1152695
customer order: creation 180.57 377 21668
customer order: refuse 15.52 335 1862
order to supplier: creation 48.51 1205 5821
order to supplier: removal 3.55 86077 426
invoice (draft): creation 48.59 3082 5831
invoice (draft): removal 54.68 688 6562
invoice accept: apply 43.33 16682 5199
invoice accept: cancel 49.20 720 5904
customer reserve: creation 4031.07 166 483728
customer reserve: removal 4829.06 50 579487
realization accept: apply 86.33 156 10360
realization accept: cancel 81.50 93 9780
payment from customer: creation 61.78 114 7413
payment from customer: removal 6.22 79 746
payment to supplier: creation 50.02 109 6002
payment to supplier: removal 3.28 58 394
service: total inventory turnovers 5.01 984 601
service: total monetary turnovers 6.69 36 803
service: aggregate perf. data 0.87 20358 104
service: refresh index statistics 0.03 227426 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 23-10-2020 01:28:44 to 23-10-2020 01:28:46.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8592
TEST_TIME 2 6295
TEST_TIME 3 6526
TEST_TIME 4 6336
TEST_TIME 5 7328
TEST_TIME 6 7187
TEST_TIME 7 6784
TEST_TIME 8 7220
TEST_TIME 9 7156
TEST_TIME 10 7328
TEST_TIME 11 7317
TEST_TIME 12 7463
TEST_TIME 13 7628
TEST_TIME 14 8047
TEST_TIME 15 8465
TEST_TIME 16 8908
TEST_TIME 17 8998
TEST_TIME 18 9127
TEST_TIME 19 9394
TEST_TIME 20 9499
TEST_TIME 21 9862
TEST_TIME 22 10635
TEST_TIME 23 11452
TEST_TIME 24 11642
TEST_TIME 25 11353
TEST_TIME 26 11049
TEST_TIME 27 10849
TEST_TIME 28 10850
TEST_TIME 29 10631
TEST_TIME 30 10543
TEST_TIME 31 10378
TEST_TIME 32 10483
TEST_TIME 33 10317
TEST_TIME 34 10163
TEST_TIME 35 10091
TEST_TIME 36 10301
TEST_TIME 37 10092
TEST_TIME 38 10014
TEST_TIME 39 10094
TEST_TIME 40 9977
TEST_TIME 41 10041
TEST_TIME 42 9934
TEST_TIME 43 10079
TEST_TIME 44 10099
TEST_TIME 45 9972
TEST_TIME 46 10164
TEST_TIME 47 10212
TEST_TIME 48 10265
TEST_TIME 49 10258
TEST_TIME 50 10308
TEST_TIME 51 10264
TEST_TIME 52 10108
TEST_TIME 53 10098
TEST_TIME 54 10023
TEST_TIME 55 9954
TEST_TIME 56 9922
TEST_TIME 57 9831
TEST_TIME 58 9779
TEST_TIME 59 9681
TEST_TIME 60 9651
TEST_TIME 61 9690
TEST_TIME 62 9688
TEST_TIME 63 9655
TEST_TIME 64 9717
TEST_TIME 65 9726
TEST_TIME 66 9689
TEST_TIME 67 9740
TEST_TIME 68 9679
TEST_TIME 69 9790
TEST_TIME 70 9842
TEST_TIME 71 9799
TEST_TIME 72 9830
TEST_TIME 73 9881
TEST_TIME 74 9904
TEST_TIME 75 9832
TEST_TIME 76 9750
TEST_TIME 77 9805
TEST_TIME 78 9740
TEST_TIME 79 9721
TEST_TIME 80 9641
TEST_TIME 81 9640
TEST_TIME 82 9588
TEST_TIME 83 9507
TEST_TIME 84 9503
TEST_TIME 85 9496
TEST_TIME 86 9558
TEST_TIME 87 9584
TEST_TIME 88 9567
TEST_TIME 89 9592
TEST_TIME 90 9620
TEST_TIME 91 9594
TEST_TIME 92 9616
TEST_TIME 93 9622
TEST_TIME 94 9640
TEST_TIME 95 9615
TEST_TIME 96 9683
TEST_TIME 97 9749
TEST_TIME 98 9720
TEST_TIME 99 9733
TEST_TIME 100 9716
TEST_TIME 101 9645
TEST_TIME 102 9628
TEST_TIME 103 9582
TEST_TIME 104 9563
TEST_TIME 105 9542
TEST_TIME 106 9498
TEST_TIME 107 9483
TEST_TIME 108 9553
TEST_TIME 109 9513
TEST_TIME 110 9487
TEST_TIME 111 9502
TEST_TIME 112 9520
TEST_TIME 113 9526
TEST_TIME 114 9536
TEST_TIME 115 9519
TEST_TIME 116 9570
TEST_TIME 117 9552
TEST_TIME 118 9577
TEST_TIME 119 9567
TEST_TIME 120 9610

Performance per minute, chart


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

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 1600525 1600525 0 0.00 0 19836 6 0 0
doc_list_biud 1600521 1600521 0 0.00 0 39514 23 0 0
sp_add_invoice_to_stock 5317 5199 118 2.21 30 190192 16683 0 118
sp_cancel_adding_invoice 5904 5904 0 0.00 5 64058 720 0 0
sp_cancel_client_order 1862 1862 0 0.00 4 19590 335 0 0
sp_cancel_customer_reserve 579487 579487 0 0.00 1 39516 50 0 0
sp_cancel_pay_from_customer 746 746 0 0.00 3 7604 80 0 0
sp_cancel_pay_to_supplier 394 394 0 0.00 3 2857 59 0 0
sp_cancel_supplier_invoice 6567 6562 5 0.07 1 20909 688 0 5
sp_cancel_supplier_order 426 426 0 0.00 5 879250 86078 0 0
sp_cancel_write_off 9780 9780 0 0.00 1 14284 93 0 0
sp_client_order 21668 21668 0 0.00 4 20236 377 0 0
sp_customer_reserve 483728 483728 0 0.00 0 127354 166 0 0
sp_fill_shopping_cart 33314 33314 0 0.00 1 14279 36 0 0
sp_fill_shopping_cart_clo_res 483714 483714 0 0.00 0 21659 12 0 0
sp_get_clo_for_invoice 5185 5185 0 0.00 0 32027 251 0 0
sp_kill_cost_storno 16824 16824 0 0.00 0 16971 42 0 0
sp_kill_qstorno_ret_qs2qd 1175742 1175742 0 0.00 0 39482 27 0 0
sp_kill_qty_storno 619566 619566 0 0.00 0 39514 59 0 0
sp_lock_selected_doc 21583 21583 0 0.00 0 5913 3 0 0
sp_make_cost_storno 28960 28960 0 0.00 0 19662 44 0 0
sp_multiply_rows_for_pdistr 28960 28960 0 0.00 0 8451 23 0 0
sp_multiply_rows_for_qdistr 21668 21668 0 0.00 0 20034 170 0 0
sp_pay_from_customer 7413 7413 0 0.00 4 14556 115 0 0
sp_pay_to_supplier 6002 6002 0 0.00 4 12712 110 0 0
sp_payment_common 13414 13414 0 0.00 1 11721 25 0 0
sp_qd_handle_on_cancel_clo 1862 1862 0 0.00 0 19584 298 0 0
sp_qd_handle_on_invoice_upd_sts 11089 11089 0 0.00 0 21074 267 0 0
sp_qd_handle_on_reserve_upd_sts 10360 10360 0 0.00 0 15583 54 0 0
sp_reserve_write_off 10360 10360 0 0.00 6 15610 156 0 0
sp_supplier_invoice 5831 5831 0 0.00 6 88606 3082 0 0
sp_supplier_order 5821 5821 0 0.00 6 40167 1206 0 0
srv_aggregate_perf_data 105 104 1 0.95 2843 575570 20359 0 1
srv_fill_mon_cache_memory 59 59 0 0.00 1336 153744 51402 0 0
srv_find_qd_qs_mism 1102773 1102773 0 0.00 0 13898 5 0 0
srv_make_invnt_saldo 601 601 0 0.00 51 67374 985 0 0
srv_make_money_saldo 803 803 0 0.00 1 7645 37 0 0
srv_recalc_idx_stat 4 4 0 0.00 143033 346895 227427 0 0
t$perf-abend:sp_add_invoice_to_stock 118 118 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5199 5199 0 0.00 0 20268 132 0 0
t$perf-norm:sp_cancel_adding_invoice 436 436 0 0.00 0 5154 97 0 0
t$perf-norm:sp_cancel_client_order 1862 1862 0 0.00 0 4139 11 0 0
t$perf-norm:sp_cancel_customer_reserve 720 720 0 0.00 0 1398 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 746 746 0 0.00 0 1795 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 394 394 0 0.00 0 119 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 442 442 0 0.00 0 19854 53 0 0
t$perf-norm:sp_cancel_supplier_order 426 426 0 0.00 0 34154 1361 0 0
t$perf-norm:sp_cancel_write_off 1015 1015 0 0.00 0 3722 12 0 0
t$perf-norm:sp_client_order 21668 21668 0 0.00 0 27273 17 0 0
t$perf-norm:sp_customer_reserve 1043 1043 0 0.00 0 593 4 0 0
t$perf-norm:sp_pay_from_customer 7413 7413 0 0.00 0 13354 10 0 0
t$perf-norm:sp_pay_to_supplier 6002 6002 0 0.00 0 6762 11 0 0
t$perf-norm:sp_reserve_write_off 10360 10360 0 0.00 0 27901 12 0 0
t$perf-norm:sp_supplier_invoice 5831 5831 0 0.00 0 9603 15 0 0
t$perf-norm:sp_supplier_order 5821 5821 0 0.00 0 6682 11 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 353 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 59 59 0 0.00 0 4156 131 0 0
t$perf-norm:srv_make_invnt_saldo 601 601 0 0.00 0 988 13 0 0
t$perf-norm:srv_make_money_saldo 803 803 0 0.00 0 1312 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 4 0 0
v_add_invoice_to_stock 10600 10364 236 2.22 0 15562 22 0 236
v_all_customers 39068 39068 0 0.00 0 14 0 0 0
v_all_suppliers 32146 32146 0 0.00 0 18 1 0 0
v_all_wares 43334 43334 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 872 872 0 0.00 0 1562 7 0 0
v_cancel_client_order 3724 3724 0 0.00 0 8286 11 0 0
v_cancel_customer_prepayment 1492 1492 0 0.00 0 7263 17 0 0
v_cancel_customer_reserve 1440 1440 0 0.00 0 1339 5 0 0
v_cancel_payment_to_supplier 788 788 0 0.00 0 2849 11 0 0
v_cancel_supplier_invoice 894 884 10 1.11 0 5012 18 0 10
v_cancel_supplier_order 852 852 0 0.00 0 331 4 0 0
v_cancel_write_off 2030 2030 0 0.00 0 11083 17 0 0
v_max_id_clo_ord 5821 5821 0 0.00 0 376 1 0 0
v_max_id_clo_res 1043 1043 0 0.00 0 3030 15 0 0
v_max_id_ord_sup 5825 5825 0 0.00 0 4638 3 0 0
v_max_non_paid_invoice 1575 1575 0 0.00 0 906 3 0 0
v_max_non_paid_realizn 4091 4091 0 0.00 0 2860 3 0 0
v_min_id_clo_ord 5821 5821 0 0.00 0 4311 7 0 0
v_min_id_clo_res 1043 1043 0 0.00 0 7262 18 0 0
v_min_id_ord_sup 5825 5825 0 0.00 0 4759 3 0 0
v_min_non_paid_invoice 6002 6002 0 0.00 0 6992 12 0 0
v_min_non_paid_realizn 7412 7412 0 0.00 0 11713 19 0 0
v_our_firm 10908 10908 0 0.00 0 16 1 0 0
v_reserve_write_off 20720 20720 0 0.00 0 12171 10 0 0
x_lock_depdocs_on_canc_invoice 12024 12024 0 0.00 0 37454 118 0 0
x_lock_depdocs_on_canc_sup_ord 426 426 0 0.00 0 14973 295 0 0
x_make_qstorno_1000_1200 5821 5821 0 0.00 2 40161 1138 0 0
x_make_qstorno_1200_2000 5831 5831 0 0.00 3 81034 2950 0 0
x_make_qstorno_2100_3300 483728 483728 0 0.00 0 127352 152 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-10-2020 01:28:52 to 23-10-2020 01:28:56.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
22:57:02 2 8192000000 8798422528 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
22:58:02 24217 8192000000 10363464752 10682441728 1415168288 14.730 100 92 185 0 1450961600 5767760 30025552
22:59:31 23690 8192000000 10414262000 10744066048 1467673568 15.193 100 88 176 1 1503471104 7619152 28178384
23:01:02 19445 8192000000 10486621344 10828087296 1530114848 15.738 100 79 158 2 1563529024 8818768 24595408
23:02:29 10452 8192000000 10572386768 10933125120 1633098704 16.621 100 77 152 0 1666295888 10828240 22368944
23:03:42 8777 8192000000 10644539984 11016069120 1706369984 17.238 100 81 162 1 1743269488 13418032 23481472
23:04:54 4286 8192000000 10719429568 11096313856 1769558448 17.763 100 83 166 0 1806047072 13430560 23058064
23:06:02 2450 8192000000 10763738736 11147972608 1825830576 18.225 100 80 161 3 1858718672 11088496 21799600
23:07:07 7452 8192000000 10796070992 11185852416 1856700832 18.477 100 80 160 1 1892667936 13845936 22121168
23:08:15 2932 8192000000 10781275632 11196715008 1864292896 18.538 100 46 92 1 1885312864 7892144 13127824
23:09:18 6958 8192000000 10776057536 11204476928 1870440224 18.588 100 29 58 1 1885981936 6756000 8785712
23:10:32 6706 8192000000 10796537424 11226898432 1886373376 18.717 100 29 58 0 1900591152 5859392 8358384
23:11:39 33023 8192000000 10813278768 11241762816 1891707680 18.760 100 24 48 1 1904161616 5910496 6543440
23:13:16 5029 8192000000 10822779792 11255443456 1908561168 18.895 100 32 64 1 1929034784 10522096 9951520
23:14:21 7398 8192000000 10827444000 11265286144 1916476736 18.959 100 25 51 0 1930602928 6233568 7892624
23:15:29 47283 8192000000 10823433344 11254525952 1866161808 18.553 97 45 85 3 1884438768 4840976 13435984
23:17:17 6523 8192000000 10765984384 11184349184 1807730320 18.077 97 35 70 2 1824015168 6070864 10213984
23:18:24 7380 8192000000 10709170416 11123163136 1746663456 17.574 97 28 56 1 1765253920 10827504 7762960
23:19:31 8723 8192000000 10684997200 11093917696 1727692768 17.416 97 29 58 0 1741994672 5315840 8986064
23:20:43 42838 8192000000 10700755520 11121618944 1736742096 17.492 96 22 43 3 1748593216 5751680 6099440
23:22:26 6798 8192000000 10753491872 11175624704 1839015568 18.333 100 36 70 2 1856265808 7924368 9325872
23:23:35 5810 8192000000 10769588752 11204063232 1862180160 18.521 100 31 60 3 1876216528 5722160 8314208
23:24:45 14910 8192000000 10784137296 11215785984 1867755728 18.566 100 8 16 1 1874749904 5012560 1981616
23:26:00 6738 8192000000 10786731632 11219509248 1877599632 18.646 100 43 86 1 1896297168 6495120 12202416
23:27:07 3779 8192000000 10828588160 11251134464 1904090656 18.859 100 52 104 1 1926753840 8435264 14227920
23:28:23 34571 8192000000 10872143216 11296542720 1934228272 19.101 100 51 101 2 1956795120 8005424 14561424
23:30:02 16374 8192000000 10904920176 11328950272 1961242016 19.316 100 59 118 0 1995306560 16520960 17543584
23:31:24 29599 8192000000 10899331632 11341463552 1969502672 19.382 100 36 72 2 1993114176 12774864 10836640
23:33:50 26355 8192000000 10919615152 11358609408 1966600976 19.358 99 51 100 3 2004235744 22670160 14964608
23:35:26 12146 8192000000 10909934336 11343126528 1951078880 19.235 99 46 90 0 1987790176 23213920 13497376
23:36:42 4077 8192000000 10942355616 11365548032 1981864864 19.479 100 63 127 2 2027519952 27070896 18584192
23:37:49 1335 8192000000 10920944288 11337703424 1940928976 19.154 98 80 161 0 1993296336 28976304 23391056
23:38:57 3875 8192000000 10909306704 11335770112 1944818256 19.185 100 68 134 1 2002413360 36497040 21098064
23:40:06 2640 8192000000 10914946928 11320889344 1926468064 19.039 99 82 162 3 1995440128 43718944 25253120
23:41:12 2145 8192000000 10933609824 11343548416 1950124368 19.227 100 82 165 1 2021780672 45619200 26037104
23:42:16 9376 8192000000 10926543520 11357577216 1950883312 19.234 100 45 90 1 2014863072 49195696 14784064
23:43:32 8383 8192000000 10915911616 11349721088 1950082576 19.227 100 37 74 0 2008009536 44969808 12957152
23:44:51 7941 8192000000 10931991024 11358257152 1954620832 19.263 100 41 82 1 2009931840 41533424 13777584
23:46:17 13753 8192000000 10911057536 11352551424 1929426880 19.062 99 29 58 3 1982310832 43057920 9826032
23:47:41 20588 8192000000 10911700400 11366060032 1937280592 19.125 100 23 46 1 1994250144 48385056 8584496
23:49:27 52769 8192000000 10886925744 11351715840 1943949248 19.178 100 16 32 1 1981338768 32132464 5257056
23:51:29 114756 8192000000 10858502352 11308466176 1889242224 18.740 98 31 57 0 1905392384 7680688 8469472
23:54:34 153744 8192000000 10867889776 11344539648 1925908576 19.034 99 18 36 0 1943233664 12114496 5210592
23:59:13 46004 8192000000 10894185184 11354980352 1948474704 19.214 99 15 30 0 1964620144 12003152 4142288
00:01:30 89373 8192000000 10894072416 11377262592 1954803632 19.265 99 11 22 0 1981835920 24030160 3002128
00:04:04 66437 8192000000 10887014720 11360002048 1962317744 19.324 100 14 28 1 1978466672 12205648 3943280
00:06:11 42933 8192000000 10921121968 11370487808 1981524928 19.477 100 42 83 0 2007945808 14593136 11827744
00:07:59 40749 8192000000 10923861696 11382288384 1991014736 19.552 100 26 52 1 2008672448 10128400 7529312
00:09:40 77581 8192000000 10946587088 11396538368 1994015536 19.576 100 14 28 0 2011214384 13312832 3886016
00:12:05 62876 8192000000 10926001424 11408789504 1999847664 19.622 100 11 22 0 2023475552 19896656 3731232
00:14:15 66372 8192000000 10937929936 11404988416 1968335872 19.372 98 26 52 1 1995621344 19487616 7797856
00:16:26 71655 8192000000 10941695408 11406938112 1990530064 19.548 99 16 32 2 2015516592 20762032 4224496
00:18:38 77561 8192000000 10918859856 11397009408 2002278608 19.641 100 43 86 0 2030764240 15358880 13126752
00:21:13 53644 8192000000 10882416400 11353473024 1928055104 19.051 97 21 42 0 1944742208 10131056 6556048
00:23:07 71079 8192000000 10910823200 11375566848 1979253472 19.459 100 15 30 0 1993663344 10050256 4359616
00:25:22 72467 8192000000 10878676816 11352539136 1924539744 19.023 97 14 28 0 1938714432 10493120 3681568
00:27:42 50926 8192000000 10842093616 11313147904 1830912160 18.267 93 14 28 0 1848803504 14170464 3720880
00:29:34 77625 8192000000 10851240992 11307999232 1855513520 18.467 95 17 34 0 1872241344 12164288 4563536
00:31:52 35119 8192000000 10853440416 11294306304 1868141856 18.569 97 51 102 0 1892048496 9517120 14389520
00:33:29 33426 8192000000 10840671360 11302043648 1892487904 18.766 99 24 48 1 1909482288 10454448 6539936
00:35:06 43695 8192000000 10864855280 11330777088 1925765248 19.033 100 18 36 0 1949184576 18267168 5152160
00:37:05 71781 8192000000 10869075552 11315937280 1858476976 18.491 96 25 50 2 1880227648 14834592 6916080
00:39:24 143546 8192000000 10891963680 11339390976 1869154208 18.577 96 12 24 0 1890480096 17905312 3420576
00:44:17 35982 8192000000 10865613664 11338379264 1945011424 19.187 100 19 38 0 1964471088 13428896 6030768
00:46:02 52525 8192000000 10862356880 11338240000 1945525120 19.191 100 8 17 0 1960789984 12829040 2435824
00:47:55 83298 8192000000 10879602896 11350118400 1954678752 19.264 100 15 30 0 1965766992 7119760 3968480
00:51:46 99912 8192000000 10879821216 11358814208 1913169552 18.932 98 6 12 0 1929777456 14662960 1944944
00:54:26 39334 8192000000 10866668320 11332706304 1899661680 18.824 97 19 38 2 1913022096 8170960 5189456
00:56:08 40811 8192000000 10883281856 11356483584 1953415408 19.254 100 22 44 0 1969057360 8957904 6684048
00:57:54 23434 8192000000 10876890640 11336376320 1955031632 19.267 100 46 90 1 1975974496 8461584 12481280
00:59:18 103542 8192000000 10891796688 11359899648 1898660400 18.816 96 11 22 1 1911019840 9196880 3162560
01:02:03 62734 8192000000 10902829408 11383853056 1931250880 19.077 98 19 38 2 1954338640 17662688 5425072
01:05:19 51546 8192000000 10902099344 11353776128 1928230384 19.053 98 24 48 0 1947408880 11053344 8125152
01:07:15 104788 8192000000 10869799984 11350233088 1905511472 18.871 97 15 30 0 1918682720 8739568 4431680
01:10:00 60156 8192000000 10882735440 11365765120 1951315136 19.237 99 8 16 0 1962012080 8324896 2372048
01:12:01 62546 8192000000 10900170544 11374342144 1958988976 19.298 99 13 26 1 1974238432 11271616 3977840
01:14:12 72015 8192000000 10874318368 11349315584 1911809792 18.921 97 19 38 3 1927214048 9703104 5701152
01:16:24 57107 8192000000 10856615664 11320324096 1848054416 18.406 95 17 34 1 1864309472 11564448 4690608
01:18:30 57123 8192000000 10814830688 11271168000 1796275424 17.983 93 27 54 1 1813618224 9753456 7589344
01:20:35 32480 8192000000 10835865824 11288612864 1862743632 18.526 98 28 56 0 1882974528 11940016 8290880
01:22:11 69655 8192000000 10836928912 11274993664 1855923072 18.470 97 30 60 2 1880686464 15767904 8995488
01:24:25 50049 8192000000 10851031984 11291217920 1854510144 18.459 97 26 52 1 1874069872 12901968 6657760
01:26:16 60598 8192000000 9078204224 9297342464 240942672 2.857 13 2 4 0 243524592 2425520 156400

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 23-10-2020 01:28:58 to 23-10-2020 01:29:12.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 1 srv_aggregate_perf_data 335544517
user_exc 118 v_add_invoice_to_stock 335544517
user_exc 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-10-2020 01:29:13 to 23-10-2020 01:29:15.

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          249342
MON$OLDEST_ACTIVE               249343
MON$OLDEST_SNAPSHOT             249343
MON$NEXT_TRANSACTION            249343
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                       2010752
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.7.33379 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.7.33379 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.7.33379 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.7.33379 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:45: 
:46: mscs-test-161.budzdorov.ru    Fri Oct 23 01:27:22 2020
:47:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:48: 
:49: 
:50: mscs-test-161.budzdorov.ru    Fri Oct 23 01:27:22 2020
:51:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:52: 
:53: 
:54: mscs-test-161.budzdorov.ru    Fri Oct 23 01:27:22 2020
:55:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:56: 
:57: 
:58: mscs-test-161.budzdorov.ru    Fri Oct 23 01:27:23 2020
:59:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:60: 
:61: 
:62: mscs-test-161.budzdorov.ru    Fri Oct 23 01:27:23 2020
:63:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:64: 
--- end of diff output ---

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

23.10.20 01:29:16. 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-23 01:29:19 No severe PSQL-related problems occured
23.10.20 01:29:20. End of report.