Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.11.2020 04:28
Common Performance Final Results
  1. Hardware and OS info
  2. 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 was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. 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 16829528 kB
MemAvailable 31482760 kB
Buffers 20 kB
Cached 14815960 kB
SwapCached 2888 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 4.0.0
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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-11-02 04:26:54
DTS_END                         2020-11-02 04:26:54.0340
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/55636
TRN_ID                          229224
ATT_ID                          648
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: 8888.25 22173 1066590
customer order: creation 166.71 395 20005
customer order: refuse 13.71 340 1645
order to supplier: creation 43.48 1411 5218
order to supplier: removal 3.15 115775 378
invoice (draft): creation 43.60 3778 5232
invoice (draft): removal 51.41 896 6169
invoice accept: apply 39.33 16312 4720
invoice accept: cancel 46.34 838 5561
customer reserve: creation 3651.50 157 438180
customer reserve: removal 4546.68 63 545602
realization accept: apply 81.35 182 9762
realization accept: cancel 77.81 120 9337
payment from customer: creation 56.61 118 6793
payment from customer: removal 5.31 67 637
payment to supplier: creation 48.14 151 5777
payment to supplier: removal 3.28 86 393
service: total inventory turnovers 3.53 3617 424
service: total monetary turnovers 5.31 611 637
service: aggregate perf. data 0.96 13028 115
service: refresh index statistics 0.04 285511 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 02-11-2020 04:29:15 to 02-11-2020 04:29:17.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2045
TEST_TIME 2 3473
TEST_TIME 3 3217
TEST_TIME 4 3342
TEST_TIME 5 3432
TEST_TIME 6 3820
TEST_TIME 7 4786
TEST_TIME 8 5153
TEST_TIME 9 5661
TEST_TIME 10 6455
TEST_TIME 11 6772
TEST_TIME 12 7102
TEST_TIME 13 7274
TEST_TIME 14 7766
TEST_TIME 15 7959
TEST_TIME 16 7905
TEST_TIME 17 8084
TEST_TIME 18 8739
TEST_TIME 19 8901
TEST_TIME 20 8881
TEST_TIME 21 9182
TEST_TIME 22 9613
TEST_TIME 23 9829
TEST_TIME 24 9636
TEST_TIME 25 10070
TEST_TIME 26 10162
TEST_TIME 27 10457
TEST_TIME 28 10972
TEST_TIME 29 11010
TEST_TIME 30 10736
TEST_TIME 31 10590
TEST_TIME 32 10760
TEST_TIME 33 10586
TEST_TIME 34 10286
TEST_TIME 35 10180
TEST_TIME 36 10143
TEST_TIME 37 10138
TEST_TIME 38 9939
TEST_TIME 39 9814
TEST_TIME 40 9718
TEST_TIME 41 9570
TEST_TIME 42 9436
TEST_TIME 43 9404
TEST_TIME 44 9343
TEST_TIME 45 9532
TEST_TIME 46 9556
TEST_TIME 47 9609
TEST_TIME 48 9545
TEST_TIME 49 9552
TEST_TIME 50 9474
TEST_TIME 51 9380
TEST_TIME 52 9334
TEST_TIME 53 9489
TEST_TIME 54 9404
TEST_TIME 55 9454
TEST_TIME 56 9466
TEST_TIME 57 9497
TEST_TIME 58 9563
TEST_TIME 59 9515
TEST_TIME 60 9399
TEST_TIME 61 9263
TEST_TIME 62 9294
TEST_TIME 63 9190
TEST_TIME 64 9151
TEST_TIME 65 9097
TEST_TIME 66 9148
TEST_TIME 67 9161
TEST_TIME 68 9133
TEST_TIME 69 9159
TEST_TIME 70 9235
TEST_TIME 71 9194
TEST_TIME 72 9176
TEST_TIME 73 9178
TEST_TIME 74 9238
TEST_TIME 75 9231
TEST_TIME 76 9250
TEST_TIME 77 9288
TEST_TIME 78 9333
TEST_TIME 79 9354
TEST_TIME 80 9243
TEST_TIME 81 9165
TEST_TIME 82 9130
TEST_TIME 83 9075
TEST_TIME 84 9019
TEST_TIME 85 8959
TEST_TIME 86 8929
TEST_TIME 87 8888
TEST_TIME 88 8868
TEST_TIME 89 8896
TEST_TIME 90 8883
TEST_TIME 91 8877
TEST_TIME 92 8966
TEST_TIME 93 8968
TEST_TIME 94 8935
TEST_TIME 95 9012
TEST_TIME 96 8987
TEST_TIME 97 9052
TEST_TIME 98 9034
TEST_TIME 99 9233
TEST_TIME 100 9179
TEST_TIME 101 9114
TEST_TIME 102 9047
TEST_TIME 103 9002
TEST_TIME 104 8982
TEST_TIME 105 8960
TEST_TIME 106 8910
TEST_TIME 107 8932
TEST_TIME 108 8950
TEST_TIME 109 8920
TEST_TIME 110 8923
TEST_TIME 111 8922
TEST_TIME 112 8891
TEST_TIME 113 8898
TEST_TIME 114 8978
TEST_TIME 115 9039
TEST_TIME 116 9036
TEST_TIME 117 9018
TEST_TIME 118 8972
TEST_TIME 119 8920
TEST_TIME 120 8894

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 02-11-2020 04:29:19 to 02-11-2020 04:29: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 1472520 1472520 0 0.00 0 13863 6 0 0
doc_list_biud 1472520 1472520 0 0.00 0 91865 30 0 0
sp_add_invoice_to_stock 4845 4720 125 2.57 20 171760 16312 0 125
sp_cancel_adding_invoice 5561 5561 0 0.00 5 98358 838 0 0
sp_cancel_client_order 1645 1645 0 0.00 5 9725 340 0 0
sp_cancel_customer_reserve 545602 545602 0 0.00 1 83721 64 0 0
sp_cancel_pay_from_customer 637 637 0 0.00 3 2313 68 0 0
sp_cancel_pay_to_supplier 393 393 0 0.00 3 5411 86 0 0
sp_cancel_supplier_invoice 6176 6169 7 0.11 2 91874 897 0 7
sp_cancel_supplier_order 378 378 0 0.00 19 930635 115776 0 0
sp_cancel_write_off 9337 9337 0 0.00 1 8875 121 0 0
sp_client_order 20005 20005 0 0.00 4 20218 395 0 0
sp_customer_reserve 438180 438180 0 0.00 0 114278 157 0 0
sp_fill_shopping_cart 30447 30447 0 0.00 1 48729 50 0 0
sp_fill_shopping_cart_clo_res 438174 438174 0 0.00 0 25095 17 0 0
sp_get_clo_for_invoice 4713 4713 0 0.00 0 36901 432 0 0
sp_kill_cost_storno 15928 15928 0 0.00 0 8586 56 0 0
sp_kill_qstorno_ret_qs2qd 1107088 1107088 0 0.00 0 91863 35 0 0
sp_kill_qty_storno 583167 583167 0 0.00 0 91864 75 0 0
sp_lock_selected_doc 19952 19952 0 0.00 0 7148 6 0 0
sp_make_cost_storno 27045 27045 0 0.00 0 12827 61 0 0
sp_multiply_rows_for_pdistr 27045 27045 0 0.00 0 9155 34 0 0
sp_multiply_rows_for_qdistr 20005 20005 0 0.00 0 20208 225 0 0
sp_pay_from_customer 6793 6793 0 0.00 4 13618 119 0 0
sp_pay_to_supplier 5777 5777 0 0.00 5 17590 151 0 0
sp_payment_common 12570 12570 0 0.00 1 8658 27 0 0
sp_qd_handle_on_cancel_clo 1645 1645 0 0.00 0 9717 310 0 0
sp_qd_handle_on_invoice_upd_sts 10274 10274 0 0.00 0 21166 363 0 0
sp_qd_handle_on_reserve_upd_sts 9762 9762 0 0.00 0 14328 56 0 0
sp_reserve_write_off 9762 9762 0 0.00 7 14342 183 0 0
sp_supplier_invoice 5232 5232 0 0.00 6 109176 3778 0 0
sp_supplier_order 5218 5218 0 0.00 6 91233 1412 0 0
srv_aggregate_perf_data 116 115 1 0.86 2541 123430 13029 0 1
srv_fill_mon_cache_memory 59 59 0 0.00 2614 156155 53637 0 0
srv_find_qd_qs_mism 1021049 1021049 0 0.00 0 10981 5 0 0
srv_make_invnt_saldo 424 424 0 0.00 61 91011 3618 0 0
srv_make_money_saldo 637 637 0 0.00 1 28423 611 0 0
srv_recalc_idx_stat 5 5 0 0.00 157623 437204 285512 0 0
t$perf-abend:sp_add_invoice_to_stock 125 125 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4720 4720 0 0.00 0 11290 154 0 0
t$perf-norm:sp_cancel_adding_invoice 396 396 0 0.00 0 2895 77 0 0
t$perf-norm:sp_cancel_client_order 1645 1645 0 0.00 0 6724 15 0 0
t$perf-norm:sp_cancel_customer_reserve 661 661 0 0.00 0 4229 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 637 637 0 0.00 0 304 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 393 393 0 0.00 0 485 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 393 393 0 0.00 0 788 18 0 0
t$perf-norm:sp_cancel_supplier_order 378 378 0 0.00 0 21893 1539 0 0
t$perf-norm:sp_cancel_write_off 974 974 0 0.00 0 6783 14 0 0
t$perf-norm:sp_client_order 20005 20005 0 0.00 0 6256 19 0 0
t$perf-norm:sp_customer_reserve 974 974 0 0.00 0 731 4 0 0
t$perf-norm:sp_pay_from_customer 6793 6793 0 0.00 0 7283 11 0 0
t$perf-norm:sp_pay_to_supplier 5777 5777 0 0.00 0 12332 11 0 0
t$perf-norm:sp_reserve_write_off 9762 9762 0 0.00 0 3851 6 0 0
t$perf-norm:sp_supplier_invoice 5232 5232 0 0.00 0 10958 17 0 0
t$perf-norm:sp_supplier_order 5218 5218 0 0.00 0 4275 14 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 304 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 59 59 0 0.00 0 153 4 0 0
t$perf-norm:srv_make_invnt_saldo 424 424 0 0.00 0 6098 29 0 0
t$perf-norm:srv_make_money_saldo 637 637 0 0.00 0 406 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 6 3 0 0
v_add_invoice_to_stock 9676 9426 250 2.58 0 3325 15 0 250
v_all_customers 36156 36156 0 0.00 0 54 1 0 0
v_all_suppliers 29456 29456 0 0.00 0 29 1 0 0
v_all_wares 40004 40004 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 792 792 0 0.00 0 378 6 0 0
v_cancel_client_order 3290 3290 0 0.00 0 1329 3 0 0
v_cancel_customer_prepayment 1274 1274 0 0.00 0 967 6 0 0
v_cancel_customer_reserve 1322 1322 0 0.00 0 112 3 0 0
v_cancel_payment_to_supplier 786 786 0 0.00 0 574 4 0 0
v_cancel_supplier_invoice 800 786 14 1.75 0 844 14 0 14
v_cancel_supplier_order 756 756 0 0.00 0 4212 9 0 0
v_cancel_write_off 1948 1948 0 0.00 0 2616 15 0 0
v_max_id_clo_ord 5216 5216 0 0.00 0 388 1 0 0
v_max_id_clo_res 974 974 0 0.00 0 4745 19 0 0
v_max_id_ord_sup 5229 5229 0 0.00 0 7787 8 0 0
v_max_non_paid_invoice 1494 1494 0 0.00 0 136 2 0 0
v_max_non_paid_realizn 3656 3656 0 0.00 0 8654 6 0 0
v_min_id_clo_ord 5216 5216 0 0.00 0 9818 9 0 0
v_min_id_clo_res 974 974 0 0.00 0 6259 18 0 0
v_min_id_ord_sup 5229 5229 0 0.00 0 5876 8 0 0
v_min_non_paid_invoice 5777 5777 0 0.00 0 6641 21 0 0
v_min_non_paid_realizn 6793 6793 0 0.00 0 6913 17 0 0
v_our_firm 10122 10122 0 0.00 0 11 1 0 0
v_reserve_write_off 19524 19524 0 0.00 0 9537 5 0 0
x_lock_depdocs_on_canc_invoice 11337 11337 0 0.00 0 19092 110 0 0
x_lock_depdocs_on_canc_sup_ord 378 378 0 0.00 0 28378 528 0 0
x_make_qstorno_1000_1200 5218 5218 0 0.00 2 89505 1327 0 0
x_make_qstorno_1200_2000 5232 5232 0 0.00 3 93828 3577 0 0
x_make_qstorno_2100_3300 438180 438180 0 0.00 0 114277 138 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-11-2020 04:29:23 to 02-11-2020 04:29: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
01:56:57 3 8192000000 8798369104 8809598976 4436416 0.054 2 1 2 0 4535888 63616 35856
01:57:59 12760 8192000000 9722193296 10020712448 804858784 8.945 100 85 170 0 833187440 7523472 20805184
01:59:17 25630 8192000000 9767014112 10076622848 847261440 9.373 100 84 168 1 878648048 11868768 19517840
02:00:50 18984 8192000000 9808120032 10124382208 873994064 9.640 100 92 184 2 915097616 19753248 21350304
02:02:12 18428 8192000000 9854153824 10186977280 904200224 9.940 100 78 156 0 962539488 40474320 17864944
02:03:35 10098 8192000000 9911368208 10253549568 949274160 10.384 100 85 170 0 1022763008 55783904 17704944
02:04:53 7558 8192000000 9972647600 10320961536 987471472 10.757 100 82 163 3 1073779312 70097312 16210528
02:06:02 4585 8192000000 9980503840 10343251968 1015018544 11.024 100 79 155 1 1100596944 69167968 16410432
02:07:10 2028 8192000000 10004802128 10370445312 1048732224 11.349 100 85 171 0 1115085920 48608288 17745408
02:08:13 11987 8192000000 10026854624 10398027776 1058458400 11.442 100 68 135 1 1140837248 68842320 13536528
02:09:37 4573 8192000000 9987562416 10375372800 1065590144 11.510 100 36 70 1 1107945264 35621280 6733840
02:10:42 9841 8192000000 9981696528 10381643776 1075087056 11.601 100 29 57 2 1097994848 16173120 6734672
02:11:55 5316 8192000000 10003080064 10398318592 1082005664 11.667 100 36 72 1 1120772064 31726464 7039936
02:13:01 62987 8192000000 10019564528 10436075520 1085313728 11.698 100 39 78 0 1115671392 22062160 8295504
02:15:06 8674 8192000000 9998592688 10399313920 1088192480 11.725 100 30 60 2 1117859888 23176720 6490688
02:16:15 5886 8192000000 9999161296 10395353088 1086825424 11.712 99 33 67 1 1115045344 21127936 7091984
02:17:21 5631 8192000000 9995833344 10393706496 1068790272 11.541 99 44 87 1 1094947568 17028464 9128832
02:18:27 36826 8192000000 10016035360 10415071232 1053008912 11.390 98 43 86 2 1095624912 33261136 9354864
02:20:05 6701 8192000000 9945440928 10331058176 1010826976 10.983 98 40 79 1 1043915760 24438240 8650544
02:21:12 11473 8192000000 9916761760 10302619648 975588896 10.641 97 36 72 5 1005135792 21049920 8496976
02:22:24 6232 8192000000 9921776912 10299809792 1013381616 11.008 100 38 73 5 1045508288 24072480 8054192
02:23:32 53321 8192000000 9961792256 10366132224 1039850192 11.263 100 30 60 2 1070410560 23872640 6687728
02:25:27 6170 8192000000 9967053552 10363543552 1052639648 11.386 100 44 87 2 1074807040 12329904 9837488
02:26:37 12488 8192000000 9981693664 10378510336 1073139408 11.582 100 26 50 2 1095489472 17122976 5227088
02:27:57 26494 8192000000 10040895728 10434154496 1099810048 11.836 100 44 87 3 1136944032 27867744 9266240
02:29:26 81353 8192000000 10105324192 10522984448 1118547040 12.013 100 41 82 0 1203328032 75256912 9524080
02:31:55 6069 8192000000 10160463088 10561531904 1134646384 12.165 100 49 98 0 1269739776 124521552 10571840
02:33:08 42083 8192000000 10183259424 10593030144 1136833696 12.186 100 57 114 0 1281258720 131359440 13065584
02:34:54 4366 8192000000 10181214240 10601107456 1146843840 12.280 100 49 98 2 1294307312 136379840 11083632
02:36:01 2616 8192000000 10241654432 10648625152 1156342912 12.369 100 68 132 1 1342391824 171717200 14331712
02:37:10 14123 8192000000 10205295296 10615930880 1167681264 12.475 100 62 122 2 1304601008 122680112 14239632
02:38:28 2648 8192000000 10222683744 10629726208 1154528288 12.352 99 78 151 2 1314493648 142098592 17866768
02:39:35 3466 8192000000 10279565328 10694352896 1158055776 12.385 99 71 142 3 1374356304 201078080 15222448
02:40:46 2601 8192000000 10266721328 10669207552 1163892352 12.440 100 76 151 1 1360033056 178974560 17166144
02:41:54 4913 8192000000 10282793664 10695622656 1157852704 12.383 99 64 128 1 1382781680 210665616 14263360
02:43:04 5872 8192000000 10329404800 10742808576 1157262896 12.378 99 77 153 0 1411839392 236893504 17682992
02:44:20 7644 8192000000 10300474016 10727256064 1135321024 12.171 100 39 77 1 1404876544 259998368 9557152
02:45:39 18546 8192000000 10265480768 10689261568 1090869920 11.751 95 30 60 1 1333257664 234932832 7454912
02:47:06 3463 8192000000 10328252992 10755289088 1148664592 12.297 100 61 120 0 1448806704 285503392 14638720
02:48:15 8789 8192000000 10310401424 10717405184 1125779408 12.082 98 42 82 2 1399032512 263540928 9712176
02:49:26 9784 8192000000 10253924864 10685292544 1120519200 12.032 100 31 62 0 1364228400 236417216 7291984
02:50:38 19890 8192000000 10307108864 10731372544 1126063776 12.084 100 24 48 0 1400287424 268054176 6169472
02:52:02 16798 8192000000 10250046608 10714423296 1122400016 12.050 100 22 44 1 1358924176 230655568 5868592
02:53:20 12253 8192000000 10221676896 10638061568 1102579248 11.862 98 38 75 0 1321426192 207636592 11210352
02:55:17 30668 8192000000 10027763568 10482319360 1102140832 11.858 100 12 24 0 1151798304 47339632 2317840
02:56:55 68316 8192000000 10149529568 10595676160 1124972208 12.074 100 29 57 1 1218371856 87608192 5791456
02:59:03 120099 8192000000 10104728656 10527014912 1132248880 12.143 100 26 52 1 1190675360 52592976 5833504
03:02:09 111144 8192000000 10043963376 10480627712 1095845536 11.798 96 23 46 1 1164933152 64649024 4438592
03:05:08 68994 8192000000 10104139808 10543828992 1108759712 11.921 97 29 58 0 1170039680 54798912 6481056
03:07:30 153747 8192000000 10208194800 10691584000 1146425712 12.276 99 19 38 1 1264889472 114155648 4308112
03:11:04 34002 8192000000 10124842384 10596999168 1145824928 12.270 100 19 36 1 1247714400 97655408 4234064
03:12:38 55553 8192000000 10090620000 10527535104 1152468336 12.333 100 28 56 4 1209250464 50680944 6101184
03:14:46 77847 8192000000 10079379984 10557722624 1142963696 12.243 99 7 15 0 1172035408 27658768 1412944
03:17:06 73381 8192000000 10160458704 10605531136 1134488336 12.164 98 25 49 2 1182119248 42010736 5620176
03:20:27 78724 8192000000 10112171200 10589143040 1151587392 12.324 99 19 37 2 1209348336 53810384 3950560
03:22:55 46074 8192000000 10129502976 10594361344 1152444000 12.332 99 20 40 0 1209945952 53580656 3921296
03:24:41 98087 8192000000 10106207184 10576179200 1139001136 12.206 98 10 20 0 1197946048 56790752 2154160
03:27:33 79062 8192000000 10067448240 10558795776 1134098608 12.160 98 24 48 0 1193736432 54638880 4998944
03:30:07 84469 8192000000 10099012688 10577797120 1132203056 12.142 98 9 18 0 1180340464 46092992 2044416
03:32:31 72188 8192000000 10107607040 10580013056 1146685872 12.278 99 24 48 1 1237981360 85838432 5457056
03:34:48 85884 8192000000 10097593568 10623561728 1110969584 11.942 97 16 32 0 1210809184 96029408 3810192
03:37:14 52066 8192000000 10113168688 10544967680 1081500144 11.662 95 21 42 2 1161761856 75315712 4946000
03:39:07 38166 8192000000 10107683168 10545213440 1114675648 11.977 99 24 49 3 1183850864 63870240 5304976
03:40:52 60900 8192000000 10063715088 10478899200 1057354176 11.431 94 19 37 1 1081243568 19708304 4181088
03:42:57 93207 8192000000 10046340784 10518396928 1030133936 11.170 93 18 36 0 1082439728 48514336 3791456
03:45:33 156155 8192000000 10031118016 10466926592 1038819120 11.253 94 13 25 1 1072109696 30658272 2632304
03:49:14 45482 8192000000 10023528576 10461040640 1099138256 11.829 100 8 16 0 1125408912 24574240 1696416
03:51:00 25249 8192000000 10018203952 10468671488 1102154544 11.858 100 3 6 0 1122911296 20287152 469600
03:52:25 82430 8192000000 10078060256 10534748160 1101781888 11.855 99 10 20 1 1141927872 38363440 1782544
03:55:29 55375 8192000000 10104301840 10530283520 1119216608 12.020 99 27 54 3 1198226400 73102048 5907744
03:57:26 62887 8192000000 10143914768 10580307968 1114406592 11.974 99 27 54 1 1212745488 92812144 5526752
03:59:36 30002 8192000000 10055517376 10480599040 1093067216 11.772 97 12 24 1 1131029632 35503776 2458640
04:01:06 60298 8192000000 10072612064 10525360128 1090076416 11.743 97 17 34 0 1136309216 43079056 3153744
04:03:14 59641 8192000000 10098480192 10520477696 1112965408 11.960 98 22 44 0 1194427936 77126960 4335568
04:05:23 108529 8192000000 10099134480 10593054720 1128741152 12.109 99 6 12 0 1196692368 66812672 1138544
04:08:12 113777 8192000000 10103851456 10568650752 1135734880 12.175 99 9 18 0 1194704544 57170784 1798880
04:11:54 88975 8192000000 10136444320 10592743424 1131066192 12.131 98 17 34 1 1202831488 68260640 3504656
04:14:23 43757 8192000000 10101253872 10541035520 1150636800 12.315 100 18 35 2 1211988384 57450032 3901552
04:16:11 51894 8192000000 10121107856 10561789952 1123293216 12.058 97 25 50 1 1172508960 44409616 4806128
04:18:22 30287 8192000000 10173482864 10610372608 1160178160 12.405 100 33 65 1 1259884656 92770192 6936304
04:19:52 71621 8192000000 10125204928 10571407360 1123599232 12.061 97 14 28 0 1252654544 125677808 3377504
04:22:08 122525 8192000000 10060245824 10476765184 1068900128 11.542 93 27 52 3 1108914704 34507888 5506688
04:25:12 79287 8192000000 10069134704 10532499456 1139098464 12.207 100 14 28 0 1186779696 44678720 3002512

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 02-11-2020 04:29:29 to 02-11-2020 04:29:43.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 125 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 02-11-2020 04:29:44 to 02-11-2020 04:29:46.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          228902
MON$OLDEST_ACTIVE               229357
MON$OLDEST_SNAPSHOT             229357
MON$NEXT_TRANSACTION            229357
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-30 07:30:08.6030 GMT
MON$PAGES                       2100480
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             781
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2242 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2242 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2242 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2242 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
on disk structure version 13.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 ---
:652: 
:653: mscs-test-161.budzdorov.ru    Mon Nov  2 04:26:55 2020
:654:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:655: 
:656: 
:657: mscs-test-161.budzdorov.ru    Mon Nov  2 04:26:57 2020
:658:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:659: 
:660: 
:661: mscs-test-161.budzdorov.ru    Mon Nov  2 04:27:00 2020
:662:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:663: 
:664: 
:665: mscs-test-161.budzdorov.ru    Mon Nov  2 04:27:11 2020
:666:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:667: 
--- end of diff output ---

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

02.11.20 04:29:47. There are 3 logs in /var/tmp/logs-oltp40 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-11-02 04:29:50 No severe PSQL-related problems occured
02.11.20 04:29:51. End of report.