Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.03.2021 13:27
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 17068020 kB
MemAvailable 31495532 kB
Buffers 0 kB
Cached 14563752 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-22 13:26:55
DTS_END                         2021-03-22 13:26:55.1510
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/53896
TRN_ID                          223533
ATT_ID                          639
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: 8493.31 21086 1019197
customer order: creation 159.28 474 19114
customer order: refuse 13.25 374 1590
order to supplier: creation 42.10 1493 5052
order to supplier: removal 3.41 120215 409
invoice (draft): creation 42.41 4067 5089
invoice (draft): removal 48.00 1084 5760
invoice accept: apply 38.88 18596 4665
invoice accept: cancel 43.16 885 5179
customer reserve: creation 3548.18 181 425781
customer reserve: removal 4288.55 75 514626
realization accept: apply 76.10 210 9132
realization accept: cancel 71.64 121 8597
payment from customer: creation 55.66 149 6679
payment from customer: removal 5.21 87 625
payment to supplier: creation 44.37 146 5324
payment to supplier: removal 3.29 101 395
service: total inventory turnovers 3.53 4026 424
service: total monetary turnovers 5.53 622 663
service: aggregate perf. data 0.74 18410 89
service: refresh index statistics 0.03 250413 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-03-2021 13:27:30 to 22-03-2021 13:27:32.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2274
TEST_TIME 2 2751
TEST_TIME 3 3402
TEST_TIME 4 3817
TEST_TIME 5 4205
TEST_TIME 6 4396
TEST_TIME 7 4582
TEST_TIME 8 4772
TEST_TIME 9 5010
TEST_TIME 10 5053
TEST_TIME 11 5072
TEST_TIME 12 5163
TEST_TIME 13 5606
TEST_TIME 14 5513
TEST_TIME 15 5601
TEST_TIME 16 5833
TEST_TIME 17 6016
TEST_TIME 18 6327
TEST_TIME 19 6765
TEST_TIME 20 6885
TEST_TIME 21 7031
TEST_TIME 22 7270
TEST_TIME 23 7306
TEST_TIME 24 7595
TEST_TIME 25 8039
TEST_TIME 26 8229
TEST_TIME 27 8547
TEST_TIME 28 9527
TEST_TIME 29 10063
TEST_TIME 30 9847
TEST_TIME 31 9675
TEST_TIME 32 9756
TEST_TIME 33 9542
TEST_TIME 34 9437
TEST_TIME 35 9525
TEST_TIME 36 9597
TEST_TIME 37 9426
TEST_TIME 38 9280
TEST_TIME 39 9228
TEST_TIME 40 9166
TEST_TIME 41 9143
TEST_TIME 42 9091
TEST_TIME 43 8992
TEST_TIME 44 8954
TEST_TIME 45 9009
TEST_TIME 46 8929
TEST_TIME 47 8916
TEST_TIME 48 8830
TEST_TIME 49 8714
TEST_TIME 50 8627
TEST_TIME 51 8551
TEST_TIME 52 8599
TEST_TIME 53 8546
TEST_TIME 54 8731
TEST_TIME 55 8666
TEST_TIME 56 8766
TEST_TIME 57 8865
TEST_TIME 58 8798
TEST_TIME 59 8817
TEST_TIME 60 8758
TEST_TIME 61 8794
TEST_TIME 62 8828
TEST_TIME 63 8910
TEST_TIME 64 8970
TEST_TIME 65 8931
TEST_TIME 66 8834
TEST_TIME 67 8793
TEST_TIME 68 8730
TEST_TIME 69 8730
TEST_TIME 70 8643
TEST_TIME 71 8601
TEST_TIME 72 8551
TEST_TIME 73 8511
TEST_TIME 74 8446
TEST_TIME 75 8433
TEST_TIME 76 8422
TEST_TIME 77 8431
TEST_TIME 78 8444
TEST_TIME 79 8504
TEST_TIME 80 8549
TEST_TIME 81 8605
TEST_TIME 82 8628
TEST_TIME 83 8596
TEST_TIME 84 8595
TEST_TIME 85 8617
TEST_TIME 86 8604
TEST_TIME 87 8523
TEST_TIME 88 8491
TEST_TIME 89 8480
TEST_TIME 90 8436
TEST_TIME 91 8417
TEST_TIME 92 8412
TEST_TIME 93 8387
TEST_TIME 94 8429
TEST_TIME 95 8399
TEST_TIME 96 8414
TEST_TIME 97 8493
TEST_TIME 98 8526
TEST_TIME 99 8509
TEST_TIME 100 8498
TEST_TIME 101 8628
TEST_TIME 102 8684
TEST_TIME 103 8680
TEST_TIME 104 8635
TEST_TIME 105 8581
TEST_TIME 106 8535
TEST_TIME 107 8490
TEST_TIME 108 8442
TEST_TIME 109 8415
TEST_TIME 110 8410
TEST_TIME 111 8395
TEST_TIME 112 8385
TEST_TIME 113 8367
TEST_TIME 114 8362
TEST_TIME 115 8378
TEST_TIME 116 8356
TEST_TIME 117 8396
TEST_TIME 118 8482
TEST_TIME 119 8493
TEST_TIME 120 8494

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 22-03-2021 13:27:34 to 22-03-2021 13:27:37.

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 1412646 1412646 0 0.00 0 28412 7 0 0
doc_list_biud 1412642 1412642 0 0.00 0 76609 35 0 0
sp_add_invoice_to_stock 4734 4665 69 1.45 55 190577 18597 0 69
sp_cancel_adding_invoice 5179 5179 0 0.00 6 63504 886 0 0
sp_cancel_client_order 1590 1590 0 0.00 5 17196 374 0 0
sp_cancel_customer_reserve 514626 514626 0 0.00 1 76610 75 0 0
sp_cancel_pay_from_customer 625 625 0 0.00 3 4971 87 0 0
sp_cancel_pay_to_supplier 395 395 0 0.00 3 6596 101 0 0
sp_cancel_supplier_invoice 5761 5760 1 0.01 3 26702 1085 0 1
sp_cancel_supplier_order 409 409 0 0.00 7 1139736 120215 0 0
sp_cancel_write_off 8597 8597 0 0.00 1 14995 122 0 0
sp_client_order 19114 19114 0 0.00 4 23258 475 0 0
sp_customer_reserve 425781 425781 0 0.00 0 50893 182 0 0
sp_fill_shopping_cart 29246 29246 0 0.00 1 16045 49 0 0
sp_fill_shopping_cart_clo_res 425778 425778 0 0.00 0 23543 21 0 0
sp_get_clo_for_invoice 4662 4662 0 0.00 0 37521 479 0 0
sp_kill_cost_storno 14796 14796 0 0.00 0 14991 65 0 0
sp_kill_qstorno_ret_qs2qd 1044018 1044018 0 0.00 0 76607 41 0 0
sp_kill_qty_storno 549954 549954 0 0.00 0 76609 89 0 0
sp_lock_selected_doc 19014 19014 0 0.00 0 7541 6 0 0
sp_make_cost_storno 25797 25797 0 0.00 0 28409 67 0 0
sp_multiply_rows_for_pdistr 25796 25796 0 0.00 0 12082 36 0 0
sp_multiply_rows_for_qdistr 19114 19114 0 0.00 0 17155 264 0 0
sp_pay_from_customer 6679 6679 0 0.00 4 17993 149 0 0
sp_pay_to_supplier 5324 5324 0 0.00 5 21300 146 0 0
sp_payment_common 12001 12001 0 0.00 1 13102 33 0 0
sp_qd_handle_on_cancel_clo 1590 1590 0 0.00 0 17171 342 0 0
sp_qd_handle_on_invoice_upd_sts 9840 9840 0 0.00 0 38266 436 0 0
sp_qd_handle_on_reserve_upd_sts 9132 9132 0 0.00 0 14492 59 0 0
sp_reserve_write_off 9132 9132 0 0.00 7 17826 210 0 0
sp_supplier_invoice 5089 5089 0 0.00 6 111731 4067 0 0
sp_supplier_order 5052 5052 0 0.00 5 28644 1493 0 0
srv_aggregate_perf_data 90 89 1 1.11 2680 152542 18411 0 1
srv_fill_mon_cache_memory 63 63 0 0.00 1382 146865 46916 0 0
srv_find_qd_qs_mism 974793 974793 0 0.00 0 15351 5 0 0
srv_make_invnt_saldo 424 424 0 0.00 61 118561 4026 0 0
srv_make_money_saldo 663 663 0 0.00 1 18014 622 0 0
srv_recalc_idx_stat 4 4 0 0.00 60635 452822 250414 0 0
t$perf-abend:sp_add_invoice_to_stock 69 69 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 1 1 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 4665 4665 0 0.00 0 21343 193 0 0
t$perf-norm:sp_cancel_adding_invoice 366 366 0 0.00 1 2978 98 0 0
t$perf-norm:sp_cancel_client_order 1590 1590 0 0.00 0 875 8 0 0
t$perf-norm:sp_cancel_customer_reserve 618 618 0 0.00 0 1632 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 625 625 0 0.00 0 5430 26 0 0
t$perf-norm:sp_cancel_pay_to_supplier 395 395 0 0.00 0 7424 27 0 0
t$perf-norm:sp_cancel_supplier_invoice 352 352 0 0.00 0 3844 30 0 0
t$perf-norm:sp_cancel_supplier_order 409 409 0 0.00 0 20440 1519 0 0
t$perf-norm:sp_cancel_write_off 867 867 0 0.00 0 1808 7 0 0
t$perf-norm:sp_client_order 19114 19114 0 0.00 0 16201 22 0 0
t$perf-norm:sp_customer_reserve 882 882 0 0.00 0 1708 5 0 0
t$perf-norm:sp_pay_from_customer 6679 6679 0 0.00 0 31129 18 0 0
t$perf-norm:sp_pay_to_supplier 5324 5324 0 0.00 0 10791 15 0 0
t$perf-norm:sp_reserve_write_off 9132 9132 0 0.00 0 9284 12 0 0
t$perf-norm:sp_supplier_invoice 5089 5089 0 0.00 0 7707 22 0 0
t$perf-norm:sp_supplier_order 5052 5052 0 0.00 0 2883 18 0 0
t$perf-norm:srv_aggregate_perf_data 89 89 0 0.00 0 692 25 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 6 1 0 0
t$perf-norm:srv_make_invnt_saldo 424 424 0 0.00 0 746 8 0 0
t$perf-norm:srv_make_money_saldo 663 663 0 0.00 0 867 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 57 17 0 0
v_add_invoice_to_stock 9458 9320 138 1.45 0 5958 20 0 138
v_all_customers 35112 35112 0 0.00 0 20 1 0 0
v_all_suppliers 27860 27860 0 0.00 0 132 1 0 0
v_all_wares 38228 38228 0 0.00 0 41 1 0 0
v_cancel_adding_invoice 732 732 0 0.00 0 5755 29 0 0
v_cancel_client_order 3180 3180 0 0.00 0 3375 5 0 0
v_cancel_customer_prepayment 1250 1250 0 0.00 0 2092 8 0 0
v_cancel_customer_reserve 1236 1236 0 0.00 0 5576 9 0 0
v_cancel_payment_to_supplier 790 790 0 0.00 0 2292 11 0 0
v_cancel_supplier_invoice 706 704 2 0.28 0 2320 16 0 2
v_cancel_supplier_order 818 818 0 0.00 0 4545 18 0 0
v_cancel_write_off 1734 1734 0 0.00 0 3689 17 0 0
v_max_id_clo_ord 5052 5052 0 0.00 0 1896 2 0 0
v_max_id_clo_res 882 882 0 0.00 0 4122 17 0 0
v_max_id_ord_sup 5080 5080 0 0.00 0 7230 8 0 0
v_max_non_paid_invoice 1525 1525 0 0.00 0 752 3 0 0
v_max_non_paid_realizn 3436 3436 0 0.00 0 1064 4 0 0
v_min_id_clo_ord 5052 5052 0 0.00 0 12531 9 0 0
v_min_id_clo_res 882 882 0 0.00 0 9320 18 0 0
v_min_id_ord_sup 5080 5080 0 0.00 0 6491 8 0 0
v_min_non_paid_invoice 5323 5323 0 0.00 0 7500 14 0 0
v_min_non_paid_realizn 6678 6678 0 0.00 0 12345 31 0 0
v_our_firm 9600 9600 0 0.00 0 14 1 0 0
v_reserve_write_off 18264 18264 0 0.00 0 5904 9 0 0
x_lock_depdocs_on_canc_invoice 10587 10587 0 0.00 0 20238 114 0 0
x_lock_depdocs_on_canc_sup_ord 409 409 0 0.00 0 7446 349 0 0
x_make_qstorno_1000_1200 5052 5052 0 0.00 2 28638 1404 0 0
x_make_qstorno_1200_2000 5089 5089 0 0.00 2 108615 3878 0 0
x_make_qstorno_2100_3300 425780 425780 0 0.00 0 50892 159 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-03-2021 13:27:38 to 22-03-2021 13:27:42.

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
10:56:58 2 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
10:57:59 24925 8192000000 9729894160 10027151360 804273520 8.940 100 88 176 0 833285360 7505376 21506464
10:59:32 20272 8192000000 9767665184 10073419776 841459216 9.314 100 94 187 1 876157776 12401520 22297040
11:01:11 25309 8192000000 9820919776 10138980352 870528704 9.605 100 88 177 1 917156144 26208944 20418496
11:02:47 12835 8192000000 9875268864 10208067584 924187824 10.137 100 81 162 2 991205904 49526080 17492000
11:04:02 7178 8192000000 9927326096 10275487744 964744592 10.535 100 75 150 2 1043204432 62528544 15931296
11:05:13 3321 8192000000 9951346240 10303234048 1001170448 10.890 100 90 181 0 1068755312 49489392 18095472
11:06:18 3839 8192000000 10006590832 10368319488 1023949696 11.110 100 80 159 0 1119883216 79474176 16459344
11:07:24 2124 8192000000 10044488704 10409779200 1052006192 11.380 100 97 197 1 1152003248 79902656 20094400
11:08:27 4524 8192000000 10019325648 10401472512 1068319888 11.536 100 63 126 0 1145526928 64003472 13203568
11:09:35 8119 8192000000 9992355584 10386939904 1071819280 11.569 100 44 86 2 1111503360 31175536 8508544
11:10:45 3039 8192000000 9998078608 10382307328 1080279600 11.650 100 43 85 0 1108597680 19763632 8554448
11:11:48 8184 8192000000 9989336032 10399580160 1088417344 11.728 100 22 44 0 1115092352 21812784 4862224
11:12:57 58982 8192000000 9999257808 10394939392 1091853040 11.760 100 26 52 2 1113079760 15990896 5235824
11:14:57 4778 8192000000 10021141056 10422767616 1096729872 11.807 100 50 99 3 1131801792 25309552 9762368
11:16:03 6018 8192000000 10024951632 10430222336 1093298720 11.774 100 45 90 0 1129964400 26610608 10055072
11:17:09 5676 8192000000 10005145120 10417811456 1088009216 11.724 100 16 32 1 1110019040 18545968 3463856
11:18:16 39787 8192000000 9988988544 10397286400 1042560416 11.289 96 18 34 0 1069116464 23052304 3503744
11:20:01 10029 8192000000 9971404448 10364837888 1045673040 11.319 99 37 74 5 1071313664 17095184 8545440
11:21:13 6045 8192000000 9945553968 10332221440 998862752 10.867 98 39 78 2 1035673264 28317872 8492640
11:22:20 69046 8192000000 9960584016 10357329920 1006256912 10.939 98 39 78 0 1029480080 14663504 8559664
11:24:37 5693 8192000000 9980281264 10372526080 1054077360 11.400 100 57 113 1 1085795888 19202688 12515840
11:25:45 10126 8192000000 9987859472 10382622720 1069339040 11.546 100 30 60 0 1101808064 26047488 6421536
11:26:55 11977 8192000000 10024146592 10415230976 1096634880 11.806 100 62 122 3 1120522784 10356352 13531552
11:28:16 34423 8192000000 10034645872 10438463488 1109701968 11.930 100 39 78 1 1144068960 25894432 8472560
11:30:00 10517 8192000000 10088968704 10494664704 1128005568 12.103 100 51 102 1 1204398112 66159280 10233264
11:31:13 26186 8192000000 10112710208 10528018432 1135506800 12.173 100 42 84 2 1221396512 76597280 9292432
11:32:53 16501 8192000000 10149748112 10557079552 1152038336 12.329 100 52 102 0 1251326544 88074896 11213312
11:34:20 2538 8192000000 10187500368 10594041856 1168723248 12.485 100 66 127 2 1291085424 108146656 14215520
11:35:27 15019 8192000000 10228224096 10650419200 1176703712 12.559 100 38 75 1 1339420688 153829968 8887008
11:36:46 14955 8192000000 10237639488 10664710144 1190301600 12.686 100 53 106 2 1362023680 158916048 12806032
11:38:06 7846 8192000000 10266349120 10686046208 1181121936 12.601 100 64 129 0 1381886928 185344560 15420432
11:39:20 5748 8192000000 10306210176 10735779840 1174346576 12.537 99 66 131 1 1397449152 207703808 15398768
11:40:35 7305 8192000000 10290837472 10705002496 1181674352 12.606 100 52 104 1 1395961536 202150800 12136384
11:41:49 2231 8192000000 10336879696 10739499008 1173134800 12.526 99 80 156 3 1432290880 241235360 17920720
11:42:56 4009 8192000000 10391149776 10798297088 1176799072 12.560 100 85 170 1 1487399056 292026176 18573808
11:44:06 5284 8192000000 10367188064 10792919040 1173009824 12.525 100 69 138 1 1470251808 281270752 15971232
11:45:16 2535 8192000000 10418835040 10827726848 1161666240 12.419 100 78 154 1 1524136560 344595424 17874896
11:46:28 7277 8192000000 10372551184 10788298752 1165421712 12.454 100 54 107 0 1477829472 299831696 12576064
11:47:40 1379 8192000000 10441281488 10842411008 1164705824 12.447 100 79 158 0 1531048912 347886016 18457072
11:48:46 8886 8192000000 10410098304 10824310784 1146090608 12.273 99 45 89 0 1500982128 343922528 10968992
11:49:59 3847 8192000000 10439891264 10854330368 1137500832 12.192 100 58 114 0 1538640256 386650320 14489104
11:51:06 10116 8192000000 10395768704 10849095680 1139105792 12.207 99 39 78 0 1498221168 348927504 10187872
11:52:29 17703 8192000000 10393136368 10880385024 1134568544 12.164 100 29 58 0 1502057952 359501760 7987648
11:53:56 14534 8192000000 10296911616 10751119360 1118214096 12.010 100 28 55 3 1414490912 288954032 7322784
11:55:12 19158 8192000000 10109693776 10588278784 1113477328 11.965 99 25 49 2 1249960640 130669840 5813472
11:56:32 35964 8192000000 10088789152 10520064000 1123119136 12.056 99 40 79 1 1172990400 40910336 8960928
11:58:14 77502 8192000000 10135176992 10591088640 1145743584 12.270 100 25 51 2 1235152224 83970496 5438144
12:00:40 46773 8192000000 10147675008 10608250880 1144766000 12.260 100 26 52 1 1260652752 109745872 6140880
12:02:32 56934 8192000000 10083752992 10523586560 1130142608 12.123 99 12 24 0 1173410400 40985696 2282096
12:04:36 96764 8192000000 10126479152 10608525312 1123867280 12.064 98 23 45 1 1225421680 95862032 5692368
12:07:29 64826 8192000000 10075404352 10532847616 1132512864 12.145 99 25 50 0 1207097536 68631696 5952976
12:09:36 41396 8192000000 10142435344 10579996672 1145208640 12.265 100 38 76 1 1238819968 85561744 8049584
12:11:31 89091 8192000000 10089736416 10576850944 1145660928 12.269 100 13 26 0 1209871680 61300400 2910352
12:14:03 75539 8192000000 10091221360 10542587904 1131401968 12.135 99 12 23 0 1177592112 43786240 2403904
12:16:19 72549 8192000000 10121579376 10578350080 1130716896 12.128 99 15 30 0 1212882464 78999760 3165808
12:18:33 64254 8192000000 10148363152 10633281536 1134261712 12.162 99 9 18 0 1221602528 85220080 2120736
12:21:01 52633 8192000000 10127629520 10578219008 1145634720 12.269 100 15 30 0 1229440848 80717472 3088656
12:22:55 70445 8192000000 10054136928 10508570624 1119680688 12.024 98 13 26 0 1167963104 45803744 2478672
12:25:12 58513 8192000000 10060919840 10510196736 1111888592 11.950 97 22 44 4 1153073504 36825392 4359520
12:27:21 86236 8192000000 10100458176 10544070656 1091179776 11.754 95 14 28 2 1120235536 26596672 2459088
12:29:51 102695 8192000000 10078986224 10561630208 1118788848 12.016 98 12 24 0 1173329904 52519440 2021616
12:32:36 130459 8192000000 10074811440 10565922816 1123120416 12.056 99 26 52 0 1191968960 63171664 5676880
12:36:27 33976 8192000000 10038069504 10507943936 1118441392 12.012 99 4 8 0 1146069808 26710288 918128
12:38:13 89508 8192000000 10023863488 10479849472 1054501408 11.404 94 22 44 0 1095285472 36283808 4500256
12:40:44 31249 8192000000 10073358208 10512134144 1127054016 12.094 100 13 26 0 1154979328 25171232 2754080
12:42:18 44543 8192000000 10093648976 10530422784 1099629664 11.834 97 26 51 0 1161661664 56699872 5332128
12:44:03 39501 8192000000 10116471040 10584121344 1112967056 11.960 99 27 53 2 1200202448 81482944 5752448
12:45:54 54923 8192000000 10120454144 10570883072 1126447040 12.088 100 26 53 3 1218533648 86215584 5871024
12:48:08 30209 8192000000 10060758992 10517245952 1126556480 12.089 100 16 32 1 1182314976 52442448 3316048
12:49:40 14647 8192000000 10106763712 10530873344 1123072992 12.056 100 63 124 0 1191637040 55835072 12728976
12:50:56 47418 8192000000 10094554816 10530840576 1070366992 11.556 96 33 66 1 1132081440 55260832 6453616
12:52:44 102481 8192000000 10035010464 10482233344 1089023200 11.733 99 18 36 0 1133882016 41528976 3329840
12:55:36 72336 8192000000 10028926912 10502930432 1113764032 11.968 100 4 7 1 1136082208 21817712 500464
12:57:49 62287 8192000000 10052534208 10509848576 1109143296 11.924 99 23 46 2 1157678016 43465776 5068944
13:00:10 71465 8192000000 10094952480 10539524096 1111127360 11.943 98 20 40 1 1144416592 29076864 4212368
13:02:22 74352 8192000000 10103948048 10577575936 1112976304 11.961 98 24 48 1 1203107360 84743024 5388032
13:04:41 82895 8192000000 10107548368 10543190016 1119606432 12.023 98 27 54 1 1199762000 74239808 5915760
13:07:04 67201 8192000000 10107742448 10613329920 1142557008 12.240 100 14 28 0 1204423584 59151632 2714944
13:09:12 146865 8192000000 10078813776 10545823744 1099023552 11.828 96 15 30 0 1139257344 37166080 3067712
13:12:39 63429 8192000000 10058922096 10518638592 1131791504 12.138 99 7 14 0 1153236064 20364560 1080000
13:16:01 63445 8192000000 10066435808 10548027392 1134583536 12.165 99 7 14 0 1171788304 35765920 1438848
13:18:06 60352 8192000000 10094820992 10556805120 1114831936 11.978 97 21 42 0 1169384688 49821952 4730800
13:20:18 109802 8192000000 10160749056 10621841408 1121561680 12.042 97 24 48 0 1215741184 89077984 5101520
13:23:19 63694 8192000000 10072251696 10571358208 1116914624 11.998 98 10 20 1 1243698432 124222464 2561344
13:25:34 56575 8192000000 10087108816 10565562368 1121812144 12.044 99 29 58 0 1189319712 61389648 6117920

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 22-03-2021 13:27:44 to 22-03-2021 13:27:59.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 69 v_add_invoice_to_stock 335544517
except 1 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-03-2021 13:28:00 to 22-03-2021 13:28:02.

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          222255
MON$OLDEST_ACTIVE               223891
MON$OLDEST_SNAPSHOT             223891
MON$NEXT_TRANSACTION            223891
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                       2078112
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             777
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2390 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2390 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2390 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.2390 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 ---
:208: 
:209: mscs-test-161.budzdorov.ru    Mon Mar 22 13:26:56 2021
:210:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:211: 
--- end of diff output ---

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

22.03.21 13:28:03. 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-22 13:28:05 No severe PSQL-related problems occured
22.03.21 13:28:06. End of report.