Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.11.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 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 16570208 kB
MemAvailable 31502156 kB
Buffers 0 kB
Cached 15061412 kB
SwapCached 0 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.8
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-11-27 22:37:54
DTS_END                         2021-11-27 22:37:54.7990
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/40826
TRN_ID                          240691
ATT_ID                          711
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: 9158.18 19998 1098982
customer order: creation 174.38 329 20925
customer order: refuse 14.94 343 1793
order to supplier: creation 46.79 1294 5615
order to supplier: removal 3.47 117356 416
invoice (draft): creation 46.27 3602 5552
invoice (draft): removal 51.93 940 6231
invoice accept: apply 41.69 17029 5003
invoice accept: cancel 46.61 927 5593
customer reserve: creation 3831.90 167 459828
customer reserve: removal 4604.77 69 552573
realization accept: apply 83.94 186 10073
realization accept: cancel 79.93 125 9592
payment from customer: creation 60.37 115 7244
payment from customer: removal 5.48 104 658
payment to supplier: creation 49.65 176 5958
payment to supplier: removal 3.83 87 460
service: total inventory turnovers 4.66 1167 559
service: total monetary turnovers 6.68 39 802
service: aggregate perf. data 0.86 19712 103
service: refresh index statistics 0.03 236189 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 27-11-2021 22:38:57 to 27-11-2021 22:39:00.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1775
TEST_TIME 2 2497
TEST_TIME 3 2470
TEST_TIME 4 2937
TEST_TIME 5 3891
TEST_TIME 6 4455
TEST_TIME 7 4767
TEST_TIME 8 5274
TEST_TIME 9 5628
TEST_TIME 10 6195
TEST_TIME 11 6240
TEST_TIME 12 6247
TEST_TIME 13 6423
TEST_TIME 14 6377
TEST_TIME 15 6399
TEST_TIME 16 6492
TEST_TIME 17 6572
TEST_TIME 18 6516
TEST_TIME 19 7051
TEST_TIME 20 7403
TEST_TIME 21 7538
TEST_TIME 22 8141
TEST_TIME 23 8329
TEST_TIME 24 8451
TEST_TIME 25 8293
TEST_TIME 26 8813
TEST_TIME 27 8880
TEST_TIME 28 9339
TEST_TIME 29 10108
TEST_TIME 30 11063
TEST_TIME 31 10982
TEST_TIME 32 10664
TEST_TIME 33 10623
TEST_TIME 34 10534
TEST_TIME 35 10367
TEST_TIME 36 10192
TEST_TIME 37 10175
TEST_TIME 38 10059
TEST_TIME 39 10099
TEST_TIME 40 10042
TEST_TIME 41 9956
TEST_TIME 42 9852
TEST_TIME 43 9765
TEST_TIME 44 9625
TEST_TIME 45 9497
TEST_TIME 46 9528
TEST_TIME 47 9522
TEST_TIME 48 9425
TEST_TIME 49 9456
TEST_TIME 50 9331
TEST_TIME 51 9314
TEST_TIME 52 9232
TEST_TIME 53 9233
TEST_TIME 54 9213
TEST_TIME 55 9103
TEST_TIME 56 9108
TEST_TIME 57 9042
TEST_TIME 58 9016
TEST_TIME 59 9005
TEST_TIME 60 8917
TEST_TIME 61 8906
TEST_TIME 62 9018
TEST_TIME 63 9000
TEST_TIME 64 8994
TEST_TIME 65 9010
TEST_TIME 66 9117
TEST_TIME 67 9057
TEST_TIME 68 9147
TEST_TIME 69 9111
TEST_TIME 70 9093
TEST_TIME 71 9188
TEST_TIME 72 9157
TEST_TIME 73 9182
TEST_TIME 74 9258
TEST_TIME 75 9343
TEST_TIME 76 9268
TEST_TIME 77 9183
TEST_TIME 78 9175
TEST_TIME 79 9105
TEST_TIME 80 9120
TEST_TIME 81 9128
TEST_TIME 82 9115
TEST_TIME 83 9064
TEST_TIME 84 9012
TEST_TIME 85 8995
TEST_TIME 86 8985
TEST_TIME 87 8963
TEST_TIME 88 9042
TEST_TIME 89 9024
TEST_TIME 90 9044
TEST_TIME 91 9066
TEST_TIME 92 9076
TEST_TIME 93 9061
TEST_TIME 94 9100
TEST_TIME 95 9190
TEST_TIME 96 9156
TEST_TIME 97 9227
TEST_TIME 98 9186
TEST_TIME 99 9225
TEST_TIME 100 9296
TEST_TIME 101 9278
TEST_TIME 102 9202
TEST_TIME 103 9177
TEST_TIME 104 9222
TEST_TIME 105 9165
TEST_TIME 106 9155
TEST_TIME 107 9129
TEST_TIME 108 9173
TEST_TIME 109 9136
TEST_TIME 110 9087
TEST_TIME 111 9061
TEST_TIME 112 9034
TEST_TIME 113 9088
TEST_TIME 114 9103
TEST_TIME 115 9100
TEST_TIME 116 9170
TEST_TIME 117 9142
TEST_TIME 118 9164
TEST_TIME 119 9192
TEST_TIME 120 9163

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 27-11-2021 22:39:01 to 27-11-2021 22:39:04.

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 1525687 1525687 0 0.00 0 53761 6 0 0
doc_list_biud 1525686 1525686 0 0.00 0 21043 31 0 0
sp_add_invoice_to_stock 5126 5003 123 2.39 27 176206 17029 0 123
sp_cancel_adding_invoice 5593 5593 0 0.00 2 82035 927 0 0
sp_cancel_client_order 1793 1793 0 0.00 4 8619 343 0 0
sp_cancel_customer_reserve 552573 552573 0 0.00 1 21044 69 0 0
sp_cancel_pay_from_customer 658 658 0 0.00 2 8819 105 0 0
sp_cancel_pay_to_supplier 460 460 0 0.00 3 8782 88 0 0
sp_cancel_supplier_invoice 6243 6231 12 0.19 1 21856 941 0 12
sp_cancel_supplier_order 416 416 0 0.00 5 1191852 117356 0 0
sp_cancel_write_off 9592 9592 0 0.00 1 13528 126 0 0
sp_client_order 20925 20925 0 0.00 3 37694 329 0 0
sp_customer_reserve 459828 459828 0 0.00 0 58751 168 0 0
sp_fill_shopping_cart 32088 32088 0 0.00 1 24536 44 0 0
sp_fill_shopping_cart_clo_res 459826 459826 0 0.00 0 19824 12 0 0
sp_get_clo_for_invoice 4995 4995 0 0.00 0 20167 301 0 0
sp_kill_cost_storno 16303 16303 0 0.00 0 15186 72 0 0
sp_kill_qstorno_ret_qs2qd 1121385 1121385 0 0.00 0 21042 37 0 0
sp_kill_qty_storno 591265 591265 0 0.00 0 21043 80 0 0
sp_lock_selected_doc 20940 20940 0 0.00 0 8885 7 0 0
sp_make_cost_storno 28270 28270 0 0.00 0 53743 65 0 0
sp_multiply_rows_for_pdistr 28270 28270 0 0.00 0 11834 33 0 0
sp_multiply_rows_for_qdistr 20925 20925 0 0.00 0 18140 142 0 0
sp_pay_from_customer 7244 7244 0 0.00 4 17587 116 0 0
sp_pay_to_supplier 5958 5958 0 0.00 4 53769 177 0 0
sp_payment_common 13202 13202 0 0.00 1 14211 33 0 0
sp_qd_handle_on_cancel_clo 1793 1793 0 0.00 0 8612 307 0 0
sp_qd_handle_on_invoice_upd_sts 10588 10588 0 0.00 0 18592 367 0 0
sp_qd_handle_on_reserve_upd_sts 10072 10072 0 0.00 0 11502 60 0 0
sp_reserve_write_off 10073 10073 0 0.00 6 20000 187 0 0
sp_supplier_invoice 5552 5552 0 0.00 7 97001 3603 0 0
sp_supplier_order 5615 5615 0 0.00 5 31957 1294 0 0
srv_aggregate_perf_data 103 103 0 0.00 2378 492774 19713 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1601 124118 45931 0 0
srv_find_qd_qs_mism 1051317 1051317 0 0.00 0 11549 5 0 0
srv_make_invnt_saldo 559 559 0 0.00 29 63484 1167 0 0
srv_make_money_saldo 802 802 0 0.00 1 8164 39 0 0
srv_recalc_idx_stat 4 4 0 0.00 140643 446020 236190 0 0
t$perf-abend:sp_add_invoice_to_stock 123 123 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 5004 5004 0 0.00 0 14161 166 0 0
t$perf-norm:sp_cancel_adding_invoice 407 407 0 0.00 0 4832 99 0 0
t$perf-norm:sp_cancel_client_order 1793 1793 0 0.00 0 1649 9 0 0
t$perf-norm:sp_cancel_customer_reserve 721 721 0 0.00 0 4064 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 658 658 0 0.00 0 2899 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 460 460 0 0.00 0 4861 22 0 0
t$perf-norm:sp_cancel_supplier_invoice 381 381 0 0.00 0 501 8 0 0
t$perf-norm:sp_cancel_supplier_order 416 416 0 0.00 0 30964 1798 0 0
t$perf-norm:sp_cancel_write_off 1037 1037 0 0.00 0 4093 11 0 0
t$perf-norm:sp_client_order 20925 20925 0 0.00 0 37496 20 0 0
t$perf-norm:sp_customer_reserve 1056 1056 0 0.00 0 4833 8 0 0
t$perf-norm:sp_pay_from_customer 7244 7244 0 0.00 0 7572 9 0 0
t$perf-norm:sp_pay_to_supplier 5958 5958 0 0.00 0 10339 14 0 0
t$perf-norm:sp_reserve_write_off 10073 10073 0 0.00 0 11449 15 0 0
t$perf-norm:sp_supplier_invoice 5552 5552 0 0.00 0 6651 18 0 0
t$perf-norm:sp_supplier_order 5615 5615 0 0.00 0 12142 22 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 227 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 311 8 0 0
t$perf-norm:srv_make_invnt_saldo 559 559 0 0.00 0 605 22 0 0
t$perf-norm:srv_make_money_saldo 802 802 0 0.00 0 1389 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10236 9990 246 2.40 0 4409 17 0 246
v_all_customers 38356 38356 0 0.00 0 13 1 0 0
v_all_suppliers 31086 31086 0 0.00 0 34 1 0 0
v_all_wares 41850 41850 0 0.00 0 157 1 0 0
v_cancel_adding_invoice 814 814 0 0.00 0 2348 16 0 0
v_cancel_client_order 3586 3586 0 0.00 0 2808 8 0 0
v_cancel_customer_prepayment 1316 1316 0 0.00 0 3009 9 0 0
v_cancel_customer_reserve 1442 1442 0 0.00 0 2455 5 0 0
v_cancel_payment_to_supplier 920 920 0 0.00 0 2063 6 0 0
v_cancel_supplier_invoice 786 762 24 3.05 0 7175 25 0 24
v_cancel_supplier_order 832 832 0 0.00 0 2660 9 0 0
v_cancel_write_off 2074 2074 0 0.00 0 3364 15 0 0
v_max_id_clo_ord 5614 5614 0 0.00 0 808 1 0 0
v_max_id_clo_res 1056 1056 0 0.00 0 4651 23 0 0
v_max_id_ord_sup 5549 5549 0 0.00 0 9068 6 0 0
v_max_non_paid_invoice 1578 1578 0 0.00 0 178 2 0 0
v_max_non_paid_realizn 3828 3828 0 0.00 0 9195 7 0 0
v_min_id_clo_ord 5614 5614 0 0.00 0 4068 11 0 0
v_min_id_clo_res 1056 1056 0 0.00 0 4885 11 0 0
v_min_id_ord_sup 5549 5549 0 0.00 0 7904 7 0 0
v_min_non_paid_invoice 5958 5958 0 0.00 0 8786 29 0 0
v_min_non_paid_realizn 7244 7244 0 0.00 0 14208 20 0 0
v_our_firm 10326 10326 0 0.00 0 15 1 0 0
v_reserve_write_off 20144 20144 0 0.00 0 3072 5 0 0
x_lock_depdocs_on_canc_invoice 11443 11443 0 0.00 0 13202 127 0 0
x_lock_depdocs_on_canc_sup_ord 416 416 0 0.00 0 13264 387 0 0
x_make_qstorno_1000_1200 5615 5615 0 0.00 1 31819 1205 0 0
x_make_qstorno_1200_2000 5552 5552 0 0.00 3 96979 3443 0 0
x_make_qstorno_2100_3300 459828 459828 0 0.00 0 58747 153 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-11-2021 22:39:05 to 27-11-2021 22:39:09.

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:58 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:00 11394 8192000000 9907298176 10221342720 987645776 10.759 100 84 168 0 1015317680 5709472 21962432
20:10:15 20148 8192000000 9946388080 10270257152 1030765808 11.176 100 89 178 1 1061238032 7828080 22644144
20:11:44 19256 8192000000 9981417872 10314661888 1062682704 11.482 100 84 168 0 1093178464 9579440 20916320
20:13:08 13926 8192000000 10033701024 10382159872 1116337904 11.992 100 78 155 0 1145665328 11383904 17943520
20:14:27 8162 8192000000 10082414688 10441650176 1165743504 12.457 100 77 154 0 1200548176 17047200 17757472
20:15:37 4438 8192000000 10126150640 10494210048 1210162432 12.871 100 87 167 2 1242261936 13145408 18954096
20:16:44 6655 8192000000 10146640896 10524966912 1239367328 13.140 100 67 134 0 1265576128 11500400 14708400
20:17:53 1635 8192000000 10201053504 10579529728 1276672592 13.483 100 91 183 1 1310928944 13458880 20797472
20:18:56 2423 8192000000 10209222592 10592411648 1291780208 13.620 100 95 190 0 1325853472 12759008 21314256
20:19:59 26668 8192000000 10213007552 10609389568 1302516768 13.718 100 72 146 1 1329020112 9851376 16651968
20:21:27 7518 8192000000 10203044880 10619097088 1316881488 13.848 100 25 50 2 1328811104 6464800 5464816
20:22:36 6617 8192000000 10207260000 10629128192 1324529904 13.918 100 21 42 1 1335200080 6080096 4590080
20:23:43 13644 8192000000 10210929344 10638766080 1330234192 13.969 100 25 49 1 1342624144 5629616 6760336
20:25:03 8345 8192000000 10225897360 10648035328 1335088272 14.013 100 29 58 1 1349022608 7864608 6069728
20:26:14 6957 8192000000 10220738240 10637500416 1314609920 13.828 99 23 46 3 1329718560 10061792 5046848
20:27:24 7003 8192000000 10222198128 10645340160 1330890000 13.975 100 21 42 1 1345632736 10239680 4503056
20:28:31 15419 8192000000 10166634704 10574454784 1165235264 12.452 89 27 53 1 1177106912 4555504 7316144
20:29:48 19814 8192000000 10157280224 10564808704 1202969008 12.804 94 34 68 1 1217731776 6418576 8344192
20:31:08 11093 8192000000 10149361808 10548264960 1222973232 12.989 97 24 48 1 1234134528 5463952 5697344
20:32:22 10467 8192000000 10146525216 10544099328 1218899568 12.951 97 16 30 1 1227941648 4992160 4049920
20:33:33 77897 8192000000 10171386704 10578055168 1255698192 13.291 98 27 54 0 1267529088 4947264 6883632
20:35:53 6852 8192000000 10182890400 10598322176 1293311456 13.634 100 25 50 0 1304527952 5759584 5456912
20:37:01 11866 8192000000 10204560192 10621448192 1311638464 13.801 100 19 38 0 1320918832 5086000 4194368
20:38:16 9051 8192000000 10235262096 10644795392 1331985664 13.985 100 37 74 1 1346718688 5574976 9158048
20:39:49 60759 8192000000 10272519152 10690240512 1361749248 14.253 100 43 86 0 1383813360 11050080 11014032
20:41:53 33038 8192000000 10273244208 10701492224 1367845024 14.308 100 31 62 1 1389932608 14351088 7736496
20:43:30 35489 8192000000 10270065872 10699665408 1362228976 14.257 99 26 52 2 1386947280 18247184 6471120
20:45:11 3130 8192000000 10317531264 10737229824 1392991632 14.533 100 58 116 3 1425155184 18996544 13167008
20:46:18 8934 8192000000 10334540768 10747678720 1400029600 14.595 100 66 132 1 1437826448 22929536 14867312
20:47:32 3603 8192000000 10345303024 10769219584 1419033040 14.764 100 64 128 1 1456591648 22711776 14846832
20:48:42 7077 8192000000 10348460880 10770804736 1409609184 14.680 99 62 124 2 1450299872 25809792 14880896
20:49:54 6501 8192000000 10330870160 10741825536 1375798496 14.379 99 69 135 0 1426409888 34489824 16121568
20:51:04 2210 8192000000 10363722656 10779820032 1412877136 14.709 100 70 140 0 1472586496 42228368 17480992
20:52:13 12649 8192000000 10371898928 10789486592 1420214176 14.775 100 70 140 0 1480079232 42008496 17856560
20:53:31 5655 8192000000 10363875696 10783547392 1405440288 14.643 99 59 118 1 1466144592 44837760 15866544
20:54:41 7898 8192000000 10389523184 10801582080 1418582224 14.760 100 72 144 2 1491590192 53937120 19070848
20:55:53 1599 8192000000 10370792528 10775080960 1385500288 14.466 100 80 159 0 1471852480 64909024 21443168
20:56:59 3757 8192000000 10371317536 10771095552 1385731936 14.468 99 84 165 1 1470901312 64310736 20858640
20:58:07 4441 8192000000 10383183968 10788417536 1398549360 14.582 100 83 165 0 1485082864 64658336 21875168
20:59:18 1779 8192000000 10392786656 10799108096 1401104960 14.605 100 84 168 1 1491874800 68402864 22366976
21:00:23 11368 8192000000 10358250544 10780909568 1395489008 14.555 100 47 94 0 1461171968 52902848 12780112
21:02:04 19109 8192000000 10362439632 10789765120 1399605696 14.591 100 38 77 1 1468108816 56838464 11664656
21:03:29 28451 8192000000 10352702032 10794819584 1386267536 14.473 99 28 56 0 1452216528 57413616 8535376
21:05:28 30957 8192000000 10345178016 10797076480 1378186160 14.400 100 24 48 0 1458247776 72492288 7569328
21:07:12 41297 8192000000 10265992784 10711494656 1327171664 13.942 96 13 26 0 1343628432 12928016 3528752
21:09:05 72498 8192000000 10278648544 10716229632 1351798592 14.164 98 25 50 0 1368240928 10519248 5923088
21:11:19 34919 8192000000 10218049792 10659799040 1283413040 13.544 93 14 28 1 1295272256 9209472 2649744
21:13:26 65034 8192000000 10275631568 10729259008 1345510624 14.107 98 18 34 1 1365121584 15648816 3962144
21:16:21 124118 8192000000 10276740544 10730049536 1361758112 14.253 99 24 48 0 1375724432 9120400 4845920
21:19:35 64309 8192000000 10283999600 10736345088 1365511104 14.287 99 17 35 0 1382107408 12839440 3756864
21:21:52 100743 8192000000 10286102496 10731646976 1346033376 14.112 97 31 62 0 1366414976 12182256 8199344
21:24:50 39406 8192000000 10292075568 10731319296 1373596528 14.359 99 40 76 1 1390355024 7974288 8784208
21:26:42 73373 8192000000 10291685952 10750701568 1387234976 14.481 100 9 18 0 1399012912 9612128 2165808
21:28:57 21397 8192000000 10295431888 10734018560 1370347040 14.330 98 64 126 1 1393623696 7748768 15527888
21:30:20 36646 8192000000 10277902144 10733215744 1355237216 14.195 98 24 49 1 1371408192 10734608 5436368
21:32:01 21493 8192000000 10264648848 10720907264 1368850480 14.317 100 8 16 0 1380232048 9122208 2259360
21:33:38 50626 8192000000 10278130976 10740154368 1382079120 14.435 100 10 20 0 1396259600 11624736 2555744
21:36:48 90619 8192000000 10279812592 10750853120 1376328960 14.384 99 9 18 0 1386765312 8515888 1920464
21:39:19 58989 8192000000 10260677024 10720612352 1315639664 13.837 95 13 26 0 1328591840 10308240 2643936
21:41:25 58210 8192000000 10278046784 10743947264 1354875472 14.191 98 17 34 2 1376927776 18263280 3789024
21:43:23 43184 8192000000 10267131536 10728423424 1358154512 14.221 99 12 24 0 1371480064 10428928 2896624
21:45:12 73786 8192000000 10247235760 10693967872 1282335664 13.534 94 17 34 2 1296154832 9922832 3896336
21:47:29 97889 8192000000 10255234272 10698399744 1303364880 13.726 95 14 28 2 1320738880 14142816 3231184
21:50:12 27522 8192000000 10267724320 10704306176 1336673168 14.027 99 46 92 0 1358610512 12302496 9634848
21:51:43 88318 8192000000 10261774816 10718543872 1342344448 14.079 99 9 18 1 1363268208 18555504 2368256
21:54:11 68449 8192000000 10253116912 10693234688 1340287008 14.060 98 43 86 2 1357292048 7341952 9663088
21:56:22 21391 8192000000 10257644480 10690846720 1330320160 13.970 99 37 74 1 1346711456 7944592 8446704
21:57:44 105962 8192000000 10262127248 10721599488 1323372896 13.907 97 15 30 0 1339377408 12562608 3441904
22:00:35 46586 8192000000 10244808416 10701950976 1327397760 13.944 97 18 37 1 1339025472 6677696 4950016
22:02:22 100581 8192000000 10268810176 10728177664 1351233488 14.159 99 7 14 0 1360187184 7622464 1331232
22:05:07 61340 8192000000 10245682400 10709024768 1320298816 13.879 97 11 22 0 1339788384 16482432 3007136
22:07:15 32833 8192000000 10285326016 10730082304 1373615696 14.359 100 34 68 1 1391254640 10199488 7439456
22:08:50 83416 8192000000 10276957120 10739056640 1332425840 13.989 96 19 38 1 1351142400 14841168 3875392
22:11:18 73467 8192000000 10297903712 10764726272 1388186464 14.490 100 23 46 3 1409818512 16248928 5383120
22:13:45 112749 8192000000 10309438384 10759049216 1369402240 14.322 98 14 28 0 1392030800 19455968 3172592
22:16:42 38237 8192000000 10305401344 10764525568 1395627296 14.556 100 8 16 0 1410266464 12672624 1966544
22:18:24 52555 8192000000 10263088816 10714402816 1332963920 13.994 96 17 34 2 1345518272 8032528 4521824
22:20:18 75799 8192000000 10280051712 10722050048 1307236800 13.761 94 24 48 1 1322537296 9792016 5508480
22:22:35 38881 8192000000 10304454432 10747990016 1368189792 14.311 99 37 73 1 1392537200 16137120 8210288
22:24:14 46368 8192000000 10263377840 10715762688 1355507216 14.197 98 26 52 1 1375235872 13878512 5850144
22:26:03 47612 8192000000 10286931872 10751660032 1386768368 14.477 100 11 22 0 1403110256 13895600 2446288
22:27:51 60623 8192000000 10307345680 10770239488 1395942848 14.559 100 17 33 0 1415250400 15472128 3835424
22:29:52 72142 8192000000 10304806112 10760085504 1388577648 14.493 99 20 40 0 1406954816 13435280 4941888
22:32:09 63327 8192000000 10294505216 10757373952 1363024112 14.264 97 22 44 2 1380827088 13188672 4614304
22:34:13 79562 8192000000 10272805136 10726850560 1310197728 13.788 96 17 34 0 1331523040 17696768 3628544
22:36:33 29903 8192000000 10261458208 10699657216 1306531184 13.755 96 23 45 0 1319970288 8603616 4835488

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 27-11-2021 22:39:11 to 27-11-2021 22:39:26.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 123 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 27-11-2021 22:39:27 to 27-11-2021 22:39:29.

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          237913
MON$OLDEST_ACTIVE               241660
MON$OLDEST_SNAPSHOT             241660
MON$NEXT_TRANSACTION            241660
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                       2051744
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.33540 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33540 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33540 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33540 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 ---
:464: 
:465: mscs-test-161.budzdorov.ru    Sat Nov 27 22:38:00 2021
:466:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:467: 
:468: 
:469: mscs-test-161.budzdorov.ru    Sat Nov 27 22:38:00 2021
:470:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:471: 
:472: 
:473: mscs-test-161.budzdorov.ru    Sat Nov 27 22:38:09 2021
:474:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:475: 
:476: 
:477: mscs-test-161.budzdorov.ru    Sat Nov 27 22:38:09 2021
:478:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:479: 
:480: 
:481: mscs-test-161.budzdorov.ru    Sat Nov 27 22:38:09 2021
:482:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:483: 
:484: 
:485: mscs-test-161.budzdorov.ru    Sat Nov 27 22:38:09 2021
:486:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:487: 
--- end of diff output ---

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

27.11.21 22:39:30. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-11-27 22:39:32 No severe PSQL-related problems occured
27.11.21 22:39:34. End of report.