Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.10.2022 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 15623524 kB
MemAvailable 30509324 kB
Buffers 0 kB
Cached 16088884 kB
SwapCached 2168 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.11
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 2022-10-13 01:38:30
DTS_END                         2022-10-13 01:38:30.5040
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/44576
TRN_ID                          241372
ATT_ID                          726
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: 9122.50 20655 1094700
customer order: creation 172.88 374 20746
customer order: refuse 14.64 324 1757
order to supplier: creation 44.78 1301 5374
order to supplier: removal 3.33 108485 399
invoice (draft): creation 45.23 3363 5427
invoice (draft): removal 52.00 779 6240
invoice accept: apply 40.96 16848 4915
invoice accept: cancel 47.23 772 5668
customer reserve: creation 3764.31 167 451717
customer reserve: removal 4649.77 61 557973
realization accept: apply 82.66 173 9919
realization accept: cancel 76.22 103 9146
payment from customer: creation 59.24 102 7109
payment from customer: removal 5.48 92 658
payment to supplier: creation 48.26 142 5791
payment to supplier: removal 3.20 65 384
service: total inventory turnovers 4.64 1302 557
service: total monetary turnovers 6.63 39 796
service: aggregate perf. data 1.00 17965 120
service: refresh index statistics 0.03 260648 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 13-10-2022 01:39:13 to 13-10-2022 01:39:16.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3466
TEST_TIME 2 3999
TEST_TIME 3 3224
TEST_TIME 4 3378
TEST_TIME 5 3006
TEST_TIME 6 3577
TEST_TIME 7 3733
TEST_TIME 8 3936
TEST_TIME 9 4078
TEST_TIME 10 4076
TEST_TIME 11 4625
TEST_TIME 12 4907
TEST_TIME 13 5303
TEST_TIME 14 5392
TEST_TIME 15 5772
TEST_TIME 16 6185
TEST_TIME 17 6316
TEST_TIME 18 6408
TEST_TIME 19 7205
TEST_TIME 20 7401
TEST_TIME 21 7555
TEST_TIME 22 7959
TEST_TIME 23 8239
TEST_TIME 24 9169
TEST_TIME 25 9475
TEST_TIME 26 10117
TEST_TIME 27 10019
TEST_TIME 28 9990
TEST_TIME 29 9892
TEST_TIME 30 9775
TEST_TIME 31 9701
TEST_TIME 32 9678
TEST_TIME 33 9720
TEST_TIME 34 9703
TEST_TIME 35 9637
TEST_TIME 36 9547
TEST_TIME 37 9323
TEST_TIME 38 9273
TEST_TIME 39 9250
TEST_TIME 40 9152
TEST_TIME 41 9029
TEST_TIME 42 8995
TEST_TIME 43 9094
TEST_TIME 44 9099
TEST_TIME 45 9040
TEST_TIME 46 8900
TEST_TIME 47 8783
TEST_TIME 48 8905
TEST_TIME 49 8841
TEST_TIME 50 8876
TEST_TIME 51 8865
TEST_TIME 52 8870
TEST_TIME 53 8811
TEST_TIME 54 8868
TEST_TIME 55 8933
TEST_TIME 56 8897
TEST_TIME 57 8998
TEST_TIME 58 9050
TEST_TIME 59 9029
TEST_TIME 60 9021
TEST_TIME 61 9059
TEST_TIME 62 8986
TEST_TIME 63 9317
TEST_TIME 64 9308
TEST_TIME 65 9198
TEST_TIME 66 9137
TEST_TIME 67 9056
TEST_TIME 68 9072
TEST_TIME 69 9064
TEST_TIME 70 9010
TEST_TIME 71 9010
TEST_TIME 72 8949
TEST_TIME 73 8925
TEST_TIME 74 9038
TEST_TIME 75 8995
TEST_TIME 76 9049
TEST_TIME 77 9064
TEST_TIME 78 9103
TEST_TIME 79 9129
TEST_TIME 80 9183
TEST_TIME 81 9176
TEST_TIME 82 9204
TEST_TIME 83 9200
TEST_TIME 84 9269
TEST_TIME 85 9336
TEST_TIME 86 9329
TEST_TIME 87 9249
TEST_TIME 88 9182
TEST_TIME 89 9161
TEST_TIME 90 9143
TEST_TIME 91 9090
TEST_TIME 92 9078
TEST_TIME 93 9078
TEST_TIME 94 9084
TEST_TIME 95 9062
TEST_TIME 96 9040
TEST_TIME 97 9040
TEST_TIME 98 9115
TEST_TIME 99 9081
TEST_TIME 100 9070
TEST_TIME 101 9123
TEST_TIME 102 9179
TEST_TIME 103 9173
TEST_TIME 104 9174
TEST_TIME 105 9202
TEST_TIME 106 9189
TEST_TIME 107 9117
TEST_TIME 108 9072
TEST_TIME 109 9049
TEST_TIME 110 9048
TEST_TIME 111 9018
TEST_TIME 112 8991
TEST_TIME 113 8968
TEST_TIME 114 8991
TEST_TIME 115 9071
TEST_TIME 116 9035
TEST_TIME 117 9063
TEST_TIME 118 9142
TEST_TIME 119 9158
TEST_TIME 120 9126

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 13-10-2022 01:39:17 to 13-10-2022 01:39:20.

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 1514483 1514483 0 0.00 0 23399 6 0 0
doc_list_biud 1514483 1514483 0 0.00 0 55079 28 0 0
sp_add_invoice_to_stock 5063 4915 148 2.92 32 179210 16848 0 148
sp_cancel_adding_invoice 5668 5668 0 0.00 5 62399 772 0 0
sp_cancel_client_order 1757 1757 0 0.00 4 10614 325 0 0
sp_cancel_customer_reserve 557973 557973 0 0.00 0 55083 62 0 0
sp_cancel_pay_from_customer 658 658 0 0.00 3 6137 93 0 0
sp_cancel_pay_to_supplier 384 384 0 0.00 3 3265 65 0 0
sp_cancel_supplier_invoice 6250 6240 10 0.16 1 24777 780 0 10
sp_cancel_supplier_order 399 399 0 0.00 7 1197895 108486 0 0
sp_cancel_write_off 9146 9146 0 0.00 1 19860 103 0 0
sp_client_order 20746 20746 0 0.00 3 28989 374 0 0
sp_customer_reserve 451717 451717 0 0.00 0 60917 167 0 0
sp_fill_shopping_cart 31541 31541 0 0.00 1 18343 38 0 0
sp_fill_shopping_cart_clo_res 451707 451707 0 0.00 0 20734 15 0 0
sp_get_clo_for_invoice 4905 4905 0 0.00 0 29851 336 0 0
sp_kill_cost_storno 15856 15856 0 0.00 0 14266 57 0 0
sp_kill_qstorno_ret_qs2qd 1131731 1131731 0 0.00 0 55071 33 0 0
sp_kill_qty_storno 596007 596007 0 0.00 0 55079 71 0 0
sp_lock_selected_doc 20406 20406 0 0.00 0 9082 5 0 0
sp_make_cost_storno 27724 27724 0 0.00 0 23396 53 0 0
sp_multiply_rows_for_pdistr 27724 27724 0 0.00 0 13485 28 0 0
sp_multiply_rows_for_qdistr 20746 20746 0 0.00 0 28799 181 0 0
sp_pay_from_customer 7109 7109 0 0.00 4 13503 103 0 0
sp_pay_to_supplier 5791 5791 0 0.00 4 20863 142 0 0
sp_payment_common 12900 12900 0 0.00 1 16917 29 0 0
sp_qd_handle_on_cancel_clo 1757 1757 0 0.00 0 10595 297 0 0
sp_qd_handle_on_invoice_upd_sts 10573 10573 0 0.00 0 14460 295 0 0
sp_qd_handle_on_reserve_upd_sts 9919 9919 0 0.00 0 29021 57 0 0
sp_reserve_write_off 9919 9919 0 0.00 6 29055 173 0 0
sp_supplier_invoice 5427 5427 0 0.00 6 89164 3363 0 0
sp_supplier_order 5374 5374 0 0.00 4 42349 1302 0 0
srv_aggregate_perf_data 121 120 1 0.82 2799 360389 17965 0 1
srv_fill_mon_cache_memory 61 61 0 0.00 1242 159188 47322 0 0
srv_find_qd_qs_mism 1048282 1048282 0 0.00 0 14540 5 0 0
srv_make_invnt_saldo 557 557 0 0.00 27 169133 1303 0 0
srv_make_money_saldo 796 796 0 0.00 1 11238 39 0 0
srv_recalc_idx_stat 4 4 0 0.00 106414 391632 260648 0 0
t$perf-abend:sp_add_invoice_to_stock 148 148 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 4915 4915 0 0.00 0 10176 166 0 0
t$perf-norm:sp_cancel_adding_invoice 406 406 0 0.00 0 4462 89 0 0
t$perf-norm:sp_cancel_client_order 1757 1757 0 0.00 0 2297 10 0 0
t$perf-norm:sp_cancel_customer_reserve 711 711 0 0.00 0 2561 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 658 658 0 0.00 0 8040 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 384 384 0 0.00 0 1109 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 364 364 0 0.00 0 1511 24 0 0
t$perf-norm:sp_cancel_supplier_order 399 399 0 0.00 0 32647 1722 0 0
t$perf-norm:sp_cancel_write_off 903 903 0 0.00 0 4359 12 0 0
t$perf-norm:sp_client_order 20746 20746 0 0.00 0 13818 19 0 0
t$perf-norm:sp_customer_reserve 957 957 0 0.00 0 3327 12 0 0
t$perf-norm:sp_pay_from_customer 7109 7109 0 0.00 0 12789 14 0 0
t$perf-norm:sp_pay_to_supplier 5791 5791 0 0.00 0 9482 11 0 0
t$perf-norm:sp_reserve_write_off 9919 9919 0 0.00 0 13326 16 0 0
t$perf-norm:sp_supplier_invoice 5427 5427 0 0.00 0 7969 22 0 0
t$perf-norm:sp_supplier_order 5374 5374 0 0.00 0 6439 14 0 0
t$perf-norm:srv_aggregate_perf_data 120 120 0 0.00 0 434 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 209 5 0 0
t$perf-norm:srv_make_invnt_saldo 557 557 0 0.00 0 5875 30 0 0
t$perf-norm:srv_make_money_saldo 796 796 0 0.00 0 5782 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10106 9810 296 2.92 0 14163 20 0 296
v_all_customers 37946 37946 0 0.00 0 96 1 0 0
v_all_suppliers 30412 30412 0 0.00 0 97 1 0 0
v_all_wares 41490 41490 0 0.00 0 26 1 0 0
v_cancel_adding_invoice 812 812 0 0.00 0 1339 9 0 0
v_cancel_client_order 3514 3514 0 0.00 0 2781 5 0 0
v_cancel_customer_prepayment 1316 1316 0 0.00 0 893 3 0 0
v_cancel_customer_reserve 1422 1422 0 0.00 0 3799 7 0 0
v_cancel_payment_to_supplier 768 768 0 0.00 0 1864 7 0 0
v_cancel_supplier_invoice 748 728 20 2.67 0 4160 26 0 20
v_cancel_supplier_order 798 798 0 0.00 0 3405 10 0 0
v_cancel_write_off 1806 1806 0 0.00 0 539 11 0 0
v_max_id_clo_ord 5374 5374 0 0.00 0 1146 2 0 0
v_max_id_clo_res 957 957 0 0.00 0 4207 19 0 0
v_max_id_ord_sup 5422 5422 0 0.00 0 4492 3 0 0
v_max_non_paid_invoice 1381 1381 0 0.00 0 2971 10 0 0
v_max_non_paid_realizn 3700 3700 0 0.00 0 724 3 0 0
v_min_id_clo_ord 5374 5374 0 0.00 0 2885 6 0 0
v_min_id_clo_res 957 957 0 0.00 0 5486 12 0 0
v_min_id_ord_sup 5422 5422 0 0.00 0 6960 9 0 0
v_min_non_paid_invoice 5791 5791 0 0.00 0 10959 22 0 0
v_min_non_paid_realizn 7109 7109 0 0.00 0 6030 18 0 0
v_our_firm 10362 10362 0 0.00 0 22 1 0 0
v_reserve_write_off 19838 19838 0 0.00 0 14174 6 0 0
x_lock_depdocs_on_canc_invoice 11544 11544 0 0.00 0 22349 125 0 0
x_lock_depdocs_on_canc_sup_ord 399 399 0 0.00 0 10856 436 0 0
x_make_qstorno_1000_1200 5374 5374 0 0.00 1 42333 1228 0 0
x_make_qstorno_1200_2000 5427 5427 0 0.00 3 82087 3223 0 0
x_make_qstorno_2100_3300 451717 451717 0 0.00 0 60883 149 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-10-2022 01:39:22 to 13-10-2022 01:39:26.

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:34 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:35 31775 8192000000 9896468976 10205585408 975895360 10.644 100 91 182 0 1006987632 6213152 24879120
23:11:19 23613 8192000000 9939240400 10258694144 1020288464 11.075 100 86 172 1 1051244000 8421904 22533632
23:12:46 13677 8192000000 9980031872 10313330688 1062958832 11.485 100 81 162 0 1093274096 9958608 20356656
23:14:03 7293 8192000000 10029492208 10379014144 1120608928 12.033 100 74 148 0 1149094672 10546064 17939680
23:15:15 3873 8192000000 10087310096 10447634432 1178787056 12.579 100 82 163 1 1209440016 12283664 18369296
23:16:23 2702 8192000000 10138278096 10506448896 1222857808 12.988 100 89 176 1 1256379792 13906528 19615456
23:17:27 1703 8192000000 10160184480 10539130880 1251372656 13.251 100 76 152 0 1279866128 12024176 16469296
23:18:30 1272 8192000000 10198124272 10582032384 1283732256 13.547 100 80 157 1 1313550656 12501168 17317232
23:19:32 930 8192000000 10221406832 10606104576 1304132944 13.733 100 93 186 0 1339568080 14555536 20879600
23:20:33 11210 8192000000 10209099504 10610692096 1308922496 13.776 100 48 94 1 1326341376 6394064 11024816
23:21:45 9622 8192000000 10201071248 10617974784 1313406752 13.817 100 17 34 0 1323964128 6750832 3806544
23:22:55 6848 8192000000 10206825168 10629804032 1324554304 13.918 100 24 48 1 1336221536 5706464 5960768
23:24:03 7854 8192000000 10218256448 10638671872 1335231712 14.014 100 21 41 0 1345017888 5215680 4570496
23:25:16 10853 8192000000 10215973184 10629943296 1324096912 13.914 99 22 45 2 1336320224 6143056 6080256
23:26:29 9863 8192000000 10233711008 10646069248 1326787024 13.938 99 37 74 1 1341224112 5040672 9396416
23:27:41 5166 8192000000 10209427968 10631352320 1313480048 13.818 99 28 52 2 1325905376 6249392 6175936
23:28:47 7650 8192000000 10176266336 10590253056 1281016544 13.522 99 24 48 0 1292303424 5900816 5386064
23:29:55 65829 8192000000 10147592384 10551992320 1180267376 12.593 92 36 73 1 1197726336 9415120 8043840
23:32:03 4512 8192000000 10142605552 10539626496 1230056704 13.055 98 25 50 0 1242087776 5484240 6546832
23:33:11 5310 8192000000 10164492624 10567663616 1261198944 13.341 99 29 54 0 1272968896 5088896 6681056
23:34:17 20312 8192000000 10190928720 10598989824 1298730000 13.684 100 28 56 1 1312178720 5615488 7833232
23:35:38 12260 8192000000 10189068560 10602315776 1300206336 13.697 100 19 38 1 1310113808 5035488 4871984
23:36:51 6282 8192000000 10197411904 10614091776 1309184144 13.779 100 21 42 0 1319485744 5464864 4836736
23:38:00 7565 8192000000 10216217104 10630049792 1322471104 13.899 100 30 62 0 1334916016 5770800 6674112
23:39:15 4502 8192000000 10246825808 10661023744 1343482864 14.089 100 34 68 0 1360093216 9880272 6730080
23:40:24 33710 8192000000 10270048272 10684641280 1359561088 14.233 100 42 84 1 1379640064 10320080 9758896
23:42:00 92174 8192000000 10287746928 10720997376 1370715968 14.333 100 31 62 0 1394212224 16012736 7483520
23:44:43 21258 8192000000 10305308640 10738524160 1393179888 14.534 100 35 68 0 1427509456 25993952 8335616
23:46:11 14611 8192000000 10312290784 10736533504 1387686496 14.485 100 56 107 0 1433881328 32707008 13487824
23:47:41 17265 8192000000 10324955408 10746454016 1387895168 14.487 99 47 90 0 1428960960 30091312 10974480
23:49:01 4332 8192000000 10335318112 10757890048 1391494464 14.519 99 57 115 2 1441694192 35803104 14396624
23:50:09 1242 8192000000 10345040368 10763255808 1402556896 14.618 100 67 130 2 1454540816 35981312 16002608
23:51:15 1722 8192000000 10365906592 10776297472 1411979584 14.702 100 72 142 0 1468373808 38941264 17452960
23:52:22 3140 8192000000 10371188800 10785107968 1411102512 14.694 100 78 157 2 1473252960 42219312 19931136
23:53:28 5105 8192000000 10343148352 10758418432 1385172144 14.463 99 66 127 0 1448535648 45812224 17551280
23:54:38 2911 8192000000 10362843680 10770382848 1397594192 14.574 100 81 161 2 1463772816 46515392 19663232
23:55:49 4478 8192000000 10344491680 10748256256 1378805328 14.406 99 77 152 0 1446692240 48192832 19694080
23:57:01 1773 8192000000 10357812512 10764623872 1388292048 14.491 100 81 161 1 1462600560 54198432 20110080
23:58:07 10161 8192000000 10327508240 10746867712 1381530480 14.430 100 42 84 0 1432439696 39540416 11368800
23:59:20 19550 8192000000 10322266832 10745737216 1379836448 14.415 100 34 64 0 1430740000 41536448 9367104
00:00:44 22143 8192000000 10334594688 10766114816 1386885856 14.478 100 39 78 1 1447236400 49199664 11150880
00:02:10 12581 8192000000 10329977392 10767888384 1382012336 14.435 99 22 44 1 1429644160 41219712 6412112
00:03:27 39473 8192000000 10301677840 10760224768 1376286768 14.383 100 16 32 0 1413882496 33178144 4417584
00:05:16 81731 8192000000 10265650432 10717650944 1303378112 13.726 95 28 51 0 1331788160 22825392 5584656
00:07:44 34937 8192000000 10288860336 10723364864 1358810560 14.227 99 34 65 1 1381937808 15500928 7626320
00:09:22 86939 8192000000 10281709456 10734317568 1337530304 14.035 97 16 32 0 1363112112 22207888 3373920
00:12:53 63500 8192000000 10248249520 10695143424 1311355264 13.798 96 11 23 0 1324454512 11443504 1655744
00:15:17 75616 8192000000 10287734272 10722504704 1365579200 14.287 100 45 90 1 1386934320 9682304 11672816
00:17:33 29551 8192000000 10281339680 10736824320 1376606320 14.386 100 31 62 0 1395915360 12473744 6835296
00:19:04 35197 8192000000 10304131520 10747367424 1394761808 14.548 100 42 82 1 1418245264 13494000 9989456
00:20:48 13881 8192000000 10301126512 10754027520 1395496480 14.555 100 25 50 0 1413796016 12060448 6239088
00:22:03 86143 8192000000 10286560576 10740678656 1368778096 14.316 98 10 19 1 1379652976 8159760 2715120
00:25:16 91985 8192000000 10291521424 10761015296 1399027600 14.586 100 12 24 0 1415921248 14099280 2794368
00:29:51 59093 8192000000 10301531616 10752090112 1382120320 14.436 99 20 40 0 1394291456 7708960 4462176
00:31:53 27544 8192000000 10308021280 10759290880 1396357920 14.563 100 41 79 2 1417933648 11696512 9879216
00:33:21 49266 8192000000 10297153568 10753814528 1345256368 14.105 96 22 42 1 1361568560 11971712 4340480
00:35:14 98618 8192000000 10297275568 10747084800 1322775728 13.902 95 22 44 1 1343170160 15751520 4642912
00:37:53 10849 8192000000 10304431408 10739834880 1373541216 14.359 99 43 86 0 1392264384 9280288 9442880
00:39:06 39859 8192000000 10276259152 10725253120 1310465872 13.790 95 12 25 0 1324005280 11118144 2421264
00:40:46 79883 8192000000 10276300752 10745147392 1338753424 14.046 97 11 22 0 1370504976 29492960 2258592
00:43:06 112975 8192000000 10265498144 10711969792 1339186416 14.050 97 18 36 1 1360078480 16463184 4428880
00:46:00 57751 8192000000 10266006192 10729320448 1345119680 14.104 97 24 45 0 1370082064 19622624 5339760
00:47:58 63937 8192000000 10272959632 10735484928 1349378000 14.142 98 11 22 0 1362184832 10592400 2214432
00:50:02 53547 8192000000 10279810000 10733740032 1356710752 14.208 98 17 34 0 1374197792 13469344 4017696
00:53:27 51140 8192000000 10293541984 10738929664 1370604800 14.332 99 20 38 0 1387287424 12034032 4648592
00:55:26 29501 8192000000 10271035952 10708570112 1329773744 13.965 97 33 65 1 1344607264 6853408 7980112
00:56:56 48068 8192000000 10266537120 10714578944 1327227312 13.942 97 17 34 0 1344361904 12868752 4265840
00:58:52 159181 8192000000 10291972240 10740346880 1323237488 13.906 96 12 24 0 1338752544 13431232 2083824
01:02:32 80560 8192000000 10275552336 10743025664 1328449312 13.953 97 15 28 0 1352229344 20463920 3316112
01:04:53 43242 8192000000 10283504512 10723614720 1349716208 14.145 98 31 62 1 1363973392 7450064 6807120
01:06:36 86613 8192000000 10285840176 10737876992 1367082048 14.301 99 28 56 0 1382918208 9564752 6271408
01:09:04 53230 8192000000 10289057776 10748362752 1394270048 14.544 100 15 30 0 1412429584 14934480 3225056
01:12:07 22421 8192000000 10307665504 10762612736 1386044544 14.471 99 22 42 1 1397323936 6615712 4663680
01:13:31 29884 8192000000 10306672320 10758565888 1391266448 14.517 99 44 79 2 1405712480 5660032 8786000
01:15:03 38640 8192000000 10308065776 10762170368 1380619952 14.422 98 26 52 0 1396016720 9349984 6046784
01:16:44 48333 8192000000 10315656304 10772336640 1392502432 14.528 99 19 38 0 1404921744 8194800 4224512
01:18:41 47060 8192000000 10311671072 10769092608 1384921792 14.461 98 23 46 2 1405479776 14999264 5558720
01:20:28 80215 8192000000 10308892048 10759405568 1359199184 14.230 97 19 38 0 1372158592 8991232 3968176
01:22:48 14761 8192000000 10294129456 10730881024 1363617680 14.270 99 41 82 0 1383357248 10667728 9071840
01:24:07 132202 8192000000 10282871104 10733580288 1357577424 14.216 98 29 58 0 1376385344 12465136 6342784
01:27:22 60262 8192000000 10266775200 10726666240 1350088320 14.148 98 10 20 0 1363613120 11266368 2258432
01:30:36 58503 8192000000 10271795312 10717339648 1332081392 13.986 97 19 37 0 1349063264 12216800 4765072
01:32:35 83424 8192000000 10296246256 10756165632 1347061344 14.121 98 21 42 0 1374924928 23087072 4776512
01:35:04 79867 8192000000 10283325888 10754699264 1366104864 14.292 99 10 20 0 1388884128 20374784 2404480
01:37:25 74521 8192000000 9091447392 9320140800 227971920 2.707 17 2 2 0 229967280 1953008 42352

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 13-10-2022 01:39:28 to 13-10-2022 01:39:43.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 13-10-2022 01:39:44 to 13-10-2022 01:39:46.

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          242231
MON$OLDEST_ACTIVE               242232
MON$OLDEST_SNAPSHOT             242232
MON$NEXT_TRANSACTION            242232
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                       2014848
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33634 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33634 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33634 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33634 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 ---
--- end of diff output ---

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

13.10.22 01:39:47. 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
2022-10-13 01:39:49 No severe PSQL-related problems occured
13.10.22 01:39:51. End of report.