Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.07.2022 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 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 15777576 kB
MemAvailable 31035460 kB
Buffers 0 kB
Cached 15937220 kB
SwapCached 2516 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-07-28 01:38:21
DTS_END                         2022-07-28 01:38:21.6160
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(113:22) ==> SP_CANCEL_WRITE_OFF(41:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/36238
TRN_ID                          239416
ATT_ID                          620
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: 9925.83 19577 1191099
customer order: creation 176.96 336 21235
customer order: refuse 14.14 353 1697
order to supplier: creation 46.55 1303 5586
order to supplier: removal 3.72 90491 446
invoice (draft): creation 46.41 3166 5569
invoice (draft): removal 59.35 777 7122
invoice accept: apply 42.18 15776 5061
invoice accept: cancel 54.09 679 6491
customer reserve: creation 3915.35 155 469842
customer reserve: removal 5258.99 48 631079
realization accept: apply 83.34 177 10001
realization accept: cancel 93.18 96 11182
payment from customer: creation 60.35 105 7242
payment from customer: removal 5.73 103 687
payment to supplier: creation 49.66 135 5959
payment to supplier: removal 3.30 82 396
service: total inventory turnovers 4.58 1185 549
service: total monetary turnovers 7.08 43 850
service: aggregate perf. data 0.84 21265 101
service: refresh index statistics 0.03 255263 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 28-07-2022 01:39:52 to 28-07-2022 01:39:55.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2540
TEST_TIME 2 4336
TEST_TIME 3 3424
TEST_TIME 4 3082
TEST_TIME 5 3528
TEST_TIME 6 5312
TEST_TIME 7 6043
TEST_TIME 8 7353
TEST_TIME 9 7153
TEST_TIME 10 7111
TEST_TIME 11 7142
TEST_TIME 12 7051
TEST_TIME 13 7253
TEST_TIME 14 7137
TEST_TIME 15 7401
TEST_TIME 16 7662
TEST_TIME 17 7904
TEST_TIME 18 8296
TEST_TIME 19 8533
TEST_TIME 20 8575
TEST_TIME 21 8582
TEST_TIME 22 8648
TEST_TIME 23 8995
TEST_TIME 24 8884
TEST_TIME 25 9219
TEST_TIME 26 9741
TEST_TIME 27 11400
TEST_TIME 28 11116
TEST_TIME 29 11121
TEST_TIME 30 10872
TEST_TIME 31 10868
TEST_TIME 32 10804
TEST_TIME 33 10770
TEST_TIME 34 10702
TEST_TIME 35 10547
TEST_TIME 36 10462
TEST_TIME 37 10484
TEST_TIME 38 10609
TEST_TIME 39 10567
TEST_TIME 40 10582
TEST_TIME 41 10691
TEST_TIME 42 10542
TEST_TIME 43 10598
TEST_TIME 44 10637
TEST_TIME 45 10523
TEST_TIME 46 10424
TEST_TIME 47 10269
TEST_TIME 48 10209
TEST_TIME 49 10258
TEST_TIME 50 10137
TEST_TIME 51 10093
TEST_TIME 52 10059
TEST_TIME 53 10136
TEST_TIME 54 10165
TEST_TIME 55 10122
TEST_TIME 56 10082
TEST_TIME 57 10042
TEST_TIME 58 9895
TEST_TIME 59 9812
TEST_TIME 60 9740
TEST_TIME 61 9923
TEST_TIME 62 9841
TEST_TIME 63 9842
TEST_TIME 64 9881
TEST_TIME 65 9830
TEST_TIME 66 9865
TEST_TIME 67 9931
TEST_TIME 68 9919
TEST_TIME 69 9966
TEST_TIME 70 9954
TEST_TIME 71 10023
TEST_TIME 72 10072
TEST_TIME 73 10009
TEST_TIME 74 10167
TEST_TIME 75 10273
TEST_TIME 76 10278
TEST_TIME 77 10220
TEST_TIME 78 10101
TEST_TIME 79 10071
TEST_TIME 80 10027
TEST_TIME 81 10028
TEST_TIME 82 10024
TEST_TIME 83 9984
TEST_TIME 84 9931
TEST_TIME 85 9871
TEST_TIME 86 9846
TEST_TIME 87 9902
TEST_TIME 88 9885
TEST_TIME 89 9886
TEST_TIME 90 9896
TEST_TIME 91 9918
TEST_TIME 92 10011
TEST_TIME 93 10029
TEST_TIME 94 10063
TEST_TIME 95 10047
TEST_TIME 96 10083
TEST_TIME 97 10082
TEST_TIME 98 10093
TEST_TIME 99 10131
TEST_TIME 100 10145
TEST_TIME 101 10241
TEST_TIME 102 10264
TEST_TIME 103 10193
TEST_TIME 104 10151
TEST_TIME 105 10137
TEST_TIME 106 10085
TEST_TIME 107 10119
TEST_TIME 108 10085
TEST_TIME 109 10081
TEST_TIME 110 10047
TEST_TIME 111 10039
TEST_TIME 112 9983
TEST_TIME 113 9930
TEST_TIME 114 9937
TEST_TIME 115 9954
TEST_TIME 116 9966
TEST_TIME 117 9943
TEST_TIME 118 9976
TEST_TIME 119 9960
TEST_TIME 120 9928

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 28-07-2022 01:39:56 to 28-07-2022 01:39:59.

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 1626089 1626089 0 0.00 0 22434 5 0 0
doc_list_biud 1626089 1626089 0 0.00 0 27133 25 0 0
sp_add_invoice_to_stock 5152 5061 91 1.76 21 188302 15777 0 91
sp_cancel_adding_invoice 6491 6491 0 0.00 5 60513 679 0 0
sp_cancel_client_order 1697 1697 0 0.00 4 8860 354 0 0
sp_cancel_customer_reserve 631079 631079 0 0.00 1 25853 49 0 0
sp_cancel_pay_from_customer 687 687 0 0.00 2 9638 103 0 0
sp_cancel_pay_to_supplier 396 396 0 0.00 2 3677 82 0 0
sp_cancel_supplier_invoice 7129 7122 7 0.09 1 17266 778 0 7
sp_cancel_supplier_order 446 446 0 0.00 5 1068494 90491 0 0
sp_cancel_write_off 11182 11182 0 0.00 1 27143 96 0 0
sp_client_order 21235 21235 0 0.00 3 15258 336 0 0
sp_customer_reserve 469842 469842 0 0.00 0 118141 155 0 0
sp_fill_shopping_cart 32380 32380 0 0.00 1 12632 36 0 0
sp_fill_shopping_cart_clo_res 469838 469838 0 0.00 0 17525 12 0 0
sp_get_clo_for_invoice 5057 5057 0 0.00 0 32227 258 0 0
sp_kill_cost_storno 18756 18756 0 0.00 0 6865 49 0 0
sp_kill_qstorno_ret_qs2qd 1280908 1280908 0 0.00 0 27130 27 0 0
sp_kill_qty_storno 673075 673075 0 0.00 0 27132 59 0 0
sp_lock_selected_doc 20755 20755 0 0.00 0 10464 6 0 0
sp_make_cost_storno 28259 28259 0 0.00 0 18923 59 0 0
sp_multiply_rows_for_pdistr 28259 28259 0 0.00 0 7965 27 0 0
sp_multiply_rows_for_qdistr 21235 21235 0 0.00 0 14960 136 0 0
sp_pay_from_customer 7242 7242 0 0.00 3 17548 105 0 0
sp_pay_to_supplier 5959 5959 0 0.00 4 18984 136 0 0
sp_payment_common 13201 13201 0 0.00 1 17543 33 0 0
sp_qd_handle_on_cancel_clo 1697 1697 0 0.00 0 8853 314 0 0
sp_qd_handle_on_invoice_upd_sts 11548 11548 0 0.00 0 21981 319 0 0
sp_qd_handle_on_reserve_upd_sts 10001 10001 0 0.00 0 18334 60 0 0
sp_reserve_write_off 10001 10001 0 0.00 6 18410 177 0 0
sp_supplier_invoice 5569 5569 0 0.00 5 94660 3167 0 0
sp_supplier_order 5586 5586 0 0.00 4 69064 1304 0 0
srv_aggregate_perf_data 101 101 0 0.00 3039 165861 21266 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1332 107184 45242 0 0
srv_find_qd_qs_mism 1142447 1142447 0 0.00 0 22432 4 0 0
srv_make_invnt_saldo 549 549 0 0.00 58 65455 1186 0 0
srv_make_money_saldo 850 850 0 0.00 1 9915 43 0 0
srv_recalc_idx_stat 4 4 0 0.00 171097 304405 255264 0 0
t$perf-abend:sp_add_invoice_to_stock 91 91 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5061 5061 0 0.00 0 70872 200 0 0
t$perf-norm:sp_cancel_adding_invoice 419 419 0 0.00 0 21492 147 0 0
t$perf-norm:sp_cancel_client_order 1697 1697 0 0.00 0 5948 16 0 0
t$perf-norm:sp_cancel_customer_reserve 685 685 0 0.00 0 1995 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 687 687 0 0.00 0 6828 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 396 396 0 0.00 0 8361 37 0 0
t$perf-norm:sp_cancel_supplier_invoice 392 392 0 0.00 0 2602 19 0 0
t$perf-norm:sp_cancel_supplier_order 446 446 0 0.00 0 42142 1804 0 0
t$perf-norm:sp_cancel_write_off 976 976 0 0.00 0 3482 23 0 0
t$perf-norm:sp_client_order 21235 21235 0 0.00 0 42013 26 0 0
t$perf-norm:sp_customer_reserve 1032 1032 0 0.00 0 1559 5 0 0
t$perf-norm:sp_pay_from_customer 7242 7242 0 0.00 0 5819 13 0 0
t$perf-norm:sp_pay_to_supplier 5959 5959 0 0.00 0 4404 12 0 0
t$perf-norm:sp_reserve_write_off 10001 10001 0 0.00 0 8808 11 0 0
t$perf-norm:sp_supplier_invoice 5569 5569 0 0.00 0 9580 29 0 0
t$perf-norm:sp_supplier_order 5586 5586 0 0.00 0 18340 18 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 1219 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 90 2 0 0
t$perf-norm:srv_make_invnt_saldo 549 549 0 0.00 0 2019 32 0 0
t$perf-norm:srv_make_money_saldo 850 850 0 0.00 0 44078 78 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 2 0 0
v_add_invoice_to_stock 10294 10112 182 1.76 0 6805 18 0 182
v_all_customers 39092 39092 0 0.00 0 66 1 0 0
v_all_suppliers 30982 30982 0 0.00 0 69 1 0 0
v_all_wares 42470 42470 0 0.00 0 29 1 0 0
v_cancel_adding_invoice 838 838 0 0.00 0 2165 9 0 0
v_cancel_client_order 3394 3394 0 0.00 0 5211 7 0 0
v_cancel_customer_prepayment 1374 1374 0 0.00 0 3653 7 0 0
v_cancel_customer_reserve 1370 1370 0 0.00 0 623 4 0 0
v_cancel_payment_to_supplier 792 792 0 0.00 0 1351 5 0 0
v_cancel_supplier_invoice 798 784 14 1.75 0 987 13 0 14
v_cancel_supplier_order 892 892 0 0.00 0 2067 6 0 0
v_cancel_write_off 1952 1952 0 0.00 0 2191 13 0 0
v_max_id_clo_ord 5584 5584 0 0.00 0 1201 2 0 0
v_max_id_clo_res 1032 1032 0 0.00 0 1772 10 0 0
v_max_id_ord_sup 5561 5561 0 0.00 0 3052 4 0 0
v_max_non_paid_invoice 1613 1613 0 0.00 0 2416 4 0 0
v_max_non_paid_realizn 3752 3752 0 0.00 0 5798 7 0 0
v_min_id_clo_ord 5584 5584 0 0.00 0 8192 14 0 0
v_min_id_clo_res 1032 1032 0 0.00 0 6861 22 0 0
v_min_id_ord_sup 5561 5561 0 0.00 0 6487 4 0 0
v_min_non_paid_invoice 5959 5959 0 0.00 0 6679 21 0 0
v_min_non_paid_realizn 7242 7242 0 0.00 0 17539 24 0 0
v_our_firm 10358 10358 0 0.00 0 146 1 0 0
v_reserve_write_off 20002 20002 0 0.00 0 3903 4 0 0
x_lock_depdocs_on_canc_invoice 13221 13221 0 0.00 0 26623 101 0 0
x_lock_depdocs_on_canc_sup_ord 446 446 0 0.00 0 8881 318 0 0
x_make_qstorno_1000_1200 5586 5586 0 0.00 1 69052 1224 0 0
x_make_qstorno_1200_2000 5569 5569 0 0.00 2 94621 3042 0 0
x_make_qstorno_2100_3300 469842 469842 0 0.00 0 118136 141 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-07-2022 01:40:00 to 28-07-2022 01:40:04.

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:25 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:26 32249 8192000000 9903365088 10217107456 988050880 10.763 100 87 174 0 1017596384 6289072 23256432
23:11:02 23708 8192000000 9951198816 10274463744 1031482368 11.183 100 89 178 0 1062997552 8626416 22888768
23:12:34 21131 8192000000 9994811584 10332192768 1074871968 11.599 100 79 156 0 1103568464 9515568 19180928
23:14:03 8248 8192000000 10046437104 10394136576 1130538112 12.126 100 78 156 0 1159976224 11344000 18094112
23:15:16 7273 8192000000 10084763104 10445979648 1170119088 12.498 100 78 154 0 1200127824 12685696 17323040
23:16:26 3537 8192000000 10127697248 10497482752 1214502688 12.911 100 87 172 0 1247244928 13438192 19304048
23:17:33 2323 8192000000 10152844160 10529902592 1247382288 13.214 100 86 170 0 1278602992 12384240 18836464
23:18:39 957 8192000000 10196120096 10578112512 1275656512 13.473 100 89 179 0 1310254352 14911984 19685856
23:19:40 2012 8192000000 10199470208 10593886208 1296327072 13.662 100 68 136 2 1323395344 12533168 14535104
23:20:44 3179 8192000000 10201099504 10606657536 1309662208 13.783 100 47 95 1 1331966368 12298192 10005968
23:21:48 20949 8192000000 10208622928 10612248576 1312666192 13.810 100 52 103 1 1329522208 5803328 11052688
23:23:09 6275 8192000000 10206168480 10620788736 1315585712 13.837 100 33 65 0 1330031984 7509904 6936368
23:24:19 8345 8192000000 10207485792 10626699264 1317061040 13.850 100 22 45 0 1327980576 6295296 4624240
23:25:30 9823 8192000000 10215011488 10633768960 1325505968 13.927 100 18 36 0 1335350656 5640720 4203968
23:26:41 24287 8192000000 10218149312 10642939904 1327531616 13.945 100 37 74 1 1341704752 5747936 8425200
23:28:07 5445 8192000000 10222311968 10644168704 1329293888 13.961 100 23 46 0 1340370832 5838288 5238656
23:29:13 7675 8192000000 10189298080 10603507712 1258328064 13.315 95 17 34 1 1271268480 9248816 3691600
23:30:23 61392 8192000000 10154998144 10561753088 1205474736 12.827 93 34 68 0 1219350192 4988048 8887408
23:32:24 6517 8192000000 10139690880 10537443328 1233127648 13.083 98 28 56 1 1245721536 6142912 6450976
23:33:34 8633 8192000000 10140892176 10543071232 1223690240 12.996 97 26 52 1 1234753040 5372848 5689952
23:34:45 8241 8192000000 10168682944 10572312576 1268549424 13.408 99 19 38 0 1278091440 5082976 4459040
23:35:56 35158 8192000000 10176760976 10593247232 1291252336 13.616 100 19 36 1 1300749600 5407552 4089712
23:37:37 7677 8192000000 10191332880 10609311744 1305371520 13.744 100 21 43 0 1315110944 5360608 4378816
23:38:57 14786 8192000000 10236445664 10650677248 1338288128 14.042 100 41 80 0 1353232992 6090832 8854032
23:40:41 9999 8192000000 10280209760 10691592192 1370738864 14.334 100 41 82 1 1389802176 9582192 9481120
23:41:54 101915 8192000000 10275069536 10712399872 1374487280 14.367 100 29 58 0 1396678080 15186432 7004368
23:44:42 12331 8192000000 10295100896 10717728768 1380302128 14.419 100 39 78 0 1405406576 15951296 9153152
23:46:00 1331 8192000000 10327101392 10738905088 1393347968 14.536 100 86 168 1 1433662864 21116848 19198048
23:47:04 15308 8192000000 10330014496 10753683456 1407218304 14.659 100 57 115 0 1436525600 15960528 13346768
23:48:32 3091 8192000000 10345483040 10771628032 1419617584 14.769 100 59 115 1 1454968080 22028896 13321600
23:49:39 4987 8192000000 10355699216 10780467200 1427803568 14.842 100 59 115 0 1465316912 24061792 13451552
23:50:50 5331 8192000000 10366689632 10793189376 1420567952 14.778 99 64 128 0 1467724464 30984032 16172480
23:52:01 4448 8192000000 10375193600 10798882816 1435920096 14.914 100 53 105 0 1483174672 34232752 13021824
23:53:07 1577 8192000000 10400107632 10818760704 1442523072 14.972 100 81 159 0 1502682512 40811328 19348112
23:54:14 1359 8192000000 10397906368 10815213568 1434428112 14.900 100 90 178 3 1502756032 45448896 22879024
23:55:19 2281 8192000000 10370449520 10778824704 1403307104 14.624 98 82 165 0 1470738992 46991376 20440512
23:56:26 6195 8192000000 10341127120 10760200192 1387884112 14.487 100 65 124 2 1446372784 43049552 15439120
23:57:36 1706 8192000000 10336136832 10748817408 1380695024 14.423 100 66 128 1 1441689888 44717648 16277216
23:58:41 5737 8192000000 10314950752 10736992256 1357858448 14.218 99 48 95 0 1413732624 42687248 13186928
23:59:58 13563 8192000000 10324388800 10746761216 1367507056 14.305 100 47 94 0 1427493360 46983536 13002768
00:01:22 12798 8192000000 10323690240 10745425920 1373155632 14.355 99 34 68 1 1425806512 43309440 9341440
00:02:47 13171 8192000000 10338249328 10766815232 1375193408 14.374 100 28 56 0 1441097600 57159632 8744560
00:04:03 16188 8192000000 10343061456 10778542080 1365610000 14.288 100 32 63 0 1441420576 66023600 9786976
00:05:22 67108 8192000000 10285138368 10733092864 1363555600 14.269 99 17 34 0 1409110976 40492128 5063248
00:07:31 59261 8192000000 10298419344 10739490816 1366240064 14.293 99 8 17 0 1375555632 7806992 1508576
00:09:31 74088 8192000000 10298195504 10757009408 1381913312 14.434 99 14 26 1 1392296176 7731248 2651616
00:12:42 55808 8192000000 10335886944 10766786560 1395589552 14.556 99 49 92 0 1415745456 9559648 10596256
00:14:42 45616 8192000000 10319411808 10773434368 1391468336 14.519 99 18 36 0 1409309040 13508208 4332496
00:16:31 107184 8192000000 10324747824 10787713024 1388280640 14.491 99 7 14 0 1418895808 28793248 1821920
00:19:18 91027 8192000000 10306976352 10754576384 1356686144 14.208 97 11 23 0 1374901360 15941920 2273296
00:21:50 51275 8192000000 10292810944 10750984192 1363759296 14.271 98 6 12 0 1378315456 13131776 1424384
00:23:42 106335 8192000000 10310457744 10758041600 1394286448 14.544 100 27 54 1 1414542400 13746464 6509488
00:26:47 14340 8192000000 10317312192 10763247616 1396599792 14.565 100 56 110 2 1418260240 9279952 12380496
00:28:03 76970 8192000000 10310565920 10764218368 1391433456 14.519 99 21 42 0 1405157584 9192880 4531248
00:30:21 25916 8192000000 10305357360 10757160960 1392456544 14.528 100 34 68 1 1410290704 8160384 9673776
00:31:49 43141 8192000000 10294157696 10763460608 1378864624 14.406 99 12 24 0 1390230256 8729536 2636096
00:33:32 77516 8192000000 10274874656 10736717824 1345072768 14.103 97 12 24 0 1358920656 11425184 2422704
00:35:50 70881 8192000000 10275371456 10733723648 1340040896 14.058 96 9 18 0 1351686608 9418496 2227216
00:38:09 77099 8192000000 10301317008 10767679488 1372984192 14.354 98 30 60 0 1392283552 13166576 6132784
00:40:30 67000 8192000000 10267990112 10731134976 1323608432 13.909 95 15 30 0 1334305392 7528672 3168288
00:42:38 24282 8192000000 10282925472 10738302976 1352341936 14.169 98 20 40 0 1366432624 9404848 4685840
00:44:05 26263 8192000000 10255908576 10696708096 1275672944 13.473 94 43 84 0 1297280528 11220016 10387568
00:45:40 62889 8192000000 10232648112 10665893888 1224573424 13.004 91 20 40 1 1238443376 9106032 4763920
00:47:50 36888 8192000000 10263170096 10696892416 1311655792 13.801 98 34 66 1 1328389072 8905792 7827488
00:49:28 96345 8192000000 10284035264 10735181824 1348978672 14.138 99 15 30 1 1378951712 26693856 3279184
00:52:04 67736 8192000000 10272325104 10721579008 1323076096 13.905 97 5 10 0 1335924304 11780224 1067984
00:54:32 76926 8192000000 10267464832 10722222080 1329818320 13.966 97 10 20 0 1343101536 11058496 2224720
00:56:53 46218 8192000000 10286081488 10733211648 1358548864 14.224 99 29 58 0 1375757008 11125344 6082800
00:58:40 37904 8192000000 10288692256 10744049664 1364299152 14.276 99 21 42 1 1384016288 14804256 4912880
01:00:18 44212 8192000000 10282543360 10743132160 1385843456 14.469 100 17 34 0 1397445472 7733952 3868064
01:02:03 48848 8192000000 10294949728 10757062656 1389910192 14.505 100 13 26 1 1406906256 14181216 2814848
01:04:47 77336 8192000000 10281136416 10752720896 1383862768 14.451 100 19 38 0 1396703232 8398560 4441904
01:07:04 53290 8192000000 10299472608 10759360512 1387332368 14.482 100 17 34 0 1400456240 9349616 3774256
01:08:58 55942 8192000000 10316588880 10788007936 1399463712 14.590 100 23 46 0 1426379104 21270928 5644464
01:10:54 80344 8192000000 10295868656 10757881856 1331040416 13.977 95 7 12 0 1343573888 11032416 1501056
01:13:15 59039 8192000000 10318924256 10765619200 1390472832 14.510 99 26 52 0 1410180976 13262224 6445920
01:15:18 44483 8192000000 10322076128 10781376512 1406619904 14.654 100 27 54 0 1426684128 14221712 5842512
01:17:03 62438 8192000000 10309498176 10765520896 1363091680 14.265 97 7 14 0 1375755040 11209888 1453472
01:19:13 51055 8192000000 10316519328 10788614144 1376819072 14.388 98 18 36 0 1405455664 24313248 4323344
01:21:04 89795 8192000000 10292303664 10752102400 1385472640 14.465 99 12 24 0 1402002704 13960496 2569568
01:23:38 51184 8192000000 10295249680 10764906496 1384320656 14.455 99 20 40 0 1404151824 15148928 4682240
01:25:52 72281 8192000000 10299909632 10777378816 1350420240 14.151 96 7 14 0 1368510112 16499888 1589984
01:28:04 60145 8192000000 10271186752 10741510144 1333605968 14.000 96 4 8 0 1342582304 8025856 950480
01:30:10 58928 8192000000 10272766144 10729631744 1327247136 13.942 96 21 42 2 1340855776 8512400 5096240
01:32:37 80210 8192000000 10286782560 10772545536 1380058928 14.417 100 9 18 0 1399462080 17209536 2193616
01:35:09 45073 8192000000 10267219456 10728673280 1329243552 13.960 97 9 18 0 1344509456 13429872 1836032
01:36:55 26610 8192000000 10263413728 10719006720 1354829312 14.191 100 11 22 0 1368370800 11238608 2302880

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 28-07-2022 01:40:06 to 28-07-2022 01:40:21.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 91 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-07-2022 01:40:22 to 28-07-2022 01:40:24.

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          238157
MON$OLDEST_ACTIVE               239721
MON$OLDEST_SNAPSHOT             239721
MON$NEXT_TRANSACTION            239721
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                       2058848
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.33613 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33613 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33613 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33613 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 ---
:353: 
:354: mscs-test-161.budzdorov.ru    Thu Jul 28 01:38:22 2022
:355:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:356: 
:357: 
:358: mscs-test-161.budzdorov.ru    Thu Jul 28 01:38:23 2022
:359:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:360: 
:361: 
:362: mscs-test-161.budzdorov.ru    Thu Jul 28 01:38:35 2022
:363:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:364: 
:365: 
:366: mscs-test-161.budzdorov.ru    Thu Jul 28 01:38:36 2022
:367:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:368: 
--- end of diff output ---

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

28.07.22 01:40:25. 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-07-28 01:40:27 No severe PSQL-related problems occured
28.07.22 01:40:28. End of report.