Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.11.2021 16: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 17110720 kB
MemAvailable 31514608 kB
Buffers 0 kB
Cached 14547784 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 16:37:59
DTS_END                         2021-11-27 16:37:59.1910
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/36660
TRN_ID                          242051
ATT_ID                          689
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: 9086.97 21132 1090436
customer order: creation 177.17 360 21260
customer order: refuse 15.33 334 1840
order to supplier: creation 46.49 1300 5579
order to supplier: removal 3.33 95006 399
invoice (draft): creation 47.63 3190 5716
invoice (draft): removal 49.78 745 5973
invoice accept: apply 42.63 16991 5115
invoice accept: cancel 45.08 767 5410
customer reserve: creation 3924.10 169 470892
customer reserve: removal 4437.08 56 532449
realization accept: apply 85.18 161 10221
realization accept: cancel 79.75 116 9570
payment from customer: creation 61.33 112 7359
payment from customer: removal 5.83 62 700
payment to supplier: creation 50.24 115 6029
payment to supplier: removal 3.29 84 395
service: total inventory turnovers 5.33 851 640
service: total monetary turnovers 6.51 22 781
service: aggregate perf. data 0.87 18848 104
service: refresh index statistics 0.03 283357 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1013
TEST_TIME 2 2192
TEST_TIME 3 3046
TEST_TIME 4 3829
TEST_TIME 5 4407
TEST_TIME 6 4429
TEST_TIME 7 4615
TEST_TIME 8 4470
TEST_TIME 9 4466
TEST_TIME 10 5267
TEST_TIME 11 5283
TEST_TIME 12 5538
TEST_TIME 13 6015
TEST_TIME 14 6230
TEST_TIME 15 6862
TEST_TIME 16 6864
TEST_TIME 17 7687
TEST_TIME 18 8422
TEST_TIME 19 8665
TEST_TIME 20 9304
TEST_TIME 21 9745
TEST_TIME 22 10395
TEST_TIME 23 10229
TEST_TIME 24 10314
TEST_TIME 25 10170
TEST_TIME 26 10130
TEST_TIME 27 9967
TEST_TIME 28 9889
TEST_TIME 29 9801
TEST_TIME 30 9609
TEST_TIME 31 9587
TEST_TIME 32 9573
TEST_TIME 33 9478
TEST_TIME 34 9320
TEST_TIME 35 9129
TEST_TIME 36 9077
TEST_TIME 37 9133
TEST_TIME 38 9163
TEST_TIME 39 9106
TEST_TIME 40 9127
TEST_TIME 41 9071
TEST_TIME 42 9150
TEST_TIME 43 9170
TEST_TIME 44 9201
TEST_TIME 45 9179
TEST_TIME 46 9274
TEST_TIME 47 9214
TEST_TIME 48 9185
TEST_TIME 49 9358
TEST_TIME 50 9368
TEST_TIME 51 9377
TEST_TIME 52 9379
TEST_TIME 53 9244
TEST_TIME 54 9199
TEST_TIME 55 9168
TEST_TIME 56 9194
TEST_TIME 57 9156
TEST_TIME 58 9081
TEST_TIME 59 9031
TEST_TIME 60 8981
TEST_TIME 61 8959
TEST_TIME 62 8931
TEST_TIME 63 8899
TEST_TIME 64 8969
TEST_TIME 65 9053
TEST_TIME 66 9111
TEST_TIME 67 9093
TEST_TIME 68 9096
TEST_TIME 69 9203
TEST_TIME 70 9223
TEST_TIME 71 9187
TEST_TIME 72 9320
TEST_TIME 73 9287
TEST_TIME 74 9351
TEST_TIME 75 9324
TEST_TIME 76 9343
TEST_TIME 77 9255
TEST_TIME 78 9235
TEST_TIME 79 9184
TEST_TIME 80 9169
TEST_TIME 81 9130
TEST_TIME 82 9099
TEST_TIME 83 9063
TEST_TIME 84 8999
TEST_TIME 85 8974
TEST_TIME 86 8961
TEST_TIME 87 9018
TEST_TIME 88 9028
TEST_TIME 89 9034
TEST_TIME 90 9061
TEST_TIME 91 9087
TEST_TIME 92 9132
TEST_TIME 93 9133
TEST_TIME 94 9146
TEST_TIME 95 9176
TEST_TIME 96 9250
TEST_TIME 97 9245
TEST_TIME 98 9264
TEST_TIME 99 9263
TEST_TIME 100 9278
TEST_TIME 101 9220
TEST_TIME 102 9149
TEST_TIME 103 9133
TEST_TIME 104 9103
TEST_TIME 105 9141
TEST_TIME 106 9086
TEST_TIME 107 9082
TEST_TIME 108 9025
TEST_TIME 109 9005
TEST_TIME 110 8984
TEST_TIME 111 8976
TEST_TIME 112 8980
TEST_TIME 113 9012
TEST_TIME 114 9017
TEST_TIME 115 9051
TEST_TIME 116 9067
TEST_TIME 117 9062
TEST_TIME 118 9063
TEST_TIME 119 9046
TEST_TIME 120 9089

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 27-11-2021 16:38:40 to 27-11-2021 16:38:43.

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 1526399 1526399 0 0.00 0 18135 6 0 0
doc_list_biud 1526399 1526399 0 0.00 0 51645 25 0 0
sp_add_invoice_to_stock 5245 5115 130 2.47 20 188405 16992 0 130
sp_cancel_adding_invoice 5410 5410 0 0.00 6 57634 767 0 0
sp_cancel_client_order 1840 1840 0 0.00 4 11779 335 0 0
sp_cancel_customer_reserve 532449 532449 0 0.00 1 51647 57 0 0
sp_cancel_pay_from_customer 700 700 0 0.00 2 6284 63 0 0
sp_cancel_pay_to_supplier 395 395 0 0.00 2 6207 85 0 0
sp_cancel_supplier_invoice 5980 5973 7 0.11 2 15388 745 0 7
sp_cancel_supplier_order 399 399 0 0.00 10 1002328 95006 0 0
sp_cancel_write_off 9570 9570 0 0.00 1 13408 116 0 0
sp_client_order 21260 21260 0 0.00 3 21199 360 0 0
sp_customer_reserve 470892 470892 0 0.00 0 47772 169 0 0
sp_fill_shopping_cart 32526 32526 0 0.00 1 18624 35 0 0
sp_fill_shopping_cart_clo_res 470888 470888 0 0.00 0 22878 16 0 0
sp_get_clo_for_invoice 5105 5105 0 0.00 0 34999 362 0 0
sp_kill_cost_storno 16075 16075 0 0.00 0 6206 50 0 0
sp_kill_qstorno_ret_qs2qd 1080840 1080840 0 0.00 0 51633 31 0 0
sp_kill_qty_storno 570963 570963 0 0.00 0 51645 66 0 0
sp_lock_selected_doc 21171 21171 0 0.00 0 9004 6 0 0
sp_make_cost_storno 28710 28710 0 0.00 0 10557 45 0 0
sp_multiply_rows_for_pdistr 28710 28710 0 0.00 0 7992 25 0 0
sp_multiply_rows_for_qdistr 21260 21260 0 0.00 0 11205 161 0 0
sp_pay_from_customer 7359 7359 0 0.00 4 15290 112 0 0
sp_pay_to_supplier 6029 6029 0 0.00 4 15232 116 0 0
sp_payment_common 13388 13388 0 0.00 1 14344 23 0 0
sp_qd_handle_on_cancel_clo 1840 1840 0 0.00 0 11772 305 0 0
sp_qd_handle_on_invoice_upd_sts 10511 10511 0 0.00 0 33603 301 0 0
sp_qd_handle_on_reserve_upd_sts 10221 10221 0 0.00 0 20343 59 0 0
sp_reserve_write_off 10221 10221 0 0.00 6 20372 161 0 0
sp_supplier_invoice 5716 5716 0 0.00 6 83684 3190 0 0
sp_supplier_order 5579 5579 0 0.00 6 41705 1300 0 0
srv_aggregate_perf_data 104 104 0 0.00 2585 242285 18849 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1490 118204 45321 0 0
srv_find_qd_qs_mism 1041454 1041454 0 0.00 0 18135 5 0 0
srv_make_invnt_saldo 640 640 0 0.00 28 83946 851 0 0
srv_make_money_saldo 781 781 0 0.00 1 3497 22 0 0
srv_recalc_idx_stat 4 4 0 0.00 211276 367110 283357 0 0
t$perf-abend:sp_add_invoice_to_stock 130 130 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5115 5115 0 0.00 0 15076 159 0 0
t$perf-norm:sp_cancel_adding_invoice 413 413 0 0.00 0 5751 86 0 0
t$perf-norm:sp_cancel_client_order 1840 1840 0 0.00 0 3248 13 0 0
t$perf-norm:sp_cancel_customer_reserve 686 686 0 0.00 0 1550 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 700 700 0 0.00 0 981 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 395 395 0 0.00 0 490 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 408 408 0 0.00 0 1299 15 0 0
t$perf-norm:sp_cancel_supplier_order 399 399 0 0.00 0 22818 1399 0 0
t$perf-norm:sp_cancel_write_off 1008 1008 0 0.00 0 10374 20 0 0
t$perf-norm:sp_client_order 21260 21260 0 0.00 0 16991 14 0 0
t$perf-norm:sp_customer_reserve 1025 1025 0 0.00 0 12437 18 0 0
t$perf-norm:sp_pay_from_customer 7359 7359 0 0.00 0 14073 10 0 0
t$perf-norm:sp_pay_to_supplier 6029 6029 0 0.00 0 5377 6 0 0
t$perf-norm:sp_reserve_write_off 10221 10221 0 0.00 0 17299 13 0 0
t$perf-norm:sp_supplier_invoice 5716 5716 0 0.00 0 9894 15 0 0
t$perf-norm:sp_supplier_order 5579 5579 0 0.00 0 12337 13 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 774 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 159 4 0 0
t$perf-norm:srv_make_invnt_saldo 640 640 0 0.00 0 1127 12 0 0
t$perf-norm:srv_make_money_saldo 781 781 0 0.00 0 4448 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10458 10198 260 2.48 0 10444 19 0 260
v_all_customers 39074 39074 0 0.00 0 12 0 0 0
v_all_suppliers 31102 31102 0 0.00 0 14 1 0 0
v_all_wares 42520 42520 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 826 826 0 0.00 0 4948 14 0 0
v_cancel_client_order 3680 3680 0 0.00 0 10624 8 0 0
v_cancel_customer_prepayment 1400 1400 0 0.00 0 2556 8 0 0
v_cancel_customer_reserve 1372 1372 0 0.00 0 4386 8 0 0
v_cancel_payment_to_supplier 790 790 0 0.00 0 948 6 0 0
v_cancel_supplier_invoice 830 816 14 1.68 0 3852 30 0 14
v_cancel_supplier_order 798 798 0 0.00 0 1593 7 0 0
v_cancel_write_off 2016 2016 0 0.00 0 5220 18 0 0
v_max_id_clo_ord 5572 5572 0 0.00 0 1079 1 0 0
v_max_id_clo_res 1025 1025 0 0.00 0 5750 24 0 0
v_max_id_ord_sup 5694 5694 0 0.00 0 2903 3 0 0
v_max_non_paid_invoice 1744 1744 0 0.00 0 1166 4 0 0
v_max_non_paid_realizn 3830 3830 0 0.00 0 2769 4 0 0
v_min_id_clo_ord 5572 5572 0 0.00 0 1205 4 0 0
v_min_id_clo_res 1025 1025 0 0.00 0 11655 18 0 0
v_min_id_ord_sup 5694 5694 0 0.00 0 8343 6 0 0
v_min_non_paid_invoice 6029 6029 0 0.00 0 8294 15 0 0
v_min_non_paid_realizn 7359 7359 0 0.00 0 14339 21 0 0
v_our_firm 10504 10504 0 0.00 0 10 1 0 0
v_reserve_write_off 20442 20442 0 0.00 0 5298 7 0 0
x_lock_depdocs_on_canc_invoice 10975 10975 0 0.00 0 23930 114 0 0
x_lock_depdocs_on_canc_sup_ord 399 399 0 0.00 0 8399 303 0 0
x_make_qstorno_1000_1200 5579 5579 0 0.00 2 41698 1237 0 0
x_make_qstorno_1200_2000 5716 5716 0 0.00 2 83673 3057 0 0
x_make_qstorno_2100_3300 470892 470892 0 0.00 0 47771 151 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-11-2021 16:38:44 to 27-11-2021 16:38:48.

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
14:08:02 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:03 20931 8192000000 9908738224 10221670400 988129728 10.763 100 90 180 0 1018410432 6321408 23959296
14:10:30 19259 8192000000 9946180128 10271154176 1028591456 11.155 100 87 174 0 1059768352 8471216 22705680
14:11:57 21992 8192000000 9996296528 10333609984 1070871136 11.560 100 80 160 1 1100276672 9746976 19658560
14:13:27 14596 8192000000 10033338528 10381496320 1117918304 12.007 100 82 161 3 1148730096 11537808 19273984
14:14:44 14161 8192000000 10087232096 10446970880 1169834448 12.495 100 80 159 0 1200361824 12443536 18083840
14:16:02 6348 8192000000 10134403536 10504531968 1218145904 12.945 100 83 167 0 1250164000 13648080 18370016
14:17:10 2706 8192000000 10162045808 10536353792 1249574320 13.234 100 89 179 0 1282608128 13401824 19631984
14:18:14 2056 8192000000 10199938880 10580574208 1280380224 13.516 100 93 188 2 1313528688 12683520 20464944
14:19:18 2248 8192000000 10209947232 10595930112 1295359680 13.653 100 86 173 1 1327182720 13025136 18797904
14:20:22 2938 8192000000 10198924192 10603831296 1305409024 13.744 100 61 120 3 1325992320 6366048 14217248
14:21:28 7615 8192000000 10194392016 10611597312 1312163408 13.806 100 23 46 0 1323947280 5829040 5954832
14:22:39 4134 8192000000 10211929072 10629976064 1323747424 13.911 100 22 44 0 1338707712 10362416 4597872
14:23:46 61917 8192000000 10228686288 10649821184 1332673568 13.991 100 32 62 1 1345064912 4760256 7631088
14:25:48 6837 8192000000 10236014944 10658172928 1338487168 14.044 100 30 60 0 1352302848 6887152 6928528
14:26:57 10055 8192000000 10230307376 10654277632 1330378576 13.971 99 24 48 1 1342152512 5958832 5815104
14:28:10 42802 8192000000 10232526976 10635878400 1317933904 13.858 100 34 68 0 1330668208 4443952 8290352
14:29:58 7260 8192000000 10178309024 10587852800 1262150240 13.350 97 29 58 2 1275232112 6536336 6545536
14:31:05 5897 8192000000 10126211984 10519687168 1167882784 12.477 93 29 57 2 1179226016 4905968 6437264
14:32:13 12056 8192000000 10152899056 10553303040 1240459248 13.150 98 24 48 0 1251314288 5067888 5787152
14:33:25 26427 8192000000 10153399824 10562027520 1243425840 13.178 98 28 55 0 1255272752 5280816 6566096
14:35:01 3906 8192000000 10174967904 10584199168 1284541488 13.554 100 28 56 0 1296989424 4981168 7466768
14:36:06 7141 8192000000 10192219408 10607243264 1298745968 13.684 100 36 72 0 1317596912 10462112 8388832
14:37:13 112388 8192000000 10225146576 10631389184 1307516096 13.764 100 28 56 0 1320035232 5360944 7158192
14:40:15 15963 8192000000 10265683872 10675335168 1357890768 14.218 100 57 112 4 1383138976 12552688 12695520
14:41:38 22207 8192000000 10277772704 10699530240 1368078416 14.310 100 39 78 2 1394326448 17073216 9174816
14:43:18 29281 8192000000 10315715536 10735968256 1390170144 14.507 100 49 99 0 1425068096 22861920 12036032
14:44:54 14227 8192000000 10328273728 10749349888 1402830976 14.620 100 51 102 0 1441384288 25930816 12622496
14:46:14 13381 8192000000 10336325840 10759675904 1406315536 14.651 100 50 98 3 1450113168 30716848 13080784
14:47:32 13856 8192000000 10349190608 10770350080 1411424240 14.697 100 58 115 1 1459369504 33678688 14266576
14:48:54 6071 8192000000 10362151120 10782097408 1419755936 14.771 100 68 135 1 1471084800 35042592 16286272
14:50:03 1489 8192000000 10382238160 10794102784 1424313232 14.811 100 79 158 5 1482179120 38349376 19516512
14:51:08 6136 8192000000 10351619952 10769768448 1403434080 14.626 100 63 126 2 1459990912 40177376 16379456
14:52:18 4811 8192000000 10352627216 10774884352 1402596352 14.618 100 67 132 0 1464712256 44798496 17317408
14:53:30 2258 8192000000 10344809984 10762031104 1386793152 14.477 100 64 124 0 1446204512 43017568 16393792
14:54:38 4444 8192000000 10368562352 10792783872 1404103536 14.632 100 60 120 0 1475225728 55306880 15815312
14:55:48 10236 8192000000 10309047488 10735333376 1372364112 14.348 100 36 72 0 1412875504 30934048 9577344
14:57:08 14140 8192000000 10272713840 10710216704 1323360384 13.907 98 27 54 0 1367495648 36698432 7436832
14:58:25 15184 8192000000 10274558992 10703929344 1312191968 13.806 97 32 64 1 1349814448 29701616 7920864
14:59:42 46697 8192000000 10251527920 10687918080 1299060208 13.687 96 15 30 1 1323498288 20453616 3984464
15:01:31 12103 8192000000 10254691168 10687729664 1325483824 13.926 99 46 92 2 1350781040 14945696 10351520
15:02:51 47431 8192000000 10251620192 10691162112 1323061088 13.904 98 23 46 0 1339135840 10511712 5563040
15:05:02 87591 8192000000 10265949184 10702544896 1314795568 13.830 97 11 21 0 1327506144 10587440 2123136
15:07:39 60829 8192000000 10248081920 10686255104 1312062784 13.805 96 18 36 0 1328854224 12416144 4375296
15:09:53 114218 8192000000 10254302784 10700931072 1348751856 14.136 99 31 62 0 1366278080 9660352 7865872
15:13:26 29149 8192000000 10271941072 10716950528 1361951520 14.255 99 24 48 0 1381709792 13706896 6051376
15:15:00 45189 8192000000 10265769440 10721878016 1366075328 14.292 100 8 16 0 1384727920 16638560 2014032
15:16:47 16821 8192000000 10271425264 10727014400 1372000336 14.345 100 21 42 0 1387572032 11045632 4526064
15:18:08 26540 8192000000 10286676704 10730287104 1375412448 14.376 100 27 54 1 1391529472 9584416 6532608
15:19:38 106890 8192000000 10281009024 10728902656 1357438336 14.214 98 11 22 1 1376895952 16893488 2564128
15:22:27 52246 8192000000 10282519680 10724016128 1369798544 14.325 99 29 57 2 1389190912 12856448 6535920
15:24:24 29737 8192000000 10297740016 10732462080 1370838944 14.335 99 40 80 0 1392565184 12573904 9152336
15:25:55 111009 8192000000 10294416864 10746789888 1363869424 14.272 98 20 40 2 1387208144 19063584 4275136
15:28:57 64223 8192000000 10307547008 10742415360 1358368160 14.223 98 18 36 1 1373949920 11847648 3734112
15:31:09 53408 8192000000 10291261104 10726883328 1365621648 14.288 99 56 112 0 1390478784 11786512 13070624
15:33:11 38054 8192000000 10295295408 10749054976 1390736784 14.512 100 27 52 1 1412078864 15695488 5646592
15:34:51 38564 8192000000 10288269360 10743431168 1374385072 14.366 98 18 36 1 1387692624 9076736 4230816
15:36:35 26457 8192000000 10294097248 10747359232 1379332384 14.411 99 20 39 0 1394879392 10068480 5478528
15:38:28 44106 8192000000 10293010576 10754297856 1386998944 14.479 99 16 32 1 1405549312 14694608 3855760
15:40:39 65332 8192000000 10301795152 10770354176 1395969136 14.559 100 10 20 0 1414728640 16434464 2325040
15:42:45 52732 8192000000 10274791136 10712743936 1318158944 13.860 95 18 36 1 1329857440 7931792 3766704
15:44:40 28497 8192000000 10274566992 10707566592 1340408032 14.061 98 48 97 2 1360285520 9000784 10876704
15:46:24 118204 8192000000 10270983328 10710351872 1293703424 13.638 94 19 38 0 1312525872 14315936 4506512
15:49:24 74365 8192000000 10263266400 10698252288 1285463360 13.563 94 21 42 2 1300981120 10967040 4550720
15:51:40 63265 8192000000 10270327024 10707304448 1333093568 13.995 98 19 38 0 1346840880 9858352 3888960
15:53:50 109439 8192000000 10267804000 10709237760 1354578704 14.189 99 22 44 0 1370736176 11278208 4879264
15:56:47 12090 8192000000 10256169152 10696376320 1345360288 14.106 99 25 50 2 1362141584 10679472 6101824
15:58:01 25939 8192000000 10248484672 10698907648 1354464544 14.188 100 9 18 0 1364001680 7668080 1869056
15:59:32 49537 8192000000 10259564752 10707189760 1336569984 14.026 98 17 34 1 1347218352 6822592 3825776
16:01:22 52271 8192000000 10263110288 10715934720 1364022784 14.273 100 13 26 1 1378851824 11458000 3371040
16:03:29 63095 8192000000 10262073152 10714927104 1341165872 14.068 98 6 12 0 1355258336 12621536 1470928
16:05:33 76736 8192000000 10260191888 10707836928 1299192064 13.688 95 15 30 0 1315307712 12882816 3232832
16:08:26 83212 8192000000 10266861552 10722734080 1333047136 13.995 97 21 39 1 1349235936 12171280 4017520
16:11:07 47047 8192000000 10261841168 10704191488 1313372624 13.817 96 20 40 1 1329230800 11360384 4497792
16:13:38 104345 8192000000 10283082640 10730971136 1329956224 13.967 97 8 16 1 1344114400 12585872 1572304
16:16:28 52810 8192000000 10308271712 10746884096 1373906880 14.362 99 28 56 2 1390633888 10652576 6074432
16:18:22 18519 8192000000 10302496352 10741182464 1386637232 14.476 100 49 98 1 1407193936 9424368 11132336
16:21:03 29893 8192000000 10291114080 10746707968 1387457296 14.483 100 12 24 0 1403208432 13056384 2694752
16:22:41 36843 8192000000 10256318560 10719256576 1347392240 14.124 98 3 6 0 1355640848 7522224 726384
16:24:18 37894 8192000000 10279375776 10733670400 1370752288 14.334 99 12 25 0 1383263584 10289920 2221376
16:25:57 86623 8192000000 10269493184 10730057728 1340170192 14.059 96 21 42 0 1353814272 8892448 4751632
16:28:24 48187 8192000000 10275959968 10733805568 1356225776 14.203 98 12 24 1 1372254512 12840928 3187808
16:30:13 53209 8192000000 10283468688 10742067200 1355093456 14.193 98 18 36 1 1374946560 15172064 4681040
16:32:06 64210 8192000000 10251117632 10691198976 1286354224 13.571 94 15 30 1 1301990464 12045056 3591184
16:34:12 54583 8192000000 10261749696 10694520832 1317020304 13.850 97 29 57 1 1332848272 9538064 6289904
16:36:10 44975 8192000000 10259141792 10700988416 1353318752 14.177 100 13 26 1 1368624752 12409824 2896176
16:37:58 9267 8192000000 8986347152 9170096128 155063984 1.857 12 1 2 0 157055824 1949488 42352

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 16:38:50 to 27-11-2021 16:39:05.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 27-11-2021 16:39:06 to 27-11-2021 16:39:08.

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          242355
MON$OLDEST_ACTIVE               242356
MON$OLDEST_SNAPSHOT             242356
MON$NEXT_TRANSACTION            242356
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                       1984640
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 ---
--- end of diff output ---

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

27.11.21 16:39:09. 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 16:39:11 No severe PSQL-related problems occured
27.11.21 16:39:12. End of report.