Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.04.2021 10:27
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 df9f488d08424df294c459d6c283e9c3
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 2021-03-04 16:07
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.26
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 16755816 kB
MemAvailable 31402216 kB
Buffers 0 kB
Cached 14857548 kB
SwapCached 0 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 2021-04-04 10:26:58
DTS_END                         2021-04-04 10:26:58.1440
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/55338
TRN_ID                          238768
ATT_ID                          662
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: 9025.28 25242 1083033
customer order: creation 171.09 424 20531
customer order: refuse 14.83 358 1780
order to supplier: creation 46.81 1358 5617
order to supplier: removal 3.35 112994 402
invoice (draft): creation 44.75 3614 5370
invoice (draft): removal 51.23 981 6147
invoice accept: apply 41.11 17043 4933
invoice accept: cancel 46.13 857 5535
customer reserve: creation 3783.22 167 453986
customer reserve: removal 4534.19 66 544103
realization accept: apply 83.09 171 9971
realization accept: cancel 78.69 111 9443
payment from customer: creation 58.22 127 6986
payment from customer: removal 5.96 65 715
payment to supplier: creation 48.17 167 5780
payment to supplier: removal 3.52 115 422
service: total inventory turnovers 3.77 3306 452
service: total monetary turnovers 6.36 520 763
service: aggregate perf. data 0.78 18400 93
service: refresh index statistics 0.03 343988 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 04-04-2021 10:27:56 to 04-04-2021 10:27:58.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1668
TEST_TIME 2 2340
TEST_TIME 3 2414
TEST_TIME 4 2594
TEST_TIME 5 2793
TEST_TIME 6 3510
TEST_TIME 7 3674
TEST_TIME 8 4060
TEST_TIME 9 4208
TEST_TIME 10 4807
TEST_TIME 11 5043
TEST_TIME 12 5297
TEST_TIME 13 5627
TEST_TIME 14 6102
TEST_TIME 15 6124
TEST_TIME 16 6355
TEST_TIME 17 7391
TEST_TIME 18 7314
TEST_TIME 19 7654
TEST_TIME 20 7655
TEST_TIME 21 8173
TEST_TIME 22 8585
TEST_TIME 23 8736
TEST_TIME 24 8815
TEST_TIME 25 9049
TEST_TIME 26 9358
TEST_TIME 27 9955
TEST_TIME 28 10672
TEST_TIME 29 11071
TEST_TIME 30 10991
TEST_TIME 31 10668
TEST_TIME 32 10394
TEST_TIME 33 10249
TEST_TIME 34 10160
TEST_TIME 35 10086
TEST_TIME 36 10114
TEST_TIME 37 10095
TEST_TIME 38 9964
TEST_TIME 39 9802
TEST_TIME 40 9935
TEST_TIME 41 9904
TEST_TIME 42 9890
TEST_TIME 43 9869
TEST_TIME 44 9931
TEST_TIME 45 9853
TEST_TIME 46 9825
TEST_TIME 47 9930
TEST_TIME 48 10041
TEST_TIME 49 10040
TEST_TIME 50 10023
TEST_TIME 51 10035
TEST_TIME 52 9990
TEST_TIME 53 9886
TEST_TIME 54 9721
TEST_TIME 55 9632
TEST_TIME 56 9547
TEST_TIME 57 9440
TEST_TIME 58 9350
TEST_TIME 59 9323
TEST_TIME 60 9372
TEST_TIME 61 9291
TEST_TIME 62 9308
TEST_TIME 63 9482
TEST_TIME 64 9436
TEST_TIME 65 9474
TEST_TIME 66 9630
TEST_TIME 67 9604
TEST_TIME 68 9555
TEST_TIME 69 9615
TEST_TIME 70 9628
TEST_TIME 71 9658
TEST_TIME 72 9693
TEST_TIME 73 9673
TEST_TIME 74 9576
TEST_TIME 75 9515
TEST_TIME 76 9470
TEST_TIME 77 9388
TEST_TIME 78 9376
TEST_TIME 79 9350
TEST_TIME 80 9315
TEST_TIME 81 9262
TEST_TIME 82 9302
TEST_TIME 83 9339
TEST_TIME 84 9274
TEST_TIME 85 9320
TEST_TIME 86 9313
TEST_TIME 87 9297
TEST_TIME 88 9333
TEST_TIME 89 9327
TEST_TIME 90 9375
TEST_TIME 91 9286
TEST_TIME 92 9238
TEST_TIME 93 9185
TEST_TIME 94 9156
TEST_TIME 95 9161
TEST_TIME 96 9135
TEST_TIME 97 9114
TEST_TIME 98 9086
TEST_TIME 99 9067
TEST_TIME 100 9141
TEST_TIME 101 9153
TEST_TIME 102 9130
TEST_TIME 103 9097
TEST_TIME 104 9068
TEST_TIME 105 9262
TEST_TIME 106 9248
TEST_TIME 107 9261
TEST_TIME 108 9296
TEST_TIME 109 9231
TEST_TIME 110 9154
TEST_TIME 111 9141
TEST_TIME 112 9103
TEST_TIME 113 9080
TEST_TIME 114 9055
TEST_TIME 115 9032
TEST_TIME 116 9031
TEST_TIME 117 9047
TEST_TIME 118 9016
TEST_TIME 119 9025
TEST_TIME 120 9027

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 04-04-2021 10:28:00 to 04-04-2021 10:28:03.

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 1504628 1504628 0 0.00 0 38877 7 0 0
doc_list_biud 1504623 1504623 0 0.00 0 51935 31 0 0
sp_add_invoice_to_stock 5079 4933 146 2.87 24 180965 17043 0 146
sp_cancel_adding_invoice 5535 5535 0 0.00 4 113390 857 0 0
sp_cancel_client_order 1780 1780 0 0.00 4 14307 358 0 0
sp_cancel_customer_reserve 544103 544103 0 0.00 1 35961 67 0 0
sp_cancel_pay_from_customer 715 715 0 0.00 3 5158 66 0 0
sp_cancel_pay_to_supplier 422 422 0 0.00 3 20601 115 0 0
sp_cancel_supplier_invoice 6159 6147 12 0.19 2 34820 981 0 12
sp_cancel_supplier_order 402 402 0 0.00 7 1137990 112995 0 0
sp_cancel_write_off 9443 9443 0 0.00 1 23511 111 0 0
sp_client_order 20531 20531 0 0.00 4 45368 425 0 0
sp_customer_reserve 453986 453986 0 0.00 0 53050 167 0 0
sp_fill_shopping_cart 31507 31507 0 0.00 1 22777 43 0 0
sp_fill_shopping_cart_clo_res 453983 453983 0 0.00 0 35165 18 0 0
sp_get_clo_for_invoice 4930 4930 0 0.00 0 45920 431 0 0
sp_kill_cost_storno 16115 16115 0 0.00 0 23505 52 0 0
sp_kill_qstorno_ret_qs2qd 1104198 1104198 0 0.00 0 35955 37 0 0
sp_kill_qty_storno 582310 582310 0 0.00 0 51934 78 0 0
sp_lock_selected_doc 20690 20690 0 0.00 0 17033 7 0 0
sp_make_cost_storno 27667 27667 0 0.00 0 33043 64 0 0
sp_multiply_rows_for_pdistr 27665 27665 0 0.00 0 15626 32 0 0
sp_multiply_rows_for_qdistr 20531 20531 0 0.00 0 45357 245 0 0
sp_pay_from_customer 6986 6986 0 0.00 4 27605 128 0 0
sp_pay_to_supplier 5780 5780 0 0.00 4 38896 168 0 0
sp_payment_common 12763 12763 0 0.00 1 25711 34 0 0
sp_qd_handle_on_cancel_clo 1780 1780 0 0.00 0 14301 315 0 0
sp_qd_handle_on_invoice_upd_sts 10464 10464 0 0.00 0 51934 353 0 0
sp_qd_handle_on_reserve_upd_sts 9971 9971 0 0.00 0 15695 46 0 0
sp_reserve_write_off 9971 9971 0 0.00 7 37751 172 0 0
sp_supplier_invoice 5370 5370 0 0.00 8 123878 3614 0 0
sp_supplier_order 5617 5617 0 0.00 5 52928 1358 0 0
srv_aggregate_perf_data 94 93 1 1.06 2491 282290 18400 0 1
srv_fill_mon_cache_memory 62 62 0 0.00 1509 120496 45877 0 0
srv_find_qd_qs_mism 1036522 1036522 0 0.00 0 24468 5 0 0
srv_make_invnt_saldo 452 452 0 0.00 52 104936 3306 0 0
srv_make_money_saldo 763 763 0 0.00 0 15259 520 0 0
srv_recalc_idx_stat 4 4 0 0.00 220375 496840 343989 0 0
t$perf-abend:sp_add_invoice_to_stock 146 146 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 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 4933 4933 0 0.00 0 8019 135 0 0
t$perf-norm:sp_cancel_adding_invoice 391 391 0 0.00 0 1524 60 0 0
t$perf-norm:sp_cancel_client_order 1780 1780 0 0.00 0 1111 10 0 0
t$perf-norm:sp_cancel_customer_reserve 685 685 0 0.00 0 736 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 715 715 0 0.00 0 1129 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 422 422 0 0.00 0 260 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 379 379 0 0.00 0 930 15 0 0
t$perf-norm:sp_cancel_supplier_order 402 402 0 0.00 0 17985 1354 0 0
t$perf-norm:sp_cancel_write_off 1016 1016 0 0.00 0 420 3 0 0
t$perf-norm:sp_client_order 20531 20531 0 0.00 0 5924 15 0 0
t$perf-norm:sp_customer_reserve 982 982 0 0.00 0 813 4 0 0
t$perf-norm:sp_pay_from_customer 6986 6986 0 0.00 0 3093 7 0 0
t$perf-norm:sp_pay_to_supplier 5780 5780 0 0.00 0 6124 8 0 0
t$perf-norm:sp_reserve_write_off 9971 9971 0 0.00 0 6647 9 0 0
t$perf-norm:sp_supplier_invoice 5370 5370 0 0.00 0 3888 16 0 0
t$perf-norm:sp_supplier_order 5617 5617 0 0.00 0 6179 16 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 1498 25 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 8 1 0 0
t$perf-norm:srv_make_invnt_saldo 452 452 0 0.00 0 576 8 0 0
t$perf-norm:srv_make_money_saldo 763 763 0 0.00 0 5090 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 3 0 0
v_add_invoice_to_stock 10150 9858 292 2.87 0 3938 16 0 292
v_all_customers 37456 37456 0 0.00 0 18 1 0 0
v_all_suppliers 30236 30236 0 0.00 0 19 1 0 0
v_all_wares 41060 41060 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 782 782 0 0.00 0 930 6 0 0
v_cancel_client_order 3560 3560 0 0.00 0 2960 7 0 0
v_cancel_customer_prepayment 1430 1430 0 0.00 0 745 4 0 0
v_cancel_customer_reserve 1370 1370 0 0.00 0 3195 9 0 0
v_cancel_payment_to_supplier 844 844 0 0.00 0 1286 9 0 0
v_cancel_supplier_invoice 782 758 24 3.06 0 216 12 0 24
v_cancel_supplier_order 804 804 0 0.00 0 1865 9 0 0
v_cancel_write_off 2032 2032 0 0.00 0 7026 19 0 0
v_max_id_clo_ord 5616 5616 0 0.00 0 3340 2 0 0
v_max_id_clo_res 982 982 0 0.00 0 8330 27 0 0
v_max_id_ord_sup 5361 5361 0 0.00 0 4717 7 0 0
v_max_non_paid_invoice 1638 1638 0 0.00 0 11256 9 0 0
v_max_non_paid_realizn 3711 3711 0 0.00 0 6187 6 0 0
v_min_id_clo_ord 5616 5616 0 0.00 0 6700 7 0 0
v_min_id_clo_res 982 982 0 0.00 0 3488 21 0 0
v_min_id_ord_sup 5361 5361 0 0.00 0 7469 8 0 0
v_min_non_paid_invoice 5779 5779 0 0.00 0 22727 27 0 0
v_min_non_paid_realizn 6984 6984 0 0.00 0 16747 26 0 0
v_our_firm 10150 10150 0 0.00 0 16 1 0 0
v_reserve_write_off 19942 19942 0 0.00 0 3455 5 0 0
x_lock_depdocs_on_canc_invoice 11303 11303 0 0.00 0 18711 96 0 0
x_lock_depdocs_on_canc_sup_ord 402 402 0 0.00 0 7947 341 0 0
x_make_qstorno_1000_1200 5617 5617 0 0.00 1 51766 1283 0 0
x_make_qstorno_1200_2000 5370 5370 0 0.00 4 123817 3447 0 0
x_make_qstorno_2100_3300 453985 453985 0 0.00 0 40756 147 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-04-2021 10:28:04 to 04-04-2021 10:28:08.

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
07:57:01 5 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
07:58:02 28866 8192000000 9710653584 10004279296 787755856 8.772 100 92 184 1 819080832 8072016 23252960
07:59:51 16374 8192000000 9755380192 10060414976 831624496 9.216 100 86 172 0 865113376 12594624 20894256
08:01:10 14657 8192000000 9804219104 10123997184 870977632 9.610 100 77 154 0 912696944 23756224 17963088
08:02:32 10717 8192000000 9870120784 10203377664 925950464 10.155 100 79 157 0 977454512 34458144 17045904
08:03:48 7877 8192000000 9929804400 10278580224 966657056 10.554 100 80 158 1 1044966368 61680848 16628464
08:04:58 4160 8192000000 9975875840 10328653824 1002143280 10.899 100 85 170 0 1094486848 75444208 16899360
08:06:05 2331 8192000000 10002314640 10366898176 1027072864 11.140 100 86 172 2 1122759360 77738624 17947872
08:07:14 1702 8192000000 10048497424 10417188864 1055537824 11.414 100 85 167 2 1154839856 82606496 16695536
08:08:17 1250 8192000000 10045197952 10418192384 1069644928 11.549 100 92 184 1 1162969808 74760096 18564784
08:09:19 5558 8192000000 10008822352 10413416448 1073244416 11.583 100 27 54 0 1120647776 42029584 5373776
08:10:28 4907 8192000000 9988944832 10383831040 1082917072 11.675 100 43 83 3 1111473504 19133296 9423136
08:11:36 7079 8192000000 9995815696 10402619392 1092124576 11.763 100 32 63 1 1125743344 26908576 6710192
08:12:45 68886 8192000000 10005689280 10420944896 1094891424 11.789 100 35 70 1 1128272032 25001200 8379408
08:14:57 5211 8192000000 10016108592 10412195840 1100438768 11.842 100 49 95 0 1126748416 15904208 10405440
08:16:02 8210 8192000000 10017078544 10429661184 1093992656 11.781 100 30 58 1 1123015408 22591472 6431280
08:17:16 6012 8192000000 9975747648 10368557056 1028527648 11.154 96 29 58 1 1056939632 21081104 7330880
08:18:23 37547 8192000000 9963780000 10364178432 1005113984 10.928 94 30 60 2 1031367904 19600624 6653296
08:20:02 10358 8192000000 9948754032 10345697280 998818144 10.867 96 38 76 2 1029122256 22131968 8172144
08:21:15 4662 8192000000 9943677456 10324815872 1029533472 11.164 100 58 116 0 1056372320 12905184 13933664
08:22:21 11140 8192000000 9978857760 10372100096 1044295088 11.306 100 38 75 1 1084686960 31598528 8793344
08:23:40 10648 8192000000 9956491472 10362830848 1043427184 11.298 99 23 46 2 1065041984 16672736 4942064
08:24:53 7577 8192000000 9980744800 10370678784 1068208368 11.535 100 59 117 2 1100139472 17945184 13985920
08:26:03 8160 8192000000 10014534928 10413219840 1074920752 11.599 100 46 91 0 1105725200 20083456 10720992
08:27:18 20506 8192000000 10017170352 10410930176 1096693344 11.806 100 48 92 1 1119357648 12817376 9846928
08:28:46 63714 8192000000 10055362336 10466910208 1116860224 11.997 100 29 59 1 1171884480 48679328 6344928
08:30:54 22771 8192000000 10120045920 10531332096 1136417232 12.182 100 42 84 3 1229207984 84238096 8552656
08:32:39 12740 8192000000 10138662608 10550980608 1147561328 12.287 100 55 110 2 1258231328 98258048 12411952
08:33:54 11422 8192000000 10190300400 10606678016 1160953920 12.412 100 49 98 2 1297440176 125917920 10568336
08:35:09 13776 8192000000 10228919168 10638778368 1161915376 12.421 100 60 120 2 1331416544 155655520 13845648
08:36:26 6013 8192000000 10221834704 10644566016 1163945872 12.440 100 61 121 1 1327061952 149982288 13133792
08:37:38 6735 8192000000 10276516864 10685874176 1157774544 12.382 99 61 120 0 1368390784 197282096 13334144
08:38:50 5553 8192000000 10320119856 10723934208 1172034256 12.516 100 69 138 0 1421268832 233751728 15482848
08:40:01 3102 8192000000 10353961008 10761900032 1171603360 12.512 99 81 161 0 1445947936 255563344 18781232
08:41:08 7671 8192000000 10353262336 10766508032 1160001680 12.403 100 61 122 1 1459979872 285993424 13984768
08:42:22 5464 8192000000 10386699568 10790952960 1146517808 12.277 100 76 150 1 1483064048 318671952 17874288
08:43:34 1507 8192000000 10388341120 10792534016 1152868240 12.336 100 86 172 0 1483655120 311375696 19411184
08:44:41 3926 8192000000 10354765184 10773114880 1140172000 12.217 100 65 128 0 1456124592 300373152 15579440
08:45:52 3044 8192000000 10359254400 10759569408 1127287312 12.096 100 81 162 2 1450089696 304182464 18619920
08:46:58 4646 8192000000 10387737584 10802044928 1124966688 12.074 100 50 99 1 1491160128 353052464 13140976
08:48:05 6294 8192000000 10363120400 10806870016 1130351776 12.125 100 47 93 0 1461212240 319551264 11309200
08:49:20 26594 8192000000 10343045664 10790998016 1115597104 11.985 99 25 50 0 1431790208 309586864 6606240
08:50:53 16549 8192000000 10365187616 10835050496 1117027712 11.999 100 30 60 1 1466325184 341343216 7954256
08:52:18 18921 8192000000 10325353168 10778816512 1136166784 12.179 100 28 56 0 1458228080 314938096 7123200
08:53:49 30707 8192000000 10271264688 10744430592 1120861632 12.035 98 21 42 2 1373413104 246868368 5683104
08:55:45 120496 8192000000 10060905696 10492219392 1104914880 11.884 98 18 36 1 1141336560 32799680 3622000
08:59:04 51412 8192000000 10093280048 10541355008 1128744592 12.110 99 26 52 2 1175942736 41790400 5407744
09:01:04 54582 8192000000 10148745248 10611400704 1153517696 12.343 100 38 77 1 1260469056 98826496 8124864
09:03:00 89003 8192000000 10111595200 10593226752 1105000944 11.885 97 12 24 0 1193979040 86005008 2973088
09:05:29 38122 8192000000 10136784336 10569764864 1108836832 11.921 98 29 57 0 1202767520 87565584 6365104
09:07:11 41962 8192000000 10076379536 10539487232 1128654240 12.109 99 15 29 1 1172514128 40430576 3429312
09:09:45 57476 8192000000 10079032096 10584346624 1141066864 12.226 99 23 46 1 1215724992 69689248 4968880
09:11:51 39205 8192000000 10156013904 10617880576 1139816880 12.214 99 25 50 1 1217389728 72501392 5071456
09:13:52 87664 8192000000 10106111824 10589122560 1146113376 12.273 99 19 38 2 1205009520 54634736 4261408
09:16:53 56818 8192000000 10095459472 10576740352 1131848064 12.139 98 25 50 2 1170619536 33790624 4980848
09:18:56 119711 8192000000 10060780336 10510217216 1136504400 12.183 99 24 48 1 1180380720 39119152 4757168
09:22:01 68569 8192000000 10068232608 10523766784 1152311056 12.331 100 12 23 0 1183777952 28991792 2475104
09:24:09 77285 8192000000 10094797072 10565623808 1120893232 12.035 98 8 16 0 1185518032 62868736 1756064
09:27:20 29875 8192000000 10097916576 10529288192 1115347840 11.983 97 30 60 1 1181115600 59597856 6169904
09:28:51 67006 8192000000 10077048720 10603425792 1141823792 12.233 100 11 22 0 1234400672 89917264 2659616
09:30:58 107848 8192000000 10094953344 10614050816 1088617104 11.730 97 22 42 3 1175274848 82410912 4246832
09:33:52 52496 8192000000 10082547312 10514116608 1069354496 11.546 95 30 58 1 1123284000 47942816 5986688
09:36:08 81193 8192000000 10111741872 10543620096 1111274016 11.944 99 17 34 1 1166155536 51270592 3610928
09:38:29 56422 8192000000 10075746448 10590625792 1094055568 11.781 97 28 56 2 1206293712 106795008 5443136
09:41:30 66552 8192000000 10041502608 10508165120 1104744976 11.883 99 16 32 0 1136201056 28784592 2671488
09:43:51 84779 8192000000 10009823536 10485870592 1042649248 11.290 93 10 20 1 1084491728 39761760 2080720
09:46:16 54951 8192000000 10087861216 10539397120 1104403504 11.879 98 28 56 0 1175311040 64872112 6035424
09:48:21 79084 8192000000 10047854080 10541178880 1094944992 11.790 98 16 30 1 1181712512 83102016 3665504
09:50:46 20466 8192000000 10088275280 10528296960 1105076064 11.886 98 46 92 0 1160057808 45636576 9345168
09:53:07 63727 8192000000 10076099376 10516787200 1103003280 11.866 98 28 56 0 1153120864 44582496 5535088
09:55:13 39933 8192000000 10089465232 10543521792 1107370608 11.908 98 13 26 0 1163257456 53263504 2623344
09:57:21 84474 8192000000 10075594864 10527596544 1130812320 12.129 100 20 40 0 1170432880 35264992 4355568
09:59:48 32317 8192000000 10091008416 10562723840 1140876672 12.224 100 6 12 1 1195315776 53228720 1210384
10:01:21 25068 8192000000 10086172048 10582167552 1138831584 12.205 100 10 20 0 1192559344 51543312 2184448
10:02:46 34659 8192000000 10100496448 10549628928 1155203968 12.358 100 28 56 2 1194664320 33291520 6168832
10:04:24 57175 8192000000 10126788560 10582450176 1107768496 11.911 96 36 73 1 1186215728 71220384 7226848
10:06:21 63258 8192000000 10063704752 10577125376 1096036560 11.800 96 14 27 1 1180596784 81211680 3348544
10:08:24 39668 8192000000 10115704320 10555699200 1124370656 12.068 98 42 84 0 1180923152 47857808 8694688
10:10:06 85552 8192000000 10187520672 10700668928 1137517840 12.192 99 16 32 1 1294457280 153119008 3820432
10:12:44 48654 8192000000 10102229248 10544115712 1119269888 12.020 98 23 46 0 1158218864 34117632 4831344
10:14:33 75065 8192000000 10079496768 10569183232 1121080800 12.037 98 8 16 0 1159029008 36184416 1763792
10:17:41 51439 8192000000 10070959056 10560139264 1134178128 12.161 100 7 14 0 1176861680 41415424 1268128
10:19:54 59593 8192000000 10059317680 10506604544 1104535280 11.881 97 21 41 1 1131463968 21962464 4966224
10:21:57 54981 8192000000 10080931472 10549936128 1106579184 11.900 97 23 46 1 1155817824 44318800 4919840
10:23:52 83140 8192000000 10130253680 10578100224 1094832784 11.789 96 19 38 1 1161577488 62341408 4403296
10:26:17 50935 8192000000 9274364560 9585672192 398188880 4.635 36 10 20 0 449365360 48906288 2270192

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 04-04-2021 10:28:10 to 04-04-2021 10:28:25.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 146 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 04-04-2021 10:28:26 to 04-04-2021 10:28:27.

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          239309
MON$OLDEST_ACTIVE               239310
MON$OLDEST_SNAPSHOT             239310
MON$NEXT_TRANSACTION            239310
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                       2097024
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                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             808
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2404 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2404 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2404 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.2404 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 ---
:19: 
:20: mscs-test-161.budzdorov.ru    Sun Apr  4 10:27:13 2021
:21:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:22: 
:23: 
:24: mscs-test-161.budzdorov.ru    Sun Apr  4 10:27:13 2021
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:26: 
:27: 
:28: mscs-test-161.budzdorov.ru    Sun Apr  4 10:27:18 2021
:29:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:30: 
--- end of diff output ---

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

04.04.21 10:28:28. 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
2021-04-04 10:28:31 No severe PSQL-related problems occured
04.04.21 10:28:32. End of report.