Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.02.2021 07:37
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 16179280 kB
MemAvailable 31119884 kB
Buffers 0 kB
Cached 15469408 kB
SwapCached 3944 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-02-19 07:36:32
DTS_END                         2021-02-19 07:36:32.6400
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/39626
TRN_ID                          228250
ATT_ID                          647
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: 9257.24 19804 1110869
customer order: creation 163.98 425 19677
customer order: refuse 13.41 352 1609
order to supplier: creation 43.63 1282 5235
order to supplier: removal 3.49 133089 419
invoice (draft): creation 44.21 3290 5305
invoice (draft): removal 54.18 933 6501
invoice accept: apply 40.32 16393 4838
invoice accept: cancel 49.03 921 5884
customer reserve: creation 3750.57 157 450068
customer reserve: removal 4812.18 77 577461
realization accept: apply 78.13 175 9375
realization accept: cancel 83.83 124 10060
payment from customer: creation 54.98 92 6597
payment from customer: removal 5.38 86 646
payment to supplier: creation 46.61 123 5593
payment to supplier: removal 3.10 58 372
service: total inventory turnovers 3.58 3659 430
service: total monetary turnovers 5.83 534 699
service: aggregate perf. data 0.79 18226 95
service: refresh index statistics 0.04 216089 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 19-02-2021 07:37:35 to 19-02-2021 07:37:38.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1897
TEST_TIME 2 4293
TEST_TIME 3 4485
TEST_TIME 4 4880
TEST_TIME 5 4559
TEST_TIME 6 4925
TEST_TIME 7 5199
TEST_TIME 8 5053
TEST_TIME 9 4972
TEST_TIME 10 5059
TEST_TIME 11 5089
TEST_TIME 12 5135
TEST_TIME 13 5340
TEST_TIME 14 5478
TEST_TIME 15 5710
TEST_TIME 16 5889
TEST_TIME 17 5983
TEST_TIME 18 6305
TEST_TIME 19 6692
TEST_TIME 20 7424
TEST_TIME 21 7375
TEST_TIME 22 7912
TEST_TIME 23 8438
TEST_TIME 24 8664
TEST_TIME 25 8603
TEST_TIME 26 9083
TEST_TIME 27 9739
TEST_TIME 28 11233
TEST_TIME 29 11180
TEST_TIME 30 11038
TEST_TIME 31 10858
TEST_TIME 32 10707
TEST_TIME 33 10530
TEST_TIME 34 10581
TEST_TIME 35 10597
TEST_TIME 36 10447
TEST_TIME 37 10401
TEST_TIME 38 10376
TEST_TIME 39 10217
TEST_TIME 40 10402
TEST_TIME 41 10387
TEST_TIME 42 10311
TEST_TIME 43 10403
TEST_TIME 44 10447
TEST_TIME 45 10316
TEST_TIME 46 10150
TEST_TIME 47 9998
TEST_TIME 48 9883
TEST_TIME 49 9830
TEST_TIME 50 9931
TEST_TIME 51 9887
TEST_TIME 52 9856
TEST_TIME 53 9774
TEST_TIME 54 9670
TEST_TIME 55 9629
TEST_TIME 56 9592
TEST_TIME 57 9493
TEST_TIME 58 9528
TEST_TIME 59 9548
TEST_TIME 60 9611
TEST_TIME 61 9589
TEST_TIME 62 9664
TEST_TIME 63 9622
TEST_TIME 64 9635
TEST_TIME 65 9702
TEST_TIME 66 9858
TEST_TIME 67 9829
TEST_TIME 68 9971
TEST_TIME 69 9941
TEST_TIME 70 9950
TEST_TIME 71 9954
TEST_TIME 72 9841
TEST_TIME 73 9763
TEST_TIME 74 9685
TEST_TIME 75 9690
TEST_TIME 76 9607
TEST_TIME 77 9585
TEST_TIME 78 9515
TEST_TIME 79 9458
TEST_TIME 80 9455
TEST_TIME 81 9452
TEST_TIME 82 9439
TEST_TIME 83 9403
TEST_TIME 84 9455
TEST_TIME 85 9478
TEST_TIME 86 9461
TEST_TIME 87 9536
TEST_TIME 88 9583
TEST_TIME 89 9588
TEST_TIME 90 9650
TEST_TIME 91 9582
TEST_TIME 92 9480
TEST_TIME 93 9489
TEST_TIME 94 9435
TEST_TIME 95 9397
TEST_TIME 96 9363
TEST_TIME 97 9350
TEST_TIME 98 9324
TEST_TIME 99 9353
TEST_TIME 100 9429
TEST_TIME 101 9421
TEST_TIME 102 9399
TEST_TIME 103 9394
TEST_TIME 104 9440
TEST_TIME 105 9452
TEST_TIME 106 9495
TEST_TIME 107 9556
TEST_TIME 108 9555
TEST_TIME 109 9532
TEST_TIME 110 9454
TEST_TIME 111 9407
TEST_TIME 112 9363
TEST_TIME 113 9320
TEST_TIME 114 9331
TEST_TIME 115 9311
TEST_TIME 116 9310
TEST_TIME 117 9331
TEST_TIME 118 9310
TEST_TIME 119 9311
TEST_TIME 120 9260

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 19-02-2021 07:37:39 to 19-02-2021 07:37:43.

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 1527312 1527312 0 0.00 0 29571 6 0 0
doc_list_biud 1527312 1527312 0 0.00 0 65459 36 0 0
sp_add_invoice_to_stock 4970 4838 132 2.65 31 177548 16393 0 132
sp_cancel_adding_invoice 5884 5884 0 0.00 5 76102 921 0 0
sp_cancel_client_order 1609 1609 0 0.00 4 14003 352 0 0
sp_cancel_customer_reserve 577461 577461 0 0.00 1 65462 77 0 0
sp_cancel_pay_from_customer 646 646 0 0.00 3 8541 87 0 0
sp_cancel_pay_to_supplier 372 372 0 0.00 3 1101 59 0 0
sp_cancel_supplier_invoice 6507 6501 6 0.09 1 38340 934 0 6
sp_cancel_supplier_order 419 419 0 0.00 9 1364825 133090 0 0
sp_cancel_write_off 10060 10060 0 0.00 1 26216 125 0 0
sp_client_order 19677 19677 0 0.00 4 20209 426 0 0
sp_customer_reserve 450068 450068 0 0.00 0 99666 157 0 0
sp_fill_shopping_cart 30189 30189 0 0.00 1 27677 38 0 0
sp_fill_shopping_cart_clo_res 450058 450058 0 0.00 0 23501 19 0 0
sp_get_clo_for_invoice 4828 4828 0 0.00 0 52917 456 0 0
sp_kill_cost_storno 16962 16962 0 0.00 0 7302 62 0 0
sp_kill_qstorno_ret_qs2qd 1171902 1171902 0 0.00 0 65454 41 0 0
sp_kill_qty_storno 616137 616137 0 0.00 0 65459 87 0 0
sp_lock_selected_doc 19628 19628 0 0.00 0 9572 6 0 0
sp_make_cost_storno 26393 26393 0 0.00 0 17975 54 0 0
sp_multiply_rows_for_pdistr 26393 26393 0 0.00 0 9744 29 0 0
sp_multiply_rows_for_qdistr 19677 19677 0 0.00 0 20199 242 0 0
sp_pay_from_customer 6597 6597 0 0.00 4 14444 93 0 0
sp_pay_to_supplier 5593 5593 0 0.00 5 21139 123 0 0
sp_payment_common 12190 12190 0 0.00 1 19254 19 0 0
sp_qd_handle_on_cancel_clo 1609 1609 0 0.00 0 13997 311 0 0
sp_qd_handle_on_invoice_upd_sts 10712 10712 0 0.00 0 20083 407 0 0
sp_qd_handle_on_reserve_upd_sts 9375 9375 0 0.00 0 32665 54 0 0
sp_reserve_write_off 9375 9375 0 0.00 6 32689 175 0 0
sp_supplier_invoice 5305 5305 0 0.00 5 133498 3291 0 0
sp_supplier_order 5235 5235 0 0.00 6 43147 1283 0 0
srv_aggregate_perf_data 96 95 1 1.04 2095 168808 18226 0 1
srv_fill_mon_cache_memory 62 62 0 0.00 1274 136767 46910 0 0
srv_find_qd_qs_mism 1065138 1065138 0 0.00 0 29571 5 0 0
srv_make_invnt_saldo 430 430 0 0.00 52 146619 3660 0 0
srv_make_money_saldo 699 699 0 0.00 1 14007 535 0 0
srv_recalc_idx_stat 5 5 0 0.00 155207 252679 216089 0 0
t$perf-abend:sp_add_invoice_to_stock 132 132 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 4838 4838 0 0.00 0 11490 166 0 0
t$perf-norm:sp_cancel_adding_invoice 372 372 0 0.00 0 5091 83 0 0
t$perf-norm:sp_cancel_client_order 1609 1609 0 0.00 0 2039 12 0 0
t$perf-norm:sp_cancel_customer_reserve 669 669 0 0.00 0 815 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 646 646 0 0.00 0 681 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 372 372 0 0.00 0 231 1 0 0
t$perf-norm:sp_cancel_supplier_invoice 387 387 0 0.00 0 330 7 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 27417 1724 0 0
t$perf-norm:sp_cancel_write_off 952 952 0 0.00 0 2339 7 0 0
t$perf-norm:sp_client_order 19677 19677 0 0.00 0 10905 20 0 0
t$perf-norm:sp_customer_reserve 957 957 0 0.00 0 623 3 0 0
t$perf-norm:sp_pay_from_customer 6597 6597 0 0.00 0 4691 6 0 0
t$perf-norm:sp_pay_to_supplier 5593 5593 0 0.00 0 5902 9 0 0
t$perf-norm:sp_reserve_write_off 9375 9375 0 0.00 0 7063 11 0 0
t$perf-norm:sp_supplier_invoice 5305 5305 0 0.00 0 6696 18 0 0
t$perf-norm:sp_supplier_order 5235 5235 0 0.00 0 11363 19 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 433 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 499 15 0 0
t$perf-norm:srv_make_invnt_saldo 430 430 0 0.00 0 232 5 0 0
t$perf-norm:srv_make_money_saldo 699 699 0 0.00 0 6253 16 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 6 3 0 0
v_add_invoice_to_stock 9918 9654 264 2.66 0 5253 18 0 264
v_all_customers 35564 35564 0 0.00 0 30 1 0 0
v_all_suppliers 29162 29162 0 0.00 0 31 1 0 0
v_all_wares 39354 39354 0 0.00 0 171 1 0 0
v_cancel_adding_invoice 744 744 0 0.00 0 863 9 0 0
v_cancel_client_order 3218 3218 0 0.00 0 3362 7 0 0
v_cancel_customer_prepayment 1292 1292 0 0.00 0 5323 10 0 0
v_cancel_customer_reserve 1338 1338 0 0.00 0 1379 5 0 0
v_cancel_payment_to_supplier 744 744 0 0.00 0 486 4 0 0
v_cancel_supplier_invoice 786 774 12 1.52 0 2486 20 0 12
v_cancel_supplier_order 838 838 0 0.00 0 8379 14 0 0
v_cancel_write_off 1904 1904 0 0.00 0 1705 14 0 0
v_max_id_clo_ord 5235 5235 0 0.00 0 920 2 0 0
v_max_id_clo_res 957 957 0 0.00 0 5834 20 0 0
v_max_id_ord_sup 5277 5277 0 0.00 0 4460 4 0 0
v_max_non_paid_invoice 1524 1524 0 0.00 0 202 2 0 0
v_max_non_paid_realizn 3532 3532 0 0.00 0 930 3 0 0
v_min_id_clo_ord 5235 5235 0 0.00 0 3109 5 0 0
v_min_id_clo_res 957 957 0 0.00 0 1276 4 0 0
v_min_id_ord_sup 5277 5277 0 0.00 0 3467 6 0 0
v_min_non_paid_invoice 5593 5593 0 0.00 0 19250 13 0 0
v_min_non_paid_realizn 6597 6597 0 0.00 0 6648 11 0 0
v_our_firm 9920 9920 0 0.00 0 22 1 0 0
v_reserve_write_off 18750 18750 0 0.00 0 3210 5 0 0
x_lock_depdocs_on_canc_invoice 11998 11998 0 0.00 0 22845 118 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 16888 381 0 0
x_make_qstorno_1000_1200 5235 5235 0 0.00 2 43138 1207 0 0
x_make_qstorno_1200_2000 5305 5305 0 0.00 3 133266 3149 0 0
x_make_qstorno_2100_3300 450068 450068 0 0.00 0 99665 135 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-02-2021 07:37:44 to 19-02-2021 07:37:48.

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:06:36 7 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
05:07:36 21734 8192000000 9712683264 10004549632 786309136 8.757 100 84 168 1 814736416 7590512 20836768
05:09:03 29719 8192000000 9767480976 10069368832 835169792 9.251 100 93 184 1 869885024 12987408 21727824
05:10:52 23181 8192000000 9818170304 10136100864 870352656 9.604 100 83 166 1 919153056 29432320 19368080
05:12:35 12605 8192000000 9888652848 10225303552 939451888 10.288 100 75 150 1 996972896 41943952 15577056
05:13:49 9090 8192000000 9933588016 10291761152 988865616 10.770 100 78 156 2 1060776064 55182704 16727744
05:15:02 4471 8192000000 10008801088 10370375680 1017120160 11.044 100 81 160 2 1116972144 83608416 16243568
05:16:08 3013 8192000000 10003477840 10370166784 1044640432 11.309 100 78 155 2 1117800800 57688672 15471696
05:17:14 2176 8192000000 10033145152 10406088704 1066385696 11.518 100 96 188 1 1161580336 76224672 18969968
05:18:17 16431 8192000000 10023905504 10424532992 1066765520 11.521 100 59 118 2 1125561792 47080176 11716096
05:19:36 4056 8192000000 10005644768 10392449024 1081919760 11.666 100 52 105 1 1118459136 25263216 11276160
05:20:42 8090 8192000000 9980332976 10387099648 1084953744 11.695 100 31 60 4 1105366496 14175728 6237024
05:21:51 4702 8192000000 9992914240 10391531520 1089168592 11.735 100 37 75 1 1121225280 24291392 7765296
05:23:00 24600 8192000000 10008145520 10415407104 1094416800 11.785 100 32 63 1 1123394208 21812560 7164848
05:24:29 6127 8192000000 9997391456 10396971008 1083549424 11.681 99 51 102 2 1106098400 12394576 10154400
05:25:37 4776 8192000000 10011502240 10416025600 1097064976 11.810 100 25 51 1 1118258208 16154096 5039136
05:26:42 68000 8192000000 9983535264 10392379392 1011461984 10.990 93 31 62 2 1045898384 27691664 6744736
05:28:50 6628 8192000000 9956669168 10344181760 1023667712 11.107 98 48 96 1 1051460224 17087280 10705232
05:29:57 7803 8192000000 9939779696 10333011968 1005722784 10.934 98 38 76 1 1031513104 17043968 8746352
05:31:06 7165 8192000000 9955543712 10338471936 1035704800 11.223 100 40 79 2 1065141808 20270256 9166752
05:32:14 24053 8192000000 9953184496 10355326976 1013940144 11.013 97 19 38 0 1045825296 28047600 3837552
05:33:38 12079 8192000000 9983761120 10385059840 1063395440 11.489 100 31 62 1 1091577552 21302304 6879808
05:34:53 9803 8192000000 9992487024 10402525184 1076445216 11.614 100 31 63 0 1103553600 19234544 7873840
05:36:05 105520 8192000000 10025193504 10429235200 1100713840 11.844 100 45 88 1 1131444432 21544096 9186496
05:39:07 14447 8192000000 10073444192 10481324032 1129310128 12.115 100 51 99 0 1187901104 47399872 11191104
05:40:24 7888 8192000000 10104290704 10509340672 1141865584 12.233 100 53 102 3 1213479728 60432048 11182096
05:41:43 21633 8192000000 10164226112 10565197824 1154703504 12.354 100 67 130 1 1264482736 95954384 13824848
05:43:11 7880 8192000000 10185295232 10610561024 1173556992 12.530 100 60 120 0 1284765392 97967520 13240880
05:44:26 14518 8192000000 10219297680 10635669504 1188146176 12.666 100 58 114 2 1334186000 132667808 13372016
05:45:48 6323 8192000000 10265630208 10682265600 1194249744 12.723 100 55 110 3 1371302864 164119808 12933312
05:46:59 19667 8192000000 10341650080 10768142336 1206428928 12.836 100 58 116 1 1438604768 218090784 14085056
05:48:26 6345 8192000000 10379569792 10799939584 1218173056 12.945 100 64 128 1 1486356832 253536480 14647296
05:49:36 6236 8192000000 10426659904 10852622336 1214018160 12.906 100 59 118 0 1523357792 295339968 13999664
05:50:50 1865 8192000000 10447814704 10854256640 1189063520 12.675 99 75 151 0 1542750624 335967600 17719504
05:51:58 2083 8192000000 10507059504 10914697216 1212972416 12.897 99 79 156 1 1595986656 365142720 17871520
05:53:04 1652 8192000000 10530302368 10958999552 1211304608 12.881 100 59 118 0 1630746016 404365440 15075968
05:54:12 1332 8192000000 10586674256 10992410624 1186616656 12.652 100 95 190 0 1681716400 471642704 23457040
05:55:20 2029 8192000000 10582548704 11005337600 1179595904 12.586 100 89 177 0 1678535520 477005824 21933792
05:56:27 1273 8192000000 10566117136 11038568448 1199469120 12.771 100 84 170 0 1665442912 445047216 20926576
05:57:32 13218 8192000000 10546694912 10968780800 1184691680 12.634 100 57 114 1 1642226960 443347280 14188000
05:58:58 12601 8192000000 10503886656 10996748288 1190093632 12.684 100 40 80 0 1604157584 403493216 10570736
06:00:20 22953 8192000000 10508422208 10948112384 1163193344 12.433 99 31 62 2 1589473152 417852592 8427216
06:02:20 22763 8192000000 10453971072 10979209216 1148451936 12.295 100 22 44 0 1589855360 435572560 5830864
06:03:43 19608 8192000000 10352338688 10814988288 1130123328 12.123 98 27 54 1 1473600128 336970400 6506400
06:05:41 63684 8192000000 10073663440 10582966272 1105220624 11.887 97 19 39 1 1147955008 38249200 4485184
06:07:45 32310 8192000000 10060114976 10493014016 1121088288 12.037 100 28 56 1 1155029856 28061408 5880160
06:09:19 76934 8192000000 10080522640 10558906368 1110665536 11.939 97 17 34 1 1169305040 54739552 3899952
06:11:38 103442 8192000000 10079224448 10536640512 1106084288 11.895 97 14 27 1 1146317696 37670256 2563152
06:14:23 67720 8192000000 10117237696 10563637248 1129366832 12.115 98 7 14 0 1161795168 30928576 1499760
06:16:34 122476 8192000000 10106479808 10547191808 1109929936 11.932 96 11 20 1 1170344080 57807248 2606896
06:19:39 109154 8192000000 10098412528 10574188544 1122556704 12.051 98 7 14 1 1162286944 38482448 1247792
06:22:31 136767 8192000000 10131290832 10588942336 1138490352 12.201 99 44 88 0 1234929936 86635296 9804288
06:25:48 42090 8192000000 10148335632 10586550272 1137040656 12.188 99 46 92 0 1223701728 76557488 10103584
06:27:39 47307 8192000000 10080723360 10531004416 1144008048 12.253 100 21 42 0 1184620336 36086416 4525872
06:29:28 39351 8192000000 10087049952 10534219776 1146065152 12.273 100 18 37 3 1173993536 23843072 4085312
06:31:32 88931 8192000000 10138197664 10621173760 1124045456 12.065 97 17 33 1 1225575120 97815168 3714496
06:34:54 78790 8192000000 10134693520 10597687296 1130763712 12.129 98 19 38 1 1207556112 72260240 4532160
06:37:13 37106 8192000000 10100927616 10546737152 1127792784 12.101 98 18 35 0 1179511792 47677984 4041024
06:39:01 66804 8192000000 10137897280 10574159872 1106141136 11.896 95 22 43 0 1172197792 61849824 4206832
06:41:09 72870 8192000000 10094181952 10647085056 1113344992 11.964 97 11 22 0 1216571728 100847360 2379376
06:43:28 34905 8192000000 10133535840 10571935744 1129415776 12.116 98 46 92 0 1188031296 48167776 10447744
06:45:06 33360 8192000000 10093301824 10536271872 1108647216 11.920 97 47 93 0 1167897104 49713408 9536480
06:46:53 50300 8192000000 10070432032 10509697024 1098509904 11.824 97 25 50 1 1158200000 54706240 4983856
06:49:23 79345 8192000000 10059194064 10519449600 1096785472 11.807 97 20 40 1 1164812448 63926432 4100544
06:51:52 53222 8192000000 10038965968 10501402624 1080454416 11.652 97 9 18 0 1121554928 39623536 1476976
06:54:00 41187 8192000000 10065486992 10508468224 1105632496 11.891 98 18 36 3 1131210496 21882608 3695392
06:55:59 47919 8192000000 10113866592 10572505088 1127560176 12.098 99 18 34 3 1194456688 63235280 3661232
06:57:51 47791 8192000000 10089168288 10532970496 1126680864 12.090 99 19 37 2 1177899312 47216752 4001696
06:59:40 46302 8192000000 10147581728 10601431040 1134391264 12.163 100 33 66 1 1232388016 91185840 6810912
07:01:46 32884 8192000000 10128015424 10577825792 1118027936 12.008 98 24 48 2 1194257696 70804992 5424768
07:03:33 36764 8192000000 10088509472 10547281920 1092583120 11.767 96 25 50 0 1167591696 69972912 5035664
07:05:14 42432 8192000000 10092084384 10520276992 1095632432 11.796 97 18 33 2 1150228688 51183936 3412320
07:07:01 67758 8192000000 10082332016 10528632832 1116685280 11.996 99 27 53 0 1161576736 39097216 5794240
07:10:02 36599 8192000000 10064412896 10539872256 1129069440 12.113 100 5 10 0 1167814592 37738608 1006544
07:11:41 46830 8192000000 10070899008 10533650432 1124131888 12.066 99 10 20 0 1159187488 33343952 1711648
07:13:29 62335 8192000000 10132833088 10586759168 1134587232 12.165 98 17 34 0 1189798848 50888016 4323600
07:15:31 84090 8192000000 10106772848 10652147712 1137450000 12.192 99 19 38 1 1244880480 103010992 4419488
07:18:07 21108 8192000000 10083531872 10514100224 1114007456 11.970 97 38 74 2 1141274880 20089008 7178416
07:19:43 84418 8192000000 10159597424 10669686784 1142621488 12.240 100 17 34 0 1250185472 103938512 3625472
07:22:08 31196 8192000000 10128149840 10575413248 1146527952 12.277 100 25 50 0 1203862656 52146464 5188240
07:23:40 63649 8192000000 10096729456 10545434624 1132541744 12.145 99 19 36 1 1170494768 34553920 3399104
07:25:47 113360 8192000000 10109120016 10576293888 1137182128 12.189 99 31 62 0 1197849968 54264832 6403008
07:29:04 83448 8192000000 10096422448 10562961408 1119672912 12.024 98 11 22 2 1177964464 56076480 2215072
07:31:32 86409 8192000000 10102443712 10595766272 1134238416 12.161 99 16 32 1 1194337984 56529920 3569648
07:34:08 83059 8192000000 10111525536 10551349248 1107323008 11.907 96 23 46 1 1165323952 53474272 4526672

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 19-02-2021 07:37:50 to 19-02-2021 07:38:05.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 132 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 19-02-2021 07:38:06 to 19-02-2021 07:38:08.

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          224759
MON$OLDEST_ACTIVE               228445
MON$OLDEST_SNAPSHOT             228445
MON$NEXT_TRANSACTION            228445
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                       2132864
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             787
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2370 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2370 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2370 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.2370 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Fri Feb 19 07:36:41 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
--- end of diff output ---

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

19.02.21 07:38:09. 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-02-19 07:38:11 No severe PSQL-related problems occured
19.02.21 07:38:13. End of report.