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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-05-09 08:45
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4793.92
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16060564 kB
MemAvailable 30736540 kB
Buffers 0 kB
Cached 15624256 kB
SwapCached 2944 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-10-01 01:37:52
DTS_END                         2021-10-01 01:37:52.4080
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/54044
TRN_ID                          226909
ATT_ID                          610
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: 9227.49 -311996 1107299
customer order: creation 158.50 -3716 19020
customer order: refuse 13.69 325 1643
order to supplier: creation 42.77 -13822 5132
order to supplier: removal 3.58 -5329536 429
invoice (draft): creation 42.78 -26703 5134
invoice (draft): removal 53.60 -11203 6432
invoice accept: apply 39.92 -272873 4790
invoice accept: cancel 48.21 800 5785
customer reserve: creation 3750.57 -2748 450068
customer reserve: removal 4799.08 -3853 575889
realization accept: apply 76.81 189 9217
realization accept: cancel 79.79 104 9575
payment from customer: creation 53.44 128 6413
payment from customer: removal 5.13 72 615
payment to supplier: creation 44.88 152 5386
payment to supplier: removal 3.27 90 392
service: total inventory turnovers 4.59 1147 551
service: total monetary turnovers 6.08 37 729
service: aggregate perf. data 0.79 -801653 95
service: refresh index statistics 0.03 223140 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 01-10-2021 01:39:00 to 01-10-2021 01:39:02.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2642
TEST_TIME 2 4171
TEST_TIME 3 4302
TEST_TIME 4 4871
TEST_TIME 5 5396
TEST_TIME 6 5010
TEST_TIME 7 4950
TEST_TIME 8 5205
TEST_TIME 9 5166
TEST_TIME 10 5625
TEST_TIME 11 5744
TEST_TIME 12 5737
TEST_TIME 13 5924
TEST_TIME 14 6017
TEST_TIME 15 6158
TEST_TIME 16 6408
TEST_TIME 17 6722
TEST_TIME 18 6819
TEST_TIME 19 6993
TEST_TIME 20 7532
TEST_TIME 21 7960
TEST_TIME 22 8175

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 01-10-2021 01:39:03 to 01-10-2021 01:39:06.

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 1489569 1489569 0 0.00 0 21114 6 0 0
doc_list_biud 1489579 1489579 0 0.00 0 35201 28 0 0
sp_add_invoice_to_stock 4709 4619 90 1.91 32 235538 19183 0 90
sp_cancel_adding_invoice 5785 5785 0 0.00 4 80286 800 0 0
sp_cancel_client_order 1643 1643 0 0.00 4 9901 325 0 0
sp_cancel_customer_reserve 560184 560184 0 0.00 1 20639 58 0 0
sp_cancel_pay_from_customer 615 615 0 0.00 2 2621 72 0 0
sp_cancel_pay_to_supplier 392 392 0 0.00 3 5049 90 0 0
sp_cancel_supplier_invoice 6318 6314 4 0.06 2 17879 863 0 4
sp_cancel_supplier_order 367 367 0 0.00 11 980174 68186 0 0
sp_cancel_write_off 9575 9575 0 0.00 1 12566 104 0 0
sp_client_order 18693 18693 0 0.00 3 25953 373 0 0
sp_customer_reserve 440914 440914 0 0.00 0 155019 187 0 0
sp_fill_shopping_cart 29285 29285 0 0.00 1 24212 46 0 0
sp_fill_shopping_cart_clo_res 450057 450057 0 0.00 0 19638 13 0 0
sp_get_clo_for_invoice 4779 4779 0 0.00 0 53731 318 0 0
sp_kill_cost_storno 16367 16367 0 0.00 0 10923 59 0 0
sp_kill_qstorno_ret_qs2qd 1136459 1136459 0 0.00 0 18898 32 0 0
sp_kill_qty_storno 597368 597368 0 0.00 0 35199 70 0 0
sp_lock_selected_doc 19306 19306 0 0.00 0 7492 5 0 0
sp_make_cost_storno 25795 25795 0 0.00 0 21111 58 0 0
sp_multiply_rows_for_pdistr 25795 25795 0 0.00 0 10595 32 0 0
sp_multiply_rows_for_qdistr 18694 18694 0 0.00 0 25940 167 0 0
sp_pay_from_customer 6413 6413 0 0.00 4 11575 128 0 0
sp_pay_to_supplier 5386 5386 0 0.00 5 21122 152 0 0
sp_payment_common 11799 11799 0 0.00 1 9508 29 0 0
sp_qd_handle_on_cancel_clo 1643 1643 0 0.00 0 9893 295 0 0
sp_qd_handle_on_invoice_upd_sts 10564 10564 0 0.00 0 35196 387 0 0
sp_qd_handle_on_reserve_upd_sts 9217 9217 0 0.00 0 11534 58 0 0
sp_reserve_write_off 9217 9217 0 0.00 7 14382 190 0 0
sp_supplier_invoice 5030 5030 0 0.00 6 114176 3545 0 0
sp_supplier_order 5058 5058 0 0.00 5 33462 1323 0 0
srv_aggregate_perf_data 93 93 0 0.00 2603 99600 16844 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 1643 177736 50243 0 0
srv_find_qd_qs_mism 1037136 1037136 0 0.00 0 18956 5 0 0
srv_make_invnt_saldo 551 551 0 0.00 38 60266 1147 0 0
srv_make_money_saldo 729 729 0 0.00 1 11444 37 0 0
srv_recalc_idx_stat 4 4 0 0.00 82876 389308 223141 0 0
t$perf-abend:sp_add_invoice_to_stock 90 90 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4790 4790 0 0.00 0 17390 222 0 0
t$perf-norm:sp_cancel_adding_invoice 351 351 0 0.00 0 4381 119 0 0
t$perf-norm:sp_cancel_client_order 1643 1643 0 0.00 0 11055 25 0 0
t$perf-norm:sp_cancel_customer_reserve 583 583 0 0.00 0 2423 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 615 615 0 0.00 0 434 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 392 392 0 0.00 0 82 1 0 0
t$perf-norm:sp_cancel_supplier_invoice 379 379 0 0.00 0 2746 19 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 30698 1809 0 0
t$perf-norm:sp_cancel_write_off 919 919 0 0.00 0 2049 9 0 0
t$perf-norm:sp_client_order 19020 19020 0 0.00 0 16599 23 0 0
t$perf-norm:sp_customer_reserve 896 896 0 0.00 0 711 4 0 0
t$perf-norm:sp_pay_from_customer 6413 6413 0 0.00 0 18272 19 0 0
t$perf-norm:sp_pay_to_supplier 5386 5386 0 0.00 0 5343 10 0 0
t$perf-norm:sp_reserve_write_off 9217 9217 0 0.00 0 13034 15 0 0
t$perf-norm:sp_supplier_invoice 5134 5134 0 0.00 0 10916 21 0 0
t$perf-norm:sp_supplier_order 5132 5132 0 0.00 0 12224 19 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 1513 34 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 19 1 0 0
t$perf-norm:srv_make_invnt_saldo 551 551 0 0.00 0 1783 26 0 0
t$perf-norm:srv_make_money_saldo 729 729 0 0.00 0 1021 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 7 4 0 0
v_add_invoice_to_stock 9736 9556 180 1.84 0 8479 21 0 180
v_all_customers 34688 34688 0 0.00 0 25 1 0 0
v_all_suppliers 28410 28410 0 0.00 0 27 1 0 0
v_all_wares 38040 38040 0 0.00 0 33 1 0 0
v_cancel_adding_invoice 702 702 0 0.00 0 1700 8 0 0
v_cancel_client_order 3286 3286 0 0.00 0 2445 7 0 0
v_cancel_customer_prepayment 1230 1230 0 0.00 0 1445 5 0 0
v_cancel_customer_reserve 1166 1166 0 0.00 0 4596 7 0 0
v_cancel_payment_to_supplier 784 784 0 0.00 0 3763 9 0 0
v_cancel_supplier_invoice 766 758 8 1.04 0 2863 24 0 8
v_cancel_supplier_order 858 858 0 0.00 0 2864 10 0 0
v_cancel_write_off 1838 1838 0 0.00 0 3003 16 0 0
v_max_id_clo_ord 5131 5131 0 0.00 0 3577 2 0 0
v_max_id_clo_res 896 896 0 0.00 0 2129 11 0 0
v_max_id_ord_sup 5133 5133 0 0.00 0 6911 6 0 0
v_max_non_paid_invoice 1445 1445 0 0.00 0 752 3 0 0
v_max_non_paid_realizn 3301 3301 0 0.00 0 1616 4 0 0
v_min_id_clo_ord 5131 5131 0 0.00 0 3980 8 0 0
v_min_id_clo_res 896 896 0 0.00 0 4147 15 0 0
v_min_id_ord_sup 5133 5133 0 0.00 0 4400 9 0 0
v_min_non_paid_invoice 5386 5386 0 0.00 0 6820 20 0 0
v_min_non_paid_realizn 6413 6413 0 0.00 0 9070 19 0 0
v_our_firm 9576 9576 0 0.00 0 127 1 0 0
v_reserve_write_off 18432 18432 0 0.00 0 9506 8 0 0
x_lock_depdocs_on_canc_invoice 11838 11838 0 0.00 0 23387 135 0 0
x_lock_depdocs_on_canc_sup_ord 429 429 0 0.00 0 6031 349 0 0
x_make_qstorno_1000_1200 5058 5058 0 0.00 1 33429 1238 0 0
x_make_qstorno_1200_2000 5030 5030 0 0.00 4 102574 3373 0 0
x_make_qstorno_2100_3300 440914 440914 0 0.00 0 155016 172 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 01-10-2021 01:39:07 to 01-10-2021 01:39:11.

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
23:07:56 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
23:08:58 26543 8192000000 10384138560 10704031744 1427399136 14.838 100 88 174 1 1461881936 5930528 28552272
23:10:29 26479 8192000000 10446064448 10779713536 1499519904 15.472 100 85 170 0 1534722096 7575856 27626336
23:12:04 15641 8192000000 10513525264 10860843008 1566347168 16.051 100 78 156 0 1599358400 8968832 24042400
23:13:23 16202 8192000000 10594873808 10956247040 1655204960 16.808 100 79 158 1 1688777824 10182960 23389904
23:14:43 11595 8192000000 10667035504 11037360128 1721886640 17.368 100 71 139 0 1752463248 10940176 19636432
23:15:58 5594 8192000000 10721488144 11104518144 1787637248 17.912 100 80 160 1 1822715952 12242208 22836496
23:17:07 8314 8192000000 10759549280 11152220160 1828492208 18.247 100 74 148 0 1861465168 12594624 20378336
23:18:17 1989 8192000000 10812845280 11206184960 1873535024 18.613 100 89 178 3 1911820976 13531168 24754784
23:19:20 4458 8192000000 10803936480 11215515648 1877670864 18.646 100 57 114 0 1904366976 10866624 15829488
23:20:26 11537 8192000000 10830249744 11230105600 1887352768 18.724 100 67 136 4 1911292736 5047488 18892480
23:21:39 4144 8192000000 10814657872 11240497152 1896656352 18.799 100 35 70 1 1915297296 9148048 9492896
23:22:45 9324 8192000000 10815747328 11248988160 1903733232 18.856 100 20 39 1 1914627728 6086032 4808464
23:23:55 11783 8192000000 10824629200 11255635968 1907059440 18.883 100 17 34 1 1918468320 6337344 5071536
23:25:08 55430 8192000000 10826548720 11264954368 1911059840 18.915 100 25 48 1 1922886208 5457536 6368832
23:27:04 3717 8192000000 10822496560 11261284352 1915686992 18.952 100 31 62 0 1930985968 6298304 9000672
23:28:08 7271 8192000000 10803767104 11226824704 1844256448 18.375 97 27 55 1 1858724960 6662048 7806464
23:29:16 15823 8192000000 10765474672 11181895680 1811370912 18.107 97 16 32 1 1821942912 5324016 5247984
23:30:32 19452 8192000000 10731450400 11149221888 1719655664 17.349 93 26 50 1 1736453808 9769952 7028192
23:31:53 7879 8192000000 10679257728 11099590656 1714438736 17.306 96 25 50 0 1726989152 5477536 7072880
23:33:09 9117 8192000000 10733327504 11159961600 1821564768 18.190 100 13 26 2 1830426448 5230096 3631584
23:34:19 22537 8192000000 10762202192 11190374400 1833137744 18.285 99 16 30 3 1843609952 5239024 5233184
23:35:43 28058 8192000000 10776305584 11205148672 1858781200 18.493 100 15 30 0 1868525072 5450704 4293168
23:37:11 10819 8192000000 10805665888 11234492416 1889173056 18.739 100 22 40 2 1899764496 4550928 6040512
23:38:29 19339 8192000000 10846748112 11265019904 1917385472 18.966 100 56 113 2 1942594960 8991472 16218016
23:39:55 5799 8192000000 10867266816 11288432640 1932379616 19.086 100 51 101 1 1959267424 9965744 16922064
23:41:05 8108 8192000000 10912061936 11319799808 1947663904 19.208 100 45 90 2 1976625648 15868576 13093168
23:42:17 21417 8192000000 10888331600 11326771200 1960113856 19.307 100 36 72 0 1985915328 14313504 11487968
23:43:41 16932 8192000000 10918893376 11342336000 1972726928 19.407 100 50 100 2 2003045440 15237920 15080592
23:45:06 25667 8192000000 10941205536 11373948928 1990367584 19.547 100 59 119 2 2030921856 23681408 16872864
23:46:41 13239 8192000000 10955254496 11387113472 2003358640 19.649 100 56 112 0 2045133104 25220048 16554416
23:47:58 7264 8192000000 10942060272 11387232256 2002047664 19.639 100 39 75 3 2037202672 21961056 13193952
23:49:13 7253 8192000000 10974136480 11404574720 2014260048 19.735 100 60 120 1 2060406880 28651904 17494928
23:50:28 2447 8192000000 10994112512 11420921856 2026814912 19.834 100 72 144 1 2082081472 33303248 21963312
23:51:36 8669 8192000000 10991482864 11426701312 2023066528 19.804 100 57 114 1 2080065120 39102000 17896592
23:52:49 3193 8192000000 11006484432 11428524032 2024578576 19.816 100 83 165 0 2090930752 41249040 25103136
23:53:57 1891 8192000000 11023471872 11453648896 2042671616 19.958 100 82 159 2 2112782752 45700064 24411072
23:55:03 2103 8192000000 11024725392 11443392512 2031283424 19.869 100 86 173 0 2106232144 47860272 27088448
23:56:09 4767 8192000000 11023911776 11440664576 2019079488 19.773 100 87 172 1 2101989360 55476112 27433760
23:57:20 1642 8192000000 10992958704 11412807680 1999355536 19.618 100 82 162 1 2072830480 47472560 26002384
23:58:25 5823 8192000000 10944288432 11379228672 1952238096 19.244 99 53 106 0 2018639472 48963664 17437712
00:00:05 10855 8192000000 10944277504 11391594496 1965390816 19.349 99 46 92 3 2029252224 49188832 14672576
00:01:20 19008 8192000000 10938268688 11373666304 1922808944 19.009 98 39 78 1 1990106992 53516816 13781232
00:02:46 16085 8192000000 10903846976 11359457280 1919295552 18.981 100 29 58 0 1990331632 60389168 10646912
00:04:19 16890 8192000000 10921601296 11375112192 1946696016 19.200 100 35 70 2 2017539344 59053520 11789808
00:05:51 44677 8192000000 10896272736 11357401088 1916263760 18.957 99 18 36 1 1970076032 47175296 6636976
00:08:34 73844 8192000000 10859078768 11316834304 1876134640 18.634 97 14 28 1 1895547072 15876496 3535936
00:10:53 113366 8192000000 10890518048 11331731456 1909816448 18.905 98 16 31 1 1924578704 10049840 4712416
00:13:51 99594 8192000000 10877763520 11349336064 1937845152 19.130 99 29 60 0 1961087424 14945872 8296400
00:16:31 177736 8192000000 10891936144 11364257792 1917721920 18.969 97 15 30 0 1932307760 10398208 4187632
00:21:05 72817 8192000000 10908959616 11377455104 1971035216 19.394 99 27 54 0 1990178256 10752096 8390944
00:23:53 65573 8192000000 10927419120 11384176640 1996439840 19.595 100 33 66 0 2016641360 10668096 9533424
00:25:59 57490 8192000000 10894396016 11382460416 1971187552 19.395 99 24 48 1 1990124080 11795584 7140944
00:28:57 47768 8192000000 10923853456 11393503232 1990307872 19.546 100 11 22 0 2001795872 8312736 3175264
00:31:05 73951 8192000000 10926638256 11403243520 2003339360 19.649 100 20 39 2 2017739792 9099712 5300720
00:33:22 52432 8192000000 10897566768 11376381952 1967398352 19.365 98 14 28 0 1987417200 16364224 3654624
00:35:16 102012 8192000000 10910780208 11401535488 1979018528 19.457 99 14 29 2 2000363328 16773488 4571312
00:38:06 71978 8192000000 10930537648 11400548352 1968667008 19.375 98 17 34 0 1985934016 12656768 4610240
00:40:21 47795 8192000000 10927430128 11392991232 1966376016 19.357 98 29 58 1 1985966432 11424432 8165984
00:42:13 73494 8192000000 10956446848 11416678400 1992357184 19.562 99 22 44 1 2011020448 11838336 6824928
00:44:28 52497 8192000000 10950270304 11421782016 1998226304 19.609 99 22 44 2 2014930288 11068960 5635024
00:46:30 65045 8192000000 10911593936 11378880512 1921271200 18.997 96 32 64 0 1939887936 9555168 9061568
00:48:38 102622 8192000000 10884369968 11360649216 1861007104 18.511 94 15 30 0 1877640928 12336704 4297120
00:51:22 87727 8192000000 10788343904 11233300480 1807198432 18.073 93 16 31 1 1827335328 15290384 4846512
00:53:57 33627 8192000000 10825363376 11288977408 1869823392 18.583 98 23 46 1 1890499792 14244576 6431824
00:55:31 71177 8192000000 10846232784 11321847808 1892545904 18.766 98 8 16 0 1905701904 10790400 2365600
00:57:46 57457 8192000000 10860077808 11327037440 1898302832 18.813 98 18 36 0 1915182496 11835232 5044432
00:59:57 86263 8192000000 10854781984 11292114944 1851574352 18.435 96 18 36 0 1867916160 11174496 5167312
01:02:36 70696 8192000000 10815855200 11297861632 1881990832 18.681 99 7 14 0 1896410784 12562912 1857040
01:04:47 39048 8192000000 10811549776 11269222400 1874577200 18.621 98 20 40 0 1891512144 11396944 5538000
01:06:27 18562 8192000000 10872786464 11320123392 1926017120 19.035 100 45 89 2 1949576576 10755008 12804448
01:07:46 70781 8192000000 10874380320 11327897600 1908476560 18.894 98 27 54 0 1931582640 16210864 6895216
01:10:06 50652 8192000000 10883044240 11355471872 1924075088 19.019 98 18 34 3 1943076288 14120304 4880896
01:11:58 118371 8192000000 10906207664 11384537088 1931409184 19.078 98 11 22 2 1950706864 16400912 2896768
01:15:24 81799 8192000000 10924141168 11379830784 1962922400 19.329 99 25 47 0 1983882688 14004560 6955728
01:17:54 73415 8192000000 10912091248 11376287744 1934152784 19.100 97 30 59 0 1953751760 11169200 8429776
01:20:09 74791 8192000000 10895852112 11361492992 1921287680 18.997 97 10 20 0 1930333152 6611856 2433616
01:22:24 59598 8192000000 10920980448 11377041408 1951062880 19.235 98 32 64 0 1973141520 12885728 9192912
01:25:39 61541 8192000000 10915074544 11379933184 1948251344 19.213 98 17 34 0 1963970432 10392880 5326208
01:27:40 99954 8192000000 10925281856 11403948032 1998395488 19.610 100 20 40 1 2017839472 13202496 6241488
01:30:21 80555 8192000000 10920207296 11407441920 1960808592 19.312 98 13 26 0 1982222416 17711264 3702560
01:32:51 79518 8192000000 10929480112 11400159232 1939107376 19.140 97 17 34 0 1967360288 23071728 5181184
01:35:11 41622 8192000000 10920839760 11396198400 1979933840 19.464 99 24 48 0 1994797168 7910960 6952368
01:37:04 44259 8192000000 10913338560 11392372736 1920253312 18.989 96 17 34 0 1942138896 17388528 4497056

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 01-10-2021 01:39:13 to 01-10-2021 01:39:28.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 90 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 01-10-2021 01:39:29 to 01-10-2021 01:39:31.

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          225999
MON$OLDEST_ACTIVE               227113
MON$OLDEST_SNAPSHOT             227113
MON$NEXT_TRANSACTION            227113
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                       2026336
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.33505 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33505 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33505 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33505 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 ---
:37: 
:38: mscs-test-161.budzdorov.ru    Fri Oct  1 01:38:01 2021
:39:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:40: 
:41: 
:42: mscs-test-161.budzdorov.ru    Fri Oct  1 01:38:02 2021
:43:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:44: 
--- end of diff output ---

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

01.10.21 01:39:32. 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-10-01 01:39:34 No severe PSQL-related problems occured
01.10.21 01:39:35. End of report.