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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 16057044 kB
MemAvailable 30845872 kB
Buffers 0 kB
Cached 15674448 kB
SwapCached 2464 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-05 01:37:57
DTS_END                         2021-11-05 01:37:57.1220
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/35492
TRN_ID                          233103
ATT_ID                          600
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: 8843.56 23213 1061227
customer order: creation 160.82 363 19299
customer order: refuse 14.02 356 1682
order to supplier: creation 42.04 1227 5045
order to supplier: removal 3.30 105749 396
invoice (draft): creation 44.09 3524 5291
invoice (draft): removal 53.21 748 6385
invoice accept: apply 38.63 19771 4636
invoice accept: cancel 47.91 832 5749
customer reserve: creation 3543.87 196 425264
customer reserve: removal 4616.21 59 553945
realization accept: apply 77.63 201 9316
realization accept: cancel 81.91 118 9829
payment from customer: creation 55.45 120 6654
payment from customer: removal 5.32 115 638
payment to supplier: creation 45.11 144 5413
payment to supplier: removal 3.16 92 379
service: total inventory turnovers 3.92 1363 470
service: total monetary turnovers 6.18 25 741
service: aggregate perf. data 0.76 20377 91
service: refresh index statistics 0.03 308883 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 05-11-2021 01:40:13 to 05-11-2021 01:40:16.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1701
TEST_TIME 2 3017
TEST_TIME 3 2645
TEST_TIME 4 3288
TEST_TIME 5 3417
TEST_TIME 6 4068
TEST_TIME 7 4420
TEST_TIME 8 4967
TEST_TIME 9 5126
TEST_TIME 10 5962
TEST_TIME 11 5941
TEST_TIME 12 6294
TEST_TIME 13 6287
TEST_TIME 14 6298
TEST_TIME 15 6492
TEST_TIME 16 6547
TEST_TIME 17 6668
TEST_TIME 18 7064
TEST_TIME 19 7279
TEST_TIME 20 7670
TEST_TIME 21 7944
TEST_TIME 22 8530
TEST_TIME 23 8933
TEST_TIME 24 8947
TEST_TIME 25 9077
TEST_TIME 26 9648
TEST_TIME 27 10012
TEST_TIME 28 10334
TEST_TIME 29 10257
TEST_TIME 30 10032
TEST_TIME 31 9822
TEST_TIME 32 9743
TEST_TIME 33 9704
TEST_TIME 34 9573
TEST_TIME 35 9430
TEST_TIME 36 9534
TEST_TIME 37 9545
TEST_TIME 38 9447
TEST_TIME 39 9300
TEST_TIME 40 9163
TEST_TIME 41 9128
TEST_TIME 42 9163
TEST_TIME 43 9044
TEST_TIME 44 8935
TEST_TIME 45 8864
TEST_TIME 46 8876
TEST_TIME 47 8872
TEST_TIME 48 8856
TEST_TIME 49 8762
TEST_TIME 50 8738
TEST_TIME 51 8841
TEST_TIME 52 8869
TEST_TIME 53 8862
TEST_TIME 54 8791
TEST_TIME 55 8768
TEST_TIME 56 8868
TEST_TIME 57 8914
TEST_TIME 58 8871
TEST_TIME 59 8901
TEST_TIME 60 8829
TEST_TIME 61 8885
TEST_TIME 62 8989
TEST_TIME 63 8911
TEST_TIME 64 9056
TEST_TIME 65 9039
TEST_TIME 66 8943
TEST_TIME 67 8820
TEST_TIME 68 8837
TEST_TIME 69 8965
TEST_TIME 70 8909
TEST_TIME 71 8928
TEST_TIME 72 8864
TEST_TIME 73 8837
TEST_TIME 74 8779
TEST_TIME 75 8769
TEST_TIME 76 8749
TEST_TIME 77 8793
TEST_TIME 78 8774
TEST_TIME 79 8750
TEST_TIME 80 8687
TEST_TIME 81 8693
TEST_TIME 82 8666
TEST_TIME 83 8617
TEST_TIME 84 8660
TEST_TIME 85 8677
TEST_TIME 86 8710
TEST_TIME 87 8749
TEST_TIME 88 8776
TEST_TIME 89 8733
TEST_TIME 90 8829
TEST_TIME 91 8815
TEST_TIME 92 8864
TEST_TIME 93 8921
TEST_TIME 94 8913
TEST_TIME 95 8911
TEST_TIME 96 8913
TEST_TIME 97 8869
TEST_TIME 98 8791
TEST_TIME 99 8793
TEST_TIME 100 8806
TEST_TIME 101 8792
TEST_TIME 102 8742
TEST_TIME 103 8703
TEST_TIME 104 8685
TEST_TIME 105 8715
TEST_TIME 106 8671
TEST_TIME 107 8760
TEST_TIME 108 8794
TEST_TIME 109 8782
TEST_TIME 110 8901
TEST_TIME 111 8939
TEST_TIME 112 8943
TEST_TIME 113 8951
TEST_TIME 114 8988
TEST_TIME 115 8979
TEST_TIME 116 8959
TEST_TIME 117 8890
TEST_TIME 118 8892
TEST_TIME 119 8861
TEST_TIME 120 8849

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 05-11-2021 01:40:17 to 05-11-2021 01:40: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 1457241 1457241 0 0.00 0 26517 6 0 0
doc_list_biud 1457238 1457238 0 0.00 0 66683 29 0 0
sp_add_invoice_to_stock 4735 4636 99 2.09 29 190422 19771 0 99
sp_cancel_adding_invoice 5749 5749 0 0.00 6 64614 832 0 0
sp_cancel_client_order 1682 1682 0 0.00 4 19252 357 0 0
sp_cancel_customer_reserve 553945 553945 0 0.00 1 66685 60 0 0
sp_cancel_pay_from_customer 638 638 0 0.00 3 5299 115 0 0
sp_cancel_pay_to_supplier 379 379 0 0.00 2 4949 92 0 0
sp_cancel_supplier_invoice 6394 6385 9 0.14 2 21914 748 0 9
sp_cancel_supplier_order 396 396 0 0.00 6 1034698 105750 0 0
sp_cancel_write_off 9829 9829 0 0.00 1 26518 119 0 0
sp_client_order 19299 19299 0 0.00 3 27093 363 0 0
sp_customer_reserve 425264 425264 0 0.00 0 52843 197 0 0
sp_fill_shopping_cart 29633 29633 0 0.00 1 30763 44 0 0
sp_fill_shopping_cart_clo_res 425259 425259 0 0.00 0 26246 15 0 0
sp_get_clo_for_invoice 4629 4629 0 0.00 0 34730 307 0 0
sp_kill_cost_storno 16595 16595 0 0.00 0 26515 58 0 0
sp_kill_qstorno_ret_qs2qd 1124500 1124500 0 0.00 0 66436 32 0 0
sp_kill_qty_storno 591931 591931 0 0.00 0 66682 70 0 0
sp_lock_selected_doc 19373 19373 0 0.00 0 8307 6 0 0
sp_make_cost_storno 26012 26012 0 0.00 0 16106 64 0 0
sp_multiply_rows_for_pdistr 26012 26012 0 0.00 0 9518 34 0 0
sp_multiply_rows_for_qdistr 19299 19299 0 0.00 0 26838 153 0 0
sp_pay_from_customer 6654 6654 0 0.00 4 12900 121 0 0
sp_pay_to_supplier 5413 5413 0 0.00 5 21334 145 0 0
sp_payment_common 12067 12067 0 0.00 2 21318 36 0 0
sp_qd_handle_on_cancel_clo 1682 1682 0 0.00 0 19242 327 0 0
sp_qd_handle_on_invoice_upd_sts 10378 10378 0 0.00 0 20078 342 0 0
sp_qd_handle_on_reserve_upd_sts 9316 9316 0 0.00 0 22667 59 0 0
sp_reserve_write_off 9316 9316 0 0.00 6 22702 201 0 0
sp_supplier_invoice 5291 5291 0 0.00 6 86370 3524 0 0
sp_supplier_order 5045 5045 0 0.00 5 61518 1227 0 0
srv_aggregate_perf_data 91 91 0 0.00 2742 281774 20377 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1750 143634 50391 0 0
srv_find_qd_qs_mism 1018046 1018046 0 0.00 0 22524 5 0 0
srv_make_invnt_saldo 470 470 0 0.00 51 102789 1363 0 0
srv_make_money_saldo 741 741 0 0.00 1 1021 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 195796 508814 308883 0 0
t$perf-abend:sp_add_invoice_to_stock 99 99 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4636 4636 0 0.00 0 16193 192 0 0
t$perf-norm:sp_cancel_adding_invoice 396 396 0 0.00 1 7954 159 0 0
t$perf-norm:sp_cancel_client_order 1682 1682 0 0.00 0 4200 17 0 0
t$perf-norm:sp_cancel_customer_reserve 647 647 0 0.00 0 2451 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 638 638 0 0.00 0 6305 23 0 0
t$perf-norm:sp_cancel_pay_to_supplier 379 379 0 0.00 0 1949 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 356 356 0 0.00 0 3014 27 0 0
t$perf-norm:sp_cancel_supplier_order 396 396 0 0.00 0 26709 1868 0 0
t$perf-norm:sp_cancel_write_off 934 934 0 0.00 0 2970 8 0 0
t$perf-norm:sp_client_order 19299 19299 0 0.00 0 11823 23 0 0
t$perf-norm:sp_customer_reserve 970 970 0 0.00 0 2619 11 0 0
t$perf-norm:sp_pay_from_customer 6654 6654 0 0.00 0 7152 11 0 0
t$perf-norm:sp_pay_to_supplier 5413 5413 0 0.00 0 6448 12 0 0
t$perf-norm:sp_reserve_write_off 9316 9316 0 0.00 0 12422 14 0 0
t$perf-norm:sp_supplier_invoice 5291 5291 0 0.00 0 7286 19 0 0
t$perf-norm:sp_supplier_order 5045 5045 0 0.00 0 4673 18 0 0
t$perf-norm:srv_aggregate_perf_data 91 91 0 0.00 0 398 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 49 1 0 0
t$perf-norm:srv_make_invnt_saldo 470 470 0 0.00 0 1262 27 0 0
t$perf-norm:srv_make_money_saldo 741 741 0 0.00 0 626 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 15 5 0 0
v_add_invoice_to_stock 9456 9258 198 2.09 0 18934 20 0 198
v_all_customers 34890 34890 0 0.00 0 197 1 0 0
v_all_suppliers 28450 28450 0 0.00 0 198 1 0 0
v_all_wares 38598 38598 0 0.00 0 83 1 0 0
v_cancel_adding_invoice 792 792 0 0.00 0 1985 11 0 0
v_cancel_client_order 3364 3364 0 0.00 0 5280 7 0 0
v_cancel_customer_prepayment 1276 1276 0 0.00 0 5283 15 0 0
v_cancel_customer_reserve 1294 1294 0 0.00 0 1777 7 0 0
v_cancel_payment_to_supplier 758 758 0 0.00 0 3369 8 0 0
v_cancel_supplier_invoice 730 712 18 2.46 0 2053 15 0 18
v_cancel_supplier_order 792 792 0 0.00 0 3684 9 0 0
v_cancel_write_off 1868 1868 0 0.00 0 5008 17 0 0
v_max_id_clo_ord 5044 5044 0 0.00 0 5235 2 0 0
v_max_id_clo_res 970 970 0 0.00 0 654 4 0 0
v_max_id_ord_sup 5290 5290 0 0.00 0 2834 5 0 0
v_max_non_paid_invoice 1522 1522 0 0.00 0 4022 9 0 0
v_max_non_paid_realizn 3571 3571 0 0.00 0 1809 6 0 0
v_min_id_clo_ord 5044 5044 0 0.00 0 6311 13 0 0
v_min_id_clo_res 970 970 0 0.00 0 9252 36 0 0
v_min_id_ord_sup 5290 5290 0 0.00 0 6030 5 0 0
v_min_non_paid_invoice 5413 5413 0 0.00 0 21313 26 0 0
v_min_non_paid_realizn 6654 6654 0 0.00 0 12118 23 0 0
v_our_firm 9874 9874 0 0.00 0 17 1 0 0
v_reserve_write_off 18632 18632 0 0.00 0 18315 9 0 0
x_lock_depdocs_on_canc_invoice 11778 11778 0 0.00 0 32532 130 0 0
x_lock_depdocs_on_canc_sup_ord 396 396 0 0.00 0 15617 425 0 0
x_make_qstorno_1000_1200 5045 5045 0 0.00 2 61491 1130 0 0
x_make_qstorno_1200_2000 5291 5291 0 0.00 2 86138 3378 0 0
x_make_qstorno_2100_3300 425264 425264 0 0.00 0 48961 179 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-11-2021 01:40:22 to 05-11-2021 01:40: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:01 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:02 30251 8192000000 9918444800 10233733120 989524016 10.777 100 86 172 0 1018295840 5779824 22992000
23:10:34 21506 8192000000 9970177024 10297192448 1044770928 11.310 100 86 170 1 1075063024 8155776 22136320
23:12:06 7648 8192000000 9991665760 10327408640 1080620464 11.653 100 86 172 0 1111714592 9904960 21189168
23:13:29 13792 8192000000 10055242016 10404769792 1136383600 12.181 100 86 172 1 1169501808 12541728 20576480
23:14:44 7523 8192000000 10085638976 10448596992 1173591824 12.530 100 75 151 1 1203856768 12601872 17663072
23:15:56 3297 8192000000 10126944416 10500296704 1217246848 12.936 100 70 139 1 1244200384 10790208 16163328
23:17:01 3146 8192000000 10166052336 10545950720 1257144592 13.304 100 76 152 1 1285539808 11837856 16557360
23:18:07 2566 8192000000 10201856480 10583654400 1283239280 13.543 100 92 184 0 1317889872 13752112 20898480
23:19:11 4864 8192000000 10200557728 10598285312 1295341296 13.653 100 72 142 1 1323658848 12906000 15411552
23:20:19 10182 8192000000 10191989840 10606157824 1303634480 13.728 100 24 50 1 1315254768 6231072 5389216
23:21:31 5706 8192000000 10210047184 10622717952 1317809280 13.857 100 30 60 1 1331619376 7029392 6780704
23:22:43 7324 8192000000 10217607248 10629087232 1324989472 13.922 100 26 52 0 1337555312 6714304 5851536
23:23:53 12750 8192000000 10216646432 10635419648 1330308720 13.970 100 25 48 1 1340989456 4784672 5896064
23:25:52 8077 8192000000 10225759904 10652176384 1339181296 14.050 100 33 66 0 1352504736 5603088 7720352
23:27:00 8570 8192000000 10219561392 10636181504 1318503680 13.863 99 26 52 4 1330041584 4966736 6571168
23:28:13 6258 8192000000 10209381392 10620870656 1295537824 13.655 98 27 54 1 1308267792 6541520 6188448
23:29:22 67320 8192000000 10139910016 10549846016 1184730240 12.634 93 34 68 0 1198300688 4756720 8813728
23:31:32 6313 8192000000 10128534784 10535473152 1231823456 13.071 99 18 36 1 1241611376 5985216 3802704
23:32:40 9691 8192000000 10145085312 10544324608 1237130944 13.120 99 29 59 2 1249832528 5683328 7018256
23:33:52 73226 8192000000 10165237344 10573086720 1272295904 13.443 100 31 61 1 1284429328 4518128 7615296
23:36:05 5972 8192000000 10179354080 10591449088 1286405088 13.571 100 27 51 1 1299440016 6289504 6745424
23:37:11 9306 8192000000 10187700992 10602770432 1297984096 13.677 100 15 30 0 1306314048 4843008 3486944
23:38:27 34999 8192000000 10226825008 10635460608 1321068752 13.886 100 49 96 2 1338565088 6328128 11168208
23:40:15 78627 8192000000 10251000432 10670927872 1347877120 14.128 100 51 102 1 1373556784 13940336 11739328
23:42:52 23395 8192000000 10262169296 10682118144 1349522176 14.143 99 43 85 3 1377637808 18761024 9354608
23:44:19 9020 8192000000 10280470528 10693812224 1352542800 14.170 99 60 117 1 1383920608 18451152 12926656
23:45:31 5774 8192000000 10305848048 10719260672 1374898256 14.371 100 68 136 1 1413265216 21884048 16482912
23:46:42 18012 8192000000 10312343408 10731442176 1374691712 14.369 100 56 110 2 1418035712 30328896 13015104
23:48:06 12275 8192000000 10315246448 10734284800 1388396912 14.492 100 51 100 2 1425995024 25957488 11640624
23:49:22 3849 8192000000 10322446432 10729758720 1383342864 14.446 100 81 160 1 1429361664 26159552 19859248
23:50:30 4624 8192000000 10318345232 10731110400 1382799728 14.442 100 61 120 2 1426150480 27848784 15501968
23:51:39 13814 8192000000 10339741632 10752798720 1398552640 14.582 100 54 107 1 1442925360 30985008 13387712
23:52:57 3043 8192000000 10367006096 10770415616 1406586048 14.654 100 85 169 0 1462850320 35800048 20464224
23:54:06 5184 8192000000 10349868352 10759634944 1388718720 14.494 99 76 151 1 1447127600 39247168 19161712
23:55:16 1749 8192000000 10364738592 10768039936 1397005184 14.568 100 92 185 1 1465613664 45126864 23481616
23:56:26 6280 8192000000 10329152704 10742206464 1362372032 14.259 99 55 109 0 1420901504 44323616 14205856
23:57:37 2407 8192000000 10348733952 10752094208 1374889120 14.371 100 88 174 0 1447961408 50292720 22779568
23:58:44 7466 8192000000 10336050848 10758590464 1378411632 14.402 100 55 110 1 1444409872 50794400 15203840
00:00:01 16778 8192000000 10320560608 10757844992 1372783280 14.352 99 37 74 0 1428840464 45773088 10284096
00:01:21 12208 8192000000 10319153648 10748399616 1375935856 14.380 100 34 67 2 1422833328 37777600 9119872
00:02:35 7826 8192000000 10327390320 10758946816 1377010032 14.390 100 35 68 0 1431419776 44680128 9729616
00:03:45 29398 8192000000 10283096224 10730192896 1349445856 14.142 99 18 36 2 1388965536 34355168 5164512
00:05:16 8855 8192000000 10286522672 10726244352 1361060272 14.247 100 48 92 3 1398231712 25739616 11431824
00:06:26 32574 8192000000 10271610368 10706984960 1341337328 14.069 99 23 46 0 1357297760 10899168 5061264
00:08:07 65355 8192000000 10269726304 10719719424 1365208288 14.284 100 18 36 0 1380064416 10584480 4271648
00:10:13 55042 8192000000 10274487632 10713878528 1355339808 14.195 99 28 56 1 1367899680 6434432 6125440
00:12:11 76836 8192000000 10286175952 10733953024 1375992144 14.381 100 12 24 0 1392817264 14153008 2672112
00:14:35 91080 8192000000 10280937456 10740555776 1353990496 14.183 98 7 14 0 1370134848 14817536 1326816
00:17:07 64990 8192000000 10306538352 10753351680 1392816768 14.531 100 21 41 0 1414538016 16452256 5268992
00:19:37 120038 8192000000 10307622304 10777894912 1372831472 14.352 98 13 26 0 1399153424 23235776 3086176
00:22:38 66339 8192000000 10298524176 10766770176 1392413328 14.527 100 18 36 0 1419409072 22970176 4025568
00:24:57 66875 8192000000 10262108896 10725990400 1351073824 14.157 98 21 43 0 1374155776 17410384 5671568
00:27:05 62982 8192000000 10284790560 10746454016 1383109104 14.444 100 14 28 0 1400532768 14468944 2954720
00:29:08 86083 8192000000 10282293408 10746892288 1362731712 14.262 98 7 14 0 1378205040 13643744 1829584
00:31:35 25982 8192000000 10303187088 10742571008 1363522464 14.269 98 50 100 0 1384390976 10341280 10527232
00:33:12 103851 8192000000 10293204000 10763550720 1362868512 14.263 98 16 32 1 1382991664 16723728 3399424
00:35:56 56691 8192000000 10295758960 10750267392 1379695552 14.414 99 27 55 1 1399062128 13439216 5927360
00:37:57 91039 8192000000 10280823200 10745937920 1337920880 14.039 96 30 60 1 1364943232 20472592 6549760
00:40:29 26389 8192000000 10285573968 10713190400 1315552448 13.836 96 48 95 1 1335458752 9807104 10099200
00:41:56 66570 8192000000 10268346896 10714087424 1309095792 13.778 96 7 14 0 1325642528 15196960 1349776
00:44:08 119905 8192000000 10287529136 10736570368 1353687184 14.181 99 20 40 1 1385295744 26171872 5436688
00:47:10 69730 8192000000 10231496768 10688086016 1302626288 13.719 96 5 10 0 1318667344 14977184 1063872
00:49:22 46955 8192000000 10249946464 10694602752 1316770688 13.847 97 18 36 1 1332100624 11260720 4069216
00:51:14 81374 8192000000 10263626032 10716672000 1346852240 14.119 99 14 28 1 1366970704 16194368 3924096
00:53:52 62683 8192000000 10258385216 10711465984 1361864464 14.254 100 15 30 1 1378207344 12369232 3973648
00:56:00 66740 8192000000 10253417584 10703532032 1350441968 14.151 99 22 43 2 1363743312 8430528 4870816
00:58:26 59823 8192000000 10258513344 10727673856 1365608672 14.288 100 11 22 1 1376692608 8725648 2358288
01:00:34 59454 8192000000 10265572688 10723422208 1352934112 14.174 99 16 32 0 1375934736 19282928 3717696
01:02:34 63268 8192000000 10280207888 10740060160 1374373088 14.366 100 16 33 0 1390721360 12625888 3722384
01:04:41 75524 8192000000 10288326304 10741788672 1338755216 14.046 97 12 24 1 1351623888 10356736 2511936
01:07:04 57411 8192000000 10267428304 10709639168 1337136624 14.032 97 26 52 1 1357268304 14597040 5534640
01:09:04 49771 8192000000 10268676768 10722304000 1346241744 14.114 98 22 44 0 1363802240 12846160 4714336
01:11:09 137460 8192000000 10296932816 10748686336 1358996704 14.228 98 11 22 0 1371720320 10661152 2062464
01:14:28 143634 8192000000 10282376160 10738446336 1358322704 14.222 98 27 54 1 1376658480 11905968 6429808
01:17:56 60239 8192000000 10273468080 10741641216 1361671792 14.252 99 11 23 3 1381086928 16515760 2899376
01:19:57 43583 8192000000 10283830272 10735820800 1367836480 14.308 99 14 29 1 1384353104 13256320 3260304
01:21:51 87066 8192000000 10299169088 10750009344 1377707184 14.396 99 15 30 0 1398684688 17123232 3854272
01:24:18 53536 8192000000 10306382288 10760224768 1381443728 14.429 99 19 36 1 1399903104 13903664 4555712
01:26:16 48416 8192000000 10301185696 10770632704 1398832528 14.585 100 11 21 1 1417987456 16387472 2767456
01:28:22 82789 8192000000 10293706480 10755956736 1336765312 14.028 96 5 10 0 1355448080 17762544 920224
01:30:54 50538 8192000000 10297386544 10760069120 1374340208 14.366 99 30 60 0 1398719712 17880048 6499456
01:33:01 23487 8192000000 10295810944 10733178880 1376973200 14.389 100 44 89 1 1397352480 10215200 10164080
01:34:32 91264 8192000000 10266437104 10706907136 1270548576 13.427 92 25 50 1 1282770832 6530528 5691728
01:37:06 25066 8192000000 10266539840 10713128960 1343311728 14.087 98 31 62 0 1361024144 11014768 6697648

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 05-11-2021 01:40:27 to 05-11-2021 01:40:42.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 99 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 05-11-2021 01:40:43 to 05-11-2021 01:40:45.

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          232382
MON$OLDEST_ACTIVE               233262
MON$OLDEST_SNAPSHOT             233262
MON$NEXT_TRANSACTION            233262
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                       2033696
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.33528 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33528 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33528 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33528 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 ---
:49: 
:50: mscs-test-161.budzdorov.ru    Fri Nov  5 01:38:03 2021
:51:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:52: 
:53: 
:54: mscs-test-161.budzdorov.ru    Fri Nov  5 01:38:05 2021
:55:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:56: 
:57: 
:58: mscs-test-161.budzdorov.ru    Fri Nov  5 01:38:16 2021
:59:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:60: 
--- end of diff output ---

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

05.11.21 01:40:46. 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-05 01:40:49 No severe PSQL-related problems occured
05.11.21 01:40:50. End of report.