Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.10.2021 04:38
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 15993632 kB
MemAvailable 30659316 kB
Buffers 20 kB
Cached 15634104 kB
SwapCached 4796 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-10-07 04:37:09
DTS_END                         2021-10-07 04:37:09.5140
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/42578
TRN_ID                          225679
ATT_ID                          635
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: 8530.87 27303 1023704
customer order: creation 159.39 470 19127
customer order: refuse 13.86 437 1663
order to supplier: creation 43.65 1491 5238
order to supplier: removal 3.24 129131 389
invoice (draft): creation 44.25 4195 5310
invoice (draft): removal 47.28 1034 5674
invoice accept: apply 40.56 22584 4867
invoice accept: cancel 42.34 1049 5081
customer reserve: creation 3705.57 224 444668
customer reserve: removal 4150.86 79 498103
realization accept: apply 77.71 214 9325
realization accept: cancel 78.02 139 9362
payment from customer: creation 57.91 131 6949
payment from customer: removal 5.06 100 607
payment to supplier: creation 47.17 146 5660
payment to supplier: removal 3.42 92 410
service: total inventory turnovers 3.49 4125 419
service: total monetary turnovers 6.22 601 746
service: aggregate perf. data 0.84 16412 101
service: refresh index statistics 0.04 363400 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-10-2021 04:38:21 to 07-10-2021 04:38:23.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2097
TEST_TIME 2 2719
TEST_TIME 3 4454
TEST_TIME 4 5409
TEST_TIME 5 5247
TEST_TIME 6 5227
TEST_TIME 7 5377
TEST_TIME 8 5184
TEST_TIME 9 5242
TEST_TIME 10 5078
TEST_TIME 11 5275
TEST_TIME 12 5173
TEST_TIME 13 5225
TEST_TIME 14 5247
TEST_TIME 15 5414
TEST_TIME 16 5761
TEST_TIME 17 5873
TEST_TIME 18 6172
TEST_TIME 19 6367
TEST_TIME 20 6572
TEST_TIME 21 6618
TEST_TIME 22 7049
TEST_TIME 23 7698
TEST_TIME 24 8329
TEST_TIME 25 8262
TEST_TIME 26 8582
TEST_TIME 27 8939
TEST_TIME 28 9506
TEST_TIME 29 10011
TEST_TIME 30 9766
TEST_TIME 31 9691
TEST_TIME 32 9573
TEST_TIME 33 9378
TEST_TIME 34 9331
TEST_TIME 35 9409
TEST_TIME 36 9256
TEST_TIME 37 9190
TEST_TIME 38 9109
TEST_TIME 39 9156
TEST_TIME 40 9030
TEST_TIME 41 8940
TEST_TIME 42 8847
TEST_TIME 43 8728
TEST_TIME 44 8682
TEST_TIME 45 8563
TEST_TIME 46 8499
TEST_TIME 47 8698
TEST_TIME 48 8672
TEST_TIME 49 8627
TEST_TIME 50 8654
TEST_TIME 51 8742
TEST_TIME 52 8678
TEST_TIME 53 8675
TEST_TIME 54 8646
TEST_TIME 55 8835
TEST_TIME 56 8844
TEST_TIME 57 9013
TEST_TIME 58 8961
TEST_TIME 59 8968
TEST_TIME 60 9053
TEST_TIME 61 8943
TEST_TIME 62 8817
TEST_TIME 63 8776
TEST_TIME 64 8744
TEST_TIME 65 8666
TEST_TIME 66 8589
TEST_TIME 67 8525
TEST_TIME 68 8555
TEST_TIME 69 8477
TEST_TIME 70 8435
TEST_TIME 71 8413
TEST_TIME 72 8387
TEST_TIME 73 8558
TEST_TIME 74 8500
TEST_TIME 75 8596
TEST_TIME 76 8668
TEST_TIME 77 8695
TEST_TIME 78 8685
TEST_TIME 79 8639
TEST_TIME 80 8702
TEST_TIME 81 8731
TEST_TIME 82 8652
TEST_TIME 83 8591
TEST_TIME 84 8607
TEST_TIME 85 8583
TEST_TIME 86 8517
TEST_TIME 87 8532
TEST_TIME 88 8506
TEST_TIME 89 8501
TEST_TIME 90 8507
TEST_TIME 91 8501
TEST_TIME 92 8543
TEST_TIME 93 8569
TEST_TIME 94 8595
TEST_TIME 95 8626
TEST_TIME 96 8621
TEST_TIME 97 8620
TEST_TIME 98 8600
TEST_TIME 99 8532
TEST_TIME 100 8491
TEST_TIME 101 8475
TEST_TIME 102 8456
TEST_TIME 103 8430
TEST_TIME 104 8431
TEST_TIME 105 8404
TEST_TIME 106 8445
TEST_TIME 107 8406
TEST_TIME 108 8429
TEST_TIME 109 8454
TEST_TIME 110 8431
TEST_TIME 111 8434
TEST_TIME 112 8454
TEST_TIME 113 8638
TEST_TIME 114 8610
TEST_TIME 115 8642
TEST_TIME 116 8654
TEST_TIME 117 8636
TEST_TIME 118 8658
TEST_TIME 119 8603
TEST_TIME 120 8532

