Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.11.2021 01: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 2a7b53d1c5ed44b9bb2712fffac5d8c6
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-11-11 18:28
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.33
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 16525920 kB
MemAvailable 31513356 kB
Buffers 0 kB
Cached 15122540 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 3.0.8
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-11-30 01:37:56
DTS_END                         2021-11-30 01:37:56.3200
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(57:17)
IP                              ::1/45860
TRN_ID                          244039
ATT_ID                          691
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: 9658.63 23119 1159036
customer order: creation 174.95 344 20994
customer order: refuse 15.56 314 1867
order to supplier: creation 47.18 1261 5661
order to supplier: removal 3.46 101357 415
invoice (draft): creation 48.46 3360 5815
invoice (draft): removal 55.25 826 6630
invoice accept: apply 42.68 16436 5121
invoice accept: cancel 50.18 795 6021
customer reserve: creation 3984.38 162 478125
customer reserve: removal 4926.81 55 591217
realization accept: apply 85.60 178 10272
realization accept: cancel 92.26 101 11071
payment from customer: creation 60.70 113 7284
payment from customer: removal 6.06 72 727
payment to supplier: creation 49.74 127 5969
payment to supplier: removal 3.60 74 432
service: total inventory turnovers 4.94 1205 593
service: total monetary turnovers 5.98 42 717
service: aggregate perf. data 0.84 23302 101
service: refresh index statistics 0.03 312250 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 30-11-2021 01:39:07 to 30-11-2021 01:39:10.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2632
TEST_TIME 2 4219
TEST_TIME 3 4000
TEST_TIME 4 3894
TEST_TIME 5 3850
TEST_TIME 6 4218
TEST_TIME 7 4646
TEST_TIME 8 5002
TEST_TIME 9 5268
TEST_TIME 10 5569
TEST_TIME 11 5659
TEST_TIME 12 5911
TEST_TIME 13 6024
TEST_TIME 14 6076
TEST_TIME 15 6142
TEST_TIME 16 6322
TEST_TIME 17 6344
TEST_TIME 18 6785
TEST_TIME 19 6832
TEST_TIME 20 7226
TEST_TIME 21 7392
TEST_TIME 22 7444
TEST_TIME 23 8149
TEST_TIME 24 8406
TEST_TIME 25 8924
TEST_TIME 26 9364
TEST_TIME 27 10005
TEST_TIME 28 10556
TEST_TIME 29 10380
TEST_TIME 30 10341
TEST_TIME 31 10160
TEST_TIME 32 10093
TEST_TIME 33 10259
TEST_TIME 34 10276
TEST_TIME 35 10443
TEST_TIME 36 10404
TEST_TIME 37 10405
TEST_TIME 38 10315
TEST_TIME 39 10453
TEST_TIME 40 10444
TEST_TIME 41 10292
TEST_TIME 42 10102
TEST_TIME 43 9996
TEST_TIME 44 10141
TEST_TIME 45 9993
TEST_TIME 46 9983
TEST_TIME 47 9950
TEST_TIME 48 9890
TEST_TIME 49 10016
TEST_TIME 50 9986
TEST_TIME 51 10058
TEST_TIME 52 10053
TEST_TIME 53 10001
TEST_TIME 54 9998
TEST_TIME 55 9853
TEST_TIME 56 9800
TEST_TIME 57 9783
TEST_TIME 58 9704
TEST_TIME 59 9730
TEST_TIME 60 9659
TEST_TIME 61 9606
TEST_TIME 62 9578
TEST_TIME 63 9592
TEST_TIME 64 9569
TEST_TIME 65 9637
TEST_TIME 66 9692
TEST_TIME 67 9714
TEST_TIME 68 9800
TEST_TIME 69 9814
TEST_TIME 70 9769
TEST_TIME 71 9827
TEST_TIME 72 9881
TEST_TIME 73 9932
TEST_TIME 74 9876
TEST_TIME 75 9934
TEST_TIME 76 9976
TEST_TIME 77 9898
TEST_TIME 78 9833
TEST_TIME 79 9834
TEST_TIME 80 9802
TEST_TIME 81 9771
TEST_TIME 82 9756
TEST_TIME 83 9857
TEST_TIME 84 9789
TEST_TIME 85 9788
TEST_TIME 86 9709
TEST_TIME 87 9666
TEST_TIME 88 9710
TEST_TIME 89 9685
TEST_TIME 90 9749
TEST_TIME 91 9721
TEST_TIME 92 9749
TEST_TIME 93 9753
TEST_TIME 94 9806
TEST_TIME 95 9795
TEST_TIME 96 9848
TEST_TIME 97 9808
TEST_TIME 98 9839
TEST_TIME 99 9854
TEST_TIME 100 9848
TEST_TIME 101 9829
TEST_TIME 102 9870
TEST_TIME 103 9856
TEST_TIME 104 9947
TEST_TIME 105 9854
TEST_TIME 106 9791
TEST_TIME 107 9799
TEST_TIME 108 9771
TEST_TIME 109 9725
TEST_TIME 110 9704
TEST_TIME 111 9702
TEST_TIME 112 9712
TEST_TIME 113 9672
TEST_TIME 114 9644
TEST_TIME 115 9573
TEST_TIME 116 9583
TEST_TIME 117 9590
TEST_TIME 118 9651
TEST_TIME 119 9660
TEST_TIME 120 9659

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 30-11-2021 01:39:11 to 30-11-2021 01:39:15.

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 1601472 1601472 0 0.00 0 21893 6 0 0
doc_list_biud 1601472 1601472 0 0.00 0 43579 26 0 0
sp_add_invoice_to_stock 5207 5121 86 1.65 27 161683 16437 0 86
sp_cancel_adding_invoice 6021 6021 0 0.00 5 76181 796 0 0
sp_cancel_client_order 1867 1867 0 0.00 4 7581 314 0 0
sp_cancel_customer_reserve 591217 591217 0 0.00 1 43587 56 0 0
sp_cancel_pay_from_customer 727 727 0 0.00 3 5322 73 0 0
sp_cancel_pay_to_supplier 432 432 0 0.00 3 5630 74 0 0
sp_cancel_supplier_invoice 6640 6630 10 0.15 2 16031 826 0 10
sp_cancel_supplier_order 415 415 0 0.00 5 1065718 101357 0 0
sp_cancel_write_off 11071 11071 0 0.00 1 19617 102 0 0
sp_client_order 20994 20994 0 0.00 3 29617 344 0 0
sp_customer_reserve 478125 478125 0 0.00 0 29642 162 0 0
sp_fill_shopping_cart 32465 32465 0 0.00 1 10064 36 0 0
sp_fill_shopping_cart_clo_res 478121 478121 0 0.00 0 15060 12 0 0
sp_get_clo_for_invoice 5117 5117 0 0.00 0 24478 265 0 0
sp_kill_cost_storno 18251 18251 0 0.00 0 7804 53 0 0
sp_kill_qstorno_ret_qs2qd 1200550 1200550 0 0.00 0 43439 31 0 0
sp_kill_qty_storno 632610 632610 0 0.00 0 43574 66 0 0
sp_lock_selected_doc 21403 21403 0 0.00 0 6759 5 0 0
sp_make_cost_storno 28642 28642 0 0.00 0 14065 49 0 0
sp_multiply_rows_for_pdistr 28642 28642 0 0.00 0 8881 30 0 0
sp_multiply_rows_for_qdistr 20994 20994 0 0.00 0 16819 165 0 0
sp_pay_from_customer 7284 7284 0 0.00 4 12221 114 0 0
sp_pay_to_supplier 5969 5969 0 0.00 5 13506 127 0 0
sp_payment_common 13253 13253 0 0.00 2 12216 28 0 0
sp_qd_handle_on_cancel_clo 1867 1867 0 0.00 0 7573 289 0 0
sp_qd_handle_on_invoice_upd_sts 11138 11138 0 0.00 0 15149 314 0 0
sp_qd_handle_on_reserve_upd_sts 10272 10272 0 0.00 0 18067 56 0 0
sp_reserve_write_off 10272 10272 0 0.00 6 18121 178 0 0
sp_supplier_invoice 5815 5815 0 0.00 6 75947 3360 0 0
sp_supplier_order 5661 5661 0 0.00 5 33888 1261 0 0
srv_aggregate_perf_data 102 101 1 0.98 2438 468873 23303 0 1
srv_fill_mon_cache_memory 64 64 0 0.00 2671 155504 46167 0 0
srv_find_qd_qs_mism 1109837 1109837 0 0.00 0 21892 5 0 0
srv_make_invnt_saldo 593 593 0 0.00 50 99207 1206 0 0
srv_make_money_saldo 717 717 0 0.00 1 4781 42 0 0
srv_recalc_idx_stat 4 4 0 0.00 196026 475449 312250 0 0
t$perf-abend:sp_add_invoice_to_stock 86 86 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 5121 5121 0 0.00 0 13829 209 0 0
t$perf-norm:sp_cancel_adding_invoice 449 449 0 0.00 0 6405 134 0 0
t$perf-norm:sp_cancel_client_order 1867 1867 0 0.00 0 3709 13 0 0
t$perf-norm:sp_cancel_customer_reserve 712 712 0 0.00 0 5776 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 727 727 0 0.00 0 4769 26 0 0
t$perf-norm:sp_cancel_pay_to_supplier 432 432 0 0.00 0 3968 25 0 0
t$perf-norm:sp_cancel_supplier_invoice 393 393 0 0.00 0 2556 31 0 0
t$perf-norm:sp_cancel_supplier_order 415 415 0 0.00 0 27128 1747 0 0
t$perf-norm:sp_cancel_write_off 1019 1019 0 0.00 0 2534 10 0 0
t$perf-norm:sp_client_order 20994 20994 0 0.00 0 11652 23 0 0
t$perf-norm:sp_customer_reserve 1071 1071 0 0.00 0 2104 6 0 0
t$perf-norm:sp_pay_from_customer 7284 7284 0 0.00 0 7028 11 0 0
t$perf-norm:sp_pay_to_supplier 5969 5969 0 0.00 0 10310 15 0 0
t$perf-norm:sp_reserve_write_off 10272 10272 0 0.00 0 8219 13 0 0
t$perf-norm:sp_supplier_invoice 5815 5815 0 0.00 0 5716 25 0 0
t$perf-norm:sp_supplier_order 5661 5661 0 0.00 0 9619 25 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 90 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 671 17 0 0
t$perf-norm:srv_make_invnt_saldo 593 593 0 0.00 0 6380 28 0 0
t$perf-norm:srv_make_money_saldo 717 717 0 0.00 0 2528 22 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 2 2 0 0
v_add_invoice_to_stock 10404 10232 172 1.65 0 6678 18 0 172
v_all_customers 38678 38678 0 0.00 0 20 0 0 0
v_all_suppliers 31684 31684 0 0.00 0 152 1 0 0
v_all_wares 41988 41988 0 0.00 0 98 1 0 0
v_cancel_adding_invoice 898 898 0 0.00 0 2373 9 0 0
v_cancel_client_order 3734 3734 0 0.00 0 3612 4 0 0
v_cancel_customer_prepayment 1454 1454 0 0.00 0 3610 8 0 0
v_cancel_customer_reserve 1424 1424 0 0.00 0 7068 13 0 0
v_cancel_payment_to_supplier 864 864 0 0.00 0 1889 5 0 0
v_cancel_supplier_invoice 806 786 20 2.48 0 875 14 0 20
v_cancel_supplier_order 830 830 0 0.00 0 2249 7 0 0
v_cancel_write_off 2038 2038 0 0.00 0 2263 15 0 0
v_max_id_clo_ord 5659 5659 0 0.00 0 248 1 0 0
v_max_id_clo_res 1071 1071 0 0.00 0 2051 13 0 0
v_max_id_ord_sup 5811 5811 0 0.00 0 8460 7 0 0
v_max_non_paid_invoice 1597 1597 0 0.00 0 2458 4 0 0
v_max_non_paid_realizn 3848 3848 0 0.00 0 2355 5 0 0
v_min_id_clo_ord 5659 5659 0 0.00 0 3643 10 0 0
v_min_id_clo_res 1071 1071 0 0.00 0 743 2 0 0
v_min_id_ord_sup 5811 5811 0 0.00 0 5564 6 0 0
v_min_non_paid_invoice 5969 5969 0 0.00 0 5232 15 0 0
v_min_non_paid_realizn 7284 7284 0 0.00 0 12188 21 0 0
v_our_firm 10182 10182 0 0.00 0 155 1 0 0
v_reserve_write_off 20544 20544 0 0.00 0 8943 10 0 0
x_lock_depdocs_on_canc_invoice 12258 12258 0 0.00 0 26171 99 0 0
x_lock_depdocs_on_canc_sup_ord 415 415 0 0.00 0 4300 340 0 0
x_make_qstorno_1000_1200 5661 5661 0 0.00 1 33517 1185 0 0
x_make_qstorno_1200_2000 5815 5815 0 0.00 3 75939 3238 0 0
x_make_qstorno_2100_3300 478125 478125 0 0.00 0 29641 147 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-11-2021 01:39:16 to 30-11-2021 01:39:20.

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
23:08:00 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:01 34854 8192000000 9926651008 10245922816 1000996704 10.888 100 87 174 0 1030422064 6700656 22724704
23:10:40 16114 8192000000 9973378976 10302275584 1047165696 11.333 100 85 170 0 1077808256 9213968 21428592
23:12:00 29984 8192000000 10005906752 10344136704 1082519136 11.671 100 85 170 0 1113727808 10674816 20533856
23:13:38 12160 8192000000 10043138800 10391580672 1127833664 12.101 100 85 170 0 1160530368 12209632 20487072
23:15:04 7704 8192000000 10088354368 10450882560 1173756272 12.532 100 79 159 0 1203955712 12206128 17993312
23:16:14 4864 8192000000 10127953600 10501763072 1217566000 12.939 100 73 147 1 1246494224 11888784 17039440
23:17:22 2610 8192000000 10170958480 10551963648 1264321456 13.370 100 83 166 0 1295505824 12563296 18621072
23:18:25 2411 8192000000 10203585024 10588303360 1290695216 13.611 100 88 175 2 1323269808 13172816 19401776
23:19:30 1875 8192000000 10220744992 10606059520 1303093072 13.723 100 94 189 0 1336622992 11458672 22071248
23:20:33 19452 8192000000 10200130192 10605002752 1307012752 13.759 100 30 60 1 1319991200 5575808 7402640
23:21:53 13416 8192000000 10199321632 10614214656 1315023456 13.832 100 13 26 1 1323913984 5677744 3212784
23:23:06 8926 8192000000 10201988944 10621849600 1320199264 13.879 100 18 35 1 1329990832 5757520 4034048
23:24:18 10850 8192000000 10204155456 10623344640 1318088208 13.859 99 23 46 0 1333439264 10600496 4750560
23:26:18 7485 8192000000 10228073440 10645258240 1329614624 13.964 100 30 60 0 1343143184 5496320 8032240
23:27:29 8294 8192000000 10221817152 10643628032 1324040640 13.913 99 21 41 1 1334778224 5809840 4927744
23:28:39 6789 8192000000 10192560688 10607173632 1292658144 13.628 99 23 46 0 1303818144 6031024 5128976
23:29:47 55683 8192000000 10170846912 10584076288 1269222160 13.414 98 49 97 1 1286824720 5968256 11634304
23:31:44 6614 8192000000 10104275088 10489225216 1139268272 12.209 92 22 44 1 1149124640 4917248 4939120
23:32:55 9741 8192000000 10163143424 10567098368 1259645584 13.327 99 28 57 2 1275732992 9594016 6493392
23:34:09 32497 8192000000 10165299408 10578006016 1255391600 13.288 98 17 34 1 1264504480 5470752 3642128
23:35:47 13745 8192000000 10181391792 10598739968 1294146640 13.642 100 13 26 0 1302100608 5053552 2900416
23:37:02 7432 8192000000 10202986416 10616541184 1308226048 13.770 100 31 61 1 1321898816 6287936 7384832
23:38:11 23187 8192000000 10228751936 10637164544 1324597808 13.918 100 37 74 0 1339550128 6867040 8085280
23:39:46 64221 8192000000 10259740368 10679853056 1355437808 14.196 100 65 133 0 1382747696 11882512 15427376
23:41:57 22165 8192000000 10286152032 10708660224 1371182752 14.338 100 36 72 0 1396570304 16700160 8687392
23:43:26 10502 8192000000 10293865168 10716028928 1381620240 14.431 100 42 82 0 1404917680 13862272 9435168
23:44:39 17174 8192000000 10315954416 10734120960 1381580560 14.431 99 73 143 2 1420551200 22124480 16846160
23:46:00 10599 8192000000 10332158576 10758307840 1404857376 14.638 100 52 99 1 1440559008 23828848 11872784
23:47:17 17401 8192000000 10351365232 10773471232 1417922080 14.754 100 57 114 0 1457268160 25801984 13544096
23:48:40 8860 8192000000 10356210896 10780336128 1420656240 14.779 100 56 111 2 1461754544 26782160 14316144
23:49:57 3548 8192000000 10366998016 10790682624 1425414064 14.821 100 61 120 2 1472849584 32738048 14697472
23:51:04 6003 8192000000 10368781728 10794811392 1431378192 14.873 100 62 122 0 1481641952 34842784 15420976
23:52:15 11709 8192000000 10362769008 10781773824 1407027168 14.658 99 58 116 1 1461053728 39094352 14932208
23:53:35 5793 8192000000 10375461712 10789814272 1414031216 14.720 100 71 139 1 1477276736 45367760 17877760
23:54:52 2668 8192000000 10391338304 10804674560 1428967904 14.852 100 86 173 1 1497664400 47110864 21585632
23:56:05 3901 8192000000 10374389552 10784591872 1406982240 14.657 100 81 161 1 1478233376 50691056 20560080
23:57:12 4830 8192000000 10394128512 10799251456 1413640944 14.716 100 89 178 2 1493489328 57172304 22676080
23:58:21 14317 8192000000 10350703040 10779906048 1395913648 14.559 100 52 104 1 1465487088 55267584 14305856
23:59:39 17280 8192000000 10343651600 10772717568 1368257952 14.311 99 33 66 3 1444291424 66532352 9501120
00:01:23 11242 8192000000 10338156112 10762067968 1358986160 14.228 99 49 97 3 1430947088 57665456 14295472
00:02:35 26445 8192000000 10309964384 10750246912 1340706160 14.064 98 25 50 2 1401964480 54258976 6999344
00:04:04 12359 8192000000 10311993776 10743828480 1352882672 14.173 99 33 66 3 1402419376 40990640 8546064
00:05:19 83985 8192000000 10266435616 10718752768 1327989280 13.949 97 7 14 0 1343458016 13908304 1560432
00:07:43 36037 8192000000 10275377168 10702143488 1313025664 13.814 96 40 81 2 1328764576 6945152 8793760
00:09:20 73785 8192000000 10272355760 10708844544 1305329024 13.744 95 15 30 2 1322277984 13997696 2951264
00:11:51 155496 8192000000 10282794032 10738909184 1350839856 14.155 99 6 12 0 1371658176 19968496 849824
00:15:27 70467 8192000000 10311041808 10772176896 1373709136 14.360 99 13 26 0 1388320112 11796736 2814240
00:17:49 78438 8192000000 10300518944 10750996480 1387637776 14.485 100 20 40 1 1402325936 9884640 4803520
00:20:08 60395 8192000000 10322864752 10766147584 1382660800 14.440 99 40 79 1 1412151760 20027472 9463488
00:22:16 28336 8192000000 10296704832 10749169664 1381166176 14.427 100 25 49 1 1402508384 16093008 5249200
00:23:48 70424 8192000000 10301254608 10754457600 1384815504 14.460 99 25 48 3 1406230560 15529632 5885424
00:26:00 56014 8192000000 10322737712 10774216704 1390420624 14.510 99 35 70 2 1418222160 19714784 8086752
00:28:07 31323 8192000000 10294317072 10765148160 1373423888 14.358 98 8 16 0 1396871376 21200544 2246944
00:29:39 52235 8192000000 10291714112 10761707520 1372039424 14.345 98 5 10 0 1380837344 7631936 1165984
00:31:31 55700 8192000000 10291710032 10755842048 1392442080 14.528 100 13 26 0 1402573264 7041936 3089248
00:33:28 68694 8192000000 10273946944 10743414784 1341726640 14.073 96 18 36 0 1353930304 8653536 3550128
00:35:51 46607 8192000000 10272422688 10737893376 1360124320 14.238 98 28 56 0 1375095536 9015520 5955696
00:37:41 109022 8192000000 10261033760 10736459776 1318721472 13.865 95 15 28 1 1330760128 8709840 3328816
00:40:30 65872 8192000000 10265122672 10722181120 1328650800 13.955 96 7 14 0 1341840384 11776608 1412976
00:42:38 74927 8192000000 10248780960 10706067456 1246366192 13.205 91 13 26 0 1260331120 11340784 2624144
00:44:53 20738 8192000000 10282125648 10730184704 1357705536 14.217 99 25 48 1 1375305440 12286512 5313392
00:46:19 29049 8192000000 10306234064 10743795712 1354065408 14.184 98 25 49 1 1369722816 9755808 5901600
00:47:50 24794 8192000000 10297219888 10736144384 1354613728 14.189 98 38 76 0 1377399056 14311136 8474192
00:49:18 69478 8192000000 10269844704 10717310976 1266735072 13.392 92 11 23 0 1281659936 12459056 2465808
00:51:28 36790 8192000000 10290875632 10727006208 1338679520 14.046 97 39 78 2 1360595584 13040528 8875536
00:53:12 85026 8192000000 10274793184 10736431104 1347680048 14.127 98 8 15 1 1362971808 13513264 1778496
00:55:45 45184 8192000000 10275777248 10725883904 1354956448 14.192 99 17 32 2 1365631216 7338720 3336048
00:57:31 40341 8192000000 10279309264 10721984512 1361371472 14.250 100 39 78 1 1383169584 13107392 8690720
00:59:12 67197 8192000000 10284491984 10751004672 1360627840 14.243 99 9 16 2 1386979344 24375408 1976096
01:01:20 54882 8192000000 10278088464 10746155008 1376768288 14.388 100 9 18 1 1389735376 10884112 2082976
01:03:16 66469 8192000000 10280522576 10741374976 1360460096 14.241 98 16 32 0 1376896112 12368352 4067664
01:07:16 62310 8192000000 10305678736 10760720384 1392851136 14.531 100 21 42 0 1413974032 17051296 4071600
01:09:24 66132 8192000000 10288388544 10741735424 1334495408 14.008 95 12 24 0 1350404368 13170064 2738896
01:11:36 68877 8192000000 10302242224 10748231680 1356752672 14.208 97 27 55 0 1371834384 8286464 6795248
01:13:45 70949 8192000000 10307541104 10762076160 1365068512 14.283 97 18 36 1 1379949664 11044944 3836208
01:16:10 81366 8192000000 10317684736 10791243776 1389139888 14.498 99 14 28 1 1407322672 14608032 3574752
01:18:34 65977 8192000000 10312148416 10779234304 1401667248 14.610 100 22 44 0 1423692736 17289328 4736160
01:20:40 33398 8192000000 10319242928 10774605824 1404822800 14.638 100 24 48 2 1427993792 17403728 5767264
01:22:22 90912 8192000000 10307458640 10757238784 1381922704 14.434 99 24 48 0 1400032144 12682416 5427024
01:25:05 26480 8192000000 10277015568 10733580288 1341059088 14.067 98 6 12 0 1360358624 18103760 1195776
01:26:32 62661 8192000000 10270481840 10725756928 1359978752 14.237 99 11 22 1 1374299920 11214080 3107088
01:28:35 59437 8192000000 10270697824 10724126720 1349096224 14.139 98 22 44 0 1365052496 10153632 5802640
01:30:49 19344 8192000000 10266752176 10716839936 1356782192 14.208 99 24 48 0 1368990496 6940592 5267712
01:32:09 69233 8192000000 10273532064 10725761024 1348035856 14.130 98 18 36 2 1363697440 11576752 4084832
01:34:21 74715 8192000000 10281223136 10730434560 1336714000 14.028 98 19 38 1 1355836880 14650784 4472096
01:36:38 50918 8192000000 10283112640 10739216384 1347520768 14.125 98 22 44 0 1369183424 16337456 5325200

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 30-11-2021 01:39:21 to 30-11-2021 01:39:36.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 86 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 30-11-2021 01:39:37 to 30-11-2021 01:39:39.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          243263
MON$OLDEST_ACTIVE               244919
MON$OLDEST_SNAPSHOT             244919
MON$NEXT_TRANSACTION            244919
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-03 16:41:55.3470
MON$PAGES                       2048864
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33540 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33540 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33540 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33540 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:577: 
:578: mscs-test-161.budzdorov.ru    Tue Nov 30 01:37:59 2021
:579:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:580: 
:581: 
:582: mscs-test-161.budzdorov.ru    Tue Nov 30 01:38:02 2021
:583:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:584: 
:585: 
:586: mscs-test-161.budzdorov.ru    Tue Nov 30 01:38:03 2021
:587:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:588: 
:589: 
:590: mscs-test-161.budzdorov.ru    Tue Nov 30 01:38:03 2021
:591:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:592: 
--- end of diff output ---

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

30.11.21 01:39:40. There are 3 logs in /var/tmp/logs-oltp30 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-11-30 01:39:43 No severe PSQL-related problems occured
30.11.21 01:39:44. End of report.