Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.11.2021 19:39
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 16742356 kB
MemAvailable 31566552 kB
Buffers 0 kB
Cached 14949488 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-26 19:37:56
DTS_END                         2021-11-26 19:37:56.0590
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/51216
TRN_ID                          243764
ATT_ID                          705
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: 9099.28 19528 1091914
customer order: creation 171.90 350 20628
customer order: refuse 15.21 346 1825
order to supplier: creation 45.88 1292 5506
order to supplier: removal 3.48 92699 418
invoice (draft): creation 45.73 3522 5488
invoice (draft): removal 51.78 726 6214
invoice accept: apply 41.53 18590 4983
invoice accept: cancel 46.48 764 5577
customer reserve: creation 3815.79 185 457895
customer reserve: removal 4565.14 55 547817
realization accept: apply 83.74 191 10049
realization accept: cancel 84.25 115 10110
payment from customer: creation 58.98 122 7077
payment from customer: removal 5.57 65 668
payment to supplier: creation 48.39 124 5807
payment to supplier: removal 3.52 45 422
service: total inventory turnovers 4.60 1168 552
service: total monetary turnovers 6.54 23 785
service: aggregate perf. data 0.74 19188 89
service: refresh index statistics 0.03 250980 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 26-11-2021 19:39:40 to 26-11-2021 19:39:42.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1971
TEST_TIME 2 1643
TEST_TIME 3 1960
TEST_TIME 4 2383
TEST_TIME 5 3022
TEST_TIME 6 3857
TEST_TIME 7 4728
TEST_TIME 8 4857
TEST_TIME 9 5076
TEST_TIME 10 5172
TEST_TIME 11 5355
TEST_TIME 12 5752
TEST_TIME 13 5568
TEST_TIME 14 6068
TEST_TIME 15 6164
TEST_TIME 16 6495
TEST_TIME 17 6685
TEST_TIME 18 7258
TEST_TIME 19 7228
TEST_TIME 20 7702
TEST_TIME 21 8022
TEST_TIME 22 8507
TEST_TIME 23 8659
TEST_TIME 24 8991
TEST_TIME 25 9299
TEST_TIME 26 9538
TEST_TIME 27 9537
TEST_TIME 28 9788
TEST_TIME 29 10168
TEST_TIME 30 10198
TEST_TIME 31 10256
TEST_TIME 32 10239
TEST_TIME 33 10049
TEST_TIME 34 9776
TEST_TIME 35 9641
TEST_TIME 36 9422
TEST_TIME 37 9423
TEST_TIME 38 9552
TEST_TIME 39 9466
TEST_TIME 40 9422
TEST_TIME 41 9354
TEST_TIME 42 9416
TEST_TIME 43 9411
TEST_TIME 44 9436
TEST_TIME 45 9265
TEST_TIME 46 9156
TEST_TIME 47 9117
TEST_TIME 48 9076
TEST_TIME 49 8949
TEST_TIME 50 8887
TEST_TIME 51 8935
TEST_TIME 52 9023
TEST_TIME 53 8965
TEST_TIME 54 8893
TEST_TIME 55 8815
TEST_TIME 56 8857
TEST_TIME 57 8798
TEST_TIME 58 8726
TEST_TIME 59 8722
TEST_TIME 60 8757
TEST_TIME 61 8868
TEST_TIME 62 8841
TEST_TIME 63 8914
TEST_TIME 64 8919
TEST_TIME 65 9071
TEST_TIME 66 9038
TEST_TIME 67 9046
TEST_TIME 68 9161
TEST_TIME 69 9159
TEST_TIME 70 9195
TEST_TIME 71 9150
TEST_TIME 72 9282
TEST_TIME 73 9265
TEST_TIME 74 9237
TEST_TIME 75 9149
TEST_TIME 76 9173
TEST_TIME 77 9118
TEST_TIME 78 9095
TEST_TIME 79 9054
TEST_TIME 80 9015
TEST_TIME 81 9025
TEST_TIME 82 8972
TEST_TIME 83 8989
TEST_TIME 84 8957
TEST_TIME 85 8993
TEST_TIME 86 8998
TEST_TIME 87 8981
TEST_TIME 88 8973
TEST_TIME 89 8950
TEST_TIME 90 9049
TEST_TIME 91 9099
TEST_TIME 92 9116
TEST_TIME 93 9097
TEST_TIME 94 9098
TEST_TIME 95 9164
TEST_TIME 96 9119
TEST_TIME 97 9169
TEST_TIME 98 9175
TEST_TIME 99 9106
TEST_TIME 100 9095
TEST_TIME 101 9037
TEST_TIME 102 9029
TEST_TIME 103 8997
TEST_TIME 104 8968
TEST_TIME 105 8951
TEST_TIME 106 8964
TEST_TIME 107 8942
TEST_TIME 108 9000
TEST_TIME 109 8961
TEST_TIME 110 8952
TEST_TIME 111 8960
TEST_TIME 112 8980
TEST_TIME 113 8995
TEST_TIME 114 9013
TEST_TIME 115 9122
TEST_TIME 116 9133
TEST_TIME 117 9100
TEST_TIME 118 9091
TEST_TIME 119 9143
TEST_TIME 120 9099
TEST_TIME 121 9024

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 26-11-2021 19:39:44 to 26-11-2021 19:39:47.

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 1516856 1516856 0 0.00 0 34570 6 0 0
doc_list_biud 1516856 1516856 0 0.00 0 71403 26 0 0
sp_add_invoice_to_stock 5142 4983 159 3.09 17 207253 18591 0 159
sp_cancel_adding_invoice 5577 5577 0 0.00 6 72592 764 0 0
sp_cancel_client_order 1825 1825 0 0.00 5 20671 346 0 0
sp_cancel_customer_reserve 547817 547817 0 0.00 1 71404 55 0 0
sp_cancel_pay_from_customer 668 668 0 0.00 3 4868 65 0 0
sp_cancel_pay_to_supplier 422 422 0 0.00 2 575 46 0 0
sp_cancel_supplier_invoice 6222 6214 8 0.12 1 12877 726 0 8
sp_cancel_supplier_order 418 418 0 0.00 6 861176 92699 0 0
sp_cancel_write_off 10110 10110 0 0.00 1 12045 115 0 0
sp_client_order 20628 20628 0 0.00 3 21523 350 0 0
sp_customer_reserve 457895 457895 0 0.00 0 44726 185 0 0
sp_fill_shopping_cart 31617 31617 0 0.00 1 10687 39 0 0
sp_fill_shopping_cart_clo_res 457894 457894 0 0.00 0 19925 14 0 0
sp_get_clo_for_invoice 4982 4982 0 0.00 0 23496 344 0 0
sp_kill_cost_storno 16777 16777 0 0.00 0 7250 53 0 0
sp_kill_qstorno_ret_qs2qd 1112376 1112376 0 0.00 0 71402 30 0 0
sp_kill_qty_storno 586992 586992 0 0.00 0 71403 65 0 0
sp_lock_selected_doc 20730 20730 0 0.00 0 9375 7 0 0
sp_make_cost_storno 27915 27915 0 0.00 0 21995 59 0 0
sp_multiply_rows_for_pdistr 27915 27915 0 0.00 0 8394 28 0 0
sp_multiply_rows_for_qdistr 20628 20628 0 0.00 0 11252 156 0 0
sp_pay_from_customer 7077 7077 0 0.00 4 15398 122 0 0
sp_pay_to_supplier 5807 5807 0 0.00 5 15398 125 0 0
sp_payment_common 12884 12884 0 0.00 2 8400 28 0 0
sp_qd_handle_on_cancel_clo 1825 1825 0 0.00 0 13704 317 0 0
sp_qd_handle_on_invoice_upd_sts 10559 10559 0 0.00 0 18346 331 0 0
sp_qd_handle_on_reserve_upd_sts 10049 10049 0 0.00 0 11117 63 0 0
sp_reserve_write_off 10049 10049 0 0.00 6 20026 191 0 0
sp_supplier_invoice 5488 5488 0 0.00 7 111199 3523 0 0
sp_supplier_order 5506 5506 0 0.00 5 39941 1293 0 0
srv_aggregate_perf_data 89 89 0 0.00 3446 144946 19189 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1109 175908 46462 0 0
srv_find_qd_qs_mism 1044940 1044940 0 0.00 0 34570 5 0 0
srv_make_invnt_saldo 552 552 0 0.00 30 100728 1169 0 0
srv_make_money_saldo 785 785 0 0.00 0 927 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 185874 319600 250980 0 0
t$perf-abend:sp_add_invoice_to_stock 159 159 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4983 4983 0 0.00 0 18397 179 0 0
t$perf-norm:sp_cancel_adding_invoice 362 362 0 0.00 0 19366 166 0 0
t$perf-norm:sp_cancel_client_order 1825 1825 0 0.00 0 4491 16 0 0
t$perf-norm:sp_cancel_customer_reserve 626 626 0 0.00 0 3695 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 668 668 0 0.00 0 2265 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 422 422 0 0.00 0 3597 24 0 0
t$perf-norm:sp_cancel_supplier_invoice 386 386 0 0.00 0 4650 25 0 0
t$perf-norm:sp_cancel_supplier_order 418 418 0 0.00 0 24723 1601 0 0
t$perf-norm:sp_cancel_write_off 992 992 0 0.00 0 3520 6 0 0
t$perf-norm:sp_client_order 20628 20628 0 0.00 0 8231 22 0 0
t$perf-norm:sp_customer_reserve 1023 1023 0 0.00 0 4283 15 0 0
t$perf-norm:sp_pay_from_customer 7077 7077 0 0.00 0 5404 13 0 0
t$perf-norm:sp_pay_to_supplier 5807 5807 0 0.00 0 8396 17 0 0
t$perf-norm:sp_reserve_write_off 10049 10049 0 0.00 0 6358 12 0 0
t$perf-norm:sp_supplier_invoice 5488 5488 0 0.00 0 5819 18 0 0
t$perf-norm:sp_supplier_order 5506 5506 0 0.00 0 6178 18 0 0
t$perf-norm:srv_aggregate_perf_data 89 89 0 0.00 0 60 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 102 4 0 0
t$perf-norm:srv_make_invnt_saldo 552 552 0 0.00 0 4713 35 0 0
t$perf-norm:srv_make_money_saldo 785 785 0 0.00 0 790 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 5 0 0
v_add_invoice_to_stock 10282 9964 318 3.09 0 11305 20 0 318
v_all_customers 37838 37838 0 0.00 0 36 1 0 0
v_all_suppliers 30238 30238 0 0.00 0 36 1 0 0
v_all_wares 41256 41256 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 724 724 0 0.00 0 1949 9 0 0
v_cancel_client_order 3650 3650 0 0.00 0 3105 4 0 0
v_cancel_customer_prepayment 1336 1336 0 0.00 0 997 4 0 0
v_cancel_customer_reserve 1252 1252 0 0.00 0 1781 6 0 0
v_cancel_payment_to_supplier 844 844 0 0.00 0 535 4 0 0
v_cancel_supplier_invoice 788 772 16 2.03 0 3443 23 0 16
v_cancel_supplier_order 836 836 0 0.00 0 1327 6 0 0
v_cancel_write_off 1984 1984 0 0.00 0 3992 19 0 0
v_max_id_clo_ord 5503 5503 0 0.00 0 2154 2 0 0
v_max_id_clo_res 1023 1023 0 0.00 0 3572 12 0 0
v_max_id_ord_sup 5486 5486 0 0.00 0 7547 7 0 0
v_max_non_paid_invoice 1677 1677 0 0.00 0 8391 8 0 0
v_max_non_paid_realizn 3678 3678 0 0.00 0 2050 4 0 0
v_min_id_clo_ord 5503 5503 0 0.00 0 2708 6 0 0
v_min_id_clo_res 1023 1023 0 0.00 0 5134 12 0 0
v_min_id_ord_sup 5486 5486 0 0.00 0 3672 5 0 0
v_min_non_paid_invoice 5807 5807 0 0.00 0 8100 15 0 0
v_min_non_paid_realizn 7077 7077 0 0.00 0 8207 22 0 0
v_our_firm 10216 10216 0 0.00 0 21 1 0 0
v_reserve_write_off 20096 20096 0 0.00 0 3274 5 0 0
x_lock_depdocs_on_canc_invoice 11405 11405 0 0.00 0 17335 122 0 0
x_lock_depdocs_on_canc_sup_ord 418 418 0 0.00 0 32327 384 0 0
x_make_qstorno_1000_1200 5506 5506 0 0.00 1 39929 1215 0 0
x_make_qstorno_1200_2000 5488 5488 0 0.00 4 111176 3384 0 0
x_make_qstorno_2100_3300 457895 457895 0 0.00 0 44722 169 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-11-2021 19:39:48 to 26-11-2021 19:39:52.

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
17:07:59 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:08:59 7166 8192000000 9887396112 10192285696 975322864 10.639 100 89 179 0 1003316448 5263680 22729904
17:10:10 13484 8192000000 9929624320 10247090176 1008979856 10.966 100 82 165 0 1037649696 7228544 21441296
17:11:32 16994 8192000000 9973041088 10301300736 1054399040 11.403 100 93 187 0 1086441360 8941360 23100960
17:12:53 13732 8192000000 10007815680 10352631808 1096943968 11.809 100 77 154 1 1125298720 9831216 18523536
17:14:16 11109 8192000000 10077582656 10439446528 1163499184 12.436 100 78 155 1 1192791984 10781024 18511776
17:15:31 4539 8192000000 10138075760 10506412032 1217053376 12.934 100 86 171 1 1250186496 14409184 18723936
17:16:37 2501 8192000000 10159596896 10536226816 1246805456 13.209 100 90 180 0 1281550832 14898976 19846400
17:17:40 2333 8192000000 10202185712 10584981504 1283935168 13.549 100 91 182 2 1318069712 13965744 20168800
17:18:44 6661 8192000000 10227267472 10615115776 1310392064 13.790 100 85 171 3 1341459552 11952096 19115392
17:19:51 11220 8192000000 10200936880 10615922688 1317156320 13.851 100 25 49 1 1328641952 5539504 5946128
17:21:04 8451 8192000000 10209476336 10628079616 1325950784 13.931 100 24 47 2 1337465040 6502128 5012128
17:22:13 8546 8192000000 10218420288 10640105472 1334763408 14.010 100 26 51 1 1347441392 6855248 5822736
17:23:23 69551 8192000000 10230408096 10653220864 1340435584 14.061 100 35 70 1 1355033808 5066096 9532128
17:25:34 3035 8192000000 10255768288 10664095744 1346385280 14.115 100 44 89 0 1363182192 6030624 10766288
17:26:37 4470 8192000000 10221862032 10643849216 1332818544 13.993 100 24 48 1 1344494496 5633600 6042352
17:27:44 8098 8192000000 10198604352 10613420032 1275079664 13.468 96 21 42 2 1286043088 5734752 5228672
17:28:52 41649 8192000000 10089845584 10482905088 1146431264 12.276 89 29 56 1 1157883264 4912992 6539008
17:30:34 8446 8192000000 10128678800 10525519872 1176980608 12.562 94 43 86 3 1193499344 5993872 10524864
17:31:46 5808 8192000000 10151740880 10552512512 1253995984 13.275 100 28 56 1 1271024176 10384512 6643680
17:32:52 16569 8192000000 10172201776 10574311424 1273437552 13.453 100 29 59 1 1287330128 5914128 7978448
17:34:09 11908 8192000000 10172556320 10582401024 1270126816 13.423 99 22 44 0 1281221504 5961760 5132928
17:35:22 5810 8192000000 10188491616 10600828928 1296516144 13.664 100 33 66 1 1310040208 5808032 7716032
17:36:28 5617 8192000000 10197888608 10612453376 1300557840 13.700 100 29 58 1 1317861280 10234608 7068832
17:37:38 7048 8192000000 10212118912 10631806976 1326194880 13.933 100 27 51 1 1337000112 5051776 5753456
17:38:54 98229 8192000000 10248324224 10664329216 1330668336 13.973 99 55 109 1 1351094704 7662880 12763488
17:41:42 8140 8192000000 10261093952 10680217600 1352847808 14.173 100 37 73 3 1374139856 12771936 8520112
17:42:53 18487 8192000000 10270845808 10691436544 1352498768 14.170 99 49 98 1 1379586624 15441664 11646192
17:44:16 9612 8192000000 10287304800 10708660224 1361726880 14.253 99 48 96 0 1391746960 18590656 11429424
17:45:31 12846 8192000000 10303856992 10726801408 1383734384 14.450 100 46 92 0 1412475712 17798464 10942864
17:46:48 12599 8192000000 10297648656 10721832960 1384566848 14.457 100 41 82 0 1410409296 16321248 9521200
17:48:09 7020 8192000000 10318087984 10745245696 1401063408 14.604 100 49 97 1 1430547584 18283760 11200416
17:49:21 13824 8192000000 10312592880 10745032704 1390602256 14.511 99 28 56 0 1414945248 17383856 6959136
17:50:39 9144 8192000000 10300873920 10712879104 1364697216 14.280 99 67 133 1 1406184576 25466768 16020592
17:51:52 2593 8192000000 10288432752 10692337664 1334497504 14.008 98 70 141 0 1379213424 27260496 17455424
17:53:01 2072 8192000000 10314557968 10719326208 1369707232 14.324 100 77 154 1 1420410912 30808288 19895392
17:54:08 1490 8192000000 10331234016 10734227456 1374559520 14.368 100 88 176 1 1437732208 38362656 24810032
17:55:12 8669 8192000000 10309230384 10720927744 1366728848 14.298 100 61 121 1 1419360768 35649104 16982816
17:56:27 3894 8192000000 10308141696 10706305024 1357535168 14.215 99 83 166 0 1410592960 31701216 21356576
17:57:40 1104 8192000000 10336433168 10736386048 1372083936 14.346 99 92 184 1 1435250864 39777952 23388976
17:58:48 3918 8192000000 10300866528 10713223168 1355661744 14.198 99 56 108 1 1403327968 32897840 14768384
17:59:59 16329 8192000000 10271525504 10694017024 1340577840 14.063 100 36 71 1 1379948432 29353232 10017360
18:01:21 15568 8192000000 10294593552 10728448000 1366234880 14.293 100 22 44 2 1397262304 25140704 5886720
18:02:42 18037 8192000000 10274035216 10702495744 1337409136 14.034 99 34 68 4 1371110160 24697760 9003264
18:04:05 28630 8192000000 10273910944 10714296320 1338979456 14.048 99 18 36 0 1371731296 28039424 4712416
18:05:35 30247 8192000000 10270759360 10707079168 1347923120 14.129 100 14 28 0 1383841872 32094544 3824208
18:07:06 56980 8192000000 10277131424 10700668928 1339081792 14.049 99 25 49 1 1360462368 15701728 5678848
18:09:04 41319 8192000000 10260264768 10693836800 1324629248 13.919 97 16 32 1 1334539952 6606896 3303808
18:10:51 81885 8192000000 10288838112 10714042368 1364417664 14.277 100 40 75 0 1381613024 7820272 9375088
18:13:15 45626 8192000000 10302070384 10739970048 1388239328 14.490 100 43 85 3 1414267872 15646560 10381984
18:15:05 69330 8192000000 10286248704 10738147328 1389037152 14.497 100 8 16 1 1408213008 16903120 2272736
18:17:15 55606 8192000000 10301896304 10758025216 1399370112 14.589 100 24 48 1 1417785888 12660960 5754816
18:19:20 45680 8192000000 10297711856 10749685760 1379383328 14.411 99 11 22 1 1395902672 13740784 2778560
18:21:06 63910 8192000000 10282636608 10749202432 1380196224 14.418 99 12 24 1 1393265536 9934176 3135136
18:23:10 61934 8192000000 10295278368 10747215872 1400724464 14.601 100 41 78 1 1416238720 6458464 9055792
18:25:13 65783 8192000000 10286818800 10748006400 1372795424 14.352 98 8 16 0 1383627232 9114688 1717120
18:27:20 52975 8192000000 10313994256 10758434816 1382940512 14.443 98 31 62 1 1402431280 12047824 7442944
18:29:41 34768 8192000000 10277762944 10751696896 1364452032 14.277 98 4 8 0 1376341456 11094800 794624
18:31:24 91427 8192000000 10279421120 10737913856 1362999824 14.264 98 17 34 0 1378457536 10787200 4670512
18:36:30 71749 8192000000 10294226464 10757787648 1380502144 14.421 99 18 36 0 1395355360 11016944 3836272
18:38:42 76593 8192000000 10288194880 10740387840 1363409904 14.268 98 20 40 0 1381703424 13928336 4365184
18:41:04 101126 8192000000 10272558144 10740969472 1310298016 13.789 94 11 22 0 1332113648 19695024 2120608
18:43:46 19854 8192000000 10290523728 10719424512 1343707488 14.091 98 54 108 0 1364466768 9222336 11536944
18:45:07 60032 8192000000 10281455232 10727235584 1363929920 14.273 100 26 52 1 1386408944 16688080 5790944
18:47:07 60467 8192000000 10268065168 10718896128 1335461872 14.016 98 13 26 1 1349482016 10712160 3307984
18:49:09 53194 8192000000 10274306272 10737561600 1358744192 14.226 99 11 22 0 1377663152 16537696 2381264
18:51:02 115531 8192000000 10269061008 10717245440 1347844976 14.128 99 23 46 1 1366794144 13745632 5203536
18:54:06 23881 8192000000 10257936176 10704080896 1336756704 14.028 98 24 48 2 1357488544 14751408 5980432
18:55:31 68561 8192000000 10255429104 10708480000 1350531792 14.152 99 13 26 0 1364883952 11433472 2918688
18:57:41 72402 8192000000 10267416192 10722193408 1367695440 14.306 100 12 24 0 1381783904 11274544 2813920
18:59:57 31180 8192000000 10266938016 10718720000 1360950208 14.246 100 20 41 0 1380062496 14219504 4892784
19:01:39 61018 8192000000 10235970160 10684157952 1291394000 13.617 94 13 26 0 1304453808 10126176 2933632
19:03:46 40369 8192000000 10251303600 10701910016 1340114368 14.058 99 18 36 0 1353302976 9242448 3946160
19:05:33 22852 8192000000 10286567616 10726879232 1365957216 14.291 100 36 72 2 1383659584 9715632 7986736
19:07:01 175908 8192000000 10289562240 10757193728 1350849520 14.155 98 11 22 0 1376588528 23160448 2578560
19:10:57 48966 8192000000 10307652720 10749304832 1374847968 14.370 99 32 64 0 1396578736 14743328 6987440
19:12:48 56831 8192000000 10318951344 10763087872 1398814368 14.584 100 22 44 2 1417048992 13173248 5061376
19:14:48 121194 8192000000 10287148256 10736435200 1310311696 13.789 94 18 36 1 1325532320 11498320 3722304
19:18:09 55026 8192000000 10278344048 10720256000 1338169952 14.041 97 27 54 1 1352759104 8811888 5777264
19:20:16 64756 8192000000 10286843744 10748137472 1391248528 14.517 100 14 28 0 1406629568 11997808 3383232
19:22:21 72964 8192000000 10300444400 10755801088 1363766048 14.271 98 16 32 0 1382654464 15029040 3859376
19:25:33 61553 8192000000 10252809856 10708201472 1304711504 13.738 95 8 16 0 1315429024 9042480 1675040
19:27:38 35232 8192000000 10278489536 10721087488 1343376528 14.088 98 18 36 0 1356395280 9009760 4008992
19:29:20 75165 8192000000 10299645632 10750636032 1363864928 14.272 99 24 48 0 1385906880 15827648 6214304
19:31:36 51961 8192000000 10277650944 10751692800 1362395536 14.259 99 12 24 1 1392604224 27122608 3086080
19:33:42 63641 8192000000 10277976576 10721259520 1320591136 13.882 96 21 41 1 1339246080 13996320 4658624
19:35:46 14582 8192000000 10284157392 10716733440 1340794688 14.065 98 50 100 1 1366028416 14697552 10536176
19:37:15 60723 8192000000 9255246528 9529241600 413234112 4.802 31 4 8 0 416126432 2193232 699088

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 26-11-2021 19:39:54 to 26-11-2021 19:40:09.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 159 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 26-11-2021 19:40:10 to 26-11-2021 19:40:11.

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          244061
MON$OLDEST_ACTIVE               244062
MON$OLDEST_SNAPSHOT             244062
MON$NEXT_TRANSACTION            244062
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                       2037088
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 ---
:391: 
:392: mscs-test-161.budzdorov.ru    Fri Nov 26 19:38:27 2021
:393:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:394: 
:395: 
:396: mscs-test-161.budzdorov.ru    Fri Nov 26 19:38:28 2021
:397:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:398: 
:399: 
:400: mscs-test-161.budzdorov.ru    Fri Nov 26 19:38:28 2021
:401:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:402: 
--- end of diff output ---

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

26.11.21 19:40:12. 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-26 19:40:15 No severe PSQL-related problems occured
26.11.21 19:40:16. End of report.