Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.04.2022 01:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16728956 kB
MemAvailable 31621328 kB
Buffers 0 kB
Cached 15016096 kB
SwapCached 2200 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.10
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-04-02 01:38:20
DTS_END                         2022-04-02 01:38:20.4640
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/42328
TRN_ID                          234544
ATT_ID                          639
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: 9425.96 23069 1131115
customer order: creation 166.85 396 20022
customer order: refuse 14.38 331 1725
order to supplier: creation 44.53 1321 5344
order to supplier: removal 3.29 107048 395
invoice (draft): creation 45.18 3480 5422
invoice (draft): removal 54.60 740 6552
invoice accept: apply 40.56 18044 4867
invoice accept: cancel 49.62 764 5954
customer reserve: creation 3829.34 174 459521
customer reserve: removal 4885.10 56 586212
realization accept: apply 82.01 184 9841
realization accept: cancel 83.29 114 9995
payment from customer: creation 58.23 108 6988
payment from customer: removal 5.33 95 639
payment to supplier: creation 47.81 124 5737
payment to supplier: removal 3.27 136 392
service: total inventory turnovers 4.97 1015 596
service: total monetary turnovers 6.60 33 792
service: aggregate perf. data 0.98 15941 117
service: refresh index statistics 0.03 311285 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 02-04-2022 01:40:11 to 02-04-2022 01:40:14.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2051
TEST_TIME 2 2451
TEST_TIME 3 3743
TEST_TIME 4 3706
TEST_TIME 5 4379
TEST_TIME 6 4109
TEST_TIME 7 4873
TEST_TIME 8 4866
TEST_TIME 9 4845
TEST_TIME 10 5203
TEST_TIME 11 5272
TEST_TIME 12 5301
TEST_TIME 13 5429
TEST_TIME 14 5690
TEST_TIME 15 6380
TEST_TIME 16 6701
TEST_TIME 17 6880
TEST_TIME 18 7044
TEST_TIME 19 7469
TEST_TIME 20 7977
TEST_TIME 21 8254
TEST_TIME 22 8588
TEST_TIME 23 9134
TEST_TIME 24 9141
TEST_TIME 25 9104
TEST_TIME 26 9700
TEST_TIME 27 9908
TEST_TIME 28 10489
TEST_TIME 29 10451
TEST_TIME 30 10793
TEST_TIME 31 10756
TEST_TIME 32 10799
TEST_TIME 33 10676
TEST_TIME 34 10696
TEST_TIME 35 10584
TEST_TIME 36 10391
TEST_TIME 37 10389
TEST_TIME 38 10511
TEST_TIME 39 10346
TEST_TIME 40 10278
TEST_TIME 41 10131
TEST_TIME 42 9960
TEST_TIME 43 10000
TEST_TIME 44 10206
TEST_TIME 45 10201
TEST_TIME 46 10098
TEST_TIME 47 10055
TEST_TIME 48 9894
TEST_TIME 49 10012
TEST_TIME 50 9972
TEST_TIME 51 9933
TEST_TIME 52 9942
TEST_TIME 53 9938
TEST_TIME 54 10063
TEST_TIME 55 9959
TEST_TIME 56 9999
TEST_TIME 57 9935
TEST_TIME 58 9896
TEST_TIME 59 10065
TEST_TIME 60 10078
TEST_TIME 61 10118
TEST_TIME 62 10185
TEST_TIME 63 10173
TEST_TIME 64 10031
TEST_TIME 65 10078
TEST_TIME 66 10002
TEST_TIME 67 9978
TEST_TIME 68 9991
TEST_TIME 69 9921
TEST_TIME 70 9898
TEST_TIME 71 9877
TEST_TIME 72 9780
TEST_TIME 73 9695
TEST_TIME 74 9695
TEST_TIME 75 9691
TEST_TIME 76 9670
TEST_TIME 77 9699
TEST_TIME 78 9675
TEST_TIME 79 9669
TEST_TIME 80 9713
TEST_TIME 81 9679
TEST_TIME 82 9673
TEST_TIME 83 9725
TEST_TIME 84 9763
TEST_TIME 85 9776
TEST_TIME 86 9818
TEST_TIME 87 9789
TEST_TIME 88 9867
TEST_TIME 89 9792
TEST_TIME 90 9738
TEST_TIME 91 9662
TEST_TIME 92 9693
TEST_TIME 93 9626
TEST_TIME 94 9593
TEST_TIME 95 9543
TEST_TIME 96 9612
TEST_TIME 97 9611
TEST_TIME 98 9612
TEST_TIME 99 9629
TEST_TIME 100 9599
TEST_TIME 101 9644
TEST_TIME 102 9660
TEST_TIME 103 9650
TEST_TIME 104 9658
TEST_TIME 105 9684
TEST_TIME 106 9677
TEST_TIME 107 9738
TEST_TIME 108 9753
TEST_TIME 109 9753
TEST_TIME 110 9680
TEST_TIME 111 9644
TEST_TIME 112 9646
TEST_TIME 113 9612
TEST_TIME 114 9569
TEST_TIME 115 9544
TEST_TIME 116 9512
TEST_TIME 117 9507
TEST_TIME 118 9466
TEST_TIME 119 9454
TEST_TIME 120 9429

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 02-04-2022 01:40:15 to 02-04-2022 01:40:18.

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 1555991 1555991 0 0.00 0 20102 6 0 0
doc_list_biud 1555989 1555989 0 0.00 0 66111 27 0 0
sp_add_invoice_to_stock 4999 4867 132 2.64 32 151736 18045 0 132
sp_cancel_adding_invoice 5954 5954 0 0.00 5 55349 764 0 0
sp_cancel_client_order 1725 1725 0 0.00 4 9247 332 0 0
sp_cancel_customer_reserve 586212 586212 0 0.00 1 66112 57 0 0
sp_cancel_pay_from_customer 639 639 0 0.00 2 4509 96 0 0
sp_cancel_pay_to_supplier 392 392 0 0.00 3 10360 137 0 0
sp_cancel_supplier_invoice 6563 6552 11 0.16 2 23062 741 0 11
sp_cancel_supplier_order 395 395 0 0.00 12 1003858 107048 0 0
sp_cancel_write_off 9995 9995 0 0.00 1 7170 115 0 0
sp_client_order 20022 20022 0 0.00 3 37907 397 0 0
sp_customer_reserve 459521 459521 0 0.00 0 75745 175 0 0
sp_fill_shopping_cart 30784 30784 0 0.00 1 40193 38 0 0
sp_fill_shopping_cart_clo_res 459516 459516 0 0.00 0 23394 14 0 0
sp_get_clo_for_invoice 4860 4860 0 0.00 0 30151 309 0 0
sp_kill_cost_storno 16980 16980 0 0.00 0 20090 63 0 0
sp_kill_qstorno_ret_qs2qd 1189366 1189366 0 0.00 0 66108 31 0 0
sp_kill_qty_storno 625534 625534 0 0.00 0 66111 66 0 0
sp_lock_selected_doc 20305 20305 0 0.00 0 7099 6 0 0
sp_make_cost_storno 27426 27426 0 0.00 0 15249 52 0 0
sp_multiply_rows_for_pdistr 27426 27426 0 0.00 0 6015 29 0 0
sp_multiply_rows_for_qdistr 20022 20022 0 0.00 0 25884 161 0 0
sp_pay_from_customer 6988 6988 0 0.00 4 15818 109 0 0
sp_pay_to_supplier 5737 5737 0 0.00 5 16858 125 0 0
sp_payment_common 12725 12725 0 0.00 2 9078 22 0 0
sp_qd_handle_on_cancel_clo 1725 1725 0 0.00 0 9230 306 0 0
sp_qd_handle_on_invoice_upd_sts 10814 10814 0 0.00 0 19393 327 0 0
sp_qd_handle_on_reserve_upd_sts 9841 9841 0 0.00 0 10575 62 0 0
sp_reserve_write_off 9841 9841 0 0.00 5 13700 185 0 0
sp_supplier_invoice 5422 5422 0 0.00 7 95560 3481 0 0
sp_supplier_order 5344 5344 0 0.00 5 39611 1321 0 0
srv_aggregate_perf_data 117 117 0 0.00 2492 86514 15941 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1772 129155 44250 0 0
srv_find_qd_qs_mism 1083888 1083888 0 0.00 0 18185 5 0 0
srv_make_invnt_saldo 596 596 0 0.00 34 39578 1016 0 0
srv_make_money_saldo 792 792 0 0.00 0 6612 33 0 0
srv_recalc_idx_stat 4 4 0 0.00 167983 480337 311285 0 0
t$perf-abend:sp_add_invoice_to_stock 132 132 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 4867 4867 0 0.00 0 12930 173 0 0
t$perf-norm:sp_cancel_adding_invoice 432 432 0 0.00 0 3243 76 0 0
t$perf-norm:sp_cancel_client_order 1725 1725 0 0.00 0 5543 17 0 0
t$perf-norm:sp_cancel_customer_reserve 637 637 0 0.00 0 2181 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 639 639 0 0.00 0 1226 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 392 392 0 0.00 0 2897 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 387 387 0 0.00 0 2377 28 0 0
t$perf-norm:sp_cancel_supplier_order 395 395 0 0.00 0 20276 1995 0 0
t$perf-norm:sp_cancel_write_off 997 997 0 0.00 0 5519 20 0 0
t$perf-norm:sp_client_order 20022 20022 0 0.00 0 8853 19 0 0
t$perf-norm:sp_customer_reserve 985 985 0 0.00 0 3782 13 0 0
t$perf-norm:sp_pay_from_customer 6988 6988 0 0.00 0 6927 10 0 0
t$perf-norm:sp_pay_to_supplier 5737 5737 0 0.00 0 5144 11 0 0
t$perf-norm:sp_reserve_write_off 9841 9841 0 0.00 0 18033 14 0 0
t$perf-norm:sp_supplier_invoice 5422 5422 0 0.00 0 5895 19 0 0
t$perf-norm:sp_supplier_order 5344 5344 0 0.00 0 6232 17 0 0
t$perf-norm:srv_aggregate_perf_data 117 117 0 0.00 0 436 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 2616 48 0 0
t$perf-norm:srv_make_invnt_saldo 596 596 0 0.00 0 910 16 0 0
t$perf-norm:srv_make_money_saldo 792 792 0 0.00 0 1635 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 9982 9718 264 2.64 0 8311 19 0 264
v_all_customers 37180 37180 0 0.00 0 11 1 0 0
v_all_suppliers 30104 30104 0 0.00 0 18 1 0 0
v_all_wares 40040 40040 0 0.00 0 30 1 0 0
v_cancel_adding_invoice 864 864 0 0.00 0 4452 12 0 0
v_cancel_client_order 3450 3450 0 0.00 0 7767 6 0 0
v_cancel_customer_prepayment 1278 1278 0 0.00 0 268 3 0 0
v_cancel_customer_reserve 1274 1274 0 0.00 0 1733 7 0 0
v_cancel_payment_to_supplier 784 784 0 0.00 0 7606 20 0 0
v_cancel_supplier_invoice 796 774 22 2.76 0 6052 26 0 22
v_cancel_supplier_order 790 790 0 0.00 0 852 5 0 0
v_cancel_write_off 1994 1994 0 0.00 0 4893 17 0 0
v_max_id_clo_ord 5344 5344 0 0.00 0 263 1 0 0
v_max_id_clo_res 985 985 0 0.00 0 1487 14 0 0
v_max_id_ord_sup 5420 5420 0 0.00 0 4435 4 0 0
v_max_non_paid_invoice 1449 1449 0 0.00 0 200 2 0 0
v_max_non_paid_realizn 3385 3385 0 0.00 0 2253 4 0 0
v_min_id_clo_ord 5344 5344 0 0.00 0 3018 6 0 0
v_min_id_clo_res 985 985 0 0.00 0 7172 12 0 0
v_min_id_ord_sup 5420 5420 0 0.00 0 5145 5 0 0
v_min_non_paid_invoice 5737 5737 0 0.00 0 5900 16 0 0
v_min_non_paid_realizn 6988 6988 0 0.00 0 4958 14 0 0
v_our_firm 10066 10066 0 0.00 0 29 1 0 0
v_reserve_write_off 19682 19682 0 0.00 0 13081 7 0 0
x_lock_depdocs_on_canc_invoice 12119 12119 0 0.00 0 22691 102 0 0
x_lock_depdocs_on_canc_sup_ord 395 395 0 0.00 0 25461 392 0 0
x_make_qstorno_1000_1200 5344 5344 0 0.00 2 39605 1244 0 0
x_make_qstorno_1200_2000 5422 5422 0 0.00 3 95539 3344 0 0
x_make_qstorno_2100_3300 459521 459521 0 0.00 0 75744 159 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-04-2022 01:40:19 to 02-04-2022 01:40:23.

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:08:24 13 8192000000 8807168800 8821252096 12292752 0.149 3 3 5 0 12487232 77360 117120
23:09:25 17981 8192000000 9892383040 10200883200 971772800 10.604 100 84 168 0 1000350416 5701328 22876288
23:10:48 30312 8192000000 9947255536 10266320896 1024917136 11.119 100 89 178 0 1055734512 8248800 22568576
23:12:23 16261 8192000000 9983297824 10312736768 1064899264 11.503 100 88 176 2 1096855744 10113600 21842880
23:13:41 17620 8192000000 10023472608 10370461696 1108263104 11.916 100 80 158 1 1138981984 11543344 19175536
23:15:02 7135 8192000000 10073115776 10430418944 1155765664 12.364 100 78 156 0 1186137184 12144640 18226880
23:16:12 4686 8192000000 10116947008 10482073600 1199673728 12.773 100 77 154 0 1229701728 12834960 17193040
23:17:19 9716 8192000000 10145428448 10519048192 1233090352 13.083 100 85 170 3 1266098240 14737632 18270256
23:18:33 1862 8192000000 10190144832 10568798208 1271692736 13.437 100 91 180 0 1306446208 14391776 20361696
23:19:37 1731 8192000000 10215290544 10597527552 1297195296 13.670 100 91 184 1 1330817776 13307664 20314816
23:20:41 11423 8192000000 10210125392 10601832448 1303846000 13.730 100 61 124 0 1327063888 9434576 13783312
23:21:54 3116 8192000000 10203650496 10612473856 1312203136 13.806 100 41 82 2 1329832576 7832368 9797072
23:22:59 8642 8192000000 10203652976 10617303040 1317884368 13.858 100 29 59 0 1331228640 6828416 6515856
23:24:11 15109 8192000000 10206121664 10628734976 1328419232 13.953 100 19 38 0 1338357440 6264560 3673648
23:25:30 31081 8192000000 10246061504 10651660288 1335907904 14.020 100 55 111 5 1354643968 5086432 13649632
23:27:01 3016 8192000000 10243642528 10656161792 1340452864 14.061 100 60 120 3 1361026560 6833568 13740128
23:28:08 12280 8192000000 10219098640 10638643200 1311219504 13.797 98 13 26 0 1319591696 5431632 2940560
23:29:22 31175 8192000000 10204011456 10624389120 1291104384 13.614 97 24 47 1 1302530496 5453968 5972144
23:30:54 5128 8192000000 10181387200 10578620416 1269514496 13.417 99 29 58 1 1281925472 5811664 6599312
23:32:00 6210 8192000000 10121138320 10524246016 1200969296 12.785 96 13 26 0 1208243904 4459168 2815440
23:33:09 18537 8192000000 10158944240 10562908160 1228343616 13.039 96 26 52 0 1239692784 5172000 6177168
23:35:18 6705 8192000000 10172559504 10584133632 1283755216 13.547 100 30 59 2 1296159232 5358048 7045968
23:36:25 6257 8192000000 10184743856 10598961152 1296261792 13.661 100 22 44 1 1307736320 6350144 5124384
23:37:32 9747 8192000000 10200553840 10612609024 1302891968 13.722 100 25 50 0 1314715248 5745792 6077488
23:38:53 85107 8192000000 10242172112 10655657984 1336190416 14.023 100 54 107 1 1355141168 7429264 11521488
23:41:24 12376 8192000000 10253148928 10669916160 1348359632 14.133 100 48 96 2 1369082464 9808096 10914736
23:42:58 13437 8192000000 10280877216 10697519104 1370000384 14.327 100 52 104 2 1392619280 10570256 12048640
23:44:14 12688 8192000000 10302516800 10715541504 1383666304 14.449 100 60 118 1 1415095008 17337120 14091584
23:45:31 8248 8192000000 10307627424 10729816064 1392294608 14.526 100 45 89 1 1420114512 16882864 10937040
23:46:45 6857 8192000000 10326502368 10748940288 1404850640 14.638 100 43 86 1 1435860512 20619184 10390688
23:48:04 1975 8192000000 10353288320 10769551360 1417873120 14.754 100 79 157 4 1460093072 22796912 19423040
23:49:10 5625 8192000000 10351538048 10775207936 1423254608 14.802 100 65 126 2 1464973040 26421344 15297088
23:50:22 7776 8192000000 10349814304 10769932288 1398089536 14.578 98 69 137 0 1445591792 28974576 18527680
23:51:37 14928 8192000000 10356393248 10774335488 1397548192 14.573 98 58 116 0 1449388048 36970016 14869840
23:52:58 1768 8192000000 10382703552 10795384832 1423478176 14.804 100 86 172 0 1486464832 40347728 22638928
23:54:03 7256 8192000000 10378707088 10809065472 1435985664 14.914 100 53 106 0 1490113184 40579248 13548272
23:55:26 5397 8192000000 10407605872 10822987776 1434486112 14.901 100 85 172 1 1503768352 47564800 21717440
23:56:36 3099 8192000000 10374845616 10782752768 1405550064 14.644 99 84 168 2 1475397056 48176000 21670992
23:57:47 2704 8192000000 10390222032 10806853632 1420446000 14.777 100 80 160 1 1489848592 47467648 21934944
23:58:52 8355 8192000000 10354817552 10788188160 1394357968 14.545 99 38 76 2 1454291296 49613088 10320240
00:00:04 10922 8192000000 10347921824 10776580096 1374591520 14.368 99 37 74 2 1439425920 54075888 10758512
00:01:19 15577 8192000000 10305565456 10739613696 1370030800 14.327 100 41 82 4 1423767904 43103328 10633776
00:02:38 16621 8192000000 10320947312 10757550080 1372679520 14.351 99 40 80 1 1422705904 39599008 10427376
00:03:59 19639 8192000000 10317252992 10752897024 1363747008 14.271 100 21 42 1 1418420800 48349376 6324416
00:06:16 35213 8192000000 10285052816 10724405248 1341566016 14.072 99 17 34 2 1392078976 45726432 4786528
00:08:06 58731 8192000000 10266756112 10721439744 1346007088 14.112 99 11 22 0 1366224576 17719536 2497952
00:10:20 115492 8192000000 10294163952 10732249088 1328272816 13.952 97 12 24 0 1348714272 17792576 2648880
00:13:20 121570 8192000000 10279195680 10718310400 1327292736 13.943 97 19 38 0 1344038272 11359760 5385776
00:16:23 56298 8192000000 10274158800 10721923072 1334998672 14.012 98 20 41 1 1358669952 19005424 4665856
00:18:22 49719 8192000000 10285944032 10733826048 1366696288 14.297 99 29 58 0 1388478784 15146880 6635616
00:20:12 53139 8192000000 10309535040 10751746048 1383635280 14.449 100 56 112 0 1418117168 19905360 14576528
00:22:15 87008 8192000000 10278303440 10731483136 1340574064 14.063 97 13 26 0 1356728912 13476736 2678112
00:24:51 19762 8192000000 10283244176 10739806208 1376399616 14.384 100 15 30 0 1394973520 15077280 3496624
00:26:11 80003 8192000000 10284262896 10753077248 1370179840 14.329 99 20 40 1 1388711792 13404576 5127376
00:28:32 30743 8192000000 10280150848 10742571008 1385995072 14.470 100 13 26 0 1395077632 6622512 2460048
00:30:18 19289 8192000000 10308062464 10757586944 1387589888 14.484 100 43 86 2 1410478864 12413456 10475520
00:31:44 122621 8192000000 10308270000 10773483520 1345831248 14.110 96 10 20 2 1365831536 17322208 2678080
00:34:48 28115 8192000000 10313035728 10765225984 1392314336 14.527 99 28 56 0 1409511376 10620848 6576192
00:36:23 83220 8192000000 10327859792 10796056576 1396142880 14.561 99 18 36 0 1422848928 22387072 4318976
00:39:01 52352 8192000000 10317430528 10782543872 1391637824 14.520 99 13 26 0 1406724496 12105536 2981136
00:40:56 58868 8192000000 10308412224 10770296832 1367718928 14.307 97 23 46 0 1390229520 17840752 4669840
00:42:55 69029 8192000000 10299565824 10760261632 1345934240 14.111 96 23 46 0 1362950448 12295568 4720640
00:45:15 7984 8192000000 10291416464 10758537216 1389072336 14.498 100 9 18 0 1405429072 14538608 1818128
00:46:29 72965 8192000000 10271524800 10731601920 1346032000 14.112 98 9 18 1 1363579968 15422048 2125920
00:48:49 35419 8192000000 10261303248 10732843008 1355551664 14.197 99 6 12 0 1372090672 15359104 1179904
00:50:29 65610 8192000000 10278295312 10730778624 1366482224 14.296 99 15 28 0 1377732848 8185344 3065280
00:52:37 65703 8192000000 10243317952 10701459456 1286166032 13.569 94 6 12 1 1303131216 15630512 1334672
00:54:43 48532 8192000000 10246252960 10696192000 1295734464 13.656 96 11 22 0 1308378464 10337968 2306032
00:56:35 40620 8192000000 10271076128 10711216128 1343918192 14.093 99 26 52 0 1359833552 10150544 5764816
00:58:18 41541 8192000000 10268200944 10720620544 1358281232 14.222 100 24 48 0 1373691568 9850784 5559552
01:00:02 44433 8192000000 10255911216 10694225920 1295295424 13.652 95 29 58 1 1313117264 11435840 6386000
01:01:48 48717 8192000000 10262377184 10725474304 1336828976 14.029 98 22 44 0 1360687440 18525984 5332480
01:03:50 69371 8192000000 10263347488 10717155328 1319164192 13.869 97 10 20 0 1340355168 18754624 2436352
01:06:22 52340 8192000000 10271354208 10711654400 1317445344 13.854 97 15 30 0 1334285696 13762288 3078064
01:08:23 68715 8192000000 10281927232 10718814208 1351586368 14.162 99 28 54 1 1364420736 7044384 5789984
01:10:33 40369 8192000000 10246065616 10707496960 1341675888 14.073 98 10 18 1 1350938944 7646368 1616688
01:12:15 64246 8192000000 10279088112 10738413568 1377804240 14.397 100 11 22 0 1391251840 10737328 2710272
01:14:21 56417 8192000000 10279628848 10740981760 1375450016 14.376 100 16 32 1 1390528256 11411216 3667024
01:16:17 48361 8192000000 10292717392 10752540672 1367132016 14.301 98 25 50 1 1384529328 11566848 5830464
01:18:49 19050 8192000000 10293400448 10736304128 1367944192 14.309 99 40 80 0 1391863568 14558688 9360688
01:20:11 49367 8192000000 10279148576 10725707776 1342025424 14.076 97 11 22 0 1353176032 8912384 2238224
01:22:01 24817 8192000000 10303816640 10744578048 1359999072 14.237 98 40 81 0 1379701536 10806704 8895760
01:23:30 60219 8192000000 10301156304 10747961344 1349501280 14.143 97 23 47 3 1363856528 9446720 4908528
01:25:36 129155 8192000000 10288475216 10748780544 1338861632 14.047 96 8 16 0 1360583616 19859040 1862944
01:28:45 84269 8192000000 10291658144 10742755328 1353825504 14.182 97 26 50 1 1367440960 8372880 5242576
01:32:02 66482 8192000000 10270763120 10732900352 1356608704 14.207 98 12 25 1 1367720752 8586352 2525696
01:34:16 42757 8192000000 10298673664 10753875968 1389839728 14.504 100 40 81 1 1411396528 11825152 9731648
01:36:04 82949 8192000000 10279589488 10740056064 1334785216 14.010 97 7 14 1 1353061568 16587504 1688848

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 02-04-2022 01:40:25 to 02-04-2022 01:40:40.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 132 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 02-04-2022 01:40:41 to 02-04-2022 01:40:43.

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          231512
MON$OLDEST_ACTIVE               234660
MON$OLDEST_SNAPSHOT             234660
MON$NEXT_TRANSACTION            234660
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                       2030688
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33574 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33574 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33574 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33574 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 ---
--- end of diff output ---

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

02.04.22 01:40:44. 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-04-02 01:40:47 No severe PSQL-related problems occured
02.04.22 01:40:48. End of report.