Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.08.2021 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 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 16453488 kB
MemAvailable 31018912 kB
Buffers 0 kB
Cached 15185364 kB
SwapCached 2572 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-08-03 22:37:40
DTS_END                         2021-08-03 22:37:40.8600
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/43398
TRN_ID                          240706
ATT_ID                          684
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: 9219.28 23701 1106314
customer order: creation 167.30 361 20076
customer order: refuse 14.55 333 1746
order to supplier: creation 45.59 1294 5471
order to supplier: removal 3.31 112169 397
invoice (draft): creation 44.48 3467 5338
invoice (draft): removal 52.55 795 6306
invoice accept: apply 41.76 19218 5011
invoice accept: cancel 47.27 796 5672
customer reserve: creation 3820.38 193 458446
customer reserve: removal 4688.00 61 562560
realization accept: apply 82.00 184 9840
realization accept: cancel 83.13 118 9976
payment from customer: creation 58.58 128 7029
payment from customer: removal 5.93 87 711
payment to supplier: creation 49.38 148 5926
payment to supplier: removal 3.22 81 386
service: total inventory turnovers 4.62 1230 554
service: total monetary turnovers 6.43 32 771
service: aggregate perf. data 0.78 22193 94
service: refresh index statistics 0.03 311131 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-08-2021 22:38:30 to 03-08-2021 22:38:32.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2855
TEST_TIME 2 2643
TEST_TIME 3 4059
TEST_TIME 4 5503
TEST_TIME 5 5515
TEST_TIME 6 5545
TEST_TIME 7 5878
TEST_TIME 8 6155
TEST_TIME 9 6429
TEST_TIME 10 6825
TEST_TIME 11 6936
TEST_TIME 12 7354
TEST_TIME 13 7259
TEST_TIME 14 7279
TEST_TIME 15 7065
TEST_TIME 16 7280
TEST_TIME 17 7329
TEST_TIME 18 7229
TEST_TIME 19 7204
TEST_TIME 20 7377
TEST_TIME 21 7675
TEST_TIME 22 7995
TEST_TIME 23 8158
TEST_TIME 24 8767
TEST_TIME 25 8763
TEST_TIME 26 8995
TEST_TIME 27 9406
TEST_TIME 28 10306
TEST_TIME 29 10836
TEST_TIME 30 10600
TEST_TIME 31 10629
TEST_TIME 32 10324
TEST_TIME 33 10385
TEST_TIME 34 10182
TEST_TIME 35 9960
TEST_TIME 36 9818
TEST_TIME 37 9862
TEST_TIME 38 9745
TEST_TIME 39 9594
TEST_TIME 40 9541
TEST_TIME 41 9729
TEST_TIME 42 9636
TEST_TIME 43 9641
TEST_TIME 44 9544
TEST_TIME 45 9466
TEST_TIME 46 9471
TEST_TIME 47 9351
TEST_TIME 48 9282
TEST_TIME 49 9275
TEST_TIME 50 9284
TEST_TIME 51 9160
TEST_TIME 52 9143
TEST_TIME 53 9081
TEST_TIME 54 9119
TEST_TIME 55 9106
TEST_TIME 56 9023
TEST_TIME 57 8911
TEST_TIME 58 8998
TEST_TIME 59 8995
TEST_TIME 60 8987
TEST_TIME 61 8995
TEST_TIME 62 9103
TEST_TIME 63 9107
TEST_TIME 64 9138
TEST_TIME 65 9223
TEST_TIME 66 9166
TEST_TIME 67 9197
TEST_TIME 68 9294
TEST_TIME 69 9289
TEST_TIME 70 9308
TEST_TIME 71 9326
TEST_TIME 72 9472
TEST_TIME 73 9446
TEST_TIME 74 9379
TEST_TIME 75 9340
TEST_TIME 76 9305
TEST_TIME 77 9283
TEST_TIME 78 9306
TEST_TIME 79 9261
TEST_TIME 80 9279
TEST_TIME 81 9238
TEST_TIME 82 9210
TEST_TIME 83 9226
TEST_TIME 84 9195
TEST_TIME 85 9148
TEST_TIME 86 9140
TEST_TIME 87 9131
TEST_TIME 88 9186
TEST_TIME 89 9167
TEST_TIME 90 9171
TEST_TIME 91 9215
TEST_TIME 92 9230
TEST_TIME 93 9291
TEST_TIME 94 9344
TEST_TIME 95 9413
TEST_TIME 96 9372
TEST_TIME 97 9374
TEST_TIME 98 9392
TEST_TIME 99 9386
TEST_TIME 100 9406
TEST_TIME 101 9376
TEST_TIME 102 9306
TEST_TIME 103 9350
TEST_TIME 104 9305
TEST_TIME 105 9303
TEST_TIME 106 9283
TEST_TIME 107 9244
TEST_TIME 108 9211
TEST_TIME 109 9233
TEST_TIME 110 9219
TEST_TIME 111 9179
TEST_TIME 112 9164
TEST_TIME 113 9167
TEST_TIME 114 9155
TEST_TIME 115 9212
TEST_TIME 116 9164
TEST_TIME 117 9233
TEST_TIME 118 9206
TEST_TIME 119 9242
TEST_TIME 120 9222

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 03-08-2021 22:38:34 to 03-08-2021 22:38:37.

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 1532134 1532134 0 0.00 0 37251 7 0 0
doc_list_biud 1532134 1532134 0 0.00 0 51085 28 0 0
sp_add_invoice_to_stock 5161 5011 150 2.90 43 177955 19219 0 150
sp_cancel_adding_invoice 5672 5672 0 0.00 5 67101 796 0 0
sp_cancel_client_order 1746 1746 0 0.00 4 13640 333 0 0
sp_cancel_customer_reserve 562560 562560 0 0.00 1 48580 62 0 0
sp_cancel_pay_from_customer 711 711 0 0.00 2 7145 88 0 0
sp_cancel_pay_to_supplier 386 386 0 0.00 2 5709 81 0 0
sp_cancel_supplier_invoice 6323 6306 17 0.26 2 51115 796 0 17
sp_cancel_supplier_order 397 397 0 0.00 7 1105260 112169 0 0
sp_cancel_write_off 9976 9976 0 0.00 1 8803 118 0 0
sp_client_order 20076 20076 0 0.00 4 49437 361 0 0
sp_customer_reserve 458446 458446 0 0.00 0 96473 193 0 0
sp_fill_shopping_cart 30881 30881 0 0.00 1 21447 40 0 0
sp_fill_shopping_cart_clo_res 458437 458437 0 0.00 0 16599 14 0 0
sp_get_clo_for_invoice 5002 5002 0 0.00 0 35138 314 0 0
sp_kill_cost_storno 16745 16745 0 0.00 0 11465 60 0 0
sp_kill_qstorno_ret_qs2qd 1141799 1141799 0 0.00 0 51075 33 0 0
sp_kill_qty_storno 601499 601499 0 0.00 0 51082 72 0 0
sp_lock_selected_doc 20654 20654 0 0.00 0 7656 3 0 0
sp_make_cost_storno 27797 27797 0 0.00 0 12407 57 0 0
sp_multiply_rows_for_pdistr 27797 27797 0 0.00 0 7555 31 0 0
sp_multiply_rows_for_qdistr 20076 20076 0 0.00 0 49422 145 0 0
sp_pay_from_customer 7029 7029 0 0.00 4 11259 128 0 0
sp_pay_to_supplier 5926 5926 0 0.00 4 18228 148 0 0
sp_payment_common 12955 12955 0 0.00 1 18221 37 0 0
sp_qd_handle_on_cancel_clo 1746 1746 0 0.00 0 13635 314 0 0
sp_qd_handle_on_invoice_upd_sts 10674 10674 0 0.00 0 23866 336 0 0
sp_qd_handle_on_reserve_upd_sts 9840 9840 0 0.00 0 14993 57 0 0
sp_reserve_write_off 9840 9840 0 0.00 6 16616 185 0 0
sp_supplier_invoice 5338 5338 0 0.00 5 124982 3467 0 0
sp_supplier_order 5471 5471 0 0.00 5 29693 1294 0 0
srv_aggregate_perf_data 94 94 0 0.00 2441 280191 22194 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1769 141595 47250 0 0
srv_find_qd_qs_mism 1059796 1059796 0 0.00 0 37251 6 0 0
srv_make_invnt_saldo 554 554 0 0.00 49 40739 1231 0 0
srv_make_money_saldo 771 771 0 0.00 1 3339 32 0 0
srv_recalc_idx_stat 4 4 0 0.00 219976 560816 311131 0 0
t$perf-abend:sp_add_invoice_to_stock 150 150 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 17 17 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5011 5011 0 0.00 0 13622 173 0 0
t$perf-norm:sp_cancel_adding_invoice 391 391 0 0.00 0 5318 84 0 0
t$perf-norm:sp_cancel_client_order 1746 1746 0 0.00 0 3730 15 0 0
t$perf-norm:sp_cancel_customer_reserve 746 746 0 0.00 0 3012 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 711 711 0 0.00 0 1828 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 386 386 0 0.00 0 1878 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 437 437 0 0.00 0 612 13 0 0
t$perf-norm:sp_cancel_supplier_order 397 397 0 0.00 0 27512 1655 0 0
t$perf-norm:sp_cancel_write_off 998 998 0 0.00 0 4111 12 0 0
t$perf-norm:sp_client_order 20076 20076 0 0.00 0 17049 19 0 0
t$perf-norm:sp_customer_reserve 949 949 0 0.00 0 856 5 0 0
t$perf-norm:sp_pay_from_customer 7029 7029 0 0.00 0 8122 9 0 0
t$perf-norm:sp_pay_to_supplier 5926 5926 0 0.00 0 8971 13 0 0
t$perf-norm:sp_reserve_write_off 9840 9840 0 0.00 0 11618 12 0 0
t$perf-norm:sp_supplier_invoice 5338 5338 0 0.00 0 11253 19 0 0
t$perf-norm:sp_supplier_order 5471 5471 0 0.00 0 18797 25 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 292 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 2062 45 0 0
t$perf-norm:srv_make_invnt_saldo 554 554 0 0.00 0 1995 29 0 0
t$perf-norm:srv_make_money_saldo 771 771 0 0.00 0 9211 27 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 21 7 0 0
v_add_invoice_to_stock 10304 10004 300 2.91 0 6764 17 0 300
v_all_customers 36748 36748 0 0.00 0 44 1 0 0
v_all_suppliers 30440 30440 0 0.00 0 19 1 0 0
v_all_wares 40152 40152 0 0.00 0 155 1 0 0
v_cancel_adding_invoice 782 782 0 0.00 0 3523 11 0 0
v_cancel_client_order 3492 3492 0 0.00 0 2186 4 0 0
v_cancel_customer_prepayment 1422 1422 0 0.00 0 3266 7 0 0
v_cancel_customer_reserve 1492 1492 0 0.00 0 879 4 0 0
v_cancel_payment_to_supplier 772 772 0 0.00 0 786 5 0 0
v_cancel_supplier_invoice 908 874 34 3.74 0 3504 27 0 34
v_cancel_supplier_order 794 794 0 0.00 0 6424 13 0 0
v_cancel_write_off 1996 1996 0 0.00 0 3568 16 0 0
v_max_id_clo_ord 5468 5468 0 0.00 0 1522 2 0 0
v_max_id_clo_res 949 949 0 0.00 0 9050 20 0 0
v_max_id_ord_sup 5337 5337 0 0.00 0 4364 3 0 0
v_max_non_paid_invoice 1511 1511 0 0.00 0 1375 3 0 0
v_max_non_paid_realizn 3733 3733 0 0.00 0 4041 6 0 0
v_min_id_clo_ord 5468 5468 0 0.00 0 5057 6 0 0
v_min_id_clo_res 949 949 0 0.00 0 6344 12 0 0
v_min_id_ord_sup 5337 5337 0 0.00 0 17224 11 0 0
v_min_non_paid_invoice 5926 5926 0 0.00 0 18218 30 0 0
v_min_non_paid_realizn 7029 7029 0 0.00 0 7375 22 0 0
v_our_firm 9996 9996 0 0.00 0 17 1 0 0
v_reserve_write_off 19680 19680 0 0.00 0 8590 9 0 0
x_lock_depdocs_on_canc_invoice 11541 11541 0 0.00 0 26848 148 0 0
x_lock_depdocs_on_canc_sup_ord 397 397 0 0.00 0 10082 377 0 0
x_make_qstorno_1000_1200 5471 5471 0 0.00 1 29682 1209 0 0
x_make_qstorno_1200_2000 5338 5338 0 0.00 2 124805 3328 0 0
x_make_qstorno_2100_3300 458446 458446 0 0.00 0 96452 176 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-08-2021 22:38:38 to 03-08-2021 22:38:42.

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:07:44 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
20:08:46 31310 8192000000 10378506768 10699390976 1429791632 14.859 100 87 174 0 1463459344 5660800 28006912
20:10:20 18756 8192000000 10439466224 10770255872 1493435776 15.419 100 91 183 1 1529951360 7371776 29143808
20:11:43 13493 8192000000 10489338896 10828394496 1541221120 15.834 100 84 168 1 1576424128 8817408 26385600
20:13:04 10319 8192000000 10579232304 10940977152 1639940416 16.679 100 81 158 1 1673580256 9704464 23935376
20:14:18 8187 8192000000 10651631568 11026210816 1716162752 17.320 100 77 154 1 1750108608 11770384 22175472
20:15:29 7248 8192000000 10711413312 11095166976 1777935312 17.832 100 76 152 0 1812235776 12830544 21469920
20:16:37 3207 8192000000 10762123040 11143962624 1818749344 18.167 100 83 165 0 1856468912 14671584 23047984
20:17:43 2080 8192000000 10805514192 11190059008 1856790304 18.477 100 90 180 0 1896735872 15367376 24578192
20:18:48 1959 8192000000 10827509296 11216941056 1876394208 18.636 100 92 184 2 1917541152 14967280 26179664
20:19:51 13929 8192000000 10822482400 11217092608 1881097264 18.674 100 69 137 2 1908316736 6927520 20291952
20:21:06 6200 8192000000 10805796960 11222126592 1882780496 18.688 100 27 54 1 1898048144 7834928 7432720
20:22:13 2581 8192000000 10802262496 11233177600 1892479072 18.766 100 27 52 1 1905654192 5542784 7632336
20:23:19 6118 8192000000 10824230688 11245756416 1903057792 18.851 100 31 62 0 1918447472 6049776 9339904
20:24:29 54353 8192000000 10845384304 11264217088 1911700944 18.920 100 47 93 2 1931802864 5093856 15008064
20:26:24 7139 8192000000 10818839552 11254988800 1905182352 18.868 100 32 64 2 1921273328 7565632 8525344
20:27:32 7328 8192000000 10810784592 11245252608 1884659072 18.703 99 29 58 2 1899292000 6814192 7818736
20:28:40 45648 8192000000 10778373888 11198676992 1855505056 18.467 99 37 73 4 1872043472 5586176 10952240
20:30:32 5353 8192000000 10664709184 11065569280 1678302896 17.003 94 28 54 1 1692362256 6064960 7994400
20:31:38 6308 8192000000 10712085760 11129618432 1768363024 17.754 98 30 60 2 1782993232 5346160 9284048
20:32:45 16373 8192000000 10760736288 11169275904 1819297872 18.172 99 33 66 2 1833919312 5276976 9344464
20:34:02 25721 8192000000 10752526800 11186307072 1847537472 18.402 100 37 74 0 1864523680 5391920 11594288
20:35:32 11716 8192000000 10771053424 11201003520 1859094432 18.496 100 16 32 1 1869821632 5649600 5077600
20:36:45 11154 8192000000 10772714304 11208740864 1865081232 18.544 100 18 36 0 1875755072 5431360 5242480
20:38:00 16043 8192000000 10836069184 11249082368 1904975216 18.866 100 50 100 2 1926276576 6907120 14394240
20:39:21 64331 8192000000 10842733952 11274629120 1918547424 18.975 100 54 108 3 1947092704 13083760 15461520
20:41:37 9151 8192000000 10872793584 11303911424 1941604320 19.160 100 44 88 0 1965675248 11406272 12664656
20:42:52 12657 8192000000 10909418640 11329044480 1964264624 19.340 100 56 112 1 1993384704 13192384 15927696
20:44:09 7992 8192000000 10912760688 11343679488 1960002400 19.306 99 56 112 0 1990346400 14192304 16151696
20:45:25 4285 8192000000 10924153536 11353731072 1981876368 19.480 100 66 130 1 2020285456 19164736 19244352
20:46:35 7186 8192000000 10928934480 11356237824 1957741104 19.288 99 59 116 2 2000836736 25922672 17172960
20:47:46 5299 8192000000 10926714176 11351298048 1950153072 19.228 99 61 118 1 1996252832 28405408 17694352
20:48:57 2237 8192000000 10957428928 11373207552 1981764480 19.479 100 81 162 0 2039843312 33565840 24512992
20:50:02 1765 8192000000 10937347680 11348213760 1955896976 19.273 100 76 152 1 2012416144 34469088 22050080
20:51:07 4308 8192000000 10930550976 11346653184 1940979408 19.155 99 68 136 2 2001993280 40329216 20684656
20:52:13 10506 8192000000 10923628000 11354750976 1943394528 19.174 99 56 112 1 2005486464 44296560 17795376
20:53:30 3849 8192000000 10941171232 11357450240 1927108224 19.044 98 69 135 2 2001392496 53467328 20816944
20:54:39 4097 8192000000 10966629424 11383975936 1976800528 19.439 100 76 152 1 2050516928 49710224 24006176
20:55:48 2898 8192000000 10991067264 11406942208 1992823776 19.566 100 76 149 2 2071308096 55380880 23103440
20:56:55 10347 8192000000 10979306176 11408109568 1970618512 19.390 99 60 120 0 2055031552 64841424 19571616
20:58:08 7086 8192000000 10988050160 11412361216 1982346848 19.483 100 55 108 1 2067534240 66640080 18547312
20:59:17 6038 8192000000 10952458528 11392135168 1954438416 19.262 100 39 76 1 2037438640 69543904 13456320
21:00:48 2727 8192000000 10972030816 11400855552 1966159152 19.355 100 59 118 0 2056616368 70927920 19529296
21:01:57 29467 8192000000 10945718224 11396071424 1966808112 19.360 100 29 58 2 2032276272 55744320 9723840
21:03:36 50077 8192000000 10958643280 11418189824 1964160560 19.339 100 23 46 1 2039033952 66052496 8820896
21:05:33 22656 8192000000 10934903568 11379867648 1946684032 19.200 100 28 57 0 1991118960 35566096 8868832
21:06:57 17254 8192000000 10903163840 11371126784 1964601120 19.343 100 24 48 2 1991166752 19915776 6649856
21:08:35 92508 8192000000 10871780416 11334045696 1871443568 18.596 95 22 45 1 1889735744 11826336 6465840
21:11:11 80972 8192000000 10901161888 11349417984 1944468752 19.182 99 23 45 2 1964008944 13792688 5747504
21:13:37 86080 8192000000 10922076128 11384930304 1954541184 19.263 98 19 38 1 1973266112 13399504 5325424
21:16:04 37768 8192000000 10963367200 11400998912 2000968176 19.630 100 45 90 3 2032016096 17699488 13348432
21:17:50 90223 8192000000 10925413840 11413917696 1976882080 19.440 99 16 32 0 2002476192 20553952 5040160
21:20:21 102604 8192000000 10914713488 11391438848 1980582928 19.469 99 7 14 0 2006579696 23665984 2330784
21:23:30 30692 8192000000 10937090560 11373481984 1969808496 19.384 98 37 73 1 1986798688 6017120 10973072
21:25:02 85443 8192000000 10927392080 11403235328 1998147472 19.608 100 28 56 2 2018530352 11934048 8448832
21:27:32 49365 8192000000 10906190608 11377922048 1973002864 19.409 99 11 22 1 1985326304 9506864 2816576
21:29:39 60006 8192000000 10913772416 11392184320 1960770368 19.312 98 24 48 2 1983491792 15726176 6995248
21:31:40 68900 8192000000 10901401504 11390443520 1954349504 19.261 98 8 16 0 1969822992 13276944 2196544
21:33:50 79401 8192000000 10916216912 11390349312 1989880240 19.543 100 19 38 0 2005731584 10034288 5817056
21:36:28 80959 8192000000 10904881392 11376857088 1934984848 19.107 97 23 46 0 1953535392 12189424 6361120
21:38:52 88202 8192000000 10903360608 11378315264 1955783616 19.273 98 13 26 0 1970218704 11370992 3064096
21:41:21 88378 8192000000 10861307936 11339575296 1816436432 18.149 91 16 32 1 1836843072 15839568 4567072
21:43:50 62573 8192000000 10896966176 11349266432 1906840576 18.881 97 30 58 1 1925848768 10211344 8796848
21:45:57 60153 8192000000 10889812688 11356155904 1926465888 19.039 98 23 46 2 1945572720 12265712 6841120
21:47:57 80307 8192000000 10878513664 11348926464 1891063712 18.754 96 17 34 1 1913699808 17667280 4968816
21:50:18 141594 8192000000 10873084368 11335036928 1854345808 18.457 94 25 49 1 1873850640 12544896 6959936
21:53:41 35350 8192000000 10887728736 11352723456 1960314944 19.309 100 25 49 2 1978604432 11595552 6693936
21:55:17 44612 8192000000 10863567200 11331317760 1899336560 18.821 97 26 52 2 1915622176 9468768 6816848
21:57:02 43873 8192000000 10873528208 11352616960 1921621056 19.000 98 8 16 1 1935769968 11480864 2668048
21:59:23 51837 8192000000 10869895632 11345281024 1922407712 19.006 98 14 28 1 1941053920 14167600 4478608
22:01:56 81393 8192000000 10857966432 11327291392 1869795984 18.583 96 5 10 0 1881441088 10458320 1186784
22:04:18 47634 8192000000 10871168816 11339669504 1903891344 18.858 97 18 36 0 1921670448 12325584 5453520
22:06:06 43931 8192000000 10906414560 11356819456 1960134208 19.307 100 24 46 2 1978520752 12049648 6336896
22:07:58 40005 8192000000 10881974000 11347709952 1952828128 19.249 100 18 36 0 1969519344 11603600 5087616
22:09:45 92851 8192000000 10900169280 11380588544 1936322656 19.117 98 20 40 0 1963692384 21111152 6258576
22:12:29 83368 8192000000 10926448480 11392712704 1984117776 19.497 100 16 32 1 2001152656 12674400 4360480
22:14:55 10469 8192000000 10934966752 11382353920 1981073024 19.473 100 61 122 2 2008555616 10458544 17024048
22:16:26 21013 8192000000 10917949936 11369422848 1962297600 19.324 99 48 95 1 1986795376 11661648 12836128
22:17:51 67241 8192000000 10912584160 11370049536 1961187920 19.315 99 45 88 2 1990403008 16487488 12727600
22:19:59 107261 8192000000 10907381680 11378896896 1957874688 19.289 99 18 36 1 1974697920 11637072 5186160
22:22:47 41078 8192000000 10895595696 11366780928 1925126272 19.028 97 19 38 1 1937117984 6728608 5263104
22:25:09 98080 8192000000 10873931632 11356811264 1918329104 18.973 97 21 43 1 1935531680 10917216 6285360
22:27:54 48150 8192000000 10863517360 11326545920 1928444048 19.054 99 24 46 0 1940860432 6121488 6294896
22:29:45 95500 8192000000 10870466560 11335139328 1838211200 18.326 94 10 20 0 1849597856 9102720 2283936
22:32:28 45770 8192000000 10853087760 11330883584 1900057376 18.827 98 9 17 0 1912701072 9884560 2759136
22:34:29 96251 8192000000 10870769728 11345108992 1851025600 18.430 95 16 32 1 1879282736 23816000 4441136
22:37:38 12569 8192000000 9122894624 9358647296 286116176 3.374 15 3 3 1 288551136 1427056 1007904

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 03-08-2021 22:38:44 to 03-08-2021 22:38:59.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 150 v_add_invoice_to_stock 335544517
except 17 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 03-08-2021 22:39:00 to 03-08-2021 22:39:02.

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          241137
MON$OLDEST_ACTIVE               241138
MON$OLDEST_SNAPSHOT             241138
MON$NEXT_TRANSACTION            241138
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                       2014208
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.33487 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33487 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33487 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33487 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 ---
:104: 
:105: mscs-test-161.budzdorov.ru    Tue Aug  3 22:37:58 2021
:106:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:107: 
--- end of diff output ---

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

03.08.21 22:39:03. 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-08-03 22:39:05 No severe PSQL-related problems occured
03.08.21 22:39:07. End of report.