Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.08.2021 15:02
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 18798284 kB
MemAvailable 30469100 kB
Buffers 0 kB
Cached 12391396 kB
SwapCached 2548 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                    PREMATURE: EXTERNAL COMMAND.
DTS_END                         2021-08-02 15:01:06.8030
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/51084
TRN_ID                          114408
ATT_ID                          377
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: 1067.85 19915 128142
customer order: creation 30.55 451 3666
customer order: refuse 2.43 496 292
order to supplier: creation 7.90 2130 948
order to supplier: removal 0.37 199685 44
invoice (draft): creation 8.16 6121 979
invoice (draft): removal 4.09 2297 491
invoice accept: apply 7.08 34536 850
invoice accept: cancel 3.67 2929 440
customer reserve: creation 597.85 366 71742
customer reserve: removal 358.55 167 43026
realization accept: apply 14.93 299 1791
realization accept: cancel 8.95 237 1074
payment from customer: creation 10.15 187 1218
payment from customer: removal 1.12 156 134
payment to supplier: creation 8.80 206 1056
payment to supplier: removal 0.54 140 65
service: total inventory turnovers 1.05 874 126
service: total monetary turnovers 1.54 38 185
service: aggregate perf. data 0.12 24826 14
service: refresh index statistics 0.01 122158 1

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 02-08-2021 15:03:23 to 02-08-2021 15:03:25.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3797
TEST_TIME 2 3223
TEST_TIME 3 5106
TEST_TIME 4 4827
TEST_TIME 5 4852
TEST_TIME 6 4597
TEST_TIME 7 4540
TEST_TIME 8 4337
TEST_TIME 9 4600
TEST_TIME 10 4591
TEST_TIME 11 5023
TEST_TIME 12 5305
TEST_TIME 13 5451
TEST_TIME 14 5578
TEST_TIME 15 6138
TEST_TIME 16 6357
TEST_TIME 17 6518
TEST_TIME 18 7123
TEST_TIME 19 6767

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:02, from 02-08-2021 15:03:26 to 02-08-2021 15:03:28.

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 192359 192359 0 0.00 0 22915 17 0 0
doc_list_biud 192359 192359 0 0.00 0 18914 51 0 0
sp_add_invoice_to_stock 888 850 38 4.27 156 150473 34537 0 38
sp_cancel_adding_invoice 440 440 0 0.00 24 48209 2929 0 0
sp_cancel_client_order 292 292 0 0.00 18 2451 496 0 0
sp_cancel_customer_reserve 43026 43026 0 0.00 1 10591 167 0 0
sp_cancel_pay_from_customer 134 134 0 0.00 4 809 157 0 0
sp_cancel_pay_to_supplier 65 65 0 0.00 4 846 140 0 0
sp_cancel_supplier_invoice 494 491 3 0.60 3 11252 2298 0 3
sp_cancel_supplier_order 44 44 0 0.00 55 964988 199686 0 0
sp_cancel_write_off 1074 1074 0 0.00 1 11761 238 0 0
sp_client_order 3666 3666 0 0.00 5 13472 451 0 0
sp_customer_reserve 71742 71742 0 0.00 0 27865 367 0 0
sp_fill_shopping_cart 5579 5579 0 0.00 1 4193 43 0 0
sp_fill_shopping_cart_clo_res 71729 71729 0 0.00 0 9996 16 0 0
sp_get_clo_for_invoice 837 837 0 0.00 0 17806 296 0 0
sp_kill_cost_storno 1713 1713 0 0.00 0 3842 147 0 0
sp_kill_qstorno_ret_qs2qd 87661 87661 0 0.00 0 11737 93 0 0
sp_kill_qty_storno 47995 47995 0 0.00 0 18912 201 0 0
sp_lock_selected_doc 3602 3602 0 0.00 0 1375 2 0 0
sp_make_cost_storno 4902 4902 0 0.00 0 22899 84 0 0
sp_multiply_rows_for_pdistr 4902 4902 0 0.00 0 10143 76 0 0
sp_multiply_rows_for_qdistr 3666 3666 0 0.00 0 12452 208 0 0
sp_pay_from_customer 1218 1218 0 0.00 5 9400 188 0 0
sp_pay_to_supplier 1056 1056 0 0.00 6 22941 207 0 0
sp_payment_common 2274 2274 0 0.00 1 9347 40 0 0
sp_qd_handle_on_cancel_clo 292 292 0 0.00 7 2417 474 0 0
sp_qd_handle_on_invoice_upd_sts 1277 1277 0 0.00 0 18907 907 0 0
sp_qd_handle_on_reserve_upd_sts 1791 1791 0 0.00 0 3038 77 0 0
sp_reserve_write_off 1791 1791 0 0.00 7 10177 299 0 0
sp_supplier_invoice 979 979 0 0.00 11 47838 6121 0 0
sp_supplier_order 948 948 0 0.00 7 20146 2131 0 0
srv_aggregate_perf_data 14 14 0 0.00 2998 100576 24826 0 0
srv_fill_mon_cache_memory 14 14 0 0.00 2681 47657 11885 0 0
srv_find_qd_qs_mism 118951 118951 0 0.00 0 16189 18 0 0
srv_make_invnt_saldo 126 126 0 0.00 92 3873 875 0 0
srv_make_money_saldo 185 185 0 0.00 1 574 39 0 0
srv_recalc_idx_stat 1 1 0 0.00 122158 122158 122158 0 0
t$perf-abend:sp_add_invoice_to_stock 38 38 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 850 850 0 0.00 1 18561 445 0 0
t$perf-norm:sp_cancel_adding_invoice 68 68 0 0.00 1 9706 374 0 0
t$perf-norm:sp_cancel_client_order 292 292 0 0.00 0 873 39 0 0
t$perf-norm:sp_cancel_customer_reserve 117 117 0 0.00 0 614 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 134 134 0 0.00 0 1136 19 0 0
t$perf-norm:sp_cancel_pay_to_supplier 65 65 0 0.00 0 83 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 63 63 0 0.00 0 236 25 0 0
t$perf-norm:sp_cancel_supplier_order 44 44 0 0.00 0 22547 4467 0 0
t$perf-norm:sp_cancel_write_off 192 192 0 0.00 0 457 10 0 0
t$perf-norm:sp_client_order 3666 3666 0 0.00 0 16141 53 0 0
t$perf-norm:sp_customer_reserve 181 181 0 0.00 0 3948 36 0 0
t$perf-norm:sp_pay_from_customer 1218 1218 0 0.00 0 3263 19 0 0
t$perf-norm:sp_pay_to_supplier 1056 1056 0 0.00 0 1004 16 0 0
t$perf-norm:sp_reserve_write_off 1791 1791 0 0.00 0 1623 20 0 0
t$perf-norm:sp_supplier_invoice 979 979 0 0.00 0 1604 46 0 0
t$perf-norm:sp_supplier_order 948 948 0 0.00 0 9738 54 0 0
t$perf-norm:srv_aggregate_perf_data 14 14 0 0.00 0 307 37 0 0
t$perf-norm:srv_fill_mon_cache_memory 14 14 0 0.00 0 866 62 0 0
t$perf-norm:srv_make_invnt_saldo 126 126 0 0.00 0 613 66 0 0
t$perf-norm:srv_make_money_saldo 185 185 0 0.00 0 618 31 0 0
t$perf-norm:srv_recalc_idx_stat 1 1 0 0.00 3 3 3 0 0
v_add_invoice_to_stock 1748 1672 76 4.34 0 2542 23 0 76
v_all_customers 6284 6284 0 0.00 0 12 1 0 0
v_all_suppliers 5226 5226 0 0.00 0 16 1 0 0
v_all_wares 7332 7332 0 0.00 0 13 1 0 0
v_cancel_adding_invoice 136 136 0 0.00 0 50 6 0 0
v_cancel_client_order 584 584 0 0.00 0 434 5 0 0
v_cancel_customer_prepayment 268 268 0 0.00 0 164 6 0 0
v_cancel_customer_reserve 234 234 0 0.00 1 566 7 0 0
v_cancel_payment_to_supplier 130 130 0 0.00 0 417 11 0 0
v_cancel_supplier_invoice 132 126 6 4.54 0 220 21 0 6
v_cancel_supplier_order 88 88 0 0.00 0 360 14 0 0
v_cancel_write_off 384 384 0 0.00 0 687 19 0 0
v_max_id_clo_ord 945 945 0 0.00 0 996 4 0 0
v_max_id_clo_res 181 181 0 0.00 0 805 16 0 0
v_max_id_ord_sup 967 967 0 0.00 0 114 2 0 0
v_max_non_paid_invoice 355 355 0 0.00 0 20 3 0 0
v_max_non_paid_realizn 759 759 0 0.00 0 680 4 0 0
v_min_id_clo_ord 945 945 0 0.00 0 3560 17 0 0
v_min_id_clo_res 181 181 0 0.00 0 4485 31 0 0
v_min_id_ord_sup 967 967 0 0.00 0 1278 3 0 0
v_min_non_paid_invoice 1056 1056 0 0.00 0 2984 13 0 0
v_min_non_paid_realizn 1218 1218 0 0.00 0 6687 31 0 0
v_our_firm 1966 1966 0 0.00 0 12 1 0 0
v_reserve_write_off 3582 3582 0 0.00 0 677 5 0 0
x_lock_depdocs_on_canc_invoice 868 868 0 0.00 0 4036 273 0 0
x_lock_depdocs_on_canc_sup_ord 44 44 0 0.00 0 1564 359 0 0
x_make_qstorno_1000_1200 948 948 0 0.00 2 18354 1976 0 0
x_make_qstorno_1200_2000 979 979 0 0.00 7 47830 5990 0 0
x_make_qstorno_2100_3300 71742 71742 0 0.00 0 27863 343 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-08-2021 15:03:29 to 02-08-2021 15:03:33.

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:13:03 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
14:14:03 24837 8192000000 10379927648 10697031680 1428459072 14.848 100 90 179 1 1464044352 6701184 28884096
14:15:33 24794 8192000000 10443075264 10778292224 1502946032 15.502 100 87 174 0 1539770384 9093136 27731216
14:17:08 18147 8192000000 10496594672 10843504640 1554941296 15.953 100 83 166 1 1590995568 10746944 25307328
14:18:39 15795 8192000000 10585992176 10944159744 1643807056 16.712 100 85 170 0 1682364608 13215376 25342176
14:19:58 6691 8192000000 10640293920 11011645440 1703146192 17.211 100 80 160 3 1739135616 12913088 23076336
14:21:06 5189 8192000000 10703496384 11078885376 1762604304 17.706 100 80 158 3 1796917584 11882512 22430768
14:22:15 3716 8192000000 10745850736 11132620800 1814478784 18.133 100 90 181 3 1852594128 12917120 25198224
14:23:20 1432 8192000000 10803763472 11187879936 1859012672 18.495 100 94 190 1 1898601792 13294912 26294208
14:24:22 1929 8192000000 10811759408 11209265152 1880896336 18.672 100 82 163 2 1913839312 9479344 23463632
14:25:25 8459 8192000000 10794711648 11216130048 1879041136 18.657 100 35 69 3 1896087264 7027376 10018752
14:26:35 8894 8192000000 10802803056 11226836992 1889905712 18.745 100 41 80 3 1910539040 9201856 11431472
14:27:45 30895 8192000000 10794423872 11235532800 1892628736 18.767 100 40 81 0 1909936496 5612384 11695376
14:29:18 6230 8192000000 10817316752 11250458624 1900848368 18.833 100 31 62 2 1916417136 7227728 8341040
14:30:26 7515 8192000000 10819315952 11249528832 1888715376 18.735 99 28 54 1 1902759472 6246288 7797808
14:31:33 56721 8192000000 10862305072 11273240576 1905529312 18.871 99 45 91 3 1929462480 9852720 14080448
14:33:30 7802 8192000000 10818885680 11238084608 1895077632 18.787 100 40 79 3 1913150672 6768336 11304704
14:34:43 6513 8192000000 10735537792 11158716416 1764519536 17.722 96 22 43 2 1776495488 5052560 6923392
14:35:49 64604 8192000000 10663240992 11081654272 1681983920 17.034 94 35 67 0 1696767344 4888880 9894544
14:37:56 8319 8192000000 10716698096 11142909952 1810663200 18.101 100 23 46 1 1822698224 5468384 6566640
14:39:07 7870 8192000000 10749295984 11170312192 1829413664 18.255 100 26 51 1 1841684768 5103520 7167584
14:40:20 14392 8192000000 10759328928 11189088256 1848850640 18.413 100 7 14 1 1855703264 4966224 1886400
14:41:35 28979 8192000000 10772051152 11199119360 1855694288 18.468 100 28 56 3 1869087232 6102544 7290400
14:43:06 4123 8192000000 10832769136 11243073536 1894662912 18.783 100 55 108 2 1921044624 10567152 15814560
14:44:17 13971 8192000000 10854799712 11269685248 1913607408 18.936 100 58 116 1 1940532784 10911600 16013776
14:45:35 19668 8192000000 10866895968 11290787840 1937078944 19.123 100 61 122 1 1967340688 13159040 17102704
14:47:24 47655 8192000000 10884334720 11322068992 1949556816 19.223 100 34 67 2 1976130240 17312048 9261376
14:49:45 12100 8192000000 10896480720 11331932160 1964218848 19.340 100 42 82 2 1994339200 16669344 13451008
14:51:06 17352 8192000000 10930264832 11366666240 1984283376 19.499 100 43 86 0 2019458768 22006896 13168496
14:52:29 15422 8192000000 10960069936 11392671744 2001857184 19.637 100 56 112 1 2049672752 30801424 17014144
14:53:49 6958 8192000000 10981484048 11405787136 2003695936 19.652 99 71 142 1 2058093824 32703232 21694656
14:55:03 3154 8192000000 10990214672 11421929472 2023950336 19.811 100 75 151 0 2084413024 36530800 23931888
14:56:13 3905 8192000000 11007941888 11444269056 2033862432 19.889 100 63 126 2 2097653632 43477408 20313792
14:57:20 2680 8192000000 10985093584 11409473536 1998705888 19.613 100 77 153 2 2070101552 47274208 24121456
14:58:26 3704 8192000000 10990087008 11422584832 2005515856 19.666 100 62 124 1 2071000160 44533808 20950496
14:59:37 4460 8192000000 10991119968 11409485824 1996037904 19.591 100 79 158 2 2073005584 51322016 25645664
15:00:48 11215 8192000000 10973676464 11412545536 1997243536 19.601 100 50 100 3 2059352768 46155280 15953952

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:10, from 02-08-2021 15:03:35 to 02-08-2021 15:03:45.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 38 v_add_invoice_to_stock 335544517
except 3 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 02-08-2021 15:03:46 to 02-08-2021 15:03:47.

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          97270
MON$OLDEST_ACTIVE               117221
MON$OLDEST_SNAPSHOT             117221
MON$NEXT_TRANSACTION            117221
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                       1902560
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33487 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33487 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33487 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33487 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:6: 
:7: mscs-test-161.budzdorov.ru    Mon Aug  2 15:01:11 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Mon Aug  2 15:01:11 2021
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Mon Aug  2 15:01:14 2021
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:17: 
:18: 
:19: mscs-test-161.budzdorov.ru    Mon Aug  2 15:01:16 2021
:20:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:21: 
:22: 
:23: mscs-test-161.budzdorov.ru    Mon Aug  2 15:01:23 2021
:24:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:25: 
:26: 
:27: mscs-test-161.budzdorov.ru    Mon Aug  2 15:01:23 2021
:28:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:29: 
:30: 
:31: mscs-test-161.budzdorov.ru    Mon Aug  2 15:01:23 2021
:32:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:33: 
--- end of diff output ---

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

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