Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.10.2021 10: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 16054492 kB
MemAvailable 30718968 kB
Buffers 0 kB
Cached 15686808 kB
SwapCached 3816 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-13 10:37:08
DTS_END                         2021-10-13 10:37:08.7670
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/54054
TRN_ID                          216254
ATT_ID                          589
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: 8682.24 24807 1041869
customer order: creation 159.32 447 19118
customer order: refuse 12.99 382 1559
order to supplier: creation 42.55 1456 5106
order to supplier: removal 3.29 133118 395
invoice (draft): creation 42.68 3784 5122
invoice (draft): removal 50.19 972 6023
invoice accept: apply 37.85 19765 4542
invoice accept: cancel 45.64 950 5477
customer reserve: creation 3494.43 193 419332
customer reserve: removal 4525.13 77 543015
realization accept: apply 75.79 225 9095
realization accept: cancel 76.66 137 9199
payment from customer: creation 54.26 131 6511
payment from customer: removal 5.19 106 623
payment to supplier: creation 43.43 137 5211
payment to supplier: removal 2.84 90 341
service: total inventory turnovers 3.48 3937 417
service: total monetary turnovers 5.78 673 694
service: aggregate perf. data 0.71 17637 85
service: refresh index statistics 0.03 311920 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-10-2021 10:38:55 to 13-10-2021 10:38:57.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5133
TEST_TIME 2 4700
TEST_TIME 3 4855
TEST_TIME 4 5316
TEST_TIME 5 5278
TEST_TIME 6 5066
TEST_TIME 7 5043
TEST_TIME 8 5141
TEST_TIME 9 5045
TEST_TIME 10 5469
TEST_TIME 11 5604
TEST_TIME 12 5655
TEST_TIME 13 5830
TEST_TIME 14 5929
TEST_TIME 15 6514
TEST_TIME 16 6625
TEST_TIME 17 6905
TEST_TIME 18 7136
TEST_TIME 19 7477
TEST_TIME 20 7674
TEST_TIME 21 8273
TEST_TIME 22 8096
TEST_TIME 23 8276
TEST_TIME 24 8470
TEST_TIME 25 8558
TEST_TIME 26 8975
TEST_TIME 27 9422
TEST_TIME 28 10570
TEST_TIME 29 10331
TEST_TIME 30 10404
TEST_TIME 31 10287
TEST_TIME 32 10085
TEST_TIME 33 10058
TEST_TIME 34 9874
TEST_TIME 35 9761
TEST_TIME 36 9661
TEST_TIME 37 9539
TEST_TIME 38 9506
TEST_TIME 39 9341
TEST_TIME 40 9253
TEST_TIME 41 9202
TEST_TIME 42 9096
TEST_TIME 43 9090
TEST_TIME 44 9051
TEST_TIME 45 9044
TEST_TIME 46 8933
TEST_TIME 47 8883
TEST_TIME 48 8755
TEST_TIME 49 8741
TEST_TIME 50 8766
TEST_TIME 51 8808
TEST_TIME 52 8809
TEST_TIME 53 8779
TEST_TIME 54 8794
TEST_TIME 55 8756
TEST_TIME 56 8905
TEST_TIME 57 8956
TEST_TIME 58 9042
TEST_TIME 59 9009
TEST_TIME 60 8943
TEST_TIME 61 9007
TEST_TIME 62 8916
TEST_TIME 63 8808
TEST_TIME 64 8759
TEST_TIME 65 8767
TEST_TIME 66 8706
TEST_TIME 67 8643
TEST_TIME 68 8564
TEST_TIME 69 8574
TEST_TIME 70 8582
TEST_TIME 71 8641
TEST_TIME 72 8824
TEST_TIME 73 8768
TEST_TIME 74 8749
TEST_TIME 75 8763
TEST_TIME 76 8849
TEST_TIME 77 8885
TEST_TIME 78 8815
TEST_TIME 79 8875
TEST_TIME 80 8870
TEST_TIME 81 8778
TEST_TIME 82 8726
TEST_TIME 83 8685
TEST_TIME 84 8664
TEST_TIME 85 8616
TEST_TIME 86 8564
TEST_TIME 87 8532
TEST_TIME 88 8539
TEST_TIME 89 8577
TEST_TIME 90 8554
TEST_TIME 91 8527
TEST_TIME 92 8526
TEST_TIME 93 8676
TEST_TIME 94 8761
TEST_TIME 95 8766
TEST_TIME 96 8800
TEST_TIME 97 8862
TEST_TIME 98 8853
TEST_TIME 99 8793
TEST_TIME 100 8730
TEST_TIME 101 8685
TEST_TIME 102 8677
TEST_TIME 103 8685
TEST_TIME 104 8635
TEST_TIME 105 8601
TEST_TIME 106 8561
TEST_TIME 107 8636
TEST_TIME 108 8619
TEST_TIME 109 8605
TEST_TIME 110 8620
TEST_TIME 111 8658
TEST_TIME 112 8657
TEST_TIME 113 8689
TEST_TIME 114 8696
TEST_TIME 115 8748
TEST_TIME 116 8810
TEST_TIME 117 8780
TEST_TIME 118 8799
TEST_TIME 119 8763
TEST_TIME 120 8691

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 13-10-2021 10:38:59 to 13-10-2021 10:39:02.

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 1430099 1430099 0 0.00 0 40772 7 0 0
doc_list_biud 1430099 1430099 0 0.00 0 63466 36 0 0
sp_add_invoice_to_stock 4647 4542 105 2.25 53 249742 19766 0 105
sp_cancel_adding_invoice 5477 5477 0 0.00 3 77273 950 0 0
sp_cancel_client_order 1559 1559 0 0.00 4 15764 382 0 0
sp_cancel_customer_reserve 543015 543015 0 0.00 1 25545 78 0 0
sp_cancel_pay_from_customer 623 623 0 0.00 3 6855 106 0 0
sp_cancel_pay_to_supplier 341 341 0 0.00 3 3924 90 0 0
sp_cancel_supplier_invoice 6033 6023 10 0.16 2 63472 973 0 10
sp_cancel_supplier_order 395 395 0 0.00 9 1011191 133119 0 0
sp_cancel_write_off 9199 9199 0 0.00 1 10939 137 0 0
sp_client_order 19118 19118 0 0.00 4 34240 448 0 0
sp_customer_reserve 419332 419332 0 0.00 0 64673 194 0 0
sp_fill_shopping_cart 29343 29343 0 0.00 1 31134 56 0 0
sp_fill_shopping_cart_clo_res 419318 419318 0 0.00 0 14483 18 0 0
sp_get_clo_for_invoice 4528 4528 0 0.00 0 30029 527 0 0
sp_kill_cost_storno 15640 15640 0 0.00 0 10705 72 0 0
sp_kill_qstorno_ret_qs2qd 1101647 1101647 0 0.00 0 63465 42 0 0
sp_kill_qty_storno 579291 579291 0 0.00 0 63466 89 0 0
sp_lock_selected_doc 18851 18851 0 0.00 0 11090 8 0 0
sp_make_cost_storno 25345 25345 0 0.00 0 40769 69 0 0
sp_multiply_rows_for_pdistr 25345 25345 0 0.00 0 18046 36 0 0
sp_multiply_rows_for_qdistr 19118 19118 0 0.00 0 34228 250 0 0
sp_pay_from_customer 6511 6511 0 0.00 4 21886 132 0 0
sp_pay_to_supplier 5211 5211 0 0.00 5 20997 137 0 0
sp_payment_common 11722 11722 0 0.00 1 14334 30 0 0
sp_qd_handle_on_cancel_clo 1559 1559 0 0.00 0 15757 351 0 0
sp_qd_handle_on_invoice_upd_sts 10005 10005 0 0.00 0 30559 384 0 0
sp_qd_handle_on_reserve_upd_sts 9095 9095 0 0.00 0 16095 68 0 0
sp_reserve_write_off 9095 9095 0 0.00 6 40782 226 0 0
sp_supplier_invoice 5122 5122 0 0.00 6 77163 3784 0 0
sp_supplier_order 5106 5106 0 0.00 5 74134 1457 0 0
srv_aggregate_perf_data 86 85 1 1.16 2524 152104 17637 0 1
srv_fill_mon_cache_memory 63 63 0 0.00 1131 162856 47760 0 0
srv_find_qd_qs_mism 998357 998357 0 0.00 0 18760 5 0 0
srv_make_invnt_saldo 417 417 0 0.00 72 101640 3938 0 0
srv_make_money_saldo 694 694 0 0.00 1 18559 674 0 0
srv_recalc_idx_stat 4 4 0 0.00 185466 406203 311921 0 0
t$perf-abend:sp_add_invoice_to_stock 105 105 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 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 4542 4542 0 0.00 0 14730 177 0 0
t$perf-norm:sp_cancel_adding_invoice 372 372 0 0.00 0 5604 107 0 0
t$perf-norm:sp_cancel_client_order 1559 1559 0 0.00 0 1833 11 0 0
t$perf-norm:sp_cancel_customer_reserve 611 611 0 0.00 0 6926 29 0 0
t$perf-norm:sp_cancel_pay_from_customer 623 623 0 0.00 0 6268 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 341 341 0 0.00 0 2660 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 358 358 0 0.00 0 1192 22 0 0
t$perf-norm:sp_cancel_supplier_order 395 395 0 0.00 0 27537 1609 0 0
t$perf-norm:sp_cancel_write_off 970 970 0 0.00 0 2350 12 0 0
t$perf-norm:sp_client_order 19118 19118 0 0.00 0 7557 20 0 0
t$perf-norm:sp_customer_reserve 896 896 0 0.00 0 2185 9 0 0
t$perf-norm:sp_pay_from_customer 6511 6511 0 0.00 0 7448 9 0 0
t$perf-norm:sp_pay_to_supplier 5211 5211 0 0.00 0 7942 11 0 0
t$perf-norm:sp_reserve_write_off 9095 9095 0 0.00 0 6840 14 0 0
t$perf-norm:sp_supplier_invoice 5122 5122 0 0.00 0 41325 24 0 0
t$perf-norm:sp_supplier_order 5106 5106 0 0.00 0 4888 15 0 0
t$perf-norm:srv_aggregate_perf_data 85 85 0 0.00 0 156 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 7 1 0 0
t$perf-norm:srv_make_invnt_saldo 417 417 0 0.00 0 1208 11 0 0
t$perf-norm:srv_make_money_saldo 694 694 0 0.00 0 1662 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 9 5 0 0
v_add_invoice_to_stock 9264 9054 210 2.26 0 6318 20 0 210
v_all_customers 35006 35006 0 0.00 0 25 1 0 0
v_all_suppliers 28106 28106 0 0.00 0 168 1 0 0
v_all_wares 38236 38236 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 744 744 0 0.00 0 208 6 0 0
v_cancel_client_order 3118 3118 0 0.00 0 3218 8 0 0
v_cancel_customer_prepayment 1246 1246 0 0.00 0 4601 14 0 0
v_cancel_customer_reserve 1222 1222 0 0.00 0 3974 7 0 0
v_cancel_payment_to_supplier 682 682 0 0.00 0 1462 6 0 0
v_cancel_supplier_invoice 736 716 20 2.71 0 3148 21 0 20
v_cancel_supplier_order 790 790 0 0.00 0 2040 9 0 0
v_cancel_write_off 1940 1940 0 0.00 0 2555 18 0 0
v_max_id_clo_ord 5106 5106 0 0.00 0 642 1 0 0
v_max_id_clo_res 896 896 0 0.00 0 2351 9 0 0
v_max_id_ord_sup 5118 5118 0 0.00 0 4776 8 0 0
v_max_non_paid_invoice 1382 1382 0 0.00 0 2212 5 0 0
v_max_non_paid_realizn 3340 3340 0 0.00 0 4591 6 0 0
v_min_id_clo_ord 5106 5106 0 0.00 0 6589 7 0 0
v_min_id_clo_res 896 896 0 0.00 0 5864 17 0 0
v_min_id_ord_sup 5118 5118 0 0.00 0 7940 8 0 0
v_min_non_paid_invoice 5211 5211 0 0.00 0 4817 16 0 0
v_min_non_paid_realizn 6511 6511 0 0.00 0 14330 25 0 0
v_our_firm 9572 9572 0 0.00 0 12 1 0 0
v_reserve_write_off 18190 18190 0 0.00 0 3222 6 0 0
x_lock_depdocs_on_canc_invoice 11142 11142 0 0.00 0 15236 128 0 0
x_lock_depdocs_on_canc_sup_ord 395 395 0 0.00 0 11047 367 0 0
x_make_qstorno_1000_1200 5106 5106 0 0.00 1 74126 1371 0 0
x_make_qstorno_1200_2000 5122 5122 0 0.00 2 75766 3557 0 0
x_make_qstorno_2100_3300 419332 419332 0 0.00 0 64658 173 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-10-2021 10:39:03 to 13-10-2021 10:39:07.

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
08:07:12 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:13 26526 8192000000 9708570864 10003730432 790385216 8.799 100 86 171 0 819519824 8074112 21060496
08:09:45 27022 8192000000 9757452224 10062024704 832539280 9.225 100 90 181 0 866359616 11836560 21983776
08:11:15 25012 8192000000 9800554592 10119372800 871080144 9.611 100 84 168 0 909892272 19360944 19451184
08:12:42 18719 8192000000 9849534176 10179493888 914046544 10.037 100 76 152 0 957654624 26934352 16673728
08:14:07 11126 8192000000 9902431088 10246922240 952090624 10.412 100 73 148 0 1018358848 50637424 15630800
08:15:20 10522 8192000000 9971328336 10323337216 985895184 10.742 100 82 163 1 1074887136 72789456 16202496
08:16:35 11020 8192000000 9970459760 10331643904 1011050208 10.986 100 68 136 0 1084925968 60124672 13751088
08:17:50 2835 8192000000 10007697120 10375303168 1042430288 11.288 100 80 160 2 1126907712 68446848 16030576
08:18:56 4220 8192000000 10019682320 10394812416 1058313280 11.440 100 89 178 1 1138803568 61968176 18522112
08:20:03 1772 8192000000 10054840016 10424786944 1073479856 11.585 100 93 185 3 1161972240 70204896 18287488
08:21:05 6510 8192000000 9999203200 10387861504 1075523968 11.605 100 36 72 1 1114001440 31375776 7101696
08:22:14 6201 8192000000 10011079424 10404978688 1084079008 11.686 100 41 82 0 1114982448 22866560 8036880
08:23:21 7622 8192000000 10002483488 10395963392 1089554448 11.738 100 42 84 0 1135515872 37560944 8400480
08:24:31 56242 8192000000 10015548464 10404061184 1094612368 11.786 100 60 121 0 1122783488 14346032 13825088
08:26:28 10350 8192000000 10006374224 10414854144 1094010432 11.781 100 29 58 1 1117009904 17133872 5865600
08:27:40 12842 8192000000 10015970752 10415079424 1091216368 11.754 99 22 44 0 1115202656 18589792 5396496
08:28:54 8169 8192000000 9969334960 10364399616 1051857936 11.378 97 28 55 1 1081667456 23608288 6201232
08:30:05 41522 8192000000 9995272896 10381955072 1068938368 11.542 99 50 98 2 1089516832 8985728 11592736
08:31:47 5014 8192000000 9953637184 10345340928 1038930912 11.254 99 41 81 3 1073313408 24303136 10079360
08:32:53 7595 8192000000 9936028176 10327384064 983621696 10.719 95 35 72 1 1015489424 23898016 7969712
08:34:01 9396 8192000000 9936855760 10325549056 1013333200 11.008 98 26 51 2 1042981552 23700064 5948288
08:35:12 61334 8192000000 9950030688 10349719552 1049307424 11.354 100 23 46 1 1073086384 19270160 4508800
08:37:17 11594 8192000000 9986500128 10377236480 1078955632 11.638 100 45 88 5 1099150496 11245536 8949328
08:38:41 9860 8192000000 10033942944 10423885824 1095570016 11.796 100 48 95 0 1136958512 31720448 9668048
08:40:06 16852 8192000000 10111332000 10511523840 1117878384 12.007 100 52 103 2 1216952816 88614112 10460320
08:41:28 11381 8192000000 10109161056 10510479360 1124223776 12.067 100 44 86 0 1217925440 85046992 8654672
08:42:47 41031 8192000000 10134352048 10547159040 1134253824 12.161 100 47 94 1 1244469920 100142736 10073360
08:44:34 15126 8192000000 10175727584 10579001344 1142410688 12.238 100 50 100 0 1282023072 129263120 10349264
08:45:53 1129 8192000000 10219199728 10626375680 1149160528 12.302 100 65 130 1 1323494512 159272944 15061040
08:47:02 11340 8192000000 10194034976 10617339904 1163880976 12.440 100 39 77 0 1314815376 142232400 8702000
08:48:17 6877 8192000000 10272743760 10686492672 1185217920 12.639 100 67 132 1 1378356272 177730464 15407888
08:49:26 4790 8192000000 10347260272 10758377472 1190059488 12.684 100 68 137 0 1436494160 230734576 15700096
08:50:37 3555 8192000000 10365007040 10775666688 1193375072 12.715 100 79 155 2 1465398432 254360720 17662640
08:51:47 1755 8192000000 10421442544 10836475904 1210887552 12.877 100 80 157 1 1522455056 294365168 17202336
08:52:54 2701 8192000000 10394324432 10841939968 1210517552 12.874 100 69 137 0 1508289120 281163776 16607792
08:54:00 2732 8192000000 10403997600 10826747904 1202041616 12.795 99 86 171 1 1506563312 284387504 20134192
08:55:11 1978 8192000000 10459304400 10883608576 1201376768 12.789 99 91 180 2 1555752560 333947008 20428784
08:56:18 6028 8192000000 10458701056 10896023552 1188224512 12.667 100 67 132 0 1562289104 358690368 15374224
08:57:29 5291 8192000000 10469273328 10892197888 1183309440 12.621 99 48 96 2 1554373200 358584672 12479088
08:58:40 11085 8192000000 10409815472 10838761472 1175268208 12.546 100 53 104 0 1499852912 311543904 13040800
08:59:56 16449 8192000000 10468355216 10894290944 1181496144 12.604 100 42 84 0 1553322544 360923264 10903136
09:01:27 12157 8192000000 10451323872 10913128448 1158891760 12.393 99 41 82 0 1560211200 390874592 10444848
09:03:00 13993 8192000000 10457655792 10900451328 1146719392 12.279 100 29 58 1 1548709648 394060592 7929664
09:04:17 21985 8192000000 10381761008 10834051072 1123884816 12.064 99 33 66 0 1470867968 338490512 8492640
09:05:44 18627 8192000000 10098991968 10526621696 1121069440 12.037 99 22 45 0 1207654112 81140176 5444496
09:07:03 25968 8192000000 10079245888 10509213696 1136118624 12.179 100 33 65 0 1222445744 79672544 6654576
09:08:48 63333 8192000000 10073083952 10524012544 1105173936 11.887 98 19 38 3 1180459648 70987664 4298048
09:10:53 146222 8192000000 10045904128 10476408832 1070321488 11.555 95 21 42 0 1108765056 34266416 4177152
09:14:21 82395 8192000000 10070877824 10536521728 1126323360 12.087 100 23 46 1 1199036224 68004208 4708656
09:16:55 25581 8192000000 10072954880 10517676032 1129720832 12.119 100 20 40 0 1179033536 45417424 3895280
09:18:44 51525 8192000000 10113274080 10553237504 1123294512 12.058 99 33 62 3 1200691568 69889840 7507216
09:20:36 120254 8192000000 10065888304 10526916608 1099369552 11.832 97 14 28 0 1186715728 84302928 3043248
09:23:50 63803 8192000000 10062771136 10524774400 1103584256 11.872 98 15 29 0 1146495680 39825920 3085504
09:25:54 70077 8192000000 10107432880 10586214400 1121722496 12.043 99 8 16 0 1216936400 93439856 1774048
09:28:22 55618 8192000000 10060210416 10499362816 1068595872 11.539 95 25 49 0 1105253696 31525504 5132320
09:30:20 65751 8192000000 10118342432 10560888832 1117241216 12.001 99 21 42 3 1214944304 93182864 4520224
09:32:40 47635 8192000000 10128296240 10645192704 1114721104 11.977 99 23 44 2 1229737936 109733184 5283648
09:34:42 89651 8192000000 10058671904 10496024576 1114542832 11.975 99 10 20 0 1193045744 76224816 2278096
09:37:17 20082 8192000000 10081571536 10492280832 1125220400 12.076 99 58 117 2 1160349552 23377840 11751312
09:38:39 99927 8192000000 10093284960 10551967744 1135101152 12.169 100 40 80 1 1224633232 81590688 7941392
09:41:26 21259 8192000000 10096207616 10539888640 1139386704 12.210 100 38 76 0 1205065280 58474624 7203952
09:42:49 48796 8192000000 10074704144 10523004928 1133641568 12.156 99 8 16 1 1177334592 42086272 1606752
09:44:40 87109 8192000000 10122981680 10577899520 1110856016 11.941 96 15 30 1 1195550400 81551632 3142752
09:47:08 63201 8192000000 10175415408 10637795328 1128581088 12.108 98 22 43 0 1277553200 143222864 5749248
09:49:28 57059 8192000000 10082155840 10539909120 1151678464 12.325 100 18 36 0 1210923104 55011520 4233120
09:51:43 70409 8192000000 10101000384 10557919232 1110693792 11.939 96 17 34 0 1182724656 68492864 3538000
09:53:54 93711 8192000000 10076969872 10487238656 1070736000 11.559 93 38 74 0 1118420384 40189680 7494704
09:56:28 162854 8192000000 10051474976 10486329344 1046664624 11.329 93 18 36 1 1090164016 39822320 3677072
10:00:13 58329 8192000000 10050015856 10530750464 1106931568 11.903 98 11 22 0 1162037664 52498528 2607568
10:02:44 58876 8192000000 10076679248 10526588928 1118915120 12.017 99 10 20 1 1163865440 42776576 2173744
10:04:45 83768 8192000000 10120559232 10593488896 1098577760 11.824 96 19 38 0 1199045296 95996304 4471232
10:07:19 63450 8192000000 10171455968 10605395968 1108901952 11.922 97 22 44 0 1229987904 115944928 5141024
10:09:41 73597 8192000000 10122779136 10626580480 1119832112 12.025 99 23 44 2 1250570480 125787648 4950720
10:12:06 64586 8192000000 10092024128 10511724544 1093029936 11.771 98 22 44 0 1167208176 69741616 4436624
10:14:14 81042 8192000000 10064756768 10501332992 1096447440 11.804 97 25 47 0 1128747936 27705376 4595120
10:16:41 59443 8192000000 10027381920 10446577664 1095917472 11.799 99 30 60 0 1119062720 16613360 6531888
10:18:41 46753 8192000000 10088366736 10521767936 1124999568 12.074 100 37 75 1 1185215504 52242752 7973184
10:20:29 62412 8192000000 10071912704 10540146688 1119382928 12.021 99 12 24 1 1177117120 54868832 2865360
10:22:32 63829 8192000000 10128803264 10575523840 1141070016 12.226 100 18 36 1 1220493088 75326624 4096448
10:24:51 79088 8192000000 10081192688 10519760896 1088405968 11.727 96 13 25 0 1128238560 37137216 2695376
10:27:18 58245 8192000000 10133933120 10628198400 1141117808 12.226 100 24 48 1 1240137840 93492464 5527568
10:29:17 67916 8192000000 10112509008 10591170560 1111792048 11.949 97 27 52 0 1203316720 85752448 5772224
10:31:25 90501 8192000000 10143798032 10621227008 1119499584 12.022 98 18 36 1 1205959200 82637392 3822224
10:33:58 28477 8192000000 10086339552 10512764928 1129981360 12.121 99 37 74 1 1167439968 29894512 7564096
10:35:29 87984 8192000000 10060065696 10523844608 1140420768 12.219 100 17 34 0 1193533744 49304000 3808976

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 13-10-2021 10:39:09 to 13-10-2021 10:39:24.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 105 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 13-10-2021 10:39:25 to 13-10-2021 10:39:27.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          214779
MON$OLDEST_ACTIVE               216483
MON$OLDEST_SNAPSHOT             216483
MON$NEXT_TRANSACTION            216483
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                       2027296
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             763
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2631 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2631 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2631 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2631 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 ---
:23: 
:24: mscs-test-161.budzdorov.ru    Wed Oct 13 10:37:15 2021
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:26: 
:27: 
:28: mscs-test-161.budzdorov.ru    Wed Oct 13 10:37:29 2021
:29:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:30: 
--- end of diff output ---

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

13.10.21 10:39:28. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-10-13 10:39:30 No severe PSQL-related problems occured
13.10.21 10:39:31. End of report.