Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.10.2022 16:40
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 15431376 kB
MemAvailable 30529076 kB
Buffers 0 kB
Cached 16311688 kB
SwapCached 2172 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.11
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 2022-10-16 16:38:30
DTS_END                         2022-10-16 16:38:30.8510
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/43382
TRN_ID                          250278
ATT_ID                          666
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: 9638.74 16742 1156649
customer order: creation 185.14 299 22217
customer order: refuse 15.51 325 1861
order to supplier: creation 48.97 1242 5876
order to supplier: removal 3.57 85169 428
invoice (draft): creation 48.28 2984 5793
invoice (draft): removal 51.50 703 6180
invoice accept: apply 45.67 17631 5480
invoice accept: cancel 47.01 763 5641
customer reserve: creation 4241.95 173 509034
customer reserve: removal 4638.45 51 556614
realization accept: apply 89.58 166 10749
realization accept: cancel 84.36 117 10123
payment from customer: creation 64.13 96 7695
payment from customer: removal 5.88 77 705
payment to supplier: creation 52.37 105 6284
payment to supplier: removal 3.76 64 451
service: total inventory turnovers 4.97 1053 597
service: total monetary turnovers 6.65 42 798
service: aggregate perf. data 0.99 18254 119
service: refresh index statistics 0.03 205525 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-10-2022 16:40:54 to 16-10-2022 16:40:56.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1876
TEST_TIME 2 3120
TEST_TIME 3 3126
TEST_TIME 4 3673
TEST_TIME 5 3968
TEST_TIME 6 4983
TEST_TIME 7 5630
TEST_TIME 8 5488
TEST_TIME 9 5925
TEST_TIME 10 6165
TEST_TIME 11 6195
TEST_TIME 12 6916
TEST_TIME 13 6925
TEST_TIME 14 7335
TEST_TIME 15 7311
TEST_TIME 16 7689
TEST_TIME 17 7822
TEST_TIME 18 8097
TEST_TIME 19 8065
TEST_TIME 20 8389
TEST_TIME 21 8486
TEST_TIME 22 9001
TEST_TIME 23 9061
TEST_TIME 24 9193
TEST_TIME 25 9096
TEST_TIME 26 9047
TEST_TIME 27 8848
TEST_TIME 28 9684
TEST_TIME 29 10225
TEST_TIME 30 10404
TEST_TIME 31 10339
TEST_TIME 32 10180
TEST_TIME 33 10237
TEST_TIME 34 10356
TEST_TIME 35 10395
TEST_TIME 36 10435
TEST_TIME 37 10300
TEST_TIME 38 10488
TEST_TIME 39 10350
TEST_TIME 40 10255
TEST_TIME 41 10032
TEST_TIME 42 10048
TEST_TIME 43 10044
TEST_TIME 44 9889
TEST_TIME 45 9813
TEST_TIME 46 9707
TEST_TIME 47 9705
TEST_TIME 48 9833
TEST_TIME 49 9844
TEST_TIME 50 9777
TEST_TIME 51 9716
TEST_TIME 52 9729
TEST_TIME 53 9652
TEST_TIME 54 9673
TEST_TIME 55 9663
TEST_TIME 56 9698
TEST_TIME 57 9616
TEST_TIME 58 9738
TEST_TIME 59 9912
TEST_TIME 60 9866
TEST_TIME 61 9948
TEST_TIME 62 9956
TEST_TIME 63 10000
TEST_TIME 64 9983
TEST_TIME 65 10087
TEST_TIME 66 10104
TEST_TIME 67 10113
TEST_TIME 68 10087
TEST_TIME 69 10133
TEST_TIME 70 9996
TEST_TIME 71 10001
TEST_TIME 72 9944
TEST_TIME 73 9902
TEST_TIME 74 9872
TEST_TIME 75 9923
TEST_TIME 76 9874
TEST_TIME 77 9855
TEST_TIME 78 9796
TEST_TIME 79 9716
TEST_TIME 80 9662
TEST_TIME 81 9653
TEST_TIME 82 9652
TEST_TIME 83 9676
TEST_TIME 84 9680
TEST_TIME 85 9675
TEST_TIME 86 9661
TEST_TIME 87 9670
TEST_TIME 88 9698
TEST_TIME 89 9695
TEST_TIME 90 9698
TEST_TIME 91 9750
TEST_TIME 92 9732
TEST_TIME 93 9781
TEST_TIME 94 9729
TEST_TIME 95 9813
TEST_TIME 96 9863
TEST_TIME 97 9784
TEST_TIME 98 9752
TEST_TIME 99 9683
TEST_TIME 100 9695
TEST_TIME 101 9667
TEST_TIME 102 9621
TEST_TIME 103 9605
TEST_TIME 104 9583
TEST_TIME 105 9531
TEST_TIME 106 9498
TEST_TIME 107 9510
TEST_TIME 108 9516
TEST_TIME 109 9524
TEST_TIME 110 9510
TEST_TIME 111 9530
TEST_TIME 112 9600
TEST_TIME 113 9605
TEST_TIME 114 9573
TEST_TIME 115 9572
TEST_TIME 116 9692
TEST_TIME 117 9726
TEST_TIME 118 9741
TEST_TIME 119 9696
TEST_TIME 120 9653

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 16-10-2022 16:40:58 to 16-10-2022 16:41: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 1625543 1625543 0 0.00 0 24576 6 0 0
doc_list_biud 1625543 1625543 0 0.00 0 55117 23 0 0
sp_add_invoice_to_stock 5610 5480 130 2.31 17 173086 17632 0 130
sp_cancel_adding_invoice 5641 5641 0 0.00 5 63560 764 0 0
sp_cancel_client_order 1861 1861 0 0.00 4 16184 326 0 0
sp_cancel_customer_reserve 556614 556614 0 0.00 0 26080 51 0 0
sp_cancel_pay_from_customer 705 705 0 0.00 3 9937 77 0 0
sp_cancel_pay_to_supplier 451 451 0 0.00 2 4602 64 0 0
sp_cancel_supplier_invoice 6191 6180 11 0.17 1 55170 704 0 11
sp_cancel_supplier_order 428 428 0 0.00 8 819881 85169 0 0
sp_cancel_write_off 10123 10123 0 0.00 1 6585 118 0 0
sp_client_order 22217 22217 0 0.00 3 21279 300 0 0
sp_customer_reserve 509034 509034 0 0.00 0 97172 174 0 0
sp_fill_shopping_cart 33870 33870 0 0.00 1 25939 38 0 0
sp_fill_shopping_cart_clo_res 509030 509030 0 0.00 0 26919 13 0 0
sp_get_clo_for_invoice 5476 5476 0 0.00 0 37272 311 0 0
sp_kill_cost_storno 16920 16920 0 0.00 0 10203 52 0 0
sp_kill_qstorno_ret_qs2qd 1129959 1129959 0 0.00 0 55116 28 0 0
sp_kill_qty_storno 597070 597070 0 0.00 0 55116 61 0 0
sp_lock_selected_doc 22268 22268 0 0.00 0 9658 4 0 0
sp_make_cost_storno 30202 30202 0 0.00 0 19650 48 0 0
sp_multiply_rows_for_pdistr 30202 30202 0 0.00 0 19703 28 0 0
sp_multiply_rows_for_qdistr 22217 22217 0 0.00 0 19225 125 0 0
sp_pay_from_customer 7695 7695 0 0.00 4 19766 97 0 0
sp_pay_to_supplier 6284 6284 0 0.00 5 10731 105 0 0
sp_payment_common 13979 13979 0 0.00 1 6500 19 0 0
sp_qd_handle_on_cancel_clo 1861 1861 0 0.00 0 16175 293 0 0
sp_qd_handle_on_invoice_upd_sts 11115 11115 0 0.00 0 21888 304 0 0
sp_qd_handle_on_reserve_upd_sts 10749 10749 0 0.00 0 22140 57 0 0
sp_reserve_write_off 10749 10749 0 0.00 6 22162 167 0 0
sp_supplier_invoice 5793 5793 0 0.00 6 81911 2985 0 0
sp_supplier_order 5876 5876 0 0.00 5 35637 1242 0 0
srv_aggregate_perf_data 119 119 0 0.00 2505 287021 18254 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1840 120244 46262 0 0
srv_find_qd_qs_mism 1103747 1103747 0 0.00 0 24575 6 0 0
srv_make_invnt_saldo 597 597 0 0.00 23 67350 1054 0 0
srv_make_money_saldo 798 798 0 0.00 0 4200 43 0 0
srv_recalc_idx_stat 4 4 0 0.00 105304 373154 205526 0 0
t$perf-abend:sp_add_invoice_to_stock 130 130 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 5480 5480 0 0.00 0 13944 173 0 0
t$perf-norm:sp_cancel_adding_invoice 421 421 0 0.00 0 2711 113 0 0
t$perf-norm:sp_cancel_client_order 1861 1861 0 0.00 0 2912 12 0 0
t$perf-norm:sp_cancel_customer_reserve 672 672 0 0.00 0 1078 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 705 705 0 0.00 0 2665 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 451 451 0 0.00 0 2073 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 424 424 0 0.00 0 1358 15 0 0
t$perf-norm:sp_cancel_supplier_order 428 428 0 0.00 0 23922 1433 0 0
t$perf-norm:sp_cancel_write_off 1083 1083 0 0.00 0 4022 10 0 0
t$perf-norm:sp_client_order 22217 22217 0 0.00 0 7323 15 0 0
t$perf-norm:sp_customer_reserve 1036 1036 0 0.00 0 1067 5 0 0
t$perf-norm:sp_pay_from_customer 7695 7695 0 0.00 0 4949 9 0 0
t$perf-norm:sp_pay_to_supplier 6284 6284 0 0.00 0 5308 12 0 0
t$perf-norm:sp_reserve_write_off 10749 10749 0 0.00 0 3277 7 0 0
t$perf-norm:sp_supplier_invoice 5793 5793 0 0.00 0 4719 15 0 0
t$perf-norm:sp_supplier_order 5876 5876 0 0.00 0 3554 15 0 0
t$perf-norm:srv_aggregate_perf_data 119 119 0 0.00 0 232 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 297 7 0 0
t$perf-norm:srv_make_invnt_saldo 597 597 0 0.00 0 2239 27 0 0
t$perf-norm:srv_make_money_saldo 798 798 0 0.00 0 1883 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 11208 10948 260 2.31 0 5387 19 0 260
v_all_customers 40090 40090 0 0.00 0 47 1 0 0
v_all_suppliers 32010 32010 0 0.00 0 34 1 0 0
v_all_wares 44434 44434 0 0.00 0 23 1 0 0
v_cancel_adding_invoice 842 842 0 0.00 0 1811 10 0 0
v_cancel_client_order 3722 3722 0 0.00 0 3090 7 0 0
v_cancel_customer_prepayment 1410 1410 0 0.00 0 454 4 0 0
v_cancel_customer_reserve 1344 1344 0 0.00 0 565 3 0 0
v_cancel_payment_to_supplier 902 902 0 0.00 0 547 3 0 0
v_cancel_supplier_invoice 870 848 22 2.52 0 2232 16 0 22
v_cancel_supplier_order 856 856 0 0.00 0 464 4 0 0
v_cancel_write_off 2166 2166 0 0.00 0 2954 15 0 0
v_max_id_clo_ord 5876 5876 0 0.00 0 1564 1 0 0
v_max_id_clo_res 1036 1036 0 0.00 0 2266 10 0 0
v_max_id_ord_sup 5777 5777 0 0.00 0 2635 3 0 0
v_max_non_paid_invoice 1932 1932 0 0.00 0 374 2 0 0
v_max_non_paid_realizn 4335 4335 0 0.00 0 602 3 0 0
v_min_id_clo_ord 5876 5876 0 0.00 0 5327 6 0 0
v_min_id_clo_res 1036 1036 0 0.00 0 1059 3 0 0
v_min_id_ord_sup 5777 5777 0 0.00 0 12380 7 0 0
v_min_non_paid_invoice 6284 6284 0 0.00 0 3800 12 0 0
v_min_non_paid_realizn 7695 7695 0 0.00 0 3600 11 0 0
v_our_firm 11064 11064 0 0.00 0 19 1 0 0
v_reserve_write_off 21498 21498 0 0.00 0 3094 6 0 0
x_lock_depdocs_on_canc_invoice 11397 11397 0 0.00 0 20709 126 0 0
x_lock_depdocs_on_canc_sup_ord 428 428 0 0.00 0 12907 339 0 0
x_make_qstorno_1000_1200 5876 5876 0 0.00 1 35623 1164 0 0
x_make_qstorno_1200_2000 5793 5793 0 0.00 3 70936 2833 0 0
x_make_qstorno_2100_3300 509034 509034 0 0.00 0 97171 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-10-2022 16:41:02 to 16-10-2022 16:41: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
14:08:34 18 8192000000 8807888544 8822427648 9108448 0.111 3 2 3 0 9250880 67472 74960
14:09:36 28270 8192000000 9903508576 10216697856 987821856 10.760 100 81 162 0 1014635680 5917328 20896496
14:11:11 17922 8192000000 9949205984 10272559104 1031280864 11.181 100 94 188 0 1064560096 9241984 24037248
14:12:36 8700 8192000000 9981770640 10314272768 1067272224 11.526 100 88 176 0 1099808432 10568560 21967648
14:13:47 9124 8192000000 10025372864 10369261568 1110264976 11.935 100 88 173 0 1142941424 11838144 20838304
14:15:01 8031 8192000000 10065797120 10419671040 1152793296 12.336 100 82 164 1 1184406448 12068048 19545104
14:16:13 3812 8192000000 10108527696 10472140800 1195787856 12.737 100 77 154 0 1226729232 13410496 17530880
14:17:20 3496 8192000000 10151807184 10523803648 1235113728 13.101 100 76 150 0 1264380464 12956704 16310032
14:18:25 1643 8192000000 10195834400 10575515648 1275824032 13.475 100 86 172 1 1306658864 12009888 18824944
14:19:29 1499 8192000000 10213789040 10596773888 1296923056 13.667 100 94 185 1 1331371440 14511168 19937216
14:20:33 6312 8192000000 10192578768 10598559744 1304248496 13.734 100 38 76 1 1320004256 7347920 8407840
14:21:40 6909 8192000000 10197290544 10609336320 1312559712 13.809 100 31 62 0 1325443184 5847776 7035696
14:22:48 9386 8192000000 10216339536 10632749056 1324746096 13.920 100 22 44 0 1340739264 10950736 5042432
14:24:06 52648 8192000000 10240425568 10643095552 1330250240 13.969 100 52 104 2 1348510640 5819536 12440864
14:25:59 8156 8192000000 10227043104 10647842816 1337032688 14.031 100 19 38 1 1347785360 6038656 4714016
14:27:13 7262 8192000000 10228189808 10649034752 1327041120 13.940 99 16 32 0 1336313008 5068528 4203360
14:28:24 46628 8192000000 10213540048 10628648960 1311363792 13.798 99 45 89 1 1327690912 6407424 9919696
14:30:12 5037 8192000000 10188438496 10595155968 1284068496 13.550 99 32 64 0 1297727552 6767632 6891424
14:31:19 9170 8192000000 10136827024 10534326272 1216704528 12.931 97 19 37 0 1225614592 4897632 4012432
14:32:28 9992 8192000000 10160183216 10562805760 1265237648 13.378 100 19 36 0 1274754512 5195536 4321328
14:33:38 22873 8192000000 10147528032 10556145664 1224824400 13.006 96 28 56 0 1237094384 5226544 7043440
14:35:07 4314 8192000000 10183177888 10591133696 1287880528 13.585 100 39 75 2 1303744960 7143440 8720992
14:36:14 10704 8192000000 10192705984 10604642304 1298521904 13.682 100 17 34 2 1307748928 5174064 4052960
14:37:30 10734 8192000000 10199286448 10612617216 1309769104 13.784 100 17 30 1 1318236736 5322992 3144640
14:38:43 74515 8192000000 10226743504 10638389248 1326241632 13.933 100 41 83 0 1340728992 5351600 9135760
14:41:09 22008 8192000000 10258964992 10677456896 1355187696 14.194 100 42 83 0 1375463872 10917584 9358592
14:42:41 30734 8192000000 10274241504 10700148736 1371594384 14.341 100 39 79 1 1394172048 13137760 9439904
14:44:25 10624 8192000000 10296461472 10716295168 1378337312 14.402 100 55 111 0 1408698608 17491936 12869360
14:45:39 13847 8192000000 10288126464 10717347840 1382035152 14.435 100 39 78 0 1409198528 17743600 9419776
14:46:58 6030 8192000000 10314617984 10732482560 1390237536 14.508 100 66 131 0 1421934496 16457264 15239696
14:48:08 8932 8192000000 10320224448 10744016896 1398941856 14.586 100 52 103 0 1429565872 18229376 12394640
14:49:22 3827 8192000000 10313405488 10736111616 1389048656 14.497 100 61 122 1 1425277296 21217856 15010784
14:50:29 8947 8192000000 10322333024 10744508416 1394424624 14.545 100 58 116 1 1430337344 22108144 13804576
14:51:44 2426 8192000000 10342924640 10759254016 1406601168 14.654 100 86 168 2 1448443792 22509696 19332928
14:52:52 4148 8192000000 10320264048 10736885760 1389489104 14.501 100 84 168 2 1431997328 22248208 20260016
14:54:04 1839 8192000000 10307152704 10712145920 1362981904 14.264 100 82 164 1 1412054384 29263040 19809440
14:55:12 4703 8192000000 10304361392 10714238976 1365462944 14.286 100 75 144 0 1411491280 27707072 18321264
14:56:25 4590 8192000000 10313341776 10717052928 1369032960 14.318 100 74 148 0 1417609888 30617024 17959904
14:57:33 4060 8192000000 10316554128 10716536832 1353600384 14.180 99 78 156 0 1410918704 38114080 19204240
14:58:49 5273 8192000000 10323080832 10735181824 1356298688 14.204 99 53 106 2 1409085056 39897696 12888672
14:59:56 2543 8192000000 10329991168 10730283008 1361412176 14.250 99 82 159 0 1425048864 43541616 20095072
15:01:03 5968 8192000000 10335946576 10755747840 1384693568 14.458 100 46 90 0 1437520048 41244288 11582192
15:02:11 18691 8192000000 10316278192 10741272576 1364273920 14.276 98 28 54 1 1414594176 43133408 7186848
15:03:33 6447 8192000000 10321898912 10748436480 1375878672 14.380 100 37 75 0 1428638544 43512112 9247760
15:04:43 13301 8192000000 10305448496 10736304128 1361908624 14.254 99 29 57 0 1415710096 45669264 8132208
15:06:22 30046 8192000000 10295264336 10755829760 1364983888 14.282 100 19 38 0 1422025728 51533472 5508368
15:08:39 84139 8192000000 10277149824 10718089216 1350573392 14.153 99 17 34 1 1364305024 10059840 3671792
15:12:01 70256 8192000000 10274333248 10713161728 1336807728 14.029 97 10 20 0 1355885664 16816128 2261808
15:14:17 67569 8192000000 10303828576 10734764032 1366413712 14.295 99 22 40 0 1393330080 22167152 4749216
15:16:29 96401 8192000000 10316943536 10753236992 1392315472 14.527 100 36 70 1 1414337424 13360640 8661312
15:19:07 120243 8192000000 10301140832 10761400320 1382607280 14.440 99 37 74 0 1400195392 9126592 8461520
15:22:09 45871 8192000000 10305258352 10778251264 1394899136 14.550 100 10 20 0 1414038544 17057504 2081904
15:24:20 42140 8192000000 10323273744 10773975040 1410958496 14.692 100 34 67 2 1432065696 13464640 7642560
15:26:07 41131 8192000000 10299128432 10769059840 1387074736 14.480 99 13 26 0 1405645776 15300896 3270144
15:27:50 93524 8192000000 10285034976 10745966592 1363687040 14.270 98 15 28 0 1374870736 8069840 3113856
15:30:24 44639 8192000000 10283933792 10752311296 1388329328 14.491 100 7 15 0 1397699760 8085424 1285008
15:32:21 77230 8192000000 10288982432 10743660544 1341509904 14.071 96 28 56 0 1357871392 9848432 6513056
15:34:39 74496 8192000000 10312141520 10760396800 1347846560 14.128 96 19 38 0 1369438352 17307696 4284096
15:36:58 59667 8192000000 10298249424 10765389824 1378213040 14.401 99 8 16 0 1399604624 19181888 2209696
15:38:58 11876 8192000000 10303377312 10739539968 1362810512 14.263 98 39 77 1 1378578560 8006560 7761488
15:40:11 56917 8192000000 10297908128 10756706304 1362445312 14.259 98 17 34 0 1376372064 10521712 3405040
15:42:08 87105 8192000000 10292776112 10755104768 1318897472 13.867 95 26 52 0 1343718448 18725776 6095200
15:45:55 49028 8192000000 10294184304 10739142656 1363970464 14.273 99 21 42 0 1378608608 10125888 4512256
15:47:46 90782 8192000000 10306263568 10745307136 1355078816 14.193 98 27 54 1 1372125888 11233632 5813440
15:50:18 93458 8192000000 10264673824 10708529152 1317174784 13.851 97 23 46 0 1331069616 8692896 5201936
15:52:53 50672 8192000000 10259386064 10712186880 1354698592 14.190 100 36 73 1 1374590384 11262976 8628816
15:54:48 82442 8192000000 10275851360 10742276096 1327930032 13.948 97 9 18 0 1349944928 20147056 1867840
15:57:12 56171 8192000000 10259015744 10712764416 1328961648 13.958 97 10 20 0 1341921952 10355664 2604640
15:59:09 48861 8192000000 10266439936 10719162368 1349185584 14.140 99 13 26 0 1363662752 11689088 2788080
16:01:04 88083 8192000000 10247603888 10709696512 1271599152 13.436 93 9 18 0 1285184240 11670000 1915088
16:03:33 32700 8192000000 10268766368 10721452032 1351955952 14.165 99 32 64 0 1368762592 9734576 7072064
16:05:15 31615 8192000000 10274066208 10723463168 1352245312 14.168 99 36 72 0 1369987344 9838608 7903424
16:06:48 62935 8192000000 10291197136 10737225728 1376606160 14.386 100 22 44 1 1391126080 9664320 4855600
16:08:56 42990 8192000000 10298504992 10748940288 1362607568 14.261 98 30 60 0 1380355840 11342464 6405808
16:10:51 50442 8192000000 10284259408 10743689216 1352554688 14.170 97 22 44 0 1368976864 11299488 5122688
16:12:45 36274 8192000000 10300247824 10762813440 1380939488 14.425 99 37 74 1 1407479280 17807088 8732704
16:14:22 115695 8192000000 10291596592 10757574656 1377064144 14.390 99 18 36 0 1399645648 18334880 4246624
16:17:34 44990 8192000000 10275415136 10735493120 1369551088 14.323 99 22 42 0 1382540048 8513440 4475520
16:19:20 61731 8192000000 10252887936 10713640960 1350631344 14.153 98 8 16 0 1359478032 7187472 1659216
16:21:38 48094 8192000000 10238328560 10695352320 1324769040 13.920 97 10 19 0 1335525920 8495648 2261232
16:23:29 72516 8192000000 10259751776 10716446720 1324423392 13.917 97 20 41 0 1337348416 8983552 3941472
16:25:51 77785 8192000000 10263541136 10726277120 1327343296 13.943 96 15 30 0 1344081744 13166816 3571632
16:28:16 21632 8192000000 10292181632 10739847168 1359212464 14.230 98 30 60 0 1374811392 8986144 6612784
16:29:38 69949 8192000000 10274804640 10747871232 1350655040 14.153 98 13 26 0 1363873456 10552464 2665952
16:31:50 71238 8192000000 10304538464 10752995328 1339762432 14.055 97 21 42 0 1359350256 15183056 4404768
16:34:02 104908 8192000000 10286035072 10751815680 1366801024 14.298 99 18 36 0 1391831808 20981248 4049536
16:36:50 52743 8192000000 10284116128 10732822528 1355487728 14.197 99 15 30 0 1371125584 11954592 3683264

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 16-10-2022 16:41:08 to 16-10-2022 16:41:23.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 130 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 16-10-2022 16:41:24 to 16-10-2022 16:41: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          248658
MON$OLDEST_ACTIVE               250390
MON$OLDEST_SNAPSHOT             250390
MON$NEXT_TRANSACTION            250390
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                       2039584
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33634 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33634 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33634 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33634 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 ---
:426: 
:427: mscs-test-161.budzdorov.ru    Sun Oct 16 16:39:09 2022
:428:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:429: 
:430: 
:431: mscs-test-161.budzdorov.ru    Sun Oct 16 16:39:19 2022
:432:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:433: 
--- end of diff output ---

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

16.10.22 16:41: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
2022-10-16 16:41:30 No severe PSQL-related problems occured
16.10.22 16:41:31. End of report.