Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.09.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 15808472 kB
MemAvailable 30806244 kB
Buffers 0 kB
Cached 15848568 kB
SwapCached 416 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-09-15 01:37:49
DTS_END                         2021-09-15 01:37:49.5820
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/59466
TRN_ID                          248137
ATT_ID                          690
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: 9714.96 18941 1165795
customer order: creation 183.09 344 21971
customer order: refuse 15.73 352 1888
order to supplier: creation 48.78 1227 5854
order to supplier: removal 3.69 105845 443
invoice (draft): creation 47.91 3436 5749
invoice (draft): removal 56.38 801 6765
invoice accept: apply 43.59 18318 5231
invoice accept: cancel 50.97 785 6116
customer reserve: creation 3995.23 184 479427
customer reserve: removal 4951.68 61 594202
realization accept: apply 87.93 174 10552
realization accept: cancel 94.74 107 11369
payment from customer: creation 62.03 123 7444
payment from customer: removal 5.89 80 707
payment to supplier: creation 50.46 135 6055
payment to supplier: removal 3.86 99 463
service: total inventory turnovers 4.85 1470 582
service: total monetary turnovers 7.40 62 888
service: aggregate perf. data 0.71 27000 85
service: refresh index statistics 0.03 218218 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-09-2021 01:38:54 to 15-09-2021 01:38:56.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3600
TEST_TIME 2 4022
TEST_TIME 3 4381
TEST_TIME 4 4159
TEST_TIME 5 4111
TEST_TIME 6 4654
TEST_TIME 7 4691
TEST_TIME 8 4941
TEST_TIME 9 5738
TEST_TIME 10 5815
TEST_TIME 11 6381
TEST_TIME 12 6330
TEST_TIME 13 6626
TEST_TIME 14 6647
TEST_TIME 15 7057
TEST_TIME 16 7235
TEST_TIME 17 7971
TEST_TIME 18 8395
TEST_TIME 19 8297
TEST_TIME 20 8582
TEST_TIME 21 8608
TEST_TIME 22 8590
TEST_TIME 23 8585
TEST_TIME 24 8499
TEST_TIME 25 9338
TEST_TIME 26 9588
TEST_TIME 27 9458
TEST_TIME 28 9593
TEST_TIME 29 10016
TEST_TIME 30 11135
TEST_TIME 31 10976
TEST_TIME 32 10986
TEST_TIME 33 11116
TEST_TIME 34 11072
TEST_TIME 35 11077
TEST_TIME 36 10954
TEST_TIME 37 10841
TEST_TIME 38 10681
TEST_TIME 39 10620
TEST_TIME 40 10717
TEST_TIME 41 10650
TEST_TIME 42 10563
TEST_TIME 43 10558
TEST_TIME 44 10489
TEST_TIME 45 10358
TEST_TIME 46 10244
TEST_TIME 47 10188
TEST_TIME 48 10332
TEST_TIME 49 10392
TEST_TIME 50 10343
TEST_TIME 51 10244
TEST_TIME 52 10161
TEST_TIME 53 10049
TEST_TIME 54 9956
TEST_TIME 55 10084
TEST_TIME 56 10023
TEST_TIME 57 10048
TEST_TIME 58 9978
TEST_TIME 59 9966
TEST_TIME 60 9854
TEST_TIME 61 9776
TEST_TIME 62 9720
TEST_TIME 63 9693
TEST_TIME 64 9648
TEST_TIME 65 9693
TEST_TIME 66 9747
TEST_TIME 67 9720
TEST_TIME 68 9813
TEST_TIME 69 9809
TEST_TIME 70 9796
TEST_TIME 71 9879
TEST_TIME 72 9875
TEST_TIME 73 9899
TEST_TIME 74 9866
TEST_TIME 75 10002
TEST_TIME 76 9967
TEST_TIME 77 9986
TEST_TIME 78 9867
TEST_TIME 79 9847
TEST_TIME 80 9842
TEST_TIME 81 9841
TEST_TIME 82 9820
TEST_TIME 83 9764
TEST_TIME 84 9707
TEST_TIME 85 9630
TEST_TIME 86 9580
TEST_TIME 87 9725
TEST_TIME 88 9711
TEST_TIME 89 9672
TEST_TIME 90 9676
TEST_TIME 91 9777
TEST_TIME 92 9770
TEST_TIME 93 9777
TEST_TIME 94 9761
TEST_TIME 95 9781
TEST_TIME 96 9849
TEST_TIME 97 9884
TEST_TIME 98 9881
TEST_TIME 99 9949
TEST_TIME 100 9942
TEST_TIME 101 9989
TEST_TIME 102 9908
TEST_TIME 103 9855
TEST_TIME 104 9850
TEST_TIME 105 9828
TEST_TIME 106 9831
TEST_TIME 107 9798
TEST_TIME 108 9749
TEST_TIME 109 9759
TEST_TIME 110 9699
TEST_TIME 111 9738
TEST_TIME 112 9707
TEST_TIME 113 9682
TEST_TIME 114 9704
TEST_TIME 115 9673
TEST_TIME 116 9727
TEST_TIME 117 9734
TEST_TIME 118 9756
TEST_TIME 119 9738
TEST_TIME 120 9719

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 15-09-2021 01:38:58 to 15-09-2021 01:39:01.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1610359 1610359 0 0.00 0 32685 6 0 0
doc_list_biud 1610358 1610358 0 0.00 0 23670 28 0 0
sp_add_invoice_to_stock 5390 5231 159 2.94 29 182409 18319 0 159
sp_cancel_adding_invoice 6116 6116 0 0.00 5 51029 786 0 0
sp_cancel_client_order 1888 1888 0 0.00 4 10503 353 0 0
sp_cancel_customer_reserve 594202 594202 0 0.00 1 23672 62 0 0
sp_cancel_pay_from_customer 707 707 0 0.00 3 6626 81 0 0
sp_cancel_pay_to_supplier 463 463 0 0.00 2 7507 100 0 0
sp_cancel_supplier_invoice 6776 6765 11 0.16 2 11733 802 0 11
sp_cancel_supplier_order 443 443 0 0.00 8 1020956 105845 0 0
sp_cancel_write_off 11369 11369 0 0.00 1 17025 107 0 0
sp_client_order 21971 21971 0 0.00 4 25723 344 0 0
sp_customer_reserve 479427 479427 0 0.00 0 50495 185 0 0
sp_fill_shopping_cart 33565 33565 0 0.00 1 17797 40 0 0
sp_fill_shopping_cart_clo_res 479420 479420 0 0.00 0 11473 14 0 0
sp_get_clo_for_invoice 5221 5221 0 0.00 0 22792 304 0 0
sp_kill_cost_storno 18655 18655 0 0.00 0 9491 56 0 0
sp_kill_qstorno_ret_qs2qd 1206981 1206981 0 0.00 0 23650 33 0 0
sp_kill_qty_storno 636555 636555 0 0.00 0 23670 71 0 0
sp_lock_selected_doc 21901 21901 0 0.00 0 7303 5 0 0
sp_make_cost_storno 29271 29271 0 0.00 0 32669 50 0 0
sp_multiply_rows_for_pdistr 29271 29271 0 0.00 0 6807 26 0 0
sp_multiply_rows_for_qdistr 21971 21971 0 0.00 0 18219 151 0 0
sp_pay_from_customer 7444 7444 0 0.00 4 34584 123 0 0
sp_pay_to_supplier 6055 6055 0 0.00 5 33278 135 0 0
sp_payment_common 13498 13498 0 0.00 2 34410 39 0 0
sp_qd_handle_on_cancel_clo 1888 1888 0 0.00 0 7446 322 0 0
sp_qd_handle_on_invoice_upd_sts 11336 11336 0 0.00 0 21497 318 0 0
sp_qd_handle_on_reserve_upd_sts 10552 10552 0 0.00 0 11897 52 0 0
sp_reserve_write_off 10552 10552 0 0.00 6 16918 174 0 0
sp_supplier_invoice 5749 5749 0 0.00 7 107694 3436 0 0
sp_supplier_order 5854 5854 0 0.00 6 26293 1227 0 0
srv_aggregate_perf_data 85 85 0 0.00 3108 436972 27000 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 2132 119315 42379 0 0
srv_find_qd_qs_mism 1116128 1116128 0 0.00 0 11604 5 0 0
srv_make_invnt_saldo 582 582 0 0.00 43 145024 1470 0 0
srv_make_money_saldo 888 888 0 0.00 1 31072 62 0 0
srv_recalc_idx_stat 4 4 0 0.00 103010 304520 218219 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 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5231 5231 0 0.00 0 8838 175 0 0
t$perf-norm:sp_cancel_adding_invoice 417 417 0 0.00 0 5580 117 0 0
t$perf-norm:sp_cancel_client_order 1888 1888 0 0.00 0 3560 16 0 0
t$perf-norm:sp_cancel_customer_reserve 769 769 0 0.00 0 2419 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 707 707 0 0.00 0 848 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 463 463 0 0.00 0 7359 23 0 0
t$perf-norm:sp_cancel_supplier_invoice 412 412 0 0.00 0 2847 24 0 0
t$perf-norm:sp_cancel_supplier_order 443 443 0 0.00 0 29104 1867 0 0
t$perf-norm:sp_cancel_write_off 1031 1031 0 0.00 0 3133 12 0 0
t$perf-norm:sp_client_order 21971 21971 0 0.00 0 12728 20 0 0
t$perf-norm:sp_customer_reserve 1096 1096 0 0.00 0 435 3 0 0
t$perf-norm:sp_pay_from_customer 7444 7444 0 0.00 0 6953 12 0 0
t$perf-norm:sp_pay_to_supplier 6055 6055 0 0.00 0 3500 11 0 0
t$perf-norm:sp_reserve_write_off 10552 10552 0 0.00 0 7097 14 0 0
t$perf-norm:sp_supplier_invoice 5749 5749 0 0.00 0 20033 27 0 0
t$perf-norm:sp_supplier_order 5854 5854 0 0.00 0 4508 15 0 0
t$perf-norm:srv_aggregate_perf_data 85 85 0 0.00 0 1110 34 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 95 5 0 0
t$perf-norm:srv_make_invnt_saldo 582 582 0 0.00 0 2893 31 0 0
t$perf-norm:srv_make_money_saldo 888 888 0 0.00 0 1164 22 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10756 10438 318 2.95 0 16545 22 0 318
v_all_customers 39712 39712 0 0.00 0 25 1 0 0
v_all_suppliers 31670 31670 0 0.00 0 18 1 0 0
v_all_wares 43940 43940 0 0.00 0 14 1 0 0
v_cancel_adding_invoice 834 834 0 0.00 0 2423 11 0 0
v_cancel_client_order 3776 3776 0 0.00 0 10249 7 0 0
v_cancel_customer_prepayment 1414 1414 0 0.00 0 5430 10 0 0
v_cancel_customer_reserve 1538 1538 0 0.00 0 2409 6 0 0
v_cancel_payment_to_supplier 926 926 0 0.00 0 1407 6 0 0
v_cancel_supplier_invoice 846 824 22 2.60 0 2333 21 0 22
v_cancel_supplier_order 886 886 0 0.00 0 1264 6 0 0
v_cancel_write_off 2062 2062 0 0.00 0 16817 28 0 0
v_max_id_clo_ord 5849 5849 0 0.00 0 604 1 0 0
v_max_id_clo_res 1096 1096 0 0.00 0 3980 25 0 0
v_max_id_ord_sup 5746 5746 0 0.00 0 3894 5 0 0
v_max_non_paid_invoice 1814 1814 0 0.00 0 911 3 0 0
v_max_non_paid_realizn 3976 3976 0 0.00 0 4330 5 0 0
v_min_id_clo_ord 5849 5849 0 0.00 0 3392 6 0 0
v_min_id_clo_res 1096 1096 0 0.00 0 3479 11 0 0
v_min_id_ord_sup 5746 5746 0 0.00 0 7676 6 0 0
v_min_non_paid_invoice 6054 6054 0 0.00 0 7684 20 0 0
v_min_non_paid_realizn 7444 7444 0 0.00 0 16222 27 0 0
v_our_firm 11164 11164 0 0.00 0 18 1 0 0
v_reserve_write_off 21104 21104 0 0.00 0 16767 12 0 0
x_lock_depdocs_on_canc_invoice 12469 12469 0 0.00 0 23258 126 0 0
x_lock_depdocs_on_canc_sup_ord 443 443 0 0.00 0 12613 342 0 0
x_make_qstorno_1000_1200 5854 5854 0 0.00 1 26287 1140 0 0
x_make_qstorno_1200_2000 5749 5749 0 0.00 3 107647 3296 0 0
x_make_qstorno_2100_3300 479427 479427 0 0.00 0 50494 168 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-09-2021 01:39:02 to 15-09-2021 01:39:06.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
23:07:53 3 8192000000 8801160608 8812462080 7256432 0.088 2 1 2 0 7375808 56816 62560
23:08:54 23613 8192000000 10372445712 10687242240 1414146096 14.721 100 93 186 0 1450464832 5405424 30913312
23:10:33 12448 8192000000 10436322736 10765549568 1495501744 15.437 100 94 189 0 1533759728 8262608 29995376
23:11:51 13164 8192000000 10474446512 10811715584 1531193104 15.747 100 86 172 1 1568035360 9596768 27245488
23:13:22 7497 8192000000 10584242336 10941640704 1641657600 16.694 100 78 155 0 1674890176 10438928 22793648
23:14:33 6751 8192000000 10664647344 11032043520 1719368400 17.347 100 87 174 3 1757323264 12352976 25601888
23:15:43 3934 8192000000 10719670272 11104301056 1789167216 17.925 100 79 158 0 1824727872 13058160 22502496
23:16:50 8444 8192000000 10768403536 11150663680 1825653056 18.224 100 79 158 1 1862341840 14891360 21797424
23:18:00 2123 8192000000 10822660064 11211522048 1872654384 18.606 100 85 169 2 1908877968 12273248 23950336
23:19:03 15336 8192000000 10800083920 11210141696 1880593584 18.670 100 62 124 0 1909858816 11653968 17611264
23:20:20 6792 8192000000 10791504464 11216822272 1882666256 18.687 100 22 42 2 1895394912 6363984 6364672
23:21:27 8525 8192000000 10800659728 11229691904 1892231008 18.764 100 25 50 0 1905174128 5710608 7232512
23:22:37 42101 8192000000 10810624176 11242074112 1895533488 18.790 100 30 60 0 1910285664 6124528 8627648
23:24:20 6671 8192000000 10828958848 11257901056 1910333600 18.909 100 28 56 0 1925250240 6387664 8528976
23:25:28 7974 8192000000 10820455456 11263401984 1917757024 18.969 100 27 54 1 1931494160 6008384 7728752
23:26:37 58359 8192000000 10846969184 11269505024 1899555264 18.823 99 30 60 2 1914253312 6258432 8439616
23:28:37 6798 8192000000 10777144688 11192590336 1823933072 18.210 97 17 34 1 1834465536 5408016 5124448
23:29:45 4973 8192000000 10721914224 11135090688 1773276496 17.794 98 25 50 0 1787101152 6710320 7114336
23:30:52 8210 8192000000 10694426352 11097047040 1693415056 17.130 95 23 45 1 1704491536 4723440 6353040
23:32:03 28934 8192000000 10695135664 11122552832 1766859648 17.741 98 34 68 1 1783115504 6538560 9717296
23:33:36 8228 8192000000 10758845072 11186900992 1831200752 18.269 99 26 52 1 1843815408 5408832 7205824
23:34:46 6886 8192000000 10779972752 11207077888 1863936368 18.535 100 27 54 1 1877508464 5947712 7624384
23:35:54 10313 8192000000 10797500640 11227283456 1882717120 18.687 100 21 41 1 1895251520 6164704 6369696
23:37:05 19725 8192000000 10810033392 11242549248 1895808640 18.793 100 26 52 0 1909304768 6193600 7302528
23:38:33 10760 8192000000 10858040736 11283267584 1925738176 19.033 100 48 95 2 1946074448 7432192 12904080
23:39:52 10113 8192000000 10890275088 11313815552 1951154496 19.236 100 49 98 0 1975182384 10218080 13809808
23:41:11 26000 8192000000 10894258128 11328880640 1962099472 19.323 100 34 68 0 1985183520 13072576 10011472
23:42:46 12329 8192000000 10927931936 11361292288 1987763184 19.526 100 53 104 1 2021125792 17084752 16277856
23:44:13 11032 8192000000 10922704384 11354050560 1969079104 19.378 99 44 89 0 1998101184 16297120 12724960
23:45:29 3969 8192000000 10947876464 11374460928 1993684880 19.573 100 60 118 1 2030144352 19378544 17080928
23:46:40 8824 8192000000 10972879840 11405565952 2018901296 19.772 100 60 120 1 2057519360 20793504 17824560
23:47:53 5374 8192000000 10946047200 11366322176 1979732864 19.463 99 87 174 2 2032885296 25910624 27241808
23:49:04 4593 8192000000 10959003424 11386388480 1984137776 19.497 99 55 108 0 2024243408 24310304 15795328
23:50:14 6825 8192000000 10982978832 11406995456 2012108832 19.718 100 69 138 0 2064642528 31296160 21237536
23:51:33 2231 8192000000 10987922992 11401965568 2006133088 19.671 100 92 185 5 2067690368 32725984 28831296
23:52:39 8562 8192000000 10973257056 11399180288 1980107856 19.466 99 76 152 0 2048677312 44421840 24147616
23:53:53 4028 8192000000 10974655104 11395624960 1979050880 19.457 99 75 149 1 2041355664 39295968 23008816
23:55:01 7035 8192000000 10957408896 11380240384 1977364400 19.444 99 63 126 1 2034438528 36712736 20361392
23:56:13 2130 8192000000 10963741680 11389472768 1984654864 19.502 99 73 145 1 2045311728 37482528 23174336
23:57:21 2762 8192000000 10950008288 11377881088 1976828640 19.440 100 55 111 0 2029967616 36258816 16880160
23:58:30 9142 8192000000 10931663584 11374010368 1953329200 19.253 99 38 76 2 2008837600 43054144 12454256
23:59:56 11479 8192000000 10922234384 11354394624 1923805792 19.017 98 42 84 0 1980387552 42057664 14524096
00:01:09 11802 8192000000 10943781072 11376459776 1946201104 19.196 99 39 78 3 2010550192 50792592 13556496
00:02:25 11339 8192000000 10947529392 11395764224 1978446320 19.452 100 43 84 0 2039045712 46669520 13929872
00:03:59 17414 8192000000 10924385664 11370442752 1955849680 19.273 99 24 48 1 2004921968 41058128 8014160
00:05:18 34081 8192000000 10917992144 11375894528 1945161168 19.188 100 19 38 0 2007673824 54627616 7885040
00:07:07 78577 8192000000 10889907344 11349880832 1903599760 18.855 97 11 22 0 1947164144 39981472 3582912
00:09:26 94153 8192000000 10908805936 11380408320 1921112656 18.996 97 13 24 1 1944983040 20361920 3508464
00:12:01 58849 8192000000 10910565488 11372388352 1952032880 19.243 99 10 20 0 1975734544 20830656 2871008
00:14:01 46310 8192000000 10910736800 11366486016 1965800304 19.352 99 26 52 0 1981763440 8398288 7564848
00:15:47 109815 8192000000 10932718912 11404062720 1997320704 19.602 100 23 46 0 2018752736 14832496 6599536
00:18:38 53917 8192000000 10923285088 11382611968 1974915680 19.424 99 25 50 1 1994636064 12538624 7181760
00:20:38 63693 8192000000 10899744496 11363311616 1925272320 19.029 97 21 42 1 1941609808 9770016 6567472
00:22:45 68577 8192000000 10935989408 11396861952 1999153248 19.616 100 26 52 0 2021003232 14429248 7420736
00:25:10 12685 8192000000 10929748400 11385274368 1957118336 19.283 98 49 98 3 1993761424 21158464 15484624
00:26:31 21306 8192000000 10918937136 11399716864 1996250624 19.593 100 34 68 0 2026997088 20138288 10608176
00:27:54 29465 8192000000 10891884976 11369656320 1918601024 18.976 96 8 16 0 1928532720 7805248 2126448
00:29:24 54071 8192000000 10903308624 11368419328 1933020320 19.091 97 17 34 2 1952424288 14238800 5165168
00:31:27 100659 8192000000 10909358224 11395731456 1960723936 19.312 99 28 56 2 1992222016 22105136 9392944
00:34:27 37890 8192000000 10884598016 11364855808 1942082704 19.163 98 10 20 0 1952347904 7264240 3000960
00:36:07 51722 8192000000 10849916736 11333525504 1867342192 18.563 95 10 20 2 1877705168 7746928 2616048
00:37:59 48781 8192000000 10883520832 11358453760 1967743840 19.368 100 18 36 1 1982622064 10066768 4811456
00:39:50 47217 8192000000 10872875200 11348643840 1924609040 19.024 98 8 16 0 1933659520 7276800 1773680
00:41:39 32255 8192000000 10885303088 11346931712 1922824896 19.009 98 33 66 0 1943923232 11326512 9771824
00:43:12 56330 8192000000 10858969808 11327590400 1852100032 18.439 95 30 60 0 1874492576 14251552 8140992
00:45:14 31451 8192000000 10868599856 11322277888 1903143680 18.852 98 26 52 1 1919669856 8902688 7623488
00:46:49 34639 8192000000 10877322144 11336409088 1911664192 18.920 98 42 83 0 1935892240 12563776 11664272
00:48:27 82458 8192000000 10870528400 11323994112 1825687872 18.224 94 15 30 0 1847409808 17951904 3770032
00:50:50 23093 8192000000 10852529232 11299794944 1870705344 18.590 97 37 74 0 1889410304 8225264 10479696
00:52:15 59712 8192000000 10853678016 11331538944 1915368608 18.950 99 13 25 1 1933794576 14963024 3462944
00:54:15 119315 8192000000 10874713104 11331723264 1918709312 18.977 98 29 57 0 1934048560 7719696 7619552
00:57:19 31532 8192000000 10875950064 11337412608 1942229072 19.165 100 35 70 0 1965018048 13362624 9426352
00:58:51 35628 8192000000 10863828416 11333640192 1944372272 19.182 100 14 28 1 1954871488 6298016 4201200
01:00:28 70512 8192000000 10850553120 11322138624 1887398192 18.725 97 10 20 0 1900220112 9865840 2956080
01:04:17 51773 8192000000 10848501744 11321851904 1899614128 18.823 98 22 44 1 1931267344 24339792 7313424
01:06:10 48922 8192000000 10882734672 11353526272 1955849056 19.273 100 32 61 4 1978218224 13736208 8632960
01:08:21 56056 8192000000 10899415216 11356041216 1964425328 19.341 100 19 38 1 1981457552 11664864 5367360
01:10:19 45972 8192000000 10889926144 11358429184 1925755648 19.033 98 30 60 3 1947881296 14228928 7896720
01:12:12 92229 8192000000 10881113504 11359965184 1924924848 19.026 98 22 44 2 1951890784 20751728 6214208
01:14:46 75460 8192000000 10901739520 11375845376 1956247136 19.276 99 14 28 0 1974473088 14828048 3397904
01:17:06 51760 8192000000 10887252592 11355410432 1889433280 18.741 96 15 30 1 1906620256 12752944 4434032
01:18:59 119143 8192000000 10898299792 11365666816 1951823680 19.241 99 24 47 0 1967875984 9511456 6540848
01:22:45 48809 8192000000 10883100416 11348684800 1922044096 19.003 98 12 24 0 1934382768 8925936 3412736
01:24:35 49795 8192000000 10855574208 11329654784 1892221120 18.764 97 12 24 0 1908298768 12676000 3401648
01:26:25 76127 8192000000 10865409184 11337957376 1940564848 19.151 100 16 32 1 1959622336 14280272 4777216
01:29:33 55168 8192000000 10862651056 11335065600 1902857648 18.849 98 17 34 1 1917256032 9844624 4553760
01:31:29 49142 8192000000 10843339520 11321294848 1891858400 18.761 98 18 36 1 1912049568 14354368 5836800
01:33:18 67199 8192000000 10841785920 11300765696 1851744048 18.436 96 17 34 1 1871130704 14649712 4736944
01:35:26 90559 8192000000 10883793936 11336691712 1865766160 18.550 96 35 70 0 1888072000 13192896 9112944

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 15-09-2021 01:39:08 to 15-09-2021 01:39:23.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-09-2021 01:39:24 to 15-09-2021 01:39:26.

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          246372
MON$OLDEST_ACTIVE               248260
MON$OLDEST_SNAPSHOT             248260
MON$NEXT_TRANSACTION            248260
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                       2058144
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.33495 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33495 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33495 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33495 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 ---
:209: 
:210: mscs-test-161.budzdorov.ru    Wed Sep 15 01:37:50 2021
:211:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:212: 
:213: 
:214: mscs-test-161.budzdorov.ru    Wed Sep 15 01:37:55 2021
:215:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:216: 
:217: 
:218: mscs-test-161.budzdorov.ru    Wed Sep 15 01:38:01 2021
:219:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:220: 
:221: 
:222: mscs-test-161.budzdorov.ru    Wed Sep 15 01:38:02 2021
:223:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:224: 
:225: 
:226: mscs-test-161.budzdorov.ru    Wed Sep 15 01:38:02 2021
:227:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:228: 
--- end of diff output ---

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

15.09.21 01:39:27. 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-09-15 01:39:30 No severe PSQL-related problems occured
15.09.21 01:39:31. End of report.