Performance per minute, chart


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

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 1433927 1433927 0 0.00 0 33094 8 0 0
doc_list_biud 1433921 1433921 0 0.00 0 63878 35 0 0
sp_add_invoice_to_stock 4985 4867 118 2.36 30 242659 22584 0 118
sp_cancel_adding_invoice 5081 5081 0 0.00 6 82151 1050 0 0
sp_cancel_client_order 1663 1663 0 0.00 5 15689 438 0 0
sp_cancel_customer_reserve 498103 498103 0 0.00 1 40973 79 0 0
sp_cancel_pay_from_customer 607 607 0 0.00 3 8679 101 0 0
sp_cancel_pay_to_supplier 410 410 0 0.00 3 2562 93 0 0
sp_cancel_supplier_invoice 5679 5674 5 0.08 2 63884 1035 0 5
sp_cancel_supplier_order 389 389 0 0.00 6 1361833 129132 0 0
sp_cancel_write_off 9362 9362 0 0.00 1 8260 139 0 0
sp_client_order 19127 19127 0 0.00 4 33132 471 0 0
sp_customer_reserve 444668 444668 0 0.00 0 91574 225 0 0
sp_fill_shopping_cart 29670 29670 0 0.00 1 46909 54 0 0
sp_fill_shopping_cart_clo_res 444664 444664 0 0.00 0 49464 20 0 0
sp_get_clo_for_invoice 4863 4863 0 0.00 0 26563 468 0 0
sp_kill_cost_storno 15460 15460 0 0.00 0 33084 75 0 0
sp_kill_qstorno_ret_qs2qd 1011631 1011631 0 0.00 0 63877 43 0 0
sp_kill_qty_storno 534460 534460 0 0.00 0 63878 92 0 0
sp_lock_selected_doc 19658 19658 0 0.00 0 7986 6 0 0
sp_make_cost_storno 26797 26797 0 0.00 0 15752 67 0 0
sp_multiply_rows_for_pdistr 26797 26797 0 0.00 0 13644 39 0 0
sp_multiply_rows_for_qdistr 19127 19127 0 0.00 0 21628 227 0 0
sp_pay_from_customer 6949 6949 0 0.00 4 13655 132 0 0
sp_pay_to_supplier 5660 5660 0 0.00 5 12402 146 0 0
sp_payment_common 12608 12608 0 0.00 1 8308 26 0 0
sp_qd_handle_on_cancel_clo 1663 1663 0 0.00 1 15662 394 0 0
sp_qd_handle_on_invoice_upd_sts 9944 9944 0 0.00 0 35566 427 0 0
sp_qd_handle_on_reserve_upd_sts 9325 9325 0 0.00 0 12613 62 0 0
sp_reserve_write_off 9325 9325 0 0.00 7 13275 215 0 0
sp_supplier_invoice 5310 5310 0 0.00 7 97886 4195 0 0
sp_supplier_order 5238 5238 0 0.00 6 76544 1491 0 0
srv_aggregate_perf_data 102 101 1 0.98 2645 103266 16413 0 1
srv_fill_mon_cache_memory 63 63 0 0.00 1822 162176 46134 0 0
srv_find_qd_qs_mism 977383 977383 0 0.00 0 16456 7 0 0
srv_make_invnt_saldo 419 419 0 0.00 75 104589 4125 0 0
srv_make_money_saldo 746 746 0 0.00 1 10751 602 0 0
srv_recalc_idx_stat 5 5 0 0.00 208569 679207 363401 0 0
t$perf-abend:sp_add_invoice_to_stock 118 118 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4867 4867 0 0.00 0 13451 201 0 0
t$perf-norm:sp_cancel_adding_invoice 389 389 0 0.00 0 5008 121 0 0
t$perf-norm:sp_cancel_client_order 1663 1663 0 0.00 0 5236 16 0 0
t$perf-norm:sp_cancel_customer_reserve 649 649 0 0.00 0 387 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 607 607 0 0.00 0 1504 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 410 410 0 0.00 0 743 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 390 390 0 0.00 0 904 16 0 0
t$perf-norm:sp_cancel_supplier_order 389 389 0 0.00 0 23427 1633 0 0
t$perf-norm:sp_cancel_write_off 973 973 0 0.00 0 5682 11 0 0
t$perf-norm:sp_client_order 19127 19127 0 0.00 0 13370 21 0 0
t$perf-norm:sp_customer_reserve 927 927 0 0.00 0 2224 8 0 0
t$perf-norm:sp_pay_from_customer 6949 6949 0 0.00 0 5768 9 0 0
t$perf-norm:sp_pay_to_supplier 5660 5660 0 0.00 0 7779 11 0 0
t$perf-norm:sp_reserve_write_off 9325 9325 0 0.00 0 10192 12 0 0
t$perf-norm:sp_supplier_invoice 5310 5310 0 0.00 0 8998 22 0 0
t$perf-norm:sp_supplier_order 5238 5238 0 0.00 0 4465 19 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 1419 24 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 242 9 0 0
t$perf-norm:srv_make_invnt_saldo 419 419 0 0.00 0 949 11 0 0
t$perf-norm:srv_make_money_saldo 746 746 0 0.00 0 3811 14 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 27 7 0 0
v_add_invoice_to_stock 9962 9726 236 2.36 0 7192 17 0 236
v_all_customers 35351 35351 0 0.00 0 17 1 0 0
v_all_suppliers 29172 29172 0 0.00 0 20 1 0 0
v_all_wares 38254 38254 0 0.00 0 41 1 0 0
v_cancel_adding_invoice 778 778 0 0.00 0 3368 12 0 0
v_cancel_client_order 3326 3326 0 0.00 0 3904 8 0 0
v_cancel_customer_prepayment 1214 1214 0 0.00 0 1876 7 0 0
v_cancel_customer_reserve 1298 1298 0 0.00 0 1691 6 0 0
v_cancel_payment_to_supplier 820 820 0 0.00 0 2542 9 0 0
v_cancel_supplier_invoice 790 780 10 1.26 0 450 13 0 10
v_cancel_supplier_order 778 778 0 0.00 0 2385 9 0 0
v_cancel_write_off 1946 1946 0 0.00 0 4189 18 0 0
v_max_id_clo_ord 5238 5238 0 0.00 0 279 1 0 0
v_max_id_clo_res 927 927 0 0.00 0 761 5 0 0
v_max_id_ord_sup 5305 5305 0 0.00 0 4258 5 0 0
v_max_non_paid_invoice 1616 1616 0 0.00 0 92 2 0 0
v_max_non_paid_realizn 3542 3542 0 0.00 0 2743 4 0 0
v_min_id_clo_ord 5238 5238 0 0.00 0 4852 11 0 0
v_min_id_clo_res 927 927 0 0.00 0 6485 18 0 0
v_min_id_ord_sup 5305 5305 0 0.00 0 6316 8 0 0
v_min_non_paid_invoice 5659 5659 0 0.00 0 8302 17 0 0
v_min_non_paid_realizn 6949 6949 0 0.00 0 8141 19 0 0
v_our_firm 9714 9714 0 0.00 0 19 1 0 0
v_reserve_write_off 18650 18650 0 0.00 0 3842 7 0 0
x_lock_depdocs_on_canc_invoice 10365 10365 0 0.00 0 21281 135 0 0
x_lock_depdocs_on_canc_sup_ord 389 389 0 0.00 0 12443 442 0 0
x_make_qstorno_1000_1200 5238 5238 0 0.00 2 76519 1394 0 0
x_make_qstorno_1200_2000 5310 5310 0 0.00 3 97877 3984 0 0
x_make_qstorno_2100_3300 444668 444668 0 0.00 0 76162 201 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-10-2021 04:38:29 to 07-10-2021 04:38:33.

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
02:07:12 1653 8192000000 8943448896 9003151360 23268944 0.283 5 3 6 0 24035168 237808 528416
02:08:16 34722 8192000000 9712565872 10008211456 773946000 8.632 100 78 156 0 800864224 7423616 19494608
02:09:56 14652 8192000000 9752116160 10056855552 818898976 9.087 100 80 160 0 849587200 11487104 19201120
02:11:13 12101 8192000000 9784464912 10097868800 848012592 9.380 100 83 166 0 884689600 18170528 18506480
02:12:31 8553 8192000000 9837567888 10164494336 890167440 9.801 100 82 163 0 943050752 35252912 17630400
02:13:45 13573 8192000000 9893375168 10231017472 932895392 10.223 100 79 158 1 1001726896 52228832 16602672
02:15:04 7507 8192000000 9952015632 10298544128 968386480 10.571 100 82 164 0 1049833760 64796288 16650992
02:16:15 3832 8192000000 9980957952 10340532224 992600864 10.807 100 85 168 1 1082785536 73067456 17117216
02:17:21 5075 8192000000 10007086064 10367401984 1010018352 10.976 100 91 180 1 1115251008 87424256 17808400
02:18:29 2270 8192000000 10000325616 10368323584 1030661392 11.175 100 88 177 1 1106937168 57870128 18405648
02:19:34 2245 8192000000 10023639936 10393968640 1049652544 11.357 100 93 186 3 1124033872 55583408 18797920
02:20:38 6036 8192000000 10031611184 10407747584 1059030480 11.447 100 78 153 1 1133389904 58313456 16045968
02:21:46 2708 8192000000 10015484272 10402451456 1065801408 11.512 100 55 107 2 1121092288 44532352 10758528
02:22:51 3421 8192000000 10029786320 10422005760 1071990240 11.571 100 64 127 2 1136672352 51667136 13014976
02:23:57 33705 8192000000 10002441856 10394374144 1078007184 11.628 100 63 125 1 1104158640 11273376 14878080
02:25:31 5032 8192000000 9998087296 10400813056 1080516880 11.652 100 44 88 1 1112525936 22603872 9405184
02:26:38 5780 8192000000 10017754608 10407727104 1082627776 11.673 100 52 104 1 1130179552 35705248 11846528
02:27:44 8619 8192000000 10008361008 10412859392 1070816944 11.560 99 39 78 2 1098211328 18858336 8536048
02:28:58 21634 8192000000 9995808448 10409172992 1069644064 11.549 99 21 42 1 1090287888 16238768 4405056
02:30:20 6035 8192000000 10027658336 10422394880 1063240080 11.487 99 50 97 1 1104610320 31460752 9909488
02:31:29 4864 8192000000 9963736400 10345230336 998094192 10.860 96 37 72 1 1021842416 14780288 8967936
02:32:37 59046 8192000000 9925948400 10304770048 961208080 10.501 95 40 80 1 987159296 17204832 8746384
02:34:36 2396 8192000000 9965985488 10346643456 1024861296 11.119 100 46 90 1 1074084096 39401792 9821008
02:35:41 5814 8192000000 9969470544 10355326976 1040322624 11.268 99 50 97 2 1068029872 16801648 10905600
02:36:49 6811 8192000000 9982422240 10373922816 1055799696 11.416 100 38 69 1 1078951456 14957040 8194720
02:37:57 80913 8192000000 10000163632 10392154112 1069370096 11.546 100 43 82 1 1097902864 20105664 8427104
02:40:22 5338 8192000000 10050337312 10450505728 1089037440 11.734 100 57 111 1 1164922448 64388128 11496880
02:41:38 8641 8192000000 10076541024 10475290624 1104404832 11.879 100 63 126 0 1188281808 71204176 12672800
02:42:50 15137 8192000000 10098978720 10510131200 1113649712 11.967 100 53 103 1 1208854288 84102816 11101760
02:44:12 8750 8192000000 10109928848 10511945728 1129251808 12.114 100 70 138 0 1217667472 73305616 15110048
02:45:28 38550 8192000000 10163435520 10573139968 1144791968 12.261 100 51 100 0 1258088016 101739808 11556240
02:47:13 31769 8192000000 10208316608 10618462208 1151495952 12.324 100 50 100 0 1296338368 133611344 11231072
02:48:51 8893 8192000000 10263795152 10669527040 1179767280 12.588 100 68 135 1 1370827376 176080976 14979120
02:50:08 3352 8192000000 10329490704 10738057216 1190394768 12.687 100 67 134 0 1425109088 219883664 14830656
02:51:17 4547 8192000000 10361226608 10765189120 1181419984 12.603 100 83 166 0 1460340624 259834944 19085696
02:52:34 1871 8192000000 10418755520 10822897664 1195941648 12.739 100 86 171 0 1516056000 300846272 19268080
02:53:42 2487 8192000000 10439583536 10854928384 1189402912 12.678 100 91 181 0 1538743488 328244368 21096208
02:54:48 2922 8192000000 10467526976 10875990016 1187058464 12.656 100 94 185 0 1564062096 355160080 21843552
02:55:57 3398 8192000000 10458520016 10881363968 1162630784 12.428 100 86 172 0 1557549808 373940768 20978256
02:57:09 1821 8192000000 10494124416 10894454784 1158134608 12.386 100 92 183 1 1585341952 405656096 21551248
02:58:15 5927 8192000000 10502911072 10909581312 1157533008 12.380 100 81 160 0 1597443248 419873600 20036640
02:59:26 2715 8192000000 10494123568 10899509248 1162830832 12.430 100 95 186 0 1586251424 401158304 22262288
03:00:33 5251 8192000000 10454271312 10883862528 1153671520 12.344 99 51 102 2 1547678640 380636544 13370576
03:01:42 20500 8192000000 10364835200 10876280832 1149684752 12.307 100 27 53 1 1461896560 305054496 7157312
03:03:08 38615 8192000000 10357781152 10817536000 1134541520 12.164 100 20 40 2 1444752240 304642384 5568336
03:05:54 20082 8192000000 10127809136 10646093824 1115230368 11.982 100 19 38 0 1288495376 169087728 4177280
03:07:17 53618 8192000000 10053898080 10500214784 1109927328 11.932 99 35 69 1 1159936064 42363200 7645536
03:10:01 26330 8192000000 10062730800 10496999424 1114678192 11.977 99 41 80 2 1167165680 43859232 8628256
03:11:34 92258 8192000000 10079819904 10576265216 1111106416 11.943 98 22 44 0 1200491616 84834992 4550208
03:14:09 60532 8192000000 10072664608 10533842944 1109253552 11.925 98 46 87 0 1174327120 55859232 9214336
03:16:14 69265 8192000000 10069964000 10514468864 1081780224 11.664 96 16 31 0 1147201056 62098400 3322432
03:18:28 103349 8192000000 10038753456 10508128256 1113940768 11.970 99 25 51 1 1160480976 41178992 5361216
03:21:30 30118 8192000000 10081099536 10533339136 1130369952 12.125 99 10 20 1 1198227232 65767200 2090080
03:23:08 105600 8192000000 10072743312 10605228032 1121882864 12.045 98 13 26 0 1227931920 103339104 2709952
03:26:04 44544 8192000000 10066001520 10518433792 1125578832 12.080 99 16 32 0 1165622000 36952368 3090800
03:27:54 38239 8192000000 10117690288 10561146880 1147084128 12.282 100 25 50 0 1202081840 50642832 4354880
03:29:33 56339 8192000000 10111826976 10562723840 1102293056 11.859 96 31 61 2 1172118992 63159312 6666624
03:31:40 65710 8192000000 10102625968 10602721280 1104371008 11.879 98 18 36 0 1260561760 151571552 4619200
03:33:46 66333 8192000000 10104649744 10608091136 1142120672 12.235 100 14 28 0 1243319824 97892352 3306800
03:35:53 46023 8192000000 10126497600 10599981056 1137922560 12.196 100 20 40 1 1209758784 67732880 4103344
03:38:39 162171 8192000000 10082884448 10543271936 1143189136 12.246 100 43 86 2 1225492384 73139776 9163472
03:42:37 87666 8192000000 10079164448 10519654400 1110183312 11.934 97 13 26 0 1169591120 56087328 3320480
03:45:05 54923 8192000000 10093351840 10574237696 1127925232 12.102 100 12 24 0 1192130720 61881728 2323760
03:47:14 50639 8192000000 10146972528 10597261312 1140452000 12.220 100 29 56 0 1243926864 97271648 6203216
03:49:08 25751 8192000000 10190919264 10638221312 1144177632 12.255 100 21 42 1 1285717808 136470576 5069600
03:50:37 55627 8192000000 10131124704 10558328832 1132541472 12.145 100 32 63 1 1224706032 85417824 6746736
03:53:15 45806 8192000000 10045099712 10474582016 1051273808 11.373 94 16 32 0 1106206032 51724272 3207952
03:55:05 45027 8192000000 10044825344 10473385984 1056936528 11.427 95 27 54 1 1107805072 45735728 5132816
03:56:53 23910 8192000000 10094395584 10528391168 1098845952 11.827 99 40 80 0 1150565088 43608000 8111136
03:58:28 115913 8192000000 10069091472 10500997120 1065222432 11.506 95 31 62 0 1117560304 45826576 6511296
04:01:40 53396 8192000000 10045347952 10485121024 1105888560 11.893 99 10 20 0 1141269344 33439776 1941008
04:03:46 45709 8192000000 10057997280 10533482496 1121385632 12.040 100 7 14 0 1170942160 47813008 1743520
04:05:34 38131 8192000000 10064973568 10513707008 1126388112 12.087 100 39 74 1 1176948000 42900032 7659856
04:07:13 75365 8192000000 10101431376 10529976320 1106633136 11.901 98 26 52 1 1176557728 64306448 5618144
04:09:28 78237 8192000000 10087154864 10576019456 1091025872 11.752 96 17 34 2 1154240144 59326288 3887984
04:11:49 68844 8192000000 10064525696 10494910464 1125763648 12.081 100 12 24 0 1155753392 27119664 2870080
04:14:05 19079 8192000000 10089252528 10510090240 1122615680 12.052 99 35 70 0 1170394944 40727792 7051472
04:15:27 106839 8192000000 10045596144 10490626048 1107201520 11.906 98 41 82 0 1160955504 45066544 8687440
04:18:14 55304 8192000000 10108604672 10567151616 1123572704 12.061 98 20 40 0 1193436464 66188656 3675104
04:20:15 53211 8192000000 10090310960 10584748032 1115255232 11.982 98 7 14 0 1172005600 55341088 1409280
04:22:14 75569 8192000000 10086050240 10576465920 1109545808 11.928 97 14 27 0 1176549008 64117200 2886000
04:24:34 38157 8192000000 10116859264 10558525440 1126477824 12.088 98 41 82 0 1197167408 61798976 8890608
04:26:14 67964 8192000000 10138840048 10576547840 1131091936 12.132 99 15 30 0 1203000480 69086528 2822016
04:28:24 84643 8192000000 10232356624 10732539904 1142416704 12.238 99 19 38 0 1259968992 113113328 4438960
04:30:53 57324 8192000000 10097086144 10523901952 1122734928 12.053 98 17 34 0 1175075680 49057056 3283696
04:32:51 47619 8192000000 10117530224 10599956480 1136123680 12.179 99 40 78 2 1205195216 61473760 7597776
04:34:39 30721 8192000000 10121955856 10562330624 1132593232 12.146 99 38 76 1 1187546352 47001072 7952048
04:36:14 73136 8192000000 8880364416 9037230080 48841040 0.592 5 2 2 1 51491744 2422688 228016

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 07-10-2021 04:38:34 to 07-10-2021 04:38:49.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 07-10-2021 04:38:50 to 07-10-2021 04:38:52.

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          226025
MON$OLDEST_ACTIVE               226026
MON$OLDEST_SNAPSHOT             226026
MON$NEXT_TRANSACTION            226026
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                       2027552
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.2623 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2623 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2623 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2623 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 ---
:37: 
:38: mscs-test-161.budzdorov.ru    Thu Oct  7 04:37:29 2021
:39:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:40: 
--- end of diff output ---

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

07.10.21 04:38:53. 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-10-07 04:38:55 No severe PSQL-related problems occured
07.10.21 04:38:57. End of report.