Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.03.2021 07:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID df9f488d08424df294c459d6c283e9c3
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-03-04 16:07
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.26
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16732700 kB
MemAvailable 31460240 kB
Buffers 0 kB
Cached 14886740 kB
SwapCached 0 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.0
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-03-27 07:39:08
DTS_END                         2021-03-27 07:39:08.1720
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/41628
TRN_ID                          232142
ATT_ID                          670
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: 9075.35 26886 1089042
customer order: creation 160.41 430 19249
customer order: refuse 14.43 344 1731
order to supplier: creation 43.94 1446 5273
order to supplier: removal 3.46 129111 415
invoice (draft): creation 45.54 3803 5465
invoice (draft): removal 52.88 995 6346
invoice accept: apply 40.18 19054 4822
invoice accept: cancel 47.73 959 5728
customer reserve: creation 3727.86 185 447343
customer reserve: removal 4660.48 75 559257
realization accept: apply 77.78 189 9334
realization accept: cancel 76.73 139 9208
payment from customer: creation 56.88 116 6825
payment from customer: removal 5.52 102 662
payment to supplier: creation 47.63 128 5716
payment to supplier: removal 3.42 71 410
service: total inventory turnovers 3.84 3694 461
service: total monetary turnovers 5.88 646 705
service: aggregate perf. data 0.73 20092 87
service: refresh index statistics 0.04 356133 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-03-2021 07:40:23 to 27-03-2021 07:40:25.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3436
TEST_TIME 2 4714
TEST_TIME 3 4899
TEST_TIME 4 5483
TEST_TIME 5 5505
TEST_TIME 6 5606
TEST_TIME 7 5680
TEST_TIME 8 5657
TEST_TIME 9 5922
TEST_TIME 10 6121
TEST_TIME 11 6210
TEST_TIME 12 6503
TEST_TIME 13 6450
TEST_TIME 14 6725
TEST_TIME 15 7117
TEST_TIME 16 7218
TEST_TIME 17 7353
TEST_TIME 18 7239
TEST_TIME 19 7172
TEST_TIME 20 7351
TEST_TIME 21 7519
TEST_TIME 22 7963
TEST_TIME 23 8455
TEST_TIME 24 8495
TEST_TIME 25 8686
TEST_TIME 26 9415
TEST_TIME 27 9700
TEST_TIME 28 10913
TEST_TIME 29 11035
TEST_TIME 30 11072
TEST_TIME 31 10904
TEST_TIME 32 10689
TEST_TIME 33 10427
TEST_TIME 34 10323
TEST_TIME 35 10223
TEST_TIME 36 10238
TEST_TIME 37 10173
TEST_TIME 38 10116
TEST_TIME 39 9942
TEST_TIME 40 9843
TEST_TIME 41 9848
TEST_TIME 42 9813
TEST_TIME 43 9607
TEST_TIME 44 9697
TEST_TIME 45 9634
TEST_TIME 46 9585
TEST_TIME 47 9637
TEST_TIME 48 9621
TEST_TIME 49 9821
TEST_TIME 50 9855
TEST_TIME 51 9787
TEST_TIME 52 9756
TEST_TIME 53 9905
TEST_TIME 54 9929
TEST_TIME 55 9945
TEST_TIME 56 9922
TEST_TIME 57 9953
TEST_TIME 58 9964
TEST_TIME 59 9846
TEST_TIME 60 9697
TEST_TIME 61 9747
TEST_TIME 62 9645
TEST_TIME 63 9545
TEST_TIME 64 9539
TEST_TIME 65 9543
TEST_TIME 66 9467
TEST_TIME 67 9405
TEST_TIME 68 9425
TEST_TIME 69 9427
TEST_TIME 70 9429
TEST_TIME 71 9382
TEST_TIME 72 9394
TEST_TIME 73 9474
TEST_TIME 74 9516
TEST_TIME 75 9469
TEST_TIME 76 9421
TEST_TIME 77 9487
TEST_TIME 78 9578
TEST_TIME 79 9651
TEST_TIME 80 9620
TEST_TIME 81 9531
TEST_TIME 82 9435
TEST_TIME 83 9397
TEST_TIME 84 9371
TEST_TIME 85 9367
TEST_TIME 86 9306
TEST_TIME 87 9325
TEST_TIME 88 9288
TEST_TIME 89 9280
TEST_TIME 90 9284
TEST_TIME 91 9269
TEST_TIME 92 9255
TEST_TIME 93 9290
TEST_TIME 94 9311
TEST_TIME 95 9430
TEST_TIME 96 9393
TEST_TIME 97 9439
TEST_TIME 98 9469
TEST_TIME 99 9463
TEST_TIME 100 9377
TEST_TIME 101 9322
TEST_TIME 102 9273
TEST_TIME 103 9239
TEST_TIME 104 9200
TEST_TIME 105 9192
TEST_TIME 106 9159
TEST_TIME 107 9099
TEST_TIME 108 9103
TEST_TIME 109 9077
TEST_TIME 110 9069
TEST_TIME 111 9093
TEST_TIME 112 9081
TEST_TIME 113 9106
TEST_TIME 114 9128
TEST_TIME 115 9151
TEST_TIME 116 9143
TEST_TIME 117 9135
TEST_TIME 118 9146
TEST_TIME 119 9153
TEST_TIME 120 9078

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 27-03-2021 07:40:27 to 27-03-2021 07:40:30.

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 1503189 1503189 0 0.00 0 12077 7 0 0
doc_list_biud 1503185 1503185 0 0.00 0 110055 35 0 0
sp_add_invoice_to_stock 4919 4822 97 1.97 58 191605 19054 0 97
sp_cancel_adding_invoice 5728 5728 0 0.00 5 80496 959 0 0
sp_cancel_client_order 1731 1731 0 0.00 4 11895 344 0 0
sp_cancel_customer_reserve 559257 559257 0 0.00 1 22362 75 0 0
sp_cancel_pay_from_customer 662 662 0 0.00 3 4977 103 0 0
sp_cancel_pay_to_supplier 410 410 0 0.00 3 3428 71 0 0
sp_cancel_supplier_invoice 6351 6346 5 0.07 2 110061 996 0 5
sp_cancel_supplier_order 415 415 0 0.00 7 1559645 129111 0 0
sp_cancel_write_off 9208 9208 0 0.00 1 14388 140 0 0
sp_client_order 19249 19249 0 0.00 4 39761 431 0 0
sp_customer_reserve 447343 447343 0 0.00 0 120884 185 0 0
sp_fill_shopping_cart 29985 29985 0 0.00 1 24979 47 0 0
sp_fill_shopping_cart_clo_res 447337 447337 0 0.00 0 19097 18 0 0
sp_get_clo_for_invoice 4816 4816 0 0.00 0 48274 387 0 0
sp_kill_cost_storno 16008 16008 0 0.00 0 7187 65 0 0
sp_kill_qstorno_ret_qs2qd 1134483 1134483 0 0.00 0 110053 40 0 0
sp_kill_qty_storno 596833 596833 0 0.00 0 110055 87 0 0
sp_lock_selected_doc 19789 19789 0 0.00 0 7525 5 0 0
sp_make_cost_storno 26691 26691 0 0.00 0 11678 62 0 0
sp_multiply_rows_for_pdistr 26690 26690 0 0.00 0 6807 35 0 0
sp_multiply_rows_for_qdistr 19249 19249 0 0.00 0 23076 241 0 0
sp_pay_from_customer 6825 6825 0 0.00 4 9850 117 0 0
sp_pay_to_supplier 5716 5716 0 0.00 4 7635 128 0 0
sp_payment_common 12540 12540 0 0.00 1 6232 22 0 0
sp_qd_handle_on_cancel_clo 1731 1731 0 0.00 0 11885 308 0 0
sp_qd_handle_on_invoice_upd_sts 10543 10543 0 0.00 0 48878 424 0 0
sp_qd_handle_on_reserve_upd_sts 9333 9333 0 0.00 0 16725 54 0 0
sp_reserve_write_off 9334 9334 0 0.00 7 16776 189 0 0
sp_supplier_invoice 5465 5465 0 0.00 7 104878 3803 0 0
sp_supplier_order 5273 5273 0 0.00 5 24991 1447 0 0
srv_aggregate_perf_data 88 87 1 1.13 3040 452451 20093 0 1
srv_fill_mon_cache_memory 68 68 0 0.00 778 132760 41171 0 0
srv_find_qd_qs_mism 1043205 1043205 0 0.00 0 9213 6 0 0
srv_make_invnt_saldo 461 461 0 0.00 66 115377 3694 0 0
srv_make_money_saldo 705 705 0 0.00 1 14593 647 0 0
srv_recalc_idx_stat 5 5 0 0.00 191133 567943 356134 0 0
t$perf-abend:sp_add_invoice_to_stock 97 97 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 4822 4822 0 0.00 0 37720 189 0 0
t$perf-norm:sp_cancel_adding_invoice 370 370 0 0.00 0 3693 117 0 0
t$perf-norm:sp_cancel_client_order 1731 1731 0 0.00 0 1884 11 0 0
t$perf-norm:sp_cancel_customer_reserve 671 671 0 0.00 0 1466 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 662 662 0 0.00 0 627 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 410 410 0 0.00 0 1278 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 408 408 0 0.00 0 838 27 0 0
t$perf-norm:sp_cancel_supplier_order 415 415 0 0.00 0 22868 1813 0 0
t$perf-norm:sp_cancel_write_off 977 977 0 0.00 0 2058 8 0 0
t$perf-norm:sp_client_order 19249 19249 0 0.00 0 8274 20 0 0
t$perf-norm:sp_customer_reserve 960 960 0 0.00 0 5256 17 0 0
t$perf-norm:sp_pay_from_customer 6825 6825 0 0.00 0 3222 7 0 0
t$perf-norm:sp_pay_to_supplier 5716 5716 0 0.00 0 3823 8 0 0
t$perf-norm:sp_reserve_write_off 9334 9334 0 0.00 0 5772 10 0 0
t$perf-norm:sp_supplier_invoice 5465 5465 0 0.00 0 6661 24 0 0
t$perf-norm:sp_supplier_order 5273 5273 0 0.00 0 9353 22 0 0
t$perf-norm:srv_aggregate_perf_data 87 87 0 0.00 0 290 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 131 3 0 0
t$perf-norm:srv_make_invnt_saldo 461 461 0 0.00 0 646 9 0 0
t$perf-norm:srv_make_money_saldo 705 705 0 0.00 0 489 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 41 12 0 0
v_add_invoice_to_stock 9818 9624 194 1.97 0 5872 17 0 194
v_all_customers 35532 35532 0 0.00 0 37 1 0 0
v_all_suppliers 29752 29752 0 0.00 0 36 1 0 0
v_all_wares 38498 38498 0 0.00 0 86 1 0 0
v_cancel_adding_invoice 740 740 0 0.00 0 545 7 0 0
v_cancel_client_order 3462 3462 0 0.00 0 3449 5 0 0
v_cancel_customer_prepayment 1324 1324 0 0.00 0 4794 12 0 0
v_cancel_customer_reserve 1342 1342 0 0.00 0 690 5 0 0
v_cancel_payment_to_supplier 820 820 0 0.00 0 3419 9 0 0
v_cancel_supplier_invoice 826 816 10 1.21 0 700 14 0 10
v_cancel_supplier_order 830 830 0 0.00 0 396 5 0 0
v_cancel_write_off 1954 1954 0 0.00 0 4018 14 0 0
v_max_id_clo_ord 5273 5273 0 0.00 0 2518 2 0 0
v_max_id_clo_res 960 960 0 0.00 0 1241 7 0 0
v_max_id_ord_sup 5463 5463 0 0.00 0 6606 3 0 0
v_max_non_paid_invoice 1575 1575 0 0.00 0 177 2 0 0
v_max_non_paid_realizn 3504 3504 0 0.00 0 3449 4 0 0
v_min_id_clo_ord 5273 5273 0 0.00 0 4247 7 0 0
v_min_id_clo_res 960 960 0 0.00 0 5187 11 0 0
v_min_id_ord_sup 5463 5463 0 0.00 0 6294 14 0 0
v_min_non_paid_invoice 5715 5715 0 0.00 0 5509 13 0 0
v_min_non_paid_realizn 6825 6825 0 0.00 0 6119 16 0 0
v_our_firm 9608 9608 0 0.00 0 39 1 0 0
v_reserve_write_off 18666 18666 0 0.00 0 3449 5 0 0
x_lock_depdocs_on_canc_invoice 11666 11666 0 0.00 0 13251 133 0 0
x_lock_depdocs_on_canc_sup_ord 415 415 0 0.00 0 8333 352 0 0
x_make_qstorno_1000_1200 5273 5273 0 0.00 2 24962 1359 0 0
x_make_qstorno_1200_2000 5465 5465 0 0.00 4 103957 3625 0 0
x_make_qstorno_2100_3300 447343 447343 0 0.00 0 120883 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-03-2021 07:40:31 to 27-03-2021 07:40:35.

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:09:11 1 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
05:10:12 35547 8192000000 9722794912 10022494208 802079600 8.917 100 86 172 0 831162528 7699584 21383344
05:11:53 19674 8192000000 9766126192 10074587136 839468912 9.294 100 91 182 0 874845152 13790720 21585520
05:13:15 19887 8192000000 9806266464 10126036992 873114480 9.631 100 83 166 0 914204960 22493328 18597152
05:14:40 9935 8192000000 9886546512 10216321024 911934016 10.016 100 83 166 1 989762480 59944480 17883984
05:15:53 4919 8192000000 9921865552 10267484160 958926352 10.479 100 76 148 3 1036646192 62139216 15580624
05:16:59 4653 8192000000 9961373200 10321178624 994166928 10.822 100 75 149 0 1080421184 71143584 15110672
05:18:05 2624 8192000000 10022420496 10383798272 1027943904 11.149 100 89 178 1 1127936960 82238112 17754944
05:19:09 2202 8192000000 10020467296 10387656704 1050350528 11.364 100 91 180 0 1132103360 62976032 18776800
05:20:12 13748 8192000000 10025199792 10404421632 1069528768 11.548 100 67 136 1 1136226112 52992608 13704736
05:21:26 6875 8192000000 10002206960 10394529792 1077073664 11.620 100 41 79 3 1103606080 18450864 8081552
05:22:34 6071 8192000000 10003262000 10404712448 1080335360 11.651 100 34 68 1 1123613216 35783968 7493888
05:23:43 39358 8192000000 9992620624 10404810752 1087327968 11.717 100 26 51 2 1122189936 29480720 5381248
05:25:23 5670 8192000000 10011998576 10407641088 1094169888 11.782 100 45 89 1 1124621200 20232656 10218656
05:26:30 8065 8192000000 10006706272 10411577344 1096637360 11.806 100 28 56 1 1123636720 20538672 6460688
05:27:41 5864 8192000000 10021601872 10425835520 1094978848 11.790 100 45 90 0 1130763328 25925872 9858608
05:28:47 35317 8192000000 9982229056 10400374784 1067916320 11.532 98 34 69 1 1098679936 23459744 7303872
05:30:23 4357 8192000000 9976896592 10368643072 1043810432 11.301 98 38 73 3 1084709808 33344496 7554880
05:31:30 3545 8192000000 9927279904 10302877696 1007753520 10.954 99 44 89 1 1032631504 14639184 10238800
05:32:38 8626 8192000000 9928853920 10324049920 1014200960 11.016 98 25 50 1 1038488896 18748384 5539552
05:33:47 29028 8192000000 9954485232 10353360896 982369856 10.707 95 28 56 0 1027609760 39298176 5941728
05:35:17 8579 8192000000 9949125360 10344652800 1039633504 11.261 100 32 64 1 1072053984 25717184 6703296
05:36:29 7264 8192000000 9975038560 10366627840 1056589232 11.424 100 47 92 2 1089953632 23199056 10165344
05:37:37 6023 8192000000 9989278272 10389544960 1064830544 11.503 100 25 50 1 1095955376 25965328 5159504
05:38:44 69824 8192000000 9980787488 10377035776 1068057744 11.534 100 26 52 0 1087488912 13319536 6111632
05:41:07 33920 8192000000 10057751312 10454827008 1092498400 11.766 100 51 99 2 1172113040 69172320 10442320
05:42:43 6176 8192000000 10075892144 10482610176 1103814048 11.874 100 50 96 1 1186314208 72255904 10244256
05:43:54 26707 8192000000 10119368224 10523328512 1118709824 12.015 100 50 100 2 1217268768 87487536 11071408
05:45:26 4575 8192000000 10149316640 10552995840 1127915040 12.102 100 56 109 1 1257277776 116909088 12453648
05:46:45 4022 8192000000 10171574192 10579230720 1141392464 12.229 100 60 120 0 1282481680 127257056 13832160
05:47:50 8631 8192000000 10206019984 10627768320 1147349600 12.285 100 48 96 0 1327811728 169696608 10765520
05:49:04 5687 8192000000 10241909392 10661109760 1149377040 12.304 99 65 131 0 1334184800 170826304 13981456
05:50:16 7525 8192000000 10244866832 10658459648 1165012240 12.450 100 58 113 0 1352309520 174591344 12705936
05:51:27 3528 8192000000 10300551856 10704269312 1166886720 12.468 100 81 160 0 1388695456 204941360 16867376
05:52:39 2659 8192000000 10298107408 10701664256 1167999232 12.478 100 81 157 0 1401518512 215540752 17978528
05:53:47 1133 8192000000 10309744608 10708828160 1152199024 12.330 100 90 180 3 1411624512 239224288 20201200
05:54:51 8131 8192000000 10327482896 10728476672 1152588032 12.334 100 79 158 1 1432216640 261860256 17768352
05:56:08 2329 8192000000 10369759120 10778574848 1167740496 12.476 100 89 174 2 1474391920 285964320 20687104
05:57:17 2819 8192000000 10387090272 10787471360 1156780880 12.373 100 84 167 0 1487279824 311290368 19208576
05:58:28 3993 8192000000 10462605008 10868297728 1168717184 12.485 99 93 186 0 1555938032 364986064 22234784
05:59:39 2430 8192000000 10471265648 10870984704 1169892096 12.496 100 92 182 1 1564812672 372803776 22116800
06:00:49 776 8192000000 10515783072 10927677440 1184241760 12.630 100 89 178 2 1612279856 405985312 22052784
06:01:54 1897 8192000000 10504502064 10909286400 1177609904 12.568 100 87 174 1 1594748800 396149952 20988944
06:03:00 4715 8192000000 10474084608 10938654720 1178779840 12.579 100 40 80 0 1577408368 387978912 10649616
06:04:15 4409 8192000000 10515872512 10953773056 1169941904 12.496 100 39 78 0 1612075568 431435712 10697952
06:05:38 17997 8192000000 10369339216 10873401344 1129067024 12.113 99 26 52 0 1468399392 331828576 7503792
06:07:31 20717 8192000000 10108996736 10659627008 1118765168 12.015 100 16 32 0 1291432576 168855360 3812048
06:08:58 42491 8192000000 10091296672 10540412928 1118297088 12.011 100 23 45 0 1185567504 62823904 4446512
06:10:45 99335 8192000000 10065202944 10519355392 1113030480 11.961 99 30 60 2 1163490768 43715744 6744544
06:13:30 10773 8192000000 10065065136 10501206016 1128320480 12.106 100 35 70 0 1158656912 23442256 6894176
06:14:42 26258 8192000000 10128166384 10571788288 1136652560 12.184 100 49 98 0 1222083296 74403536 11027200
06:16:14 19500 8192000000 10058262544 10491256832 1113182592 11.963 99 37 74 3 1146808208 26028672 7596944
06:17:37 58767 8192000000 10059771392 10507468800 1107790752 11.911 98 20 39 3 1151618320 39359920 4467648
06:19:37 132760 8192000000 10081558336 10533539840 1100247152 11.840 97 15 30 0 1200431888 96923424 3261312
06:23:03 47853 8192000000 10062891680 10536075264 1129253056 12.114 100 17 34 0 1169343024 36522608 3567360
06:24:51 57065 8192000000 10146003872 10605277184 1133674224 12.156 99 36 72 0 1208003408 66518752 7810432
06:27:07 33722 8192000000 10125958352 10573680640 1147407392 12.285 100 12 24 0 1218982784 69289712 2285680
06:28:51 74450 8192000000 10102148896 10595033088 1139762240 12.213 100 16 32 1 1208401680 65243664 3395776
06:31:26 45861 8192000000 10176288000 10639650816 1123570640 12.061 98 29 56 1 1251018400 120526464 6921296
06:33:19 44420 8192000000 10074131392 10536083456 1131624720 12.137 99 17 34 0 1163457440 28161856 3670864
06:35:06 33445 8192000000 10102901776 10532065280 1135372672 12.172 99 35 69 1 1187801200 45598944 6829584
06:36:40 50538 8192000000 10065596976 10531123200 1128720656 12.109 99 18 36 0 1200044656 67785024 3538976
06:38:31 132756 8192000000 10090644240 10560114688 1111314480 11.945 98 24 48 0 1179868144 63405968 5147696
06:41:46 51073 8192000000 10094585888 10595577856 1117414608 12.003 99 5 10 0 1183419664 64987264 1017792
06:43:37 69308 8192000000 10036329616 10520399872 1091060976 11.753 97 9 18 0 1156104176 63030720 2012480
06:45:47 70093 8192000000 10093202192 10564624384 1125655504 12.080 100 12 24 1 1195930528 67845616 2429408
06:47:58 48188 8192000000 10083529184 10546577408 1103191056 11.868 97 24 48 0 1155302000 46517888 5593056
06:49:49 46786 8192000000 10097637792 10537336832 1092305936 11.765 96 22 44 3 1154566736 57536640 4724160
06:51:57 69611 8192000000 10080370288 10552008704 1114238080 11.973 99 27 54 5 1192296560 72446864 5611616
06:54:09 11334 8192000000 10050123808 10469892096 1079909120 11.647 97 50 99 2 1113219616 23412256 9898240
06:55:21 52715 8192000000 10093459408 10556411904 1082342560 11.670 97 43 86 1 1158549552 67684048 8522944
06:57:14 82049 8192000000 10103920608 10615312384 1074684032 11.597 96 10 20 0 1194189744 117265072 2240640
06:59:37 46540 8192000000 10052683104 10535960576 1105462368 11.889 99 9 18 0 1151253968 43961808 1829792
07:01:25 53063 8192000000 10041088928 10489032704 1103383792 11.870 99 10 20 1 1122928640 17837984 1706864
07:03:18 56737 8192000000 10069148880 10502008832 1099325152 11.831 97 9 16 3 1128370032 27337264 1707616
07:05:20 24733 8192000000 10073489728 10501816320 1121008096 12.037 99 31 60 0 1153630704 26327008 6295600
07:06:50 51168 8192000000 10068898928 10507456512 1093110832 11.772 96 9 18 1 1116489664 21659616 1719216
07:08:49 68404 8192000000 10092811568 10535317504 1107805520 11.912 97 21 42 0 1143470048 31319328 4345200
07:10:57 94772 8192000000 10144037584 10601185280 1124263456 12.067 99 15 30 0 1182304608 54879488 3161664
07:13:32 73044 8192000000 10083608432 10564374528 1070294976 11.555 95 13 26 0 1153616032 80322128 2998928
07:16:19 80479 8192000000 10105606288 10591084544 1104392080 11.879 98 13 26 2 1163755008 56428336 2934592
07:18:42 99277 8192000000 10076558784 10563899392 1125871616 12.082 100 18 36 0 1167360272 36715456 4773200
07:21:30 33013 8192000000 10064584192 10532954112 1133984992 12.159 100 11 22 0 1174205584 37947760 2272832
07:23:15 40924 8192000000 10090671040 10549477376 1140076592 12.216 100 9 19 0 1197958816 56025984 1856240
07:24:56 66591 8192000000 10061784496 10531090432 1144937408 12.262 100 10 20 0 1179896960 33005296 1954256
07:27:03 72274 8192000000 10063356544 10542571520 1105372720 11.889 96 12 24 0 1139557712 32069744 2115248
07:29:15 42932 8192000000 10095062928 10543308800 1144269600 12.256 100 21 41 2 1194390400 45593680 4527120
07:30:59 54493 8192000000 10116743360 10560126976 1140560544 12.221 100 24 48 0 1206870528 61035248 5274736
07:32:58 57792 8192000000 10143257104 10601017344 1116555488 11.994 97 21 42 1 1198669952 76881904 5232560
07:34:57 43057 8192000000 10100534240 10554802176 1139350752 12.209 99 31 62 0 1175129136 29299664 6478720
07:36:48 60216 8192000000 10128633648 10601869312 1128103888 12.103 98 24 46 1 1185431056 52429744 4897424
07:38:52 24297 8192000000 9093275904 9360949248 239987200 2.846 21 8 13 1 261068320 19625888 1455232

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 27-03-2021 07:40:37 to 27-03-2021 07:40:53.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 97 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:01, from 27-03-2021 07:40:54 to 27-03-2021 07:40:55.

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          232934
MON$OLDEST_ACTIVE               232935
MON$OLDEST_SNAPSHOT             232935
MON$NEXT_TRANSACTION            232935
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                       2105792
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             790
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2392 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2392 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2392 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.2392 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    Sat Mar 27 07:39:21 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'

27.03.21 07:40:57. 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-03-27 07:40:59 No severe PSQL-related problems occured
27.03.21 07:41:00. End of report.