Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.12.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 16826052 kB
MemAvailable 31435868 kB
Buffers 0 kB
Cached 14822948 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.9
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-12-10 16:38:01
DTS_END                         2021-12-10 16:38:01.8210
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/42684
TRN_ID                          238086
ATT_ID                          709
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: 8905.00 27532 1068600
customer order: creation 166.06 352 19927
customer order: refuse 14.63 373 1755
order to supplier: creation 44.83 1297 5380
order to supplier: removal 3.47 96606 416
invoice (draft): creation 45.12 3762 5414
invoice (draft): removal 48.60 865 5832
invoice accept: apply 41.84 19916 5021
invoice accept: cancel 43.82 899 5258
customer reserve: creation 3899.98 195 467998
customer reserve: removal 4310.52 60 517262
realization accept: apply 82.14 179 9857
realization accept: cancel 74.58 123 8949
payment from customer: creation 59.17 122 7100
payment from customer: removal 5.62 87 674
payment to supplier: creation 48.72 156 5846
payment to supplier: removal 3.68 58 442
service: total inventory turnovers 4.74 1227 569
service: total monetary turnovers 6.66 33 799
service: aggregate perf. data 0.81 20242 97
service: refresh index statistics 0.03 404087 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-12-2021 16:38:54 to 10-12-2021 16:38:56.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5286
TEST_TIME 2 4088
TEST_TIME 3 4550
TEST_TIME 4 4441
TEST_TIME 5 4453
TEST_TIME 6 5010
TEST_TIME 7 4935
TEST_TIME 8 5422
TEST_TIME 9 5642
TEST_TIME 10 5818
TEST_TIME 11 5876
TEST_TIME 12 6185
TEST_TIME 13 6657
TEST_TIME 14 6768
TEST_TIME 15 7424
TEST_TIME 16 7660
TEST_TIME 17 7715
TEST_TIME 18 7899
TEST_TIME 19 8119
TEST_TIME 20 8166
TEST_TIME 21 8647
TEST_TIME 22 9047
TEST_TIME 23 9422
TEST_TIME 24 9760
TEST_TIME 25 9826
TEST_TIME 26 9644
TEST_TIME 27 10841
TEST_TIME 28 10994
TEST_TIME 29 10861
TEST_TIME 30 10764
TEST_TIME 31 10580
TEST_TIME 32 10612
TEST_TIME 33 10454
TEST_TIME 34 10301
TEST_TIME 35 10130
TEST_TIME 36 9959
TEST_TIME 37 9844
TEST_TIME 38 9799
TEST_TIME 39 9726
TEST_TIME 40 9652
TEST_TIME 41 9716
TEST_TIME 42 9653
TEST_TIME 43 9528
TEST_TIME 44 9506
TEST_TIME 45 9544
TEST_TIME 46 9400
TEST_TIME 47 9463
TEST_TIME 48 9423
TEST_TIME 49 9319
TEST_TIME 50 9284
TEST_TIME 51 9170
TEST_TIME 52 9309
TEST_TIME 53 9233
TEST_TIME 54 9183
TEST_TIME 55 9119
TEST_TIME 56 9101
TEST_TIME 57 9063
TEST_TIME 58 9039
TEST_TIME 59 8982
TEST_TIME 60 8924
TEST_TIME 61 8907
TEST_TIME 62 8835
TEST_TIME 63 8899
TEST_TIME 64 8901
TEST_TIME 65 8966
TEST_TIME 66 8915
TEST_TIME 67 8946
TEST_TIME 68 8971
TEST_TIME 69 8953
TEST_TIME 70 9024
TEST_TIME 71 9099
TEST_TIME 72 9098
TEST_TIME 73 9153
TEST_TIME 74 9091
TEST_TIME 75 9139
TEST_TIME 76 9233
TEST_TIME 77 9198
TEST_TIME 78 9115
TEST_TIME 79 9100
TEST_TIME 80 9046
TEST_TIME 81 9012
TEST_TIME 82 9007
TEST_TIME 83 8944
TEST_TIME 84 8913
TEST_TIME 85 8954
TEST_TIME 86 8889
TEST_TIME 87 8972
TEST_TIME 88 8982
TEST_TIME 89 9018
TEST_TIME 90 8991
TEST_TIME 91 8992
TEST_TIME 92 9027
TEST_TIME 93 9063
TEST_TIME 94 9039
TEST_TIME 95 9062
TEST_TIME 96 9050
TEST_TIME 97 9091
TEST_TIME 98 9138
TEST_TIME 99 9128
TEST_TIME 100 9155
TEST_TIME 101 9181
TEST_TIME 102 9125
TEST_TIME 103 9059
TEST_TIME 104 9082
TEST_TIME 105 9048
TEST_TIME 106 9027
TEST_TIME 107 8994
TEST_TIME 108 8970
TEST_TIME 109 8958
TEST_TIME 110 8936
TEST_TIME 111 8918
TEST_TIME 112 8918
TEST_TIME 113 8902
TEST_TIME 114 8888
TEST_TIME 115 8929
TEST_TIME 116 8950
TEST_TIME 117 8933
TEST_TIME 118 8945
TEST_TIME 119 8984
TEST_TIME 120 8909

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 10-12-2021 16:38:57 to 10-12-2021 16:39:01.

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 1498525 1498525 0 0.00 0 21315 7 0 0
doc_list_biud 1498523 1498523 0 0.00 0 64821 27 0 0
sp_add_invoice_to_stock 5117 5021 96 1.87 26 319970 19917 0 96
sp_cancel_adding_invoice 5258 5258 0 0.00 3 64849 899 0 0
sp_cancel_client_order 1755 1755 0 0.00 4 14437 373 0 0
sp_cancel_customer_reserve 517262 517262 0 0.00 1 23965 60 0 0
sp_cancel_pay_from_customer 674 674 0 0.00 3 8353 88 0 0
sp_cancel_pay_to_supplier 442 442 0 0.00 3 2527 59 0 0
sp_cancel_supplier_invoice 5842 5832 10 0.17 1 40024 866 0 10
sp_cancel_supplier_order 416 416 0 0.00 6 1031665 96606 0 0
sp_cancel_write_off 8949 8949 0 0.00 1 16714 124 0 0
sp_client_order 19927 19927 0 0.00 3 20650 352 0 0
sp_customer_reserve 467998 467998 0 0.00 0 110623 196 0 0
sp_fill_shopping_cart 30711 30711 0 0.00 1 24071 47 0 0
sp_fill_shopping_cart_clo_res 467995 467995 0 0.00 0 18038 14 0 0
sp_get_clo_for_invoice 5016 5016 0 0.00 0 32933 309 0 0
sp_kill_cost_storno 15323 15323 0 0.00 0 16602 69 0 0
sp_kill_qstorno_ret_qs2qd 1049721 1049721 0 0.00 0 39984 33 0 0
sp_kill_qty_storno 554344 554344 0 0.00 0 64820 72 0 0
sp_lock_selected_doc 20618 20618 0 0.00 0 8968 6 0 0
sp_make_cost_storno 27819 27819 0 0.00 0 21313 63 0 0
sp_multiply_rows_for_pdistr 27818 27818 0 0.00 0 9899 34 0 0
sp_multiply_rows_for_qdistr 19927 19927 0 0.00 0 20626 154 0 0
sp_pay_from_customer 7100 7100 0 0.00 4 17626 123 0 0
sp_pay_to_supplier 5846 5846 0 0.00 5 21322 157 0 0
sp_payment_common 12946 12946 0 0.00 1 9674 31 0 0
sp_qd_handle_on_cancel_clo 1755 1755 0 0.00 0 14422 344 0 0
sp_qd_handle_on_invoice_upd_sts 10273 10273 0 0.00 0 64819 379 0 0
sp_qd_handle_on_reserve_upd_sts 9857 9857 0 0.00 0 18834 54 0 0
sp_reserve_write_off 9857 9857 0 0.00 6 18869 179 0 0
sp_supplier_invoice 5414 5414 0 0.00 8 78143 3763 0 0
sp_supplier_order 5380 5380 0 0.00 5 21484 1298 0 0
srv_aggregate_perf_data 97 97 0 0.00 2094 157512 20242 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1436 166450 45811 0 0
srv_find_qd_qs_mism 1019407 1019407 0 0.00 0 19726 6 0 0
srv_make_invnt_saldo 569 569 0 0.00 34 35456 1227 0 0
srv_make_money_saldo 799 799 0 0.00 0 6098 34 0 0
srv_recalc_idx_stat 4 4 0 0.00 292711 577427 404087 0 0
t$perf-abend:sp_add_invoice_to_stock 96 96 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5022 5022 0 0.00 0 19199 155 0 0
t$perf-norm:sp_cancel_adding_invoice 405 405 0 0.00 1 4185 102 0 0
t$perf-norm:sp_cancel_client_order 1755 1755 0 0.00 0 6626 17 0 0
t$perf-norm:sp_cancel_customer_reserve 689 689 0 0.00 0 1923 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 674 674 0 0.00 0 735 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 442 442 0 0.00 0 874 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 404 404 0 0.00 0 1030 14 0 0
t$perf-norm:sp_cancel_supplier_order 416 416 0 0.00 0 25761 1493 0 0
t$perf-norm:sp_cancel_write_off 961 961 0 0.00 0 859 4 0 0
t$perf-norm:sp_client_order 19927 19927 0 0.00 0 15374 19 0 0
t$perf-norm:sp_customer_reserve 1020 1020 0 0.00 0 8412 20 0 0
t$perf-norm:sp_pay_from_customer 7100 7100 0 0.00 0 9003 13 0 0
t$perf-norm:sp_pay_to_supplier 5846 5846 0 0.00 0 7725 14 0 0
t$perf-norm:sp_reserve_write_off 9857 9857 0 0.00 0 6405 9 0 0
t$perf-norm:sp_supplier_invoice 5414 5414 0 0.00 0 13381 22 0 0
t$perf-norm:sp_supplier_order 5380 5380 0 0.00 0 6672 21 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 352 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 81 2 0 0
t$perf-norm:srv_make_invnt_saldo 569 569 0 0.00 0 3845 36 0 0
t$perf-norm:srv_make_money_saldo 799 799 0 0.00 0 854 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10222 10030 192 1.87 0 4658 21 0 192
v_all_customers 36608 36608 0 0.00 0 97 1 0 0
v_all_suppliers 29846 29846 0 0.00 0 22 1 0 0
v_all_wares 39854 39854 0 0.00 0 116 1 0 0
v_cancel_adding_invoice 810 810 0 0.00 0 600 6 0 0
v_cancel_client_order 3510 3510 0 0.00 0 3166 5 0 0
v_cancel_customer_prepayment 1348 1348 0 0.00 0 329 4 0 0
v_cancel_customer_reserve 1378 1378 0 0.00 0 3697 11 0 0
v_cancel_payment_to_supplier 884 884 0 0.00 0 2511 6 0 0
v_cancel_supplier_invoice 828 808 20 2.41 0 828 13 0 20
v_cancel_supplier_order 832 832 0 0.00 0 1593 8 0 0
v_cancel_write_off 1922 1922 0 0.00 0 6167 19 0 0
v_max_id_clo_ord 5379 5379 0 0.00 0 324 1 0 0
v_max_id_clo_res 1020 1020 0 0.00 0 1549 9 0 0
v_max_id_ord_sup 5405 5405 0 0.00 0 6003 7 0 0
v_max_non_paid_invoice 1707 1707 0 0.00 0 4421 6 0 0
v_max_non_paid_realizn 3797 3797 0 0.00 0 880 3 0 0
v_min_id_clo_ord 5379 5379 0 0.00 0 15024 14 0 0
v_min_id_clo_res 1020 1020 0 0.00 0 2677 12 0 0
v_min_id_ord_sup 5405 5405 0 0.00 0 7778 8 0 0
v_min_non_paid_invoice 5846 5846 0 0.00 0 9660 29 0 0
v_min_non_paid_realizn 7100 7100 0 0.00 0 7429 20 0 0
v_our_firm 9852 9852 0 0.00 0 14 1 0 0
v_reserve_write_off 19714 19714 0 0.00 0 5673 8 0 0
x_lock_depdocs_on_canc_invoice 10686 10686 0 0.00 0 16666 139 0 0
x_lock_depdocs_on_canc_sup_ord 416 416 0 0.00 0 3077 321 0 0
x_make_qstorno_1000_1200 5380 5380 0 0.00 2 21429 1199 0 0
x_make_qstorno_1200_2000 5414 5414 0 0.00 3 75380 3598 0 0
x_make_qstorno_2100_3300 467998 467998 0 0.00 0 110623 178 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-12-2021 16:39:02 to 10-12-2021 16:39:06.

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:05 11 8192000000 8802453488 8814891008 6674320 0.081 3 1 2 0 6775248 58144 42784
14:09:06 30621 8192000000 9911508384 10227154944 993939232 10.820 100 80 160 0 1026007376 10909056 21159088
14:10:43 8559 8192000000 9949846608 10272993280 1029774224 11.166 100 89 177 0 1060586336 8366912 22445200
14:12:03 23986 8192000000 9993518144 10331045888 1076650688 11.616 100 86 172 0 1107990192 10605728 20733776
14:13:29 13682 8192000000 10038111728 10389020672 1127124864 12.094 100 89 178 0 1159943760 11676976 21141920
14:14:47 13373 8192000000 10082537056 10439389184 1166907056 12.468 100 81 162 1 1198297744 13003376 18387312
14:16:04 4888 8192000000 10117241344 10488270848 1203525760 12.809 100 74 148 0 1233850832 13362992 16962080
14:17:11 3725 8192000000 10146626432 10517151744 1228534576 13.041 100 84 168 0 1260960240 14525936 17899728
14:18:17 2258 8192000000 10183907168 10560741376 1265303360 13.379 100 90 180 1 1300715424 15339344 20072720
14:19:21 1952 8192000000 10218410256 10601414656 1297058400 13.668 100 91 182 2 1331134400 14244784 19831216
14:20:25 4739 8192000000 10211241776 10608771072 1302633856 13.719 100 76 152 1 1330530208 10888176 17008176
14:21:31 8291 8192000000 10205383008 10613673984 1311045984 13.796 100 25 50 0 1323345472 6973328 5326160
14:22:40 9614 8192000000 10199383632 10621210624 1319682784 13.874 100 24 46 3 1332122256 7160768 5278704
14:23:50 66126 8192000000 10232071808 10650566656 1331983456 13.985 100 27 54 1 1343565312 5268640 6313216
14:25:57 4497 8192000000 10235397088 10656481280 1343898384 14.093 100 23 46 2 1354498400 5430928 5169088
14:27:02 3007 8192000000 10239066992 10656989184 1340412512 14.061 100 39 75 3 1356737360 7927360 8397488
14:28:06 9149 8192000000 10222586896 10645069824 1316296528 13.843 98 15 30 0 1324645856 4986848 3362480
14:29:18 59853 8192000000 10174299120 10586931200 1181975248 12.609 89 26 50 0 1191892624 4295888 5621488
14:31:24 8775 8192000000 10163560496 10572349440 1249385952 13.233 98 33 66 2 1267916736 10610384 7920400
14:32:33 7051 8192000000 10140602992 10538668032 1240461168 13.150 99 22 41 1 1251373856 5788224 5124464
14:33:42 20642 8192000000 10172932016 10576609280 1279156608 13.505 100 25 50 3 1289963792 5375712 5431472
14:35:03 19777 8192000000 10175328000 10583056384 1274910496 13.467 99 20 40 0 1284434368 5658320 3865552
14:36:25 9213 8192000000 10197183408 10612572160 1307890688 13.767 100 25 48 0 1318991008 5305312 5795008
14:37:35 13717 8192000000 10212176192 10626310144 1319036544 13.868 100 27 54 1 1330976832 5337264 6603024
14:38:56 24171 8192000000 10252517504 10662596608 1343772864 14.091 100 38 75 0 1361218400 9215840 8229696
14:40:24 31664 8192000000 10275120160 10689908736 1364645344 14.279 100 37 74 1 1386391184 12142720 9603120
14:42:09 46893 8192000000 10279661728 10707464192 1374940160 14.371 100 27 54 0 1398199632 16802848 6456624
14:44:01 19234 8192000000 10309425168 10725494784 1383271424 14.446 100 55 110 1 1418286032 22479840 12534768
14:45:23 16944 8192000000 10316593552 10738458624 1394443712 14.545 100 50 100 1 1428262624 21918560 11900352
14:46:47 6547 8192000000 10327920160 10751639552 1405915040 14.648 100 57 114 0 1442289888 22988832 13386016
14:48:00 4206 8192000000 10353545440 10775924736 1417209120 14.748 100 68 136 1 1461605200 27927984 16468096
14:49:08 11053 8192000000 10370794352 10791112704 1427102240 14.836 100 70 140 1 1475460000 32014272 16343488
14:50:28 1435 8192000000 10358134304 10769997824 1407104464 14.658 99 71 142 3 1462356336 37544144 17707728
14:51:34 3450 8192000000 10359106480 10773528576 1401757728 14.611 100 87 173 0 1461846672 39288784 20800160
14:52:42 3045 8192000000 10365226720 10785353728 1412237040 14.704 100 82 164 4 1471921280 39812144 19872096
14:53:51 2678 8192000000 10366311472 10783780864 1414887904 14.727 100 79 156 1 1472895968 39294096 18713968
14:54:59 3668 8192000000 10336086576 10747662336 1354303552 14.186 97 70 140 1 1410864352 38670480 17890320
14:56:08 5480 8192000000 10374028176 10779623424 1401553824 14.609 100 84 164 0 1471032784 48827776 20651184
14:57:20 3827 8192000000 10368945568 10772054016 1388076000 14.489 99 83 164 1 1464767680 55884720 20806960
14:58:29 9273 8192000000 10347389552 10772209664 1375570096 14.377 98 43 87 0 1442342688 55025792 11746800
14:59:42 9088 8192000000 10355794800 10776637440 1387080096 14.480 100 53 106 1 1454194192 52419552 14694544
15:00:56 10312 8192000000 10325598864 10741256192 1351064944 14.157 98 45 90 3 1412883872 49364528 12454400
15:02:13 19236 8192000000 10319609728 10759131136 1366084304 14.292 98 23 46 0 1412572736 39959792 6528640
15:03:54 31820 8192000000 10324363344 10765553664 1379207536 14.409 100 19 38 0 1426165968 41651664 5306768
15:05:29 67720 8192000000 10290945424 10739015680 1340603520 14.063 98 19 38 1 1380028704 33826320 5598864
15:07:39 43770 8192000000 10287674496 10731069440 1359294240 14.231 99 18 37 2 1383420816 19949664 4176912
15:09:26 114207 8192000000 10289353376 10747129856 1380984352 14.425 100 20 40 0 1399282784 13331312 4967120
15:12:20 59867 8192000000 10287175344 10735759360 1359795552 14.236 98 14 28 0 1378283280 15632576 2855152
15:14:25 10759 8192000000 10282603184 10708426752 1359892784 14.236 100 49 95 0 1380844736 10329728 10622224
15:15:38 12066 8192000000 10294228144 10733682688 1378954336 14.407 100 41 81 1 1401505312 12812848 9738128
15:16:50 30133 8192000000 10278827952 10726211584 1371172528 14.338 100 37 74 1 1389503792 9568576 8762688
15:18:43 166450 8192000000 10299141552 10758938624 1375033088 14.372 99 26 52 0 1397778624 16720608 6024928
15:22:29 88727 8192000000 10287475104 10746961920 1378111088 14.400 99 12 24 1 1400978832 19488864 3378880
15:25:03 85903 8192000000 10300942368 10771349504 1399682064 14.592 100 20 40 1 1418970432 14249760 5038608
15:27:37 46550 8192000000 10311058768 10775101440 1408870896 14.674 100 17 34 0 1425192208 12401072 3920240
15:30:02 51307 8192000000 10277185376 10750656512 1362766512 14.262 98 10 20 1 1378696496 13775216 2154768
15:31:56 59156 8192000000 10314276912 10766364672 1387374880 14.482 99 30 60 1 1405561632 11147120 7039632
15:34:04 85275 8192000000 10297395584 10758905856 1398235392 14.579 100 26 52 0 1413558080 9207264 6115424
15:36:40 52395 8192000000 10278482224 10731663360 1347715072 14.127 97 18 36 1 1358956912 7394208 3847632
15:38:40 56102 8192000000 10293035472 10751156224 1385341744 14.464 99 36 72 1 1403112784 9129024 8642016
15:40:37 69402 8192000000 10262090048 10727268352 1328779232 13.956 96 14 28 0 1341035456 9194272 3061952
15:42:51 47220 8192000000 10276896192 10736005120 1369767776 14.325 99 14 28 1 1380651056 7520400 3362880
15:44:46 52359 8192000000 10268371264 10719490048 1348952976 14.138 98 15 30 0 1361890496 9447104 3490416
15:46:38 43109 8192000000 10292147360 10734018560 1350886256 14.155 98 17 34 1 1367844048 12928608 4029184
15:48:26 15728 8192000000 10300836096 10735120384 1358220416 14.221 98 60 120 0 1382987664 11925856 12841392
15:49:45 66576 8192000000 10272559712 10723639296 1304921216 13.740 95 23 46 0 1321046480 11166080 4959184
15:51:52 36711 8192000000 10280211232 10713415680 1351459088 14.161 99 45 88 2 1369247344 7050832 10737424
15:53:29 64911 8192000000 10280831504 10730819584 1355967744 14.201 99 22 44 0 1379512208 18670096 4874368
15:55:34 35995 8192000000 10290979360 10733780992 1357641616 14.216 99 19 37 0 1375136640 13292032 4202992
15:57:11 79859 8192000000 10278680368 10718781440 1337002944 14.030 97 28 56 0 1349518528 6695328 5820256
15:59:41 26013 8192000000 10289414320 10729701376 1357621504 14.216 99 53 106 1 1387821840 18461008 11739328
16:01:38 72947 8192000000 10282679648 10742726656 1363249232 14.267 99 10 20 0 1384332448 18435056 2648160
16:03:52 44835 8192000000 10278351024 10736873472 1364280160 14.276 99 7 14 0 1382928848 17010944 1637744
16:05:37 83687 8192000000 10272581488 10737922048 1324687040 13.919 96 16 32 0 1349421216 20717248 4016928
16:08:01 33309 8192000000 10269468464 10705874944 1301915616 13.713 95 31 62 1 1321461984 12434848 7111520
16:09:35 65976 8192000000 10275733760 10735091712 1342596400 14.081 98 20 40 0 1358469840 11504608 4368832
16:11:44 69523 8192000000 10287665088 10731548672 1342107056 14.076 97 15 30 0 1357889664 12790928 2991680
16:14:06 49528 8192000000 10314542480 10756861952 1378651248 14.404 99 36 72 3 1397919712 10385136 8883328
16:15:57 61115 8192000000 10300942160 10756927488 1380474192 14.421 99 21 42 1 1397601152 12618944 4508016
16:17:59 70216 8192000000 10301991072 10757275648 1374929104 14.371 99 13 26 0 1395015840 17149056 2937680
16:20:11 126281 8192000000 10277388272 10740428800 1342513104 14.080 97 27 54 0 1365979392 17509072 5957216
16:23:36 51577 8192000000 10252371312 10713722880 1341513152 14.071 98 19 38 1 1354142624 7893264 4736208
16:26:25 63352 8192000000 10258645776 10714804224 1331205936 13.978 97 10 20 1 1341831968 8172880 2453152
16:28:29 46279 8192000000 10241282352 10693586944 1312352304 13.807 97 10 20 1 1322510928 8114160 2044464
16:30:16 50073 8192000000 10252894096 10705080320 1342545104 14.080 99 14 28 0 1354312896 8250352 3517440
16:32:09 38020 8192000000 10246514576 10689269760 1280845856 13.521 94 20 39 1 1295644000 9871632 4926512
16:33:47 87855 8192000000 10243838384 10694922240 1307347552 13.762 97 8 16 0 1327371808 18023200 2001056
16:36:22 84975 8192000000 10270473136 10734825472 1359331472 14.231 100 15 28 0 1384997792 22405296 3261024

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 10-12-2021 16:39:07 to 10-12-2021 16:39:22.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 96 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-12-2021 16:39:23 to 10-12-2021 16:39:25.

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          236812
MON$OLDEST_ACTIVE               238215
MON$OLDEST_SNAPSHOT             238215
MON$NEXT_TRANSACTION            238215
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                       2012288
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33542 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33542 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33542 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33542 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 ---
:194: 
:195: mscs-test-161.budzdorov.ru    Fri Dec 10 16:38:10 2021
:196:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:197: 
:198: 
:199: mscs-test-161.budzdorov.ru    Fri Dec 10 16:38:10 2021
:200:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:201: 
--- end of diff output ---

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

10.12.21 16:39:26. 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-12-10 16:39:29 No severe PSQL-related problems occured
10.12.21 16:39:30. End of report.