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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-05-09 08:45
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4793.92
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15764944 kB
MemAvailable 30720496 kB
Buffers 0 kB
Cached 15897412 kB
SwapCached 2944 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-09-30 16:43:18
DTS_END                         2021-09-30 16:43:18.8690
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/48510
TRN_ID                          244469
ATT_ID                          721
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: 9160.99 21032 1099319
customer order: creation 178.62 338 21434
customer order: refuse 15.22 387 1826
order to supplier: creation 47.28 1310 5673
order to supplier: removal 3.19 112439 383
invoice (draft): creation 47.93 3797 5751
invoice (draft): removal 49.13 881 5895
invoice accept: apply 43.90 20064 5268
invoice accept: cancel 44.12 897 5294
customer reserve: creation 4065.85 198 487902
customer reserve: removal 4369.04 64 524285
realization accept: apply 85.62 180 10274
realization accept: cancel 77.43 129 9292
payment from customer: creation 60.62 123 7274
payment from customer: removal 5.61 118 673
payment to supplier: creation 50.17 149 6020
payment to supplier: removal 3.57 126 428
service: total inventory turnovers 5.25 1076 630
service: total monetary turnovers 7.62 28 914
service: aggregate perf. data 0.83 20031 99
service: refresh index statistics 0.03 258296 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 30-09-2021 16:44:14 to 30-09-2021 16:44:17.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5888
TEST_TIME 2 5978
TEST_TIME 3 5224
TEST_TIME 4 6157
TEST_TIME 5 6118
TEST_TIME 6 6045
TEST_TIME 7 6112
TEST_TIME 8 6373
TEST_TIME 9 6794
TEST_TIME 10 6705
TEST_TIME 11 6799
TEST_TIME 12 6790
TEST_TIME 13 7041
TEST_TIME 14 7244
TEST_TIME 15 7345
TEST_TIME 16 7416
TEST_TIME 17 7486
TEST_TIME 18 7521
TEST_TIME 19 7485
TEST_TIME 20 7864
TEST_TIME 21 8292
TEST_TIME 22 8288
TEST_TIME 23 8432
TEST_TIME 24 8875
TEST_TIME 25 8732
TEST_TIME 26 9012
TEST_TIME 27 8855
TEST_TIME 28 8969
TEST_TIME 29 9154
TEST_TIME 30 9864
TEST_TIME 31 10362
TEST_TIME 32 10986
TEST_TIME 33 11063
TEST_TIME 34 10990
TEST_TIME 35 10730
TEST_TIME 36 10648
TEST_TIME 37 10648
TEST_TIME 38 10456
TEST_TIME 39 10334
TEST_TIME 40 10257
TEST_TIME 41 10103
TEST_TIME 42 10039
TEST_TIME 43 9917
TEST_TIME 44 9869
TEST_TIME 45 9874
TEST_TIME 46 9774
TEST_TIME 47 9687
TEST_TIME 48 9648
TEST_TIME 49 9570
TEST_TIME 50 9587
TEST_TIME 51 9515
TEST_TIME 52 9446
TEST_TIME 53 9351
TEST_TIME 54 9304
TEST_TIME 55 9215
TEST_TIME 56 9189
TEST_TIME 57 9192
TEST_TIME 58 9306
TEST_TIME 59 9348
TEST_TIME 60 9479
TEST_TIME 61 9513
TEST_TIME 62 9479
TEST_TIME 63 9495
TEST_TIME 64 9512
TEST_TIME 65 9543
TEST_TIME 66 9489
TEST_TIME 67 9545
TEST_TIME 68 9546
TEST_TIME 69 9575
TEST_TIME 70 9544
TEST_TIME 71 9504
TEST_TIME 72 9575
TEST_TIME 73 9617
TEST_TIME 74 9649
TEST_TIME 75 9528
TEST_TIME 76 9453
TEST_TIME 77 9455
TEST_TIME 78 9403
TEST_TIME 79 9419
TEST_TIME 80 9377
TEST_TIME 81 9386
TEST_TIME 82 9371
TEST_TIME 83 9299
TEST_TIME 84 9351
TEST_TIME 85 9292
TEST_TIME 86 9357
TEST_TIME 87 9346
TEST_TIME 88 9327
TEST_TIME 89 9300
TEST_TIME 90 9291
TEST_TIME 91 9299
TEST_TIME 92 9367
TEST_TIME 93 9335
TEST_TIME 94 9336
TEST_TIME 95 9392
TEST_TIME 96 9375
TEST_TIME 97 9357
TEST_TIME 98 9413
TEST_TIME 99 9428
TEST_TIME 100 9425
TEST_TIME 101 9450
TEST_TIME 102 9479
TEST_TIME 103 9399
TEST_TIME 104 9370
TEST_TIME 105 9317
TEST_TIME 106 9328
TEST_TIME 107 9274
TEST_TIME 108 9268
TEST_TIME 109 9248
TEST_TIME 110 9201
TEST_TIME 111 9192
TEST_TIME 112 9188
TEST_TIME 113 9218
TEST_TIME 114 9180
TEST_TIME 115 9153
TEST_TIME 116 9212
TEST_TIME 117 9196
TEST_TIME 118 9214
TEST_TIME 119 9191
TEST_TIME 120 9162

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 30-09-2021 16:44:18 to 30-09-2021 16:44:21.

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 1548840 1548840 0 0.00 0 23834 7 0 0
doc_list_biud 1548840 1548840 0 0.00 0 21107 28 0 0
sp_add_invoice_to_stock 5378 5268 110 2.04 45 181661 20065 0 110
sp_cancel_adding_invoice 5294 5294 0 0.00 4 55918 897 0 0
sp_cancel_client_order 1826 1826 0 0.00 4 12256 387 0 0
sp_cancel_customer_reserve 524285 524285 0 0.00 1 18725 65 0 0
sp_cancel_pay_from_customer 673 673 0 0.00 3 8558 119 0 0
sp_cancel_pay_to_supplier 428 428 0 0.00 3 8928 126 0 0
sp_cancel_supplier_invoice 5901 5895 6 0.10 1 18124 881 0 6
sp_cancel_supplier_order 383 383 0 0.00 6 1157516 112439 0 0
sp_cancel_write_off 9292 9292 0 0.00 1 10742 129 0 0
sp_client_order 21434 21434 0 0.00 4 18685 338 0 0
sp_customer_reserve 487902 487902 0 0.00 0 102989 199 0 0
sp_fill_shopping_cart 32848 32848 0 0.00 1 44271 46 0 0
sp_fill_shopping_cart_clo_res 487899 487899 0 0.00 0 17531 13 0 0
sp_get_clo_for_invoice 5264 5264 0 0.00 0 20113 296 0 0
sp_kill_cost_storno 15687 15687 0 0.00 0 12946 65 0 0
sp_kill_qstorno_ret_qs2qd 1064140 1064140 0 0.00 0 21104 35 0 0
sp_kill_qty_storno 562511 562511 0 0.00 0 21106 75 0 0
sp_lock_selected_doc 21414 21414 0 0.00 0 7287 5 0 0
sp_make_cost_storno 28830 28830 0 0.00 0 15422 55 0 0
sp_multiply_rows_for_pdistr 28830 28830 0 0.00 0 8681 31 0 0
sp_multiply_rows_for_qdistr 21434 21434 0 0.00 0 12532 142 0 0
sp_pay_from_customer 7274 7274 0 0.00 4 12054 123 0 0
sp_pay_to_supplier 6020 6020 0 0.00 5 15410 149 0 0
sp_payment_common 13294 13294 0 0.00 1 9215 29 0 0
sp_qd_handle_on_cancel_clo 1826 1826 0 0.00 0 11590 344 0 0
sp_qd_handle_on_invoice_upd_sts 10556 10556 0 0.00 0 15713 356 0 0
sp_qd_handle_on_reserve_upd_sts 10274 10274 0 0.00 0 16563 49 0 0
sp_reserve_write_off 10274 10274 0 0.00 6 21156 180 0 0
sp_supplier_invoice 5751 5751 0 0.00 5 114744 3797 0 0
sp_supplier_order 5673 5673 0 0.00 4 30857 1311 0 0
srv_aggregate_perf_data 99 99 0 0.00 2675 325687 20031 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1992 166248 41995 0 0
srv_find_qd_qs_mism 1048484 1048484 0 0.00 0 23834 6 0 0
srv_make_invnt_saldo 630 630 0 0.00 31 46075 1076 0 0
srv_make_money_saldo 914 914 0 0.00 1 623 29 0 0
srv_recalc_idx_stat 4 4 0 0.00 152034 384326 258297 0 0
t$perf-abend:sp_add_invoice_to_stock 110 110 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5268 5268 0 0.00 0 18870 224 0 0
t$perf-norm:sp_cancel_adding_invoice 390 390 0 0.00 0 3213 108 0 0
t$perf-norm:sp_cancel_client_order 1826 1826 0 0.00 0 2649 16 0 0
t$perf-norm:sp_cancel_customer_reserve 694 694 0 0.00 0 2331 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 673 673 0 0.00 0 972 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 428 428 0 0.00 0 3791 22 0 0
t$perf-norm:sp_cancel_supplier_invoice 419 419 0 0.00 0 2413 24 0 0
t$perf-norm:sp_cancel_supplier_order 383 383 0 0.00 0 22862 1795 0 0
t$perf-norm:sp_cancel_write_off 1065 1065 0 0.00 0 2695 12 0 0
t$perf-norm:sp_client_order 21434 21434 0 0.00 0 10017 19 0 0
t$perf-norm:sp_customer_reserve 1029 1029 0 0.00 0 1280 6 0 0
t$perf-norm:sp_pay_from_customer 7274 7274 0 0.00 0 13026 15 0 0
t$perf-norm:sp_pay_to_supplier 6020 6020 0 0.00 0 5570 10 0 0
t$perf-norm:sp_reserve_write_off 10274 10274 0 0.00 0 15837 13 0 0
t$perf-norm:sp_supplier_invoice 5751 5751 0 0.00 0 10063 20 0 0
t$perf-norm:sp_supplier_order 5673 5673 0 0.00 0 7059 20 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 433 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 185 5 0 0
t$perf-norm:srv_make_invnt_saldo 630 630 0 0.00 0 1247 34 0 0
t$perf-norm:srv_make_money_saldo 914 914 0 0.00 0 992 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 4 0 0
v_add_invoice_to_stock 10744 10524 220 2.04 0 11922 23 0 220
v_all_customers 38564 38564 0 0.00 0 48 1 0 0
v_all_suppliers 31298 31298 0 0.00 0 20 1 0 0
v_all_wares 42868 42868 0 0.00 0 26 1 0 0
v_cancel_adding_invoice 780 780 0 0.00 0 7701 22 0 0
v_cancel_client_order 3652 3652 0 0.00 0 11928 12 0 0
v_cancel_customer_prepayment 1346 1346 0 0.00 0 5432 8 0 0
v_cancel_customer_reserve 1388 1388 0 0.00 0 10149 22 0 0
v_cancel_payment_to_supplier 856 856 0 0.00 0 8904 25 0 0
v_cancel_supplier_invoice 850 838 12 1.41 0 1409 18 0 12
v_cancel_supplier_order 766 766 0 0.00 0 945 7 0 0
v_cancel_write_off 2130 2130 0 0.00 0 8659 23 0 0
v_max_id_clo_ord 5673 5673 0 0.00 0 273 1 0 0
v_max_id_clo_res 1029 1029 0 0.00 0 7733 22 0 0
v_max_id_ord_sup 5741 5741 0 0.00 0 5037 8 0 0
v_max_non_paid_invoice 1785 1785 0 0.00 0 817 3 0 0
v_max_non_paid_realizn 4067 4067 0 0.00 0 4552 4 0 0
v_min_id_clo_ord 5673 5673 0 0.00 0 6092 9 0 0
v_min_id_clo_res 1029 1029 0 0.00 0 6792 22 0 0
v_min_id_ord_sup 5741 5741 0 0.00 0 3886 7 0 0
v_min_non_paid_invoice 6020 6020 0 0.00 0 8367 17 0 0
v_min_non_paid_realizn 7274 7274 0 0.00 0 9207 19 0 0
v_our_firm 10718 10718 0 0.00 0 15 1 0 0
v_reserve_write_off 20548 20548 0 0.00 0 12647 10 0 0
x_lock_depdocs_on_canc_invoice 10770 10770 0 0.00 0 20641 141 0 0
x_lock_depdocs_on_canc_sup_ord 383 383 0 0.00 0 4183 350 0 0
x_make_qstorno_1000_1200 5673 5673 0 0.00 2 30848 1223 0 0
x_make_qstorno_1200_2000 5751 5751 0 0.00 2 109470 3616 0 0
x_make_qstorno_2100_3300 487902 487902 0 0.00 0 102989 183 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-09-2021 16:44:22 to 30-09-2021 16:44: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
14:13:22 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
14:14:24 15067 8192000000 10379368928 10698915840 1437054544 14.924 100 83 166 0 1468773984 5725584 25993856
14:15:51 13587 8192000000 10447117360 10777346048 1499073424 15.468 100 90 180 0 1536384496 8328704 28982368
14:17:06 14267 8192000000 10473787536 10810896384 1531038512 15.746 100 91 182 0 1569395072 9488240 28868320
14:18:28 15612 8192000000 10557322704 10914324480 1612109776 16.443 100 72 144 0 1644237280 10134768 21992736
14:19:49 10161 8192000000 10637571744 11002785792 1696181424 17.153 100 86 172 3 1733774544 12742272 24850848
14:21:02 5260 8192000000 10695818528 11073081344 1757934240 17.667 100 85 170 1 1794701856 12404816 24362800
14:22:10 3953 8192000000 10747004432 11124498432 1800846752 18.021 100 89 177 1 1840717584 14419632 25451200
14:23:16 1835 8192000000 10796847440 11180802048 1849589248 18.419 100 92 182 1 1888784688 14003520 25191920
14:24:19 1624 8192000000 10823783120 11211730944 1874872944 18.624 100 89 176 0 1912249904 11566304 25810656
14:25:22 13630 8192000000 10773127728 11202752512 1868226000 18.570 100 29 56 0 1882686336 5062368 9397968
14:26:39 5663 8192000000 10800389872 11225399296 1887459296 18.725 100 28 53 2 1901421280 6364528 7597456
14:27:48 12475 8192000000 10798010928 11234103296 1892283712 18.764 100 17 34 1 1902356064 5409152 4663200
14:29:02 25492 8192000000 10815908416 11258662912 1909461616 18.902 100 26 52 2 1922931520 6219824 7250080
14:30:27 1392 8192000000 10869156192 11276152832 1922564192 19.007 100 90 182 1 1957455360 8897456 25993712
14:31:33 4376 8192000000 10841977808 11270766592 1917471312 18.967 100 30 60 0 1932646544 6804624 8370608
14:32:39 3591 8192000000 10802160704 11237285888 1877229296 18.643 99 29 58 0 1892517408 7150016 8138096
14:33:45 50541 8192000000 10745049056 11170959360 1803903056 18.046 97 26 53 0 1817638352 6698272 7037024
14:35:37 7101 8192000000 10729290080 11143143424 1763804400 17.716 97 32 64 0 1779475456 6397264 9273792
14:36:46 4643 8192000000 10729475552 11149074432 1783063728 17.875 98 30 60 1 1801807488 10622704 8121056
14:37:53 71542 8192000000 10741074304 11161505792 1793241728 17.958 98 26 50 0 1806000672 5247616 7511328
14:40:08 6609 8192000000 10787879024 11208687616 1864853328 18.543 100 26 52 1 1878911152 6797280 7260544
14:41:16 6834 8192000000 10797109168 11225456640 1877734016 18.647 100 27 54 0 1892127872 6720496 7673360
14:42:25 20459 8192000000 10796742240 11232804864 1886819936 18.720 100 18 36 0 1897797392 5091664 5885792
14:44:02 7693 8192000000 10860658640 11276296192 1923172384 19.012 100 52 104 1 1944997392 7346416 14478592
14:45:14 10392 8192000000 10881217744 11304955904 1943722560 19.176 100 54 104 1 1968069424 9436800 14910064
14:46:31 4141 8192000000 10901953680 11322527744 1959767504 19.304 100 55 110 1 1985529520 9796736 15965280
14:47:44 13839 8192000000 10899849952 11330420736 1968005008 19.370 100 67 132 0 1998276192 11858928 18412256
14:49:05 7246 8192000000 10911844224 11343527936 1973515536 19.413 100 55 110 2 2003914576 13935968 16463072
14:50:16 7019 8192000000 10930526800 11357413376 1987581472 19.525 100 60 119 1 2020979872 16123888 17274512
14:51:26 5268 8192000000 10944608192 11362541568 1985769072 19.510 100 74 146 1 2025493280 18672944 21051264
14:52:35 6184 8192000000 10946165136 11369451520 1989822672 19.542 100 70 141 0 2032372048 20495216 22054160
14:53:48 7142 8192000000 10929308352 11358498816 1979581168 19.461 100 60 120 1 2015839408 18668064 17590176
14:55:03 3513 8192000000 10905245408 11324948480 1954448400 19.262 99 57 113 0 1988819344 17593376 16777568
14:56:12 5272 8192000000 10938741648 11359338496 1969968224 19.385 99 74 146 2 2016783760 25707056 21108480
14:57:21 3625 8192000000 10927155856 11350466560 1932654864 19.088 97 72 143 1 1978820592 24926128 21239600
14:58:32 5000 8192000000 10934451248 11358494720 1967664064 19.367 100 63 126 0 2013812528 26334848 19813616
14:59:42 5722 8192000000 10900766032 11318366208 1938466768 19.135 99 69 138 0 1987291232 28122736 20701728
15:00:53 2631 8192000000 10948788960 11368779776 1979079120 19.457 100 71 142 0 2030956336 30384528 21492688
15:02:01 3089 8192000000 10957311872 11367124992 1971233888 19.395 100 84 169 0 2037992000 40595616 26162496
15:03:08 1991 8192000000 10973051104 11383664640 1966242912 19.356 100 88 173 1 2045253888 51930512 27080464
15:04:15 4277 8192000000 10962971376 11377348608 1971241568 19.395 100 83 162 3 2044515984 47548896 25725520
15:05:28 2163 8192000000 10974693616 11381309440 1975090432 19.426 100 90 182 0 2051170880 48150080 27930368
15:06:36 8561 8192000000 10952368496 11388547072 1970579184 19.390 100 44 88 2 2035151904 49747024 14825696
15:07:57 7465 8192000000 10971845920 11411501056 1995238240 19.585 100 47 94 2 2059363152 49278096 14846816
15:09:08 10252 8192000000 10977993984 11420090368 1998744192 19.613 100 46 93 0 2061077728 47143472 15190064
15:10:30 12486 8192000000 10962796048 11415097344 1985358480 19.507 100 31 60 1 2044051456 48406352 10286624
15:11:46 21472 8192000000 10948970480 11409129472 1977136288 19.442 100 35 70 0 2045840352 56307120 12396944
15:13:16 31150 8192000000 10943731952 11414302720 1967670272 19.367 100 20 39 1 2035714672 60447392 7597008
15:14:48 35640 8192000000 10939942880 11405180928 1984410208 19.500 100 12 24 1 2023232048 35296352 3525488
15:16:26 47207 8192000000 10929684256 11398291456 1987934224 19.527 100 11 22 1 2010188384 19295552 2958608
15:18:16 37017 8192000000 10904383024 11345739776 1937416512 19.126 98 39 78 1 1955249824 5957920 11875392
15:19:54 43012 8192000000 10883959888 11356958720 1949451072 19.222 99 19 39 1 1966914992 11502832 5961088
15:22:03 166247 8192000000 10901373728 11370053632 1959649440 19.303 99 12 24 0 1973485136 10885072 2950624
15:25:50 42106 8192000000 10886028352 11356704768 1944846304 19.185 99 20 41 3 1963406928 13208512 5352112
15:27:32 63718 8192000000 10901524432 11362488320 1951014400 19.235 99 11 22 0 1967198064 12939232 3244432
15:29:42 75537 8192000000 10914417664 11361296384 1969524480 19.382 100 28 56 0 1987508704 9533024 8451200
15:32:04 27989 8192000000 10908366720 11376238592 1976553520 19.437 99 46 93 0 1997069072 6011168 14504384
15:33:41 58849 8192000000 10879485744 11349032960 1911600560 18.919 97 22 44 1 1929690624 11501696 6588368
15:35:41 34784 8192000000 10879251680 11353546752 1931560448 19.079 98 13 26 0 1953489040 18230368 3698224
15:37:20 59313 8192000000 10923165552 11381841920 1971822304 19.400 100 32 64 2 2005414864 24275280 9317280
15:39:21 86427 8192000000 10908725600 11382435840 1905174736 18.868 96 22 44 0 1939094864 26810848 7109280
15:42:22 69985 8192000000 10870309168 11356176384 1887520512 18.726 96 16 31 3 1910964896 19284656 4159728
15:44:32 46093 8192000000 10891684672 11348574208 1880295808 18.667 96 22 44 1 1903834512 17414624 6124080
15:46:36 51262 8192000000 10884980928 11346591744 1902599744 18.847 97 24 48 0 1919818480 11044688 6174048
15:48:46 36534 8192000000 10880999104 11337732096 1910790240 18.913 98 25 50 0 1928095120 9156576 8148304
15:50:24 42535 8192000000 10893515200 11340992512 1892628016 18.767 97 20 40 0 1907409600 9273168 5508416
15:52:12 81008 8192000000 10885610912 11345358848 1898644608 18.815 97 26 52 2 1920119232 14224848 7249776
15:54:34 119130 8192000000 10898091888 11377258496 1882991056 18.689 96 27 54 1 1903330304 13369472 6969776
15:57:33 114931 8192000000 10859934304 11332046848 1881330016 18.676 96 10 20 0 1900344704 16304192 2710496
16:00:28 51037 8192000000 10850406944 11327033344 1888470672 18.733 97 21 42 1 1906785168 12007152 6307344
16:02:22 83023 8192000000 10875222048 11347873792 1916480336 18.959 98 23 46 2 1936032112 13126000 6425776
16:04:49 52343 8192000000 10912009728 11379462144 1945383520 19.190 99 13 26 0 1963504480 14268928 3852032
16:07:13 60135 8192000000 10895985840 11387904000 1968414592 19.373 100 14 28 1 1985444960 13053152 3977216
16:09:25 57512 8192000000 10887564496 11381657600 1945483520 19.190 99 16 32 0 1967824848 17582064 4759264
16:11:23 65960 8192000000 10908031024 11372556288 1929656688 19.064 98 15 30 0 1950684912 16835456 4192768
16:13:37 38001 8192000000 10923828672 11387469824 1989055936 19.536 100 33 65 0 2011780576 13988992 8735648
16:15:24 83060 8192000000 10930763168 11394703360 1987008192 19.520 100 20 40 0 2005739936 13774384 4957360
16:17:49 83293 8192000000 10896658752 11374534656 1905051104 18.867 96 14 28 1 1920817792 11868976 3897712
16:20:12 15172 8192000000 10931482496 11384066048 1983350304 19.491 100 49 96 0 2004379568 7561152 13468112
16:21:31 54093 8192000000 10914126912 11392180224 1983352032 19.491 100 25 50 0 2002628800 12317312 6959456
16:23:26 73818 8192000000 10876315248 11358052352 1900161984 18.828 97 26 52 2 1920697376 13304720 7230672
16:25:50 148091 8192000000 10875980176 11366961152 1887014672 18.722 96 14 28 0 1908259792 17354640 3890480
16:29:42 79576 8192000000 10854882624 11339866112 1865676720 18.549 95 8 16 0 1877273904 9420128 2177056
16:32:17 45879 8192000000 10861170768 11342995456 1912162512 18.924 98 19 38 0 1931378064 13851456 5364096
16:34:20 82123 8192000000 10858844960 11351117824 1914801600 18.945 99 7 14 0 1933806448 17059984 1944864
16:37:28 39810 8192000000 10878554704 11353133056 1939585392 19.143 99 24 48 0 1954291584 8706144 6000048
16:39:12 80313 8192000000 10869320096 11344384000 1897173040 18.804 97 18 36 0 1911595840 9024320 5398480
16:41:44 28301 8192000000 10893644176 11344384000 1931781664 19.081 99 38 76 5 1949160896 7600288 9778944
16:43:15 13186 8192000000 9413790384 9717514240 559207536 6.390 29 4 6 1 566293488 6155248 930704

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-09-2021 16:44:28 to 30-09-2021 16:44:43.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 110 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 30-09-2021 16:44:44 to 30-09-2021 16:44: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          244882
MON$OLDEST_ACTIVE               244883
MON$OLDEST_SNAPSHOT             244883
MON$NEXT_TRANSACTION            244883
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                       2057440
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.33505 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33505 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33505 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33505 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Thu Sep 30 16:43:31 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:9: 
--- end of diff output ---

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

30.09.21 16:44: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
2021-09-30 16:44:50 No severe PSQL-related problems occured
30.09.21 16:44:51. End of report.