Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.09.2021 07:48
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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 15705672 kB
MemAvailable 30855420 kB
Buffers 0 kB
Cached 16004624 kB
SwapCached 416 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.1
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-09-17 07:47:38
DTS_END                         2021-09-17 07:47:38.1940
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/41792
TRN_ID                          225984
ATT_ID                          576
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: 8893.42 28745 1067210
customer order: creation 158.78 424 19053
customer order: refuse 14.27 352 1712
order to supplier: creation 42.68 1640 5121
order to supplier: removal 3.46 111260 415
invoice (draft): creation 42.41 3936 5089
invoice (draft): removal 52.95 941 6354
invoice accept: apply 38.50 19940 4620
invoice accept: cancel 48.09 906 5771
customer reserve: creation 3531.76 197 423811
customer reserve: removal 4684.56 63 562147
realization accept: apply 77.58 227 9309
realization accept: cancel 79.15 132 9498
payment from customer: creation 54.83 120 6579
payment from customer: removal 4.95 86 594
payment to supplier: creation 45.93 149 5511
payment to supplier: removal 3.43 76 412
service: total inventory turnovers 3.30 4133 396
service: total monetary turnovers 5.98 613 718
service: aggregate perf. data 0.79 18284 95
service: refresh index statistics 0.04 411426 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-09-2021 07:48:57 to 17-09-2021 07:48:59.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4673
TEST_TIME 2 3630
TEST_TIME 3 4239
TEST_TIME 4 4591
TEST_TIME 5 4443
TEST_TIME 6 4437
TEST_TIME 7 4946
TEST_TIME 8 5225
TEST_TIME 9 5283
TEST_TIME 10 5544
TEST_TIME 11 5608
TEST_TIME 12 6036
TEST_TIME 13 6475
TEST_TIME 14 7003
TEST_TIME 15 6926
TEST_TIME 16 6995
TEST_TIME 17 7005
TEST_TIME 18 7384
TEST_TIME 19 8038
TEST_TIME 20 8177
TEST_TIME 21 8397
TEST_TIME 22 8545
TEST_TIME 23 8708
TEST_TIME 24 8692
TEST_TIME 25 8819
TEST_TIME 26 9397
TEST_TIME 27 9435
TEST_TIME 28 9941
TEST_TIME 29 10189
TEST_TIME 30 10315
TEST_TIME 31 10247
TEST_TIME 32 10142
TEST_TIME 33 10296
TEST_TIME 34 10088
TEST_TIME 35 9864
TEST_TIME 36 9892
TEST_TIME 37 9924
TEST_TIME 38 9892
TEST_TIME 39 9826
TEST_TIME 40 9801
TEST_TIME 41 9646
TEST_TIME 42 9605
TEST_TIME 43 9674
TEST_TIME 44 9569
TEST_TIME 45 9395
TEST_TIME 46 9439
TEST_TIME 47 9465
TEST_TIME 48 9593
TEST_TIME 49 9486
TEST_TIME 50 9412
TEST_TIME 51 9346
TEST_TIME 52 9492
TEST_TIME 53 9480
TEST_TIME 54 9468
TEST_TIME 55 9416
TEST_TIME 56 9356
TEST_TIME 57 9389
TEST_TIME 58 9425
TEST_TIME 59 9435
TEST_TIME 60 9405
TEST_TIME 61 9426
TEST_TIME 62 9302
TEST_TIME 63 9216
TEST_TIME 64 9174
TEST_TIME 65 9114
TEST_TIME 66 9109
TEST_TIME 67 9113
TEST_TIME 68 9110
TEST_TIME 69 9079
TEST_TIME 70 9003
TEST_TIME 71 8961
TEST_TIME 72 8953
TEST_TIME 73 8855
TEST_TIME 74 8981
TEST_TIME 75 8956
TEST_TIME 76 8982
TEST_TIME 77 8991
TEST_TIME 78 8966
TEST_TIME 79 8996
TEST_TIME 80 9064
TEST_TIME 81 9160
TEST_TIME 82 9111
TEST_TIME 83 9073
TEST_TIME 84 9219
TEST_TIME 85 9183
TEST_TIME 86 9174
TEST_TIME 87 9293
TEST_TIME 88 9219
TEST_TIME 89 9150
TEST_TIME 90 9118
TEST_TIME 91 9060
TEST_TIME 92 9058
TEST_TIME 93 8997
TEST_TIME 94 8980
TEST_TIME 95 8928
TEST_TIME 96 8921
TEST_TIME 97 8902
TEST_TIME 98 8897
TEST_TIME 99 9005
TEST_TIME 100 8984
TEST_TIME 101 8983
TEST_TIME 102 9040
TEST_TIME 103 9018
TEST_TIME 104 9099
TEST_TIME 105 9087
TEST_TIME 106 9101
TEST_TIME 107 9152
TEST_TIME 108 9197
TEST_TIME 109 9182
TEST_TIME 110 9198
TEST_TIME 111 9137
TEST_TIME 112 9131
TEST_TIME 113 9089
TEST_TIME 114 9045
TEST_TIME 115 9027
TEST_TIME 116 8992
TEST_TIME 117 8952
TEST_TIME 118 8918
TEST_TIME 119 8939
TEST_TIME 120 8898

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-09-2021 07:49:01 to 17-09-2021 07:49:04.

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 1459908 1459908 0 0.00 0 21738 7 0 0
doc_list_biud 1459903 1459903 0 0.00 0 63733 31 0 0
sp_add_invoice_to_stock 4726 4620 106 2.24 20 222978 19941 0 106
sp_cancel_adding_invoice 5771 5771 0 0.00 5 84251 906 0 0
sp_cancel_client_order 1712 1712 0 0.00 5 11132 352 0 0
sp_cancel_customer_reserve 562147 562147 0 0.00 1 63743 64 0 0
sp_cancel_pay_from_customer 594 594 0 0.00 3 4621 86 0 0
sp_cancel_pay_to_supplier 412 412 0 0.00 3 3738 77 0 0
sp_cancel_supplier_invoice 6360 6354 6 0.09 2 47808 941 0 6
sp_cancel_supplier_order 415 415 0 0.00 7 1171186 111261 0 0
sp_cancel_write_off 9498 9498 0 0.00 1 12685 132 0 0
sp_client_order 19053 19053 0 0.00 4 29430 424 0 0
sp_customer_reserve 423811 423811 0 0.00 0 100686 197 0 0
sp_fill_shopping_cart 29238 29238 0 0.00 1 10282 48 0 0
sp_fill_shopping_cart_clo_res 423804 423804 0 0.00 0 18983 15 0 0
sp_get_clo_for_invoice 4612 4612 0 0.00 0 38344 365 0 0
sp_kill_cost_storno 16275 16275 0 0.00 0 12899 66 0 0
sp_kill_qstorno_ret_qs2qd 1140561 1140561 0 0.00 0 63695 35 0 0
sp_kill_qty_storno 599815 599815 0 0.00 0 63732 76 0 0
sp_lock_selected_doc 19403 19403 0 0.00 0 7425 5 0 0
sp_make_cost_storno 26011 26011 0 0.00 0 21499 66 0 0
sp_multiply_rows_for_pdistr 26009 26009 0 0.00 0 19700 40 0 0
sp_multiply_rows_for_qdistr 19053 19053 0 0.00 0 21255 184 0 0
sp_pay_from_customer 6579 6579 0 0.00 4 22047 121 0 0
sp_pay_to_supplier 5511 5511 0 0.00 5 18390 149 0 0
sp_payment_common 12089 12089 0 0.00 2 18295 30 0 0
sp_qd_handle_on_cancel_clo 1712 1712 0 0.00 0 11124 317 0 0
sp_qd_handle_on_invoice_upd_sts 10381 10381 0 0.00 0 22950 393 0 0
sp_qd_handle_on_reserve_upd_sts 9308 9308 0 0.00 0 13911 72 0 0
sp_reserve_write_off 9309 9309 0 0.00 7 21844 227 0 0
sp_supplier_invoice 5089 5089 0 0.00 9 76066 3937 0 0
sp_supplier_order 5121 5121 0 0.00 5 52272 1641 0 0
srv_aggregate_perf_data 96 95 1 1.04 2599 205002 18285 0 1
srv_fill_mon_cache_memory 64 64 0 0.00 1296 110253 45547 0 0
srv_find_qd_qs_mism 1023325 1023325 0 0.00 0 16349 5 0 0
srv_make_invnt_saldo 396 396 0 0.00 45 104201 4133 0 0
srv_make_money_saldo 718 718 0 0.00 1 15015 613 0 0
srv_recalc_idx_stat 5 5 0 0.00 111851 602352 411427 0 0
t$perf-abend:sp_add_invoice_to_stock 106 106 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 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 4620 4620 0 0.00 0 13852 189 0 0
t$perf-norm:sp_cancel_adding_invoice 399 399 0 0.00 1 3535 97 0 0
t$perf-norm:sp_cancel_client_order 1712 1712 0 0.00 0 4547 13 0 0
t$perf-norm:sp_cancel_customer_reserve 654 654 0 0.00 0 10293 30 0 0
t$perf-norm:sp_cancel_pay_from_customer 594 594 0 0.00 0 929 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 412 412 0 0.00 0 2261 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 374 374 0 0.00 0 2428 18 0 0
t$perf-norm:sp_cancel_supplier_order 415 415 0 0.00 0 20655 1713 0 0
t$perf-norm:sp_cancel_write_off 925 925 0 0.00 0 1780 7 0 0
t$perf-norm:sp_client_order 19053 19053 0 0.00 0 12520 22 0 0
t$perf-norm:sp_customer_reserve 942 942 0 0.00 0 2068 10 0 0
t$perf-norm:sp_pay_from_customer 6579 6579 0 0.00 0 9660 14 0 0
t$perf-norm:sp_pay_to_supplier 5511 5511 0 0.00 0 10322 14 0 0
t$perf-norm:sp_reserve_write_off 9309 9309 0 0.00 0 9263 15 0 0
t$perf-norm:sp_supplier_invoice 5089 5089 0 0.00 0 13975 27 0 0
t$perf-norm:sp_supplier_order 5121 5121 0 0.00 0 7055 18 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 1192 18 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 15 1 0 0
t$perf-norm:srv_make_invnt_saldo 396 396 0 0.00 0 2421 16 0 0
t$perf-norm:srv_make_money_saldo 718 718 0 0.00 0 2834 8 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 63 15 0 0
v_add_invoice_to_stock 9432 9220 212 2.24 0 8822 17 0 212
v_all_customers 34718 34718 0 0.00 0 24 1 0 0
v_all_suppliers 28278 28278 0 0.00 0 17 1 0 0
v_all_wares 38106 38106 0 0.00 0 29 1 0 0
v_cancel_adding_invoice 798 798 0 0.00 0 3977 10 0 0
v_cancel_client_order 3424 3424 0 0.00 0 4248 7 0 0
v_cancel_customer_prepayment 1188 1188 0 0.00 0 4611 10 0 0
v_cancel_customer_reserve 1308 1308 0 0.00 0 343 4 0 0
v_cancel_payment_to_supplier 824 824 0 0.00 0 1020 5 0 0
v_cancel_supplier_invoice 760 748 12 1.57 0 789 15 0 12
v_cancel_supplier_order 830 830 0 0.00 0 1370 7 0 0
v_cancel_write_off 1850 1850 0 0.00 0 569 13 0 0
v_max_id_clo_ord 5119 5119 0 0.00 0 2238 2 0 0
v_max_id_clo_res 942 942 0 0.00 0 6349 19 0 0
v_max_id_ord_sup 5066 5066 0 0.00 0 8438 6 0 0
v_max_non_paid_invoice 1557 1557 0 0.00 0 761 3 0 0
v_max_non_paid_realizn 3480 3480 0 0.00 0 1346 3 0 0
v_min_id_clo_ord 5119 5119 0 0.00 0 8055 17 0 0
v_min_id_clo_res 942 942 0 0.00 0 8006 27 0 0
v_min_id_ord_sup 5066 5066 0 0.00 0 3616 5 0 0
v_min_non_paid_invoice 5511 5511 0 0.00 0 18291 26 0 0
v_min_non_paid_realizn 6578 6578 0 0.00 0 16371 21 0 0
v_our_firm 9584 9584 0 0.00 0 29 1 0 0
v_reserve_write_off 18614 18614 0 0.00 0 4921 6 0 0
x_lock_depdocs_on_canc_invoice 11751 11751 0 0.00 0 25815 147 0 0
x_lock_depdocs_on_canc_sup_ord 415 415 0 0.00 0 18765 422 0 0
x_make_qstorno_1000_1200 5121 5121 0 0.00 2 52241 1536 0 0
x_make_qstorno_1200_2000 5089 5089 0 0.00 4 76057 3770 0 0
x_make_qstorno_2100_3300 423811 423811 0 0.00 0 100684 179 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-09-2021 07:49:05 to 17-09-2021 07:49:09.

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
05:17:42 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:18:43 23507 8192000000 9722174080 10018701312 795958800 8.855 100 88 173 1 825476368 7929168 21588400
05:20:20 21354 8192000000 9767195856 10074640384 842905168 9.329 100 88 176 0 877037824 13186000 20946656
05:21:45 21670 8192000000 9816414624 10135891968 871966032 9.620 100 80 160 1 927808320 37293504 18548784
05:23:13 13310 8192000000 9874762080 10212265984 921211840 10.108 100 73 146 0 980758080 43456192 16090048
05:24:29 9234 8192000000 9920767072 10267828224 969563424 10.582 100 82 164 1 1031249888 44391264 17295200
05:25:41 6964 8192000000 9973703792 10328961024 1000440624 10.883 100 83 166 0 1090695696 73486992 16768080
05:26:50 4094 8192000000 9991820144 10360815616 1022881984 11.100 100 79 158 1 1104664576 65632320 16150272
05:27:58 2556 8192000000 10035812224 10405031936 1054518336 11.404 100 88 176 0 1157445408 85196768 17730304
05:29:02 15124 8192000000 10039905536 10413146112 1071964176 11.571 100 83 168 1 1157581568 68290096 17327296
05:30:18 8964 8192000000 9985841376 10392141824 1074695584 11.597 100 31 62 0 1115514720 34587456 6231680
05:31:29 5202 8192000000 10008002880 10405466112 1084840016 11.694 100 45 90 2 1123939952 29783856 9316080
05:32:36 9108 8192000000 10008730352 10417078272 1091385520 11.756 100 22 44 1 1118171792 21977968 4808304
05:33:50 50578 8192000000 10013301632 10433568768 1099814528 11.836 100 42 85 0 1129473840 20319872 9339440
05:35:41 4040 8192000000 10017236848 10421690368 1103744336 11.873 100 39 78 3 1137611264 25269008 8597920
05:36:47 7372 8192000000 10007994960 10404560896 1090594400 11.748 99 22 44 0 1114120448 18640896 4885152
05:37:57 3544 8192000000 10002383888 10400608256 1079571872 11.643 99 40 82 0 1109464912 20233984 9659056
05:39:01 42841 8192000000 9953914640 10357858304 999697088 10.876 92 28 55 3 1023375888 17322384 6356416
05:40:44 7100 8192000000 9953437120 10341273600 1013745600 11.012 99 49 100 0 1053247376 27781040 11720736
05:41:54 7074 8192000000 9930314224 10311176192 1005952112 10.936 99 42 82 2 1034098096 18760560 9385424
05:43:02 11784 8192000000 9931268240 10323599360 1025283136 11.123 99 44 86 3 1058501856 22715520 10503200
05:44:16 26387 8192000000 9956203104 10355535872 1042390624 11.288 99 46 92 1 1071765344 19154736 10219984
05:45:46 6750 8192000000 9999225504 10386059264 1062737888 11.483 100 57 116 1 1106454784 31744112 11972784
05:46:54 6392 8192000000 10004225568 10397925376 1077013728 11.619 100 40 81 2 1108369424 22260768 9094928
05:48:05 17884 8192000000 10017024864 10418159616 1098908976 11.827 100 44 89 0 1125055584 16763120 9383488
05:49:36 68565 8192000000 10065603152 10477252608 1107641216 11.910 100 39 78 0 1168707712 52995424 8071072
05:51:50 19522 8192000000 10080814736 10494861312 1128067904 12.103 100 36 72 0 1203847728 68124992 7654832
05:53:17 17630 8192000000 10153542992 10561241088 1146533280 12.277 100 62 124 2 1264395376 104767344 13094752
05:54:39 3586 8192000000 10203000272 10609958912 1152262784 12.331 100 69 138 4 1303667552 136508096 14896672
05:55:47 15715 8192000000 10203638640 10617458688 1147016912 12.281 99 47 93 0 1304747104 146763856 10966336
05:57:07 7624 8192000000 10224377344 10651185152 1170538512 12.502 100 55 108 2 1340622528 157459680 12624336
05:58:26 2543 8192000000 10268249104 10680365056 1177020320 12.562 100 64 129 1 1369533552 178265536 14247696
05:59:43 2547 8192000000 10294560896 10704842752 1175535744 12.549 100 83 166 0 1397415776 202637808 19242224
06:00:50 10280 8192000000 10297462240 10716315648 1176099136 12.554 100 62 123 0 1402180080 211966928 14114016
06:02:04 1725 8192000000 10311638928 10715979776 1169759328 12.495 100 88 174 0 1411572752 221777968 20035456
06:03:08 1936 8192000000 10324495360 10737041408 1163690080 12.438 100 70 138 1 1420632880 241369056 15573744
06:04:17 1609 8192000000 10370980304 10778763264 1164444368 12.445 100 91 182 1 1465163744 280552672 20166704
06:05:22 1841 8192000000 10406167072 10813448192 1158217648 12.387 100 90 180 1 1504142624 325260128 20664848
06:06:29 9497 8192000000 10310657280 10740404224 1143333376 12.247 100 42 84 1 1427519376 274037104 10148896
06:07:45 1295 8192000000 10303466912 10734264320 1124235360 12.067 99 82 164 1 1393364384 249551984 19577040
06:08:51 1309 8192000000 10351749744 10770575360 1152303360 12.331 100 92 183 1 1441992832 268427088 21262384
06:09:55 8411 8192000000 10307287536 10731757568 1132756992 12.147 100 34 68 0 1408819968 267715856 8347120
06:11:09 5807 8192000000 10310832336 10733772800 1106766928 11.902 99 38 77 0 1407197680 290928608 9502144
06:12:21 14886 8192000000 10315616928 10760847360 1116448928 11.993 100 31 59 0 1402050224 277996096 7605200
06:13:37 10755 8192000000 10273617552 10769133568 1123051712 12.056 100 29 56 0 1420888224 290930976 6905536
06:14:49 32419 8192000000 10231363952 10690650112 1109499296 11.928 100 18 36 0 1375782336 261388064 4894976
06:16:25 13179 8192000000 10223260864 10679988224 1130986960 12.131 100 33 66 0 1347920112 209316112 7617040
06:17:41 82791 8192000000 10146918016 10613764096 1128832272 12.110 99 24 46 2 1232783216 98581120 5369824
06:20:14 52344 8192000000 10138191696 10670743552 1130395072 12.125 100 15 30 0 1227357168 93547136 3414960
06:22:18 40506 8192000000 10141052416 10565689344 1125053616 12.075 99 32 65 0 1217281664 85130976 7097072
06:24:01 28187 8192000000 10137082096 10590801920 1137296992 12.190 100 25 50 1 1229977392 87092768 5587632
06:25:30 39223 8192000000 10083030672 10555781120 1114260640 11.973 98 17 34 0 1188485504 70370464 3854400
06:27:43 55767 8192000000 10113689056 10550112256 1106517296 11.899 97 18 36 0 1179659952 68885744 4256912
06:29:39 56497 8192000000 10119542016 10570104832 1126071568 12.084 99 31 62 0 1255015392 121758144 7185680
06:31:43 72251 8192000000 10120810912 10559365120 1125339120 12.077 98 32 63 3 1203275184 71002976 6933088
06:34:03 76087 8192000000 10148469232 10675675136 1148333776 12.294 100 13 26 0 1279167776 127819600 3014400
06:36:41 54095 8192000000 10101194496 10539241472 1149928512 12.309 100 44 86 2 1190783328 31075840 9778976
06:38:37 85510 8192000000 10095686880 10610221056 1152693024 12.335 100 14 28 0 1247818960 92093344 3032592
06:41:25 68702 8192000000 10071145008 10551623680 1110206240 11.934 96 24 49 0 1154599600 39478752 4914608
06:43:38 110253 8192000000 10097969312 10614132736 1103114960 11.867 96 22 44 0 1149528512 41791280 4622272
06:46:29 95361 8192000000 10098193952 10559741952 1146885600 12.280 100 10 20 2 1178572192 29745536 1941056
06:49:05 86825 8192000000 10075262848 10514423808 1137877824 12.196 99 26 50 1 1171886688 28341904 5666960
06:51:34 60489 8192000000 10124265936 10560331776 1123736896 12.062 98 40 80 0 1200255984 68271632 8247456
06:53:48 29137 8192000000 10115542640 10557100032 1120017616 12.027 98 42 83 2 1193880848 65189392 8673840
06:55:18 46279 8192000000 10057432480 10494046208 1088191680 11.725 96 21 42 0 1135342288 42365840 4784768
06:57:05 48795 8192000000 10121880480 10581110784 1136832992 12.186 100 13 26 0 1236244192 96243568 3167632
06:59:52 74793 8192000000 10157788320 10608697344 1138695648 12.203 100 24 48 2 1235508160 91972160 4840352
07:02:08 37994 8192000000 10075814064 10536820736 1119843584 12.026 98 9 17 1 1192995504 71569920 1582000
07:03:50 47260 8192000000 10090490352 10527875072 1107497376 11.909 99 18 36 0 1159545632 48296864 3751392
07:05:44 78259 8192000000 10134587616 10611798016 1075795216 11.607 95 23 46 0 1167350336 86809728 4745392
07:08:07 76292 8192000000 10069902816 10508460032 1090967440 11.752 96 15 30 1 1153787584 59727376 3092768
07:10:34 66365 8192000000 10109566704 10624241664 1119364608 12.021 98 38 76 1 1219008176 91829872 7813696
07:13:06 67637 8192000000 10166158768 10638753792 1123316560 12.058 98 15 30 0 1227795600 101485776 2993264
07:15:14 102039 8192000000 10052911728 10489495552 1110613472 11.938 98 21 42 0 1134386624 19519088 4254064
07:18:02 32170 8192000000 10069559264 10507853824 1125878784 12.082 99 26 52 1 1149133536 17918368 5336384
07:19:34 61018 8192000000 10112078640 10570268672 1137183712 12.189 99 21 42 1 1222762848 80966816 4612320
07:21:37 93169 8192000000 10147039424 10626445312 1134069248 12.160 98 15 30 1 1257623472 119983424 3570800
07:24:29 62798 8192000000 10150777792 10619944960 1148962768 12.300 100 18 36 0 1261341296 107858080 4520448
07:26:34 102125 8192000000 10112793984 10601865216 1121606512 12.042 97 18 36 0 1182843344 57209136 4027696
07:29:20 46311 8192000000 10097314048 10567680000 1141918096 12.234 99 19 38 2 1215851360 69813216 4120048
07:31:07 69843 8192000000 10096387408 10593255424 1146055456 12.272 99 16 32 0 1207436784 58325552 3055776
07:33:20 55996 8192000000 10172167728 10626494464 1153214864 12.340 99 25 50 0 1226188928 67690848 5283216
07:35:28 67215 8192000000 10074020704 10543923200 1122191952 12.048 97 21 43 1 1172964720 46266080 4506688
07:37:43 86184 8192000000 10116374432 10560471040 1142873984 12.243 100 35 70 1 1221514016 71416048 7223984
07:40:35 42259 8192000000 10071231168 10555097088 1142753120 12.241 100 5 10 0 1190954112 47219872 981120
07:42:25 70019 8192000000 10070496880 10520236032 1100594400 11.843 96 10 21 0 1141895024 39231008 2069616
07:44:35 55665 8192000000 10082879184 10551209984 1123717008 12.062 99 10 20 0 1183056640 57315792 2023840
07:46:41 59796 8192000000 9762224000 10161410048 854126512 9.441 75 13 26 0 889566896 33117200 2323184

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 17-09-2021 07:49:11 to 17-09-2021 07:49:26.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 106 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-09-2021 07:49:27 to 17-09-2021 07:49:29.

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          226385
MON$OLDEST_ACTIVE               226386
MON$OLDEST_SNAPSHOT             226386
MON$NEXT_TRANSACTION            226386
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                       2085472
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             779
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2604 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2604 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2604 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2604 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17: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 ---
--- end of diff output ---

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

17.09.21 07:49:30. 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-09-17 07:49:33 No severe PSQL-related problems occured
17.09.21 07:49:34. End of report.