Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.10.2021 19: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 15931164 kB
MemAvailable 30689284 kB
Buffers 0 kB
Cached 15784328 kB
SwapCached 3796 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-13 19:37:54
DTS_END                         2021-10-13 19:37:54.4520
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/54462
TRN_ID                          237376
ATT_ID                          689
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 9467.59 19063 1136111
customer order: creation 167.93 375 20152
customer order: refuse 14.16 326 1699
order to supplier: creation 44.76 1189 5371
order to supplier: removal 3.43 117018 411
invoice (draft): creation 45.79 3129 5495
invoice (draft): removal 54.42 738 6530
invoice accept: apply 40.58 18413 4870
invoice accept: cancel 49.39 789 5927
customer reserve: creation 3873.09 177 464771
customer reserve: removal 4872.25 66 584670
realization accept: apply 81.65 182 9798
realization accept: cancel 93.32 108 11198
payment from customer: creation 58.59 116 7031
payment from customer: removal 5.58 94 670
payment to supplier: creation 47.36 133 5683
payment to supplier: removal 3.38 66 406
service: total inventory turnovers 4.48 1239 538
service: total monetary turnovers 6.49 23 779
service: aggregate perf. data 0.90 16182 108
service: refresh index statistics 0.03 220904 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-10-2021 19:39:18 to 13-10-2021 19:39:20.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3788
TEST_TIME 2 2606
TEST_TIME 3 3081
TEST_TIME 4 3191
TEST_TIME 5 3409
TEST_TIME 6 3533
TEST_TIME 7 3813
TEST_TIME 8 4244
TEST_TIME 9 4326
TEST_TIME 10 5411
TEST_TIME 11 5614
TEST_TIME 12 5737
TEST_TIME 13 5930
TEST_TIME 14 6020
TEST_TIME 15 6244
TEST_TIME 16 6420
TEST_TIME 17 6668
TEST_TIME 18 6960
TEST_TIME 19 7123
TEST_TIME 20 7477
TEST_TIME 21 7944
TEST_TIME 22 8518
TEST_TIME 23 8453
TEST_TIME 24 8862
TEST_TIME 25 9162
TEST_TIME 26 9535
TEST_TIME 27 10302
TEST_TIME 28 11049
TEST_TIME 29 11314
TEST_TIME 30 11223
TEST_TIME 31 11092
TEST_TIME 32 10962
TEST_TIME 33 10870
TEST_TIME 34 10696
TEST_TIME 35 10660
TEST_TIME 36 10513
TEST_TIME 37 10562
TEST_TIME 38 10434
TEST_TIME 39 10269
TEST_TIME 40 10249
TEST_TIME 41 10182
TEST_TIME 42 10148
TEST_TIME 43 10165
TEST_TIME 44 10112
TEST_TIME 45 10139
TEST_TIME 46 10277
TEST_TIME 47 10241
TEST_TIME 48 10155
TEST_TIME 49 10148
TEST_TIME 50 10090
TEST_TIME 51 10073
TEST_TIME 52 9985
TEST_TIME 53 10097
TEST_TIME 54 10074
TEST_TIME 55 10044
TEST_TIME 56 10065
TEST_TIME 57 10040
TEST_TIME 58 10091
TEST_TIME 59 10071
TEST_TIME 60 10176
TEST_TIME 61 10172
TEST_TIME 62 10182
TEST_TIME 63 10079
TEST_TIME 64 9963
TEST_TIME 65 10017
TEST_TIME 66 10007
TEST_TIME 67 9907
TEST_TIME 68 9843
TEST_TIME 69 9818
TEST_TIME 70 9727
TEST_TIME 71 9784
TEST_TIME 72 9811
TEST_TIME 73 9682
TEST_TIME 74 9847
TEST_TIME 75 9804
TEST_TIME 76 9861
TEST_TIME 77 9818
TEST_TIME 78 9885
TEST_TIME 79 9843
TEST_TIME 80 9812
TEST_TIME 81 9908
TEST_TIME 82 9881
TEST_TIME 83 9869
TEST_TIME 84 9815
TEST_TIME 85 9832
TEST_TIME 86 9904
TEST_TIME 87 9885
TEST_TIME 88 9880
TEST_TIME 89 9798
TEST_TIME 90 9823
TEST_TIME 91 9750
TEST_TIME 92 9746
TEST_TIME 93 9728
TEST_TIME 94 9653
TEST_TIME 95 9639
TEST_TIME 96 9643
TEST_TIME 97 9631
TEST_TIME 98 9591
TEST_TIME 99 9590
TEST_TIME 100 9629
TEST_TIME 101 9673
TEST_TIME 102 9661
TEST_TIME 103 9689
TEST_TIME 104 9655
TEST_TIME 105 9710
TEST_TIME 106 9752
TEST_TIME 107 9734
TEST_TIME 108 9719
TEST_TIME 109 9707
TEST_TIME 110 9640
TEST_TIME 111 9658
TEST_TIME 112 9615
TEST_TIME 113 9602
TEST_TIME 114 9585
TEST_TIME 115 9546
TEST_TIME 116 9505
TEST_TIME 117 9523
TEST_TIME 118 9466
TEST_TIME 119 9492
TEST_TIME 120 9474

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 13-10-2021 19:39:22 to 13-10-2021 19:39:25.

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 1565780 1565780 0 0.00 0 18509 6 0 0
doc_list_biud 1565779 1565779 0 0.00 0 58258 30 0 0
sp_add_invoice_to_stock 5045 4870 175 3.46 53 210443 18413 0 175
sp_cancel_adding_invoice 5927 5927 0 0.00 6 77021 790 0 0
sp_cancel_client_order 1699 1699 0 0.00 4 6900 326 0 0
sp_cancel_customer_reserve 584670 584670 0 0.00 1 58259 66 0 0
sp_cancel_pay_from_customer 670 670 0 0.00 2 7730 95 0 0
sp_cancel_pay_to_supplier 406 406 0 0.00 2 2585 66 0 0
sp_cancel_supplier_invoice 6554 6530 24 0.36 2 15550 739 0 24
sp_cancel_supplier_order 411 411 0 0.00 6 1047801 117019 0 0
sp_cancel_write_off 11198 11198 0 0.00 1 17419 108 0 0
sp_client_order 20152 20152 0 0.00 4 22749 376 0 0
sp_customer_reserve 464771 464771 0 0.00 0 82557 177 0 0
sp_fill_shopping_cart 31014 31014 0 0.00 1 10867 34 0 0
sp_fill_shopping_cart_clo_res 464760 464760 0 0.00 0 33095 17 0 0
sp_get_clo_for_invoice 4857 4857 0 0.00 0 32755 305 0 0
sp_kill_cost_storno 18201 18201 0 0.00 0 12823 56 0 0
sp_kill_qstorno_ret_qs2qd 1187479 1187479 0 0.00 0 58254 35 0 0
sp_kill_qty_storno 625087 625087 0 0.00 0 58258 75 0 0
sp_lock_selected_doc 20251 20251 0 0.00 0 8883 6 0 0
sp_make_cost_storno 27367 27367 0 0.00 0 13079 56 0 0
sp_multiply_rows_for_pdistr 27367 27367 0 0.00 0 7119 26 0 0
sp_multiply_rows_for_qdistr 20152 20152 0 0.00 0 22738 180 0 0
sp_pay_from_customer 7031 7031 0 0.00 4 12618 117 0 0
sp_pay_to_supplier 5683 5683 0 0.00 5 14205 133 0 0
sp_payment_common 12714 12714 0 0.00 1 12614 28 0 0
sp_qd_handle_on_cancel_clo 1699 1699 0 0.00 0 6890 299 0 0
sp_qd_handle_on_invoice_upd_sts 10781 10781 0 0.00 0 38363 321 0 0
sp_qd_handle_on_reserve_upd_sts 9798 9798 0 0.00 0 20272 63 0 0
sp_reserve_write_off 9798 9798 0 0.00 6 20286 182 0 0
sp_supplier_invoice 5495 5495 0 0.00 6 76057 3129 0 0
sp_supplier_order 5371 5371 0 0.00 5 47814 1189 0 0
srv_aggregate_perf_data 108 108 0 0.00 3070 118581 16182 0 0
srv_fill_mon_cache_memory 59 59 0 0.00 2833 162162 53103 0 0
srv_find_qd_qs_mism 1088543 1088543 0 0.00 0 18508 5 0 0
srv_make_invnt_saldo 538 538 0 0.00 43 84353 1240 0 0
srv_make_money_saldo 779 779 0 0.00 0 2446 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 109192 365182 220904 0 0
t$perf-abend:sp_add_invoice_to_stock 175 175 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 24 24 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4870 4870 0 0.00 0 21632 166 0 0
t$perf-norm:sp_cancel_adding_invoice 390 390 0 0.00 0 6173 118 0 0
t$perf-norm:sp_cancel_client_order 1699 1699 0 0.00 0 1809 12 0 0
t$perf-norm:sp_cancel_customer_reserve 668 668 0 0.00 0 1284 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 670 670 0 0.00 0 5191 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 406 406 0 0.00 0 845 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 380 380 0 0.00 0 1698 11 0 0
t$perf-norm:sp_cancel_supplier_order 411 411 0 0.00 0 24664 1672 0 0
t$perf-norm:sp_cancel_write_off 975 975 0 0.00 0 4794 10 0 0
t$perf-norm:sp_client_order 20152 20152 0 0.00 0 10496 18 0 0
t$perf-norm:sp_customer_reserve 918 918 0 0.00 0 1347 4 0 0
t$perf-norm:sp_pay_from_customer 7031 7031 0 0.00 0 5261 10 0 0
t$perf-norm:sp_pay_to_supplier 5683 5683 0 0.00 0 8441 14 0 0
t$perf-norm:sp_reserve_write_off 9798 9798 0 0.00 0 10605 14 0 0
t$perf-norm:sp_supplier_invoice 5495 5495 0 0.00 0 7450 18 0 0
t$perf-norm:sp_supplier_order 5371 5371 0 0.00 0 6005 17 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 133 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 59 59 0 0.00 0 74 2 0 0
t$perf-norm:srv_make_invnt_saldo 538 538 0 0.00 0 6547 42 0 0
t$perf-norm:srv_make_money_saldo 779 779 0 0.00 0 1762 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 4 0 0
v_add_invoice_to_stock 10058 9708 350 3.47 0 5329 20 0 350
v_all_customers 36932 36932 0 0.00 0 19 0 0 0
v_all_suppliers 30074 30074 0 0.00 0 15 1 0 0
v_all_wares 40304 40304 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 780 780 0 0.00 0 2740 10 0 0
v_cancel_client_order 3398 3398 0 0.00 0 2793 8 0 0
v_cancel_customer_prepayment 1340 1340 0 0.00 0 7723 15 0 0
v_cancel_customer_reserve 1336 1336 0 0.00 0 1762 6 0 0
v_cancel_payment_to_supplier 812 812 0 0.00 0 2576 7 0 0
v_cancel_supplier_invoice 808 760 48 5.94 0 4195 27 0 48
v_cancel_supplier_order 822 822 0 0.00 0 5760 12 0 0
v_cancel_write_off 1950 1950 0 0.00 0 2321 15 0 0
v_max_id_clo_ord 5370 5370 0 0.00 0 2688 2 0 0
v_max_id_clo_res 918 918 0 0.00 0 1345 15 0 0
v_max_id_ord_sup 5491 5491 0 0.00 0 4063 4 0 0
v_max_non_paid_invoice 1507 1507 0 0.00 0 798 3 0 0
v_max_non_paid_realizn 3666 3666 0 0.00 0 3459 6 0 0
v_min_id_clo_ord 5370 5370 0 0.00 0 6462 7 0 0
v_min_id_clo_res 918 918 0 0.00 0 8327 51 0 0
v_min_id_ord_sup 5491 5491 0 0.00 0 4972 4 0 0
v_min_non_paid_invoice 5683 5683 0 0.00 0 6033 17 0 0
v_min_non_paid_realizn 7031 7031 0 0.00 0 8522 19 0 0
v_our_firm 10102 10102 0 0.00 0 56 1 0 0
v_reserve_write_off 19596 19596 0 0.00 0 7752 7 0 0
x_lock_depdocs_on_canc_invoice 12077 12077 0 0.00 0 31371 135 0 0
x_lock_depdocs_on_canc_sup_ord 411 411 0 0.00 0 18035 479 0 0
x_make_qstorno_1000_1200 5371 5371 0 0.00 1 47803 1110 0 0
x_make_qstorno_1200_2000 5495 5495 0 0.00 3 76025 3017 0 0
x_make_qstorno_2100_3300 464771 464771 0 0.00 0 82554 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-10-2021 19:39:26 to 13-10-2021 19:39:30.

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:58 3 8192000000 8800905936 8812859392 6924032 0.084 2 2 3 0 7065120 66144 74944
17:08:59 18258 8192000000 9899560672 10209202176 984063200 10.724 100 87 174 0 1013049920 6145408 22841312
17:10:21 23552 8192000000 9940890192 10260639744 1017408864 11.047 100 87 174 0 1048669984 8539984 22721136
17:11:50 14100 8192000000 9967270736 10299482112 1054832800 11.407 100 83 166 0 1085992016 9695104 21464112
17:13:10 13964 8192000000 10029417232 10374656000 1112410960 11.955 100 80 160 1 1142254752 11022608 18821184
17:14:29 13132 8192000000 10078420720 10438156288 1164236992 12.443 100 76 152 0 1193574416 12067136 17270288
17:15:46 6077 8192000000 10126782656 10494685184 1216339312 12.928 100 87 174 2 1247597328 11409504 19848512
17:16:55 3101 8192000000 10166618576 10543128576 1248175216 13.221 100 74 148 0 1279102992 14620624 16307152
17:17:59 3004 8192000000 10184431136 10570575872 1273857040 13.457 100 83 165 0 1306151072 13439664 18854368
17:19:04 2326 8192000000 10207607680 10589147136 1288702368 13.592 100 91 183 1 1323083616 13357088 21024160
17:20:08 8408 8192000000 10194716576 10594922496 1297606096 13.673 100 46 93 0 1316807168 8795104 10405968
17:21:20 3408 8192000000 10209230512 10608263168 1304859808 13.739 100 44 88 0 1321689376 7061440 9768128
17:22:26 7725 8192000000 10202084112 10616229888 1316937920 13.849 100 19 38 1 1326834320 6015088 3881312
17:23:36 60803 8192000000 10211815488 10633715712 1323047792 13.904 100 30 60 2 1335857888 6068560 6741536
17:25:41 7534 8192000000 10213632256 10637488128 1325848176 13.930 100 27 55 2 1338678992 6079632 6751184
17:26:50 11102 8192000000 10219425088 10640150528 1326395296 13.935 100 9 18 0 1334404368 5634096 2374976
17:28:04 67136 8192000000 10181066224 10590703616 1245881200 13.200 95 41 83 0 1260579296 4866544 9831552
17:30:12 5153 8192000000 10154033056 10557489152 1255197072 13.286 99 28 56 0 1267556224 6165024 6194128
17:31:18 6693 8192000000 10162547600 10560253952 1236587216 13.115 98 31 63 0 1248846640 5353856 6905568
17:32:28 9862 8192000000 10128432832 10525757440 1218359200 12.946 97 19 38 1 1227969664 5585792 4024672
17:33:41 36338 8192000000 10144344128 10552094720 1224814800 13.006 97 20 40 0 1234806880 5109248 4882832
17:35:20 6355 8192000000 10183652576 10583896064 1288117520 13.587 100 16 32 1 1296840816 5076064 3647232
17:36:27 6722 8192000000 10195848496 10605514752 1299638352 13.692 100 26 52 1 1311447776 5934160 5875264
17:37:34 22678 8192000000 10213426224 10628415488 1320774480 13.884 100 26 52 2 1333126656 6138240 6213936
17:39:07 101150 8192000000 10267456304 10683465728 1333166064 13.996 99 49 96 0 1355524848 10950864 11407920
17:42:13 16961 8192000000 10283241712 10702696448 1373175040 14.355 100 35 69 0 1399519840 16908400 9436400
17:43:35 9005 8192000000 10306898592 10727342080 1386417760 14.474 100 34 68 0 1414324096 19555680 8350656
17:44:52 6682 8192000000 10336735712 10753400832 1399905584 14.594 100 59 115 1 1446104496 32449632 13749280
17:46:15 11001 8192000000 10349341584 10768474112 1408626176 14.672 100 61 121 1 1462027248 37871168 15529904
17:47:39 11446 8192000000 10348582304 10770255872 1409064000 14.676 100 55 110 4 1461711632 38749632 13898000
17:48:53 5649 8192000000 10359793440 10781675520 1413392320 14.714 100 63 126 1 1466427472 36848240 16186912
17:50:04 5023 8192000000 10352125776 10773032960 1403715536 14.628 100 61 122 1 1460737056 42887552 14133968
17:51:17 3697 8192000000 10371220544 10801049600 1425592544 14.822 100 48 94 0 1481257728 43561632 12103552
17:52:27 2833 8192000000 10376359968 10792386560 1414305680 14.722 100 72 141 0 1483598736 51750336 17542720
17:53:36 3655 8192000000 10386268304 10799706112 1419828064 14.771 100 71 142 1 1486193040 48674080 17690896
17:54:44 12381 8192000000 10367427520 10787872768 1397184768 14.570 99 55 110 1 1467528656 55231856 15112032
17:56:03 5199 8192000000 10383368928 10802237440 1409264496 14.677 100 65 129 1 1485193680 58905632 17023552
17:57:23 7932 8192000000 10386903360 10800865280 1402859408 14.620 100 66 132 0 1486121472 64738976 18523088
17:58:37 11030 8192000000 10383825808 10807566336 1413460016 14.715 100 53 105 0 1491510784 64107184 13943584
18:00:06 11310 8192000000 10342129968 10769256448 1382896768 14.442 100 38 76 0 1445090832 51667968 10526096
18:01:21 20149 8192000000 10333824400 10776879104 1371911584 14.344 99 31 62 1 1443308704 62034096 9363024
18:02:43 30300 8192000000 10326117264 10769715200 1369338384 14.321 100 24 48 0 1432391504 55927184 7125936
18:04:23 41965 8192000000 10306915296 10757341184 1326441552 13.935 98 18 36 0 1388354784 56695328 5217904
18:06:22 131633 8192000000 10263018368 10713456640 1317909120 13.858 96 15 28 1 1339545072 18484304 3151648
18:09:34 73262 8192000000 10234190720 10669690880 1311757200 13.802 96 19 38 0 1333110032 16908112 4444720
18:11:48 115068 8192000000 10289056480 10741682176 1375178160 14.373 100 24 49 0 1392392816 11365824 5848832
18:14:44 20875 8192000000 10290608736 10733805568 1347299600 14.123 98 28 56 0 1362406768 8558688 6548480
18:16:09 67455 8192000000 10304590432 10756984832 1383328672 14.446 100 37 74 1 1408898768 16428272 9141824
18:18:35 54908 8192000000 10308419792 10764341248 1371170528 14.338 99 27 54 1 1397473968 19217184 7086256
18:20:55 67262 8192000000 10300580112 10763374592 1373905040 14.362 99 14 28 0 1405842304 28300976 3636288
18:23:12 40092 8192000000 10281555408 10746572800 1353064672 14.175 98 11 22 2 1375377472 19823056 2489744
18:24:53 57506 8192000000 10271695040 10738692096 1365036944 14.283 99 12 24 0 1378265168 10275152 2953072
18:26:54 94496 8192000000 10298405920 10748948480 1355530336 14.197 98 8 16 0 1369899040 12459776 1908928
18:29:34 52442 8192000000 10304950176 10762657792 1394707840 14.548 100 32 64 1 1416212896 14287152 7217904
18:31:29 41359 8192000000 10296060944 10751176704 1380216400 14.418 99 22 40 3 1392380528 7345312 4818816
18:33:29 102864 8192000000 10313016480 10764603392 1387130816 14.480 99 18 36 0 1401407056 10420480 3855760
18:36:17 48947 8192000000 10310564752 10762903552 1382805856 14.442 99 18 36 0 1399613856 13048672 3759328
18:38:07 105492 8192000000 10269010000 10715226112 1241279648 13.158 89 11 22 0 1251849872 8631648 1938576
18:41:03 47749 8192000000 10290968592 10737090560 1379214992 14.410 100 32 64 0 1396949600 10266432 7468176
18:42:57 49900 8192000000 10270012608 10706706432 1325219344 13.924 97 35 69 1 1344747216 12117712 7410160
18:44:50 7436 8192000000 10268934704 10709467136 1356888288 14.209 100 45 91 0 1375833936 8721504 10224144
18:45:58 63824 8192000000 10271739776 10719539200 1345571600 14.108 98 25 50 0 1367950192 16628560 5750032
18:48:03 70868 8192000000 10278450784 10742431744 1358743984 14.226 99 21 43 0 1391315632 27345856 5225792
18:51:38 91444 8192000000 10257366832 10703806464 1299735232 13.693 95 6 12 0 1314025776 13072704 1217840
18:54:10 14714 8192000000 10273670576 10717057024 1336833008 14.029 98 41 83 3 1357286800 10617920 9835872
18:55:25 73127 8192000000 10271248288 10722189312 1339483168 14.053 98 23 46 1 1355403712 10897936 5022608
18:57:46 78036 8192000000 10276239920 10732335104 1330941248 13.976 97 19 38 0 1347612944 12079424 4592272
19:00:07 83708 8192000000 10279932880 10716573696 1319459840 13.872 96 23 44 1 1334795648 10066784 5269024
19:02:31 50721 8192000000 10292506736 10742530048 1365958880 14.291 99 34 69 0 1388493600 15264144 7270576
19:04:22 73920 8192000000 10260618576 10715275264 1318909200 13.867 97 13 26 0 1332758944 11556016 2293728
19:06:36 123896 8192000000 10248265440 10704494592 1323145216 13.905 97 16 32 1 1337736272 11315856 3275200
19:09:41 57439 8192000000 10267936544 10730467328 1346085632 14.112 98 15 30 0 1362784432 13916640 2782160
19:12:48 77113 8192000000 10287635200 10749120512 1381678560 14.432 100 6 12 0 1393109088 10131760 1298768
19:15:06 25820 8192000000 10294258416 10740748288 1359715472 14.235 98 28 56 0 1376005504 9638416 6651616
19:16:32 121403 8192000000 10287285440 10753822720 1356288800 14.204 98 16 32 1 1378716480 18479952 3947728
19:19:41 97376 8192000000 10302825344 10760327168 1390822720 14.513 100 20 40 0 1410262800 14393120 5046960
19:22:19 19660 8192000000 10300045792 10745393152 1369099360 14.319 99 26 52 1 1390262192 15289408 5873424
19:24:42 94370 8192000000 10280526352 10737012736 1342924960 14.084 97 25 50 0 1363640288 15505968 5209360
19:27:17 162162 8192000000 10275437312 10740506624 1342312944 14.078 97 25 50 0 1358517376 10873200 5331232
19:31:00 47861 8192000000 10255538496 10716987392 1352503648 14.170 99 13 26 0 1363160560 7851120 2805792
19:32:48 82776 8192000000 10225863760 10672590848 1275587072 13.473 92 10 18 1 1286657296 9136400 1933824
19:35:17 103993 8192000000 10254369888 10723102720 1318056960 13.859 96 7 14 0 1331554848 11906320 1591568

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 13-10-2021 19:39:32 to 13-10-2021 19:39:46.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 175 v_add_invoice_to_stock 335544517
except 24 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 13-10-2021 19:39:47 to 13-10-2021 19:39:49.

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          236233
MON$OLDEST_ACTIVE               237485
MON$OLDEST_SNAPSHOT             237485
MON$NEXT_TRANSACTION            237485
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                       2032288
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.33520 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33520 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33520 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33520 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 ---
:271: 
:272: mscs-test-161.budzdorov.ru    Wed Oct 13 19:37:56 2021
:273:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:274: 
:275: 
:276: mscs-test-161.budzdorov.ru    Wed Oct 13 19:37:56 2021
:277:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:278: 
:279: 
:280: mscs-test-161.budzdorov.ru    Wed Oct 13 19:37:57 2021
:281:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:282: 
--- end of diff output ---

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

13.10.21 19:39:50. 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-13 19:39:53 No severe PSQL-related problems occured
13.10.21 19:39:54. End of report.