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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16624744 kB
MemAvailable 31314036 kB
Buffers 0 kB
Cached 15030160 kB
SwapCached 1232 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.9
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-01-01 22:38:00
DTS_END                         2022-01-01 22:38:00.0750
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/52004
TRN_ID                          237790
ATT_ID                          713
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: 8955.28 22275 1074634
customer order: creation 168.18 374 20181
customer order: refuse 14.42 379 1730
order to supplier: creation 45.68 1323 5481
order to supplier: removal 3.33 109176 399
invoice (draft): creation 44.97 3290 5396
invoice (draft): removal 49.68 819 5962
invoice accept: apply 40.61 20512 4873
invoice accept: cancel 45.18 871 5422
customer reserve: creation 3761.05 204 451326
customer reserve: removal 4491.93 63 539032
realization accept: apply 81.54 191 9785
realization accept: cancel 83.17 118 9980
payment from customer: creation 56.03 115 6724
payment from customer: removal 5.72 99 686
payment to supplier: creation 47.46 126 5695
payment to supplier: removal 3.77 57 452
service: total inventory turnovers 5.18 1170 621
service: total monetary turnovers 6.58 42 789
service: aggregate perf. data 0.80 18549 96
service: refresh index statistics 0.03 288013 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 01-01-2022 22:38:56 to 01-01-2022 22:38:58.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5002
TEST_TIME 2 3348
TEST_TIME 3 3571
TEST_TIME 4 4322
TEST_TIME 5 4408
TEST_TIME 6 5461
TEST_TIME 7 5345
TEST_TIME 8 6030
TEST_TIME 9 5955
TEST_TIME 10 6338
TEST_TIME 11 6791
TEST_TIME 12 6831
TEST_TIME 13 7016
TEST_TIME 14 7275
TEST_TIME 15 7220
TEST_TIME 16 7900
TEST_TIME 17 8086
TEST_TIME 18 8119
TEST_TIME 19 8561
TEST_TIME 20 8364
TEST_TIME 21 9153
TEST_TIME 22 9081
TEST_TIME 23 9129
TEST_TIME 24 9209
TEST_TIME 25 9172
TEST_TIME 26 9391
TEST_TIME 27 9456
TEST_TIME 28 9790
TEST_TIME 29 10587
TEST_TIME 30 10754
TEST_TIME 31 10908
TEST_TIME 32 10853
TEST_TIME 33 10634
TEST_TIME 34 10441
TEST_TIME 35 10285
TEST_TIME 36 10146
TEST_TIME 37 10024
TEST_TIME 38 9956
TEST_TIME 39 9937
TEST_TIME 40 9880
TEST_TIME 41 9701
TEST_TIME 42 9805
TEST_TIME 43 9789
TEST_TIME 44 9705
TEST_TIME 45 9585
TEST_TIME 46 9634
TEST_TIME 47 9590
TEST_TIME 48 9495
TEST_TIME 49 9457
TEST_TIME 50 9401
TEST_TIME 51 9296
TEST_TIME 52 9182
TEST_TIME 53 9103
TEST_TIME 54 9135
TEST_TIME 55 9015
TEST_TIME 56 9160
TEST_TIME 57 9133
TEST_TIME 58 9096
TEST_TIME 59 9137
TEST_TIME 60 9092
TEST_TIME 61 9084
TEST_TIME 62 9200
TEST_TIME 63 9183
TEST_TIME 64 9184
TEST_TIME 65 9186
TEST_TIME 66 9165
TEST_TIME 67 9230
TEST_TIME 68 9254
TEST_TIME 69 9242
TEST_TIME 70 9262
TEST_TIME 71 9230
TEST_TIME 72 9271
TEST_TIME 73 9289
TEST_TIME 74 9417
TEST_TIME 75 9297
TEST_TIME 76 9241
TEST_TIME 77 9172
TEST_TIME 78 9141
TEST_TIME 79 9114
TEST_TIME 80 9126
TEST_TIME 81 9056
TEST_TIME 82 9097
TEST_TIME 83 9061
TEST_TIME 84 8972
TEST_TIME 85 9108
TEST_TIME 86 9090
TEST_TIME 87 9055
TEST_TIME 88 9048
TEST_TIME 89 9069
TEST_TIME 90 9029
TEST_TIME 91 9070
TEST_TIME 92 9075
TEST_TIME 93 9105
TEST_TIME 94 9142
TEST_TIME 95 9128
TEST_TIME 96 9217
TEST_TIME 97 9241
TEST_TIME 98 9254
TEST_TIME 99 9204
TEST_TIME 100 9214
TEST_TIME 101 9202
TEST_TIME 102 9122
TEST_TIME 103 9119
TEST_TIME 104 9088
TEST_TIME 105 9067
TEST_TIME 106 9021
TEST_TIME 107 9028
TEST_TIME 108 8998
TEST_TIME 109 8962
TEST_TIME 110 8948
TEST_TIME 111 8965
TEST_TIME 112 8945
TEST_TIME 113 8961
TEST_TIME 114 8967
TEST_TIME 115 8941
TEST_TIME 116 8954
TEST_TIME 117 9003
TEST_TIME 118 8980
TEST_TIME 119 9031
TEST_TIME 120 8955

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 01-01-2022 22:38:59 to 01-01-2022 22:39:03.

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 1494102 1494102 0 0.00 0 29338 7 0 0
doc_list_biud 1494102 1494102 0 0.00 0 37663 29 0 0
sp_add_invoice_to_stock 4967 4873 94 1.89 72 201247 20512 0 94
sp_cancel_adding_invoice 5422 5422 0 0.00 6 75846 872 0 0
sp_cancel_client_order 1730 1730 0 0.00 3 17776 380 0 0
sp_cancel_customer_reserve 539032 539032 0 0.00 1 23657 64 0 0
sp_cancel_pay_from_customer 686 686 0 0.00 3 15955 100 0 0
sp_cancel_pay_to_supplier 452 452 0 0.00 3 1948 57 0 0
sp_cancel_supplier_invoice 5974 5962 12 0.20 2 21221 820 0 12
sp_cancel_supplier_order 399 399 0 0.00 7 931237 109177 0 0
sp_cancel_write_off 9980 9980 0 0.00 1 9180 119 0 0
sp_client_order 20181 20181 0 0.00 3 71044 374 0 0
sp_customer_reserve 451326 451326 0 0.00 0 78305 204 0 0
sp_fill_shopping_cart 31052 31052 0 0.00 1 12207 41 0 0
sp_fill_shopping_cart_clo_res 451323 451323 0 0.00 0 24624 14 0 0
sp_get_clo_for_invoice 4870 4870 0 0.00 0 20735 301 0 0
sp_kill_cost_storno 16540 16540 0 0.00 0 7616 58 0 0
sp_kill_qstorno_ret_qs2qd 1094405 1094405 0 0.00 0 23576 34 0 0
sp_kill_qty_storno 577180 577180 0 0.00 0 37663 74 0 0
sp_lock_selected_doc 20320 20320 0 0.00 0 10089 4 0 0
sp_make_cost_storno 27074 27074 0 0.00 0 27650 56 0 0
sp_multiply_rows_for_pdistr 27074 27074 0 0.00 0 6231 29 0 0
sp_multiply_rows_for_qdistr 20181 20181 0 0.00 0 71034 166 0 0
sp_pay_from_customer 6724 6724 0 0.00 4 10618 115 0 0
sp_pay_to_supplier 5695 5695 0 0.00 5 20854 126 0 0
sp_payment_common 12419 12419 0 0.00 2 7194 28 0 0
sp_qd_handle_on_cancel_clo 1730 1730 0 0.00 0 17769 344 0 0
sp_qd_handle_on_invoice_upd_sts 10292 10292 0 0.00 0 37660 367 0 0
sp_qd_handle_on_reserve_upd_sts 9785 9785 0 0.00 0 25302 67 0 0
sp_reserve_write_off 9785 9785 0 0.00 6 25312 192 0 0
sp_supplier_invoice 5396 5396 0 0.00 6 81202 3291 0 0
sp_supplier_order 5481 5481 0 0.00 5 41100 1323 0 0
srv_aggregate_perf_data 96 96 0 0.00 2887 214572 18550 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 2382 124994 49159 0 0
srv_find_qd_qs_mism 1028867 1028867 0 0.00 0 29336 6 0 0
srv_make_invnt_saldo 621 621 0 0.00 44 105210 1170 0 0
srv_make_money_saldo 789 789 0 0.00 1 12386 42 0 0
srv_recalc_idx_stat 4 4 0 0.00 191891 405833 288013 0 0
t$perf-abend:sp_add_invoice_to_stock 94 94 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4873 4873 0 0.00 0 13911 192 0 0
t$perf-norm:sp_cancel_adding_invoice 407 407 0 0.00 0 23287 144 0 0
t$perf-norm:sp_cancel_client_order 1730 1730 0 0.00 0 2351 12 0 0
t$perf-norm:sp_cancel_customer_reserve 617 617 0 0.00 0 1145 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 686 686 0 0.00 0 6204 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 452 452 0 0.00 0 1372 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 400 400 0 0.00 0 3834 26 0 0
t$perf-norm:sp_cancel_supplier_order 399 399 0 0.00 0 24609 1818 0 0
t$perf-norm:sp_cancel_write_off 977 977 0 0.00 0 3320 7 0 0
t$perf-norm:sp_client_order 20181 20181 0 0.00 0 17286 20 0 0
t$perf-norm:sp_customer_reserve 963 963 0 0.00 0 10688 15 0 0
t$perf-norm:sp_pay_from_customer 6724 6724 0 0.00 0 13805 13 0 0
t$perf-norm:sp_pay_to_supplier 5695 5695 0 0.00 0 11164 10 0 0
t$perf-norm:sp_reserve_write_off 9785 9785 0 0.00 0 6440 11 0 0
t$perf-norm:sp_supplier_invoice 5396 5396 0 0.00 0 12988 24 0 0
t$perf-norm:sp_supplier_order 5481 5481 0 0.00 0 9060 23 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 324 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 97 4 0 0
t$perf-norm:srv_make_invnt_saldo 621 621 0 0.00 0 873 22 0 0
t$perf-norm:srv_make_money_saldo 789 789 0 0.00 0 6207 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 3 0 0
v_add_invoice_to_stock 9922 9734 188 1.89 0 8596 19 0 188
v_all_customers 36486 36486 0 0.00 0 107 1 0 0
v_all_suppliers 29780 29780 0 0.00 0 19 1 0 0
v_all_wares 40356 40356 0 0.00 0 14 1 0 0
v_cancel_adding_invoice 814 814 0 0.00 0 410 6 0 0
v_cancel_client_order 3460 3460 0 0.00 0 3503 7 0 0
v_cancel_customer_prepayment 1372 1372 0 0.00 0 8896 14 0 0
v_cancel_customer_reserve 1234 1234 0 0.00 0 4238 7 0 0
v_cancel_payment_to_supplier 904 904 0 0.00 0 1934 7 0 0
v_cancel_supplier_invoice 824 800 24 2.91 0 1571 17 0 24
v_cancel_supplier_order 798 798 0 0.00 0 261 3 0 0
v_cancel_write_off 1954 1954 0 0.00 0 9052 19 0 0
v_max_id_clo_ord 5479 5479 0 0.00 0 12108 4 0 0
v_max_id_clo_res 963 963 0 0.00 0 2124 10 0 0
v_max_id_ord_sup 5395 5395 0 0.00 0 2877 5 0 0
v_max_non_paid_invoice 1679 1679 0 0.00 0 330 2 0 0
v_max_non_paid_realizn 3704 3704 0 0.00 0 2779 6 0 0
v_min_id_clo_ord 5479 5479 0 0.00 0 3029 8 0 0
v_min_id_clo_res 963 963 0 0.00 0 7149 15 0 0
v_min_id_ord_sup 5395 5395 0 0.00 0 7089 10 0 0
v_min_non_paid_invoice 5695 5695 0 0.00 0 5134 20 0 0
v_min_non_paid_realizn 6724 6724 0 0.00 0 5533 20 0 0
v_our_firm 9910 9910 0 0.00 0 14 1 0 0
v_reserve_write_off 19570 19570 0 0.00 0 8212 7 0 0
x_lock_depdocs_on_canc_invoice 10984 10984 0 0.00 0 31400 152 0 0
x_lock_depdocs_on_canc_sup_ord 399 399 0 0.00 0 5253 367 0 0
x_make_qstorno_1000_1200 5481 5481 0 0.00 2 41069 1234 0 0
x_make_qstorno_1200_2000 5396 5396 0 0.00 2 81173 3150 0 0
x_make_qstorno_2100_3300 451326 451326 0 0.00 0 78304 188 0 0

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

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
20:08:03 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:05 26274 8192000000 9894611696 10205855744 985703744 10.740 100 86 172 0 1014053472 5830080 22519648
20:10:46 20924 8192000000 9940907680 10263674880 1022577344 11.097 100 85 170 0 1053046576 8734512 21734720
20:12:12 23455 8192000000 9984161376 10319077376 1063535616 11.490 100 78 156 0 1092730128 10125744 19068768
20:13:37 9286 8192000000 10053579936 10408300544 1138165920 12.198 100 76 152 2 1167394224 11360640 17867664
20:14:49 6327 8192000000 10080348576 10443579392 1171759568 12.513 100 71 142 1 1199791040 11645136 16386336
20:15:58 4717 8192000000 10131206256 10498568192 1216273824 12.927 100 79 158 2 1247681984 14222224 17185936
20:17:04 2856 8192000000 10155383088 10537803776 1251966176 13.256 100 77 152 2 1280917440 12090848 16860416
20:18:09 2568 8192000000 10205354176 10587090944 1287560080 13.582 100 91 183 3 1321265328 12444880 21260368
20:19:13 7302 8192000000 10217125744 10607501312 1305413680 13.744 100 88 178 3 1337921392 11763552 20744160
20:20:22 6010 8192000000 10197748976 10609364992 1312798288 13.811 100 51 102 3 1332596736 7776384 12022064
20:21:28 14325 8192000000 10221788256 10626883584 1318110272 13.860 100 59 118 0 1337872448 6114256 13647920
20:22:43 6285 8192000000 10208849344 10629660672 1320605888 13.882 100 38 75 2 1335531392 6035024 8890480
20:23:52 8607 8192000000 10228060912 10648502272 1331153712 13.978 100 24 48 1 1342567344 6382064 5031568
20:25:03 5097 8192000000 10243550448 10650853376 1339541520 14.053 100 51 105 0 1355950848 5068432 11340896
20:26:09 1444 8192000000 10261115008 10662805504 1339268288 14.051 100 92 186 1 1371273840 10396240 21609312
20:27:10 7020 8192000000 10219802064 10646962176 1324541008 13.918 99 38 76 1 1348029152 13508448 9979696
20:28:20 7181 8192000000 10172510176 10586013696 1269452928 13.417 98 17 34 2 1278870576 5625744 3791904
20:29:30 44908 8192000000 10167511440 10577625088 1203660544 12.810 93 31 62 1 1219986240 9134832 7190864
20:31:15 8513 8192000000 10145207888 10536685568 1202025088 12.795 96 39 78 1 1215874192 4344320 9504784
20:32:29 8591 8192000000 10131823312 10534617088 1233767792 13.089 99 22 44 1 1245148112 6367760 5012560
20:33:38 68052 8192000000 10171084400 10581393408 1275010416 13.467 100 23 46 0 1285840880 5761872 5068592
20:35:47 7492 8192000000 10192622928 10602721280 1299913296 13.694 100 30 58 2 1313485872 7094416 6478160
20:36:55 15327 8192000000 10190971952 10610810880 1304385792 13.735 100 15 30 0 1313403936 5328592 3689552
20:38:16 15048 8192000000 10226479008 10630569984 1321638112 13.892 100 33 65 1 1334845328 5385120 7822096
20:39:47 78569 8192000000 10263086544 10677010432 1351936128 14.165 100 54 108 3 1377563376 13360768 12266480
20:42:22 36575 8192000000 10273683696 10697146368 1367161360 14.302 100 37 73 2 1390835184 14986432 8687392
20:44:05 12209 8192000000 10288603840 10710372352 1374201888 14.365 100 42 84 2 1405010816 20761152 10047776
20:45:21 5164 8192000000 10310902368 10725675008 1383266992 14.446 100 63 126 0 1418227152 20588832 14371328
20:46:32 8489 8192000000 10309826432 10735951872 1389432496 14.501 100 47 95 2 1422556928 21335456 11788976
20:47:43 8004 8192000000 10324046128 10746445824 1385917952 14.469 99 67 131 0 1426411168 24571552 15921664
20:48:56 3377 8192000000 10343789232 10762653696 1408341152 14.669 100 72 142 1 1448232640 22905728 16985760
20:50:06 2518 8192000000 10318283120 10733969408 1393277824 14.535 100 70 139 1 1428599104 19231440 16089840
20:51:14 4338 8192000000 10313096096 10727018496 1381540480 14.430 99 67 133 4 1421123088 23106832 16475776
20:52:23 5306 8192000000 10329471200 10747269120 1397173264 14.570 100 64 128 0 1439012112 26172192 15666656
20:53:33 2374 8192000000 10341469360 10751074304 1390168784 14.507 100 75 148 1 1441129008 32514112 18446112
20:54:46 5257 8192000000 10308862944 10713313280 1364009312 14.273 100 72 144 1 1412017072 30797856 17209904
20:55:57 4964 8192000000 10317093216 10723254272 1363825568 14.272 100 71 142 0 1416746704 35330352 17590784
20:57:08 3338 8192000000 10344296368 10758434816 1390172704 14.507 100 60 120 1 1446569248 40793648 15602896
20:58:22 16177 8192000000 10312942800 10728022016 1360280144 14.240 100 49 96 1 1416524336 42749312 13494880
20:59:41 7558 8192000000 10296184400 10717978624 1350096496 14.148 99 37 74 0 1394275792 34125040 10054256
21:01:01 4960 8192000000 10329423616 10744696832 1377334400 14.393 100 55 110 2 1432119072 40398352 14386320
21:02:13 23373 8192000000 10322560880 10753560576 1380723344 14.423 100 30 60 2 1426881200 38021920 8135936
21:04:03 19445 8192000000 10321171776 10752434176 1370462576 14.331 100 28 56 0 1424045968 45741728 7841664
21:05:34 20123 8192000000 10309634880 10739204096 1344901376 14.102 99 23 46 0 1406988080 55101840 6984864
21:06:57 47369 8192000000 10291621616 10736312320 1336115776 14.022 99 19 38 0 1393616448 51952160 5548512
21:08:50 44352 8192000000 10272163552 10708946944 1343423280 14.088 99 17 34 1 1360913440 13650336 3839824
21:10:47 62019 8192000000 10243511376 10677633024 1320492960 13.881 97 12 24 0 1335062416 11536272 3033184
21:12:58 66346 8192000000 10277881648 10724282368 1355606752 14.198 99 24 48 0 1370965056 9614480 5743824
21:15:48 91129 8192000000 10292664256 10751127552 1369040768 14.318 99 29 59 0 1397619088 21073696 7504624
21:18:27 87985 8192000000 10303535728 10754326528 1390838688 14.513 100 9 18 1 1410770016 17803088 2128240
21:20:59 49848 8192000000 10306912528 10751156224 1391637728 14.520 100 38 75 0 1407364368 7366048 8360592
21:22:53 51785 8192000000 10304432896 10753232896 1381694720 14.432 99 27 53 2 1399489680 11958128 5836832
21:25:00 44166 8192000000 10281519136 10746372096 1385081776 14.462 100 10 20 0 1397209920 9559936 2568208
21:27:03 16433 8192000000 10303633632 10751283200 1395039536 14.551 100 63 124 0 1418145424 8082288 15023600
21:28:26 85168 8192000000 10320376896 10776915968 1406170224 14.650 100 12 24 0 1423360640 14723440 2466976
21:31:31 78861 8192000000 10316197248 10796548096 1411882976 14.701 100 13 26 2 1433007328 17744336 3380016
21:33:58 73790 8192000000 10290827520 10761912320 1386214336 14.472 98 9 18 2 1399511360 11159072 2137952
21:36:23 124994 8192000000 10296009216 10765414400 1371930400 14.344 97 10 20 2 1385619248 11404672 2284176
21:39:28 37426 8192000000 10298803584 10753437696 1356368464 14.205 96 28 56 1 1373139232 10457744 6313024
21:41:13 39134 8192000000 10301548768 10765557760 1373633040 14.360 98 17 34 1 1387801952 10073872 4095040
21:42:56 52799 8192000000 10292918368 10741010432 1312339968 13.807 94 39 78 2 1329504480 8641824 8522688
21:44:49 16986 8192000000 10274230112 10717810688 1354433216 14.187 100 36 72 0 1374076304 11836768 7806320
21:46:09 113512 8192000000 10273902032 10722033664 1282019040 13.531 93 26 52 0 1299555664 11952880 5583744
21:49:06 53557 8192000000 10254629312 10694823936 1317973792 13.858 97 20 40 0 1336417216 14543600 3899824
21:51:04 54909 8192000000 10281926592 10721140736 1342331808 14.078 99 27 54 1 1366018240 17604112 6082320
21:53:00 124183 8192000000 10258274928 10702082048 1321223296 13.888 97 10 20 0 1334121376 10818672 2079408
21:56:49 89405 8192000000 10250159552 10703966208 1336286256 14.024 98 13 26 0 1351339344 11836752 3216336
21:59:21 66213 8192000000 10262245152 10710126592 1338181728 14.041 98 14 28 0 1351820272 10069936 3568608
22:01:46 82658 8192000000 10263174384 10724102144 1349248448 14.141 99 12 24 1 1379261840 26894832 3118560
22:04:16 61778 8192000000 10236243232 10686435328 1326019648 13.931 98 20 40 0 1341229120 9948720 5260752
22:06:19 71663 8192000000 10231724928 10682601472 1302852912 13.721 97 7 14 0 1317048096 12668784 1526400
22:09:02 119170 8192000000 10254265136 10708230144 1298535264 13.682 95 17 34 0 1315699312 13633504 3530544
22:12:01 42064 8192000000 10280819824 10725097472 1355563536 14.198 99 29 58 2 1377594320 15874240 6156544
22:13:44 23632 8192000000 10311195232 10751668224 1389098080 14.498 100 49 98 1 1416065264 16362320 10604864
22:15:37 109432 8192000000 10309288704 10756128768 1377746832 14.396 99 21 42 0 1403030832 20569408 4714592
22:18:31 74108 8192000000 10278172816 10734125056 1356900336 14.210 98 26 52 2 1373872128 11575424 5396368
22:20:45 106532 8192000000 10269128528 10718863360 1342965280 14.084 97 23 46 1 1356907792 9033312 4909200
22:23:46 63990 8192000000 10284822480 10746515456 1386170688 14.472 100 7 14 1 1399733792 12267328 1295776
22:25:52 49711 8192000000 10284682560 10740895744 1382151760 14.436 100 8 16 0 1396994944 12690320 2152864
22:27:44 62258 8192000000 10283475040 10751463424 1386238736 14.472 100 15 30 1 1405191456 15168384 3784336
22:30:09 41201 8192000000 10308047600 10758414336 1383233568 14.445 99 20 40 1 1398871248 11095056 4542624
22:31:55 57746 8192000000 10259368752 10710147072 1291553616 13.618 93 21 42 2 1305689152 9661200 4474336
22:34:00 93898 8192000000 10250310736 10699939840 1257530560 13.307 91 28 55 1 1276717184 12087024 7099600
22:36:35 79168 8192000000 10224444048 10666606592 1244565504 13.188 92 20 40 1 1261757824 12502352 4689968

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 01-01-2022 22:39:10 to 01-01-2022 22:39:24.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 94 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 01-01-2022 22:39:25 to 01-01-2022 22:39:27.

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          238325
MON$OLDEST_ACTIVE               238326
MON$OLDEST_SNAPSHOT             238326
MON$NEXT_TRANSACTION            238326
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                       2021760
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33548 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33548 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33548 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33548 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 ---
:276: 
:277: mscs-test-161.budzdorov.ru    Sat Jan  1 22:38:19 2022
:278:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:279: 
:280: 
:281: mscs-test-161.budzdorov.ru    Sat Jan  1 22:38:19 2022
:282:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:283: 
:284: 
:285: mscs-test-161.budzdorov.ru    Sat Jan  1 22:38:21 2022
:286:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:287: 
--- end of diff output ---

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

01.01.22 22:39:28. 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-01-01 22:39:31 No severe PSQL-related problems occured
01.01.22 22:39:32. End of report.