Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.01.2023 16:43
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 15165248 kB
MemAvailable 30427136 kB
Buffers 0 kB
Cached 16567784 kB
SwapCached 2452 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 2023-01-17 16:42:30
DTS_END                         2023-01-17 16:42:30.4960
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_TO_SUPPLIER(26:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/33680
TRN_ID                          243877
ATT_ID                          683
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: 9394.80 24960 1127376
customer order: creation 170.81 365 20497
customer order: refuse 15.07 375 1808
order to supplier: creation 46.08 1157 5530
order to supplier: removal 3.40 106754 408
invoice (draft): creation 46.63 3382 5595
invoice (draft): removal 53.53 852 6423
invoice accept: apply 42.63 18483 5115
invoice accept: cancel 48.14 791 5777
customer reserve: creation 3938.24 183 472589
customer reserve: removal 4727.17 58 567260
realization accept: apply 84.47 192 10136
realization accept: cancel 87.58 108 10510
payment from customer: creation 60.13 114 7215
payment from customer: removal 5.50 106 660
payment to supplier: creation 49.69 142 5963
payment to supplier: removal 3.63 87 435
service: total inventory turnovers 4.71 1378 565
service: total monetary turnovers 6.56 95 787
service: aggregate perf. data 0.83 22229 99
service: refresh index statistics 0.03 342341 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-01-2023 16:44:11 to 17-01-2023 16:44:13.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4626
TEST_TIME 2 3825
TEST_TIME 3 3527
TEST_TIME 4 3347
TEST_TIME 5 3418
TEST_TIME 6 3894
TEST_TIME 7 4764
TEST_TIME 8 5459
TEST_TIME 9 6111
TEST_TIME 10 6558
TEST_TIME 11 6915
TEST_TIME 12 6938
TEST_TIME 13 7101
TEST_TIME 14 7239
TEST_TIME 15 7121
TEST_TIME 16 7473
TEST_TIME 17 7450
TEST_TIME 18 7484
TEST_TIME 19 7665
TEST_TIME 20 7770
TEST_TIME 21 7799
TEST_TIME 22 8258
TEST_TIME 23 8246
TEST_TIME 24 8668
TEST_TIME 25 8818
TEST_TIME 26 9011
TEST_TIME 27 9432
TEST_TIME 28 10021
TEST_TIME 29 10848
TEST_TIME 30 10756
TEST_TIME 31 10619
TEST_TIME 32 10877
TEST_TIME 33 10854
TEST_TIME 34 10754
TEST_TIME 35 10949
TEST_TIME 36 10758
TEST_TIME 37 10632
TEST_TIME 38 10429
TEST_TIME 39 10296
TEST_TIME 40 10240
TEST_TIME 41 10160
TEST_TIME 42 10096
TEST_TIME 43 10129
TEST_TIME 44 10198
TEST_TIME 45 10113
TEST_TIME 46 10051
TEST_TIME 47 9935
TEST_TIME 48 9910
TEST_TIME 49 9861
TEST_TIME 50 9848
TEST_TIME 51 9773
TEST_TIME 52 9797
TEST_TIME 53 9726
TEST_TIME 54 9732
TEST_TIME 55 9704
TEST_TIME 56 9659
TEST_TIME 57 9735
TEST_TIME 58 9684
TEST_TIME 59 9659
TEST_TIME 60 9704
TEST_TIME 61 9854
TEST_TIME 62 9813
TEST_TIME 63 9962
TEST_TIME 64 9940
TEST_TIME 65 9918
TEST_TIME 66 9976
TEST_TIME 67 9907
TEST_TIME 68 9814
TEST_TIME 69 9812
TEST_TIME 70 9831
TEST_TIME 71 9734
TEST_TIME 72 9787
TEST_TIME 73 9706
TEST_TIME 74 9647
TEST_TIME 75 9615
TEST_TIME 76 9538
TEST_TIME 77 9540
TEST_TIME 78 9498
TEST_TIME 79 9517
TEST_TIME 80 9527
TEST_TIME 81 9538
TEST_TIME 82 9545
TEST_TIME 83 9622
TEST_TIME 84 9672
TEST_TIME 85 9680
TEST_TIME 86 9625
TEST_TIME 87 9735
TEST_TIME 88 9708
TEST_TIME 89 9758
TEST_TIME 90 9744
TEST_TIME 91 9736
TEST_TIME 92 9672
TEST_TIME 93 9689
TEST_TIME 94 9642
TEST_TIME 95 9649
TEST_TIME 96 9583
TEST_TIME 97 9518
TEST_TIME 98 9501
TEST_TIME 99 9581
TEST_TIME 100 9516
TEST_TIME 101 9506
TEST_TIME 102 9460
TEST_TIME 103 9586
TEST_TIME 104 9564
TEST_TIME 105 9574
TEST_TIME 106 9649
TEST_TIME 107 9624
TEST_TIME 108 9646
TEST_TIME 109 9637
TEST_TIME 110 9651
TEST_TIME 111 9601
TEST_TIME 112 9553
TEST_TIME 113 9500
TEST_TIME 114 9525
TEST_TIME 115 9490
TEST_TIME 116 9471
TEST_TIME 117 9448
TEST_TIME 118 9401
TEST_TIME 119 9399
TEST_TIME 120 9398

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-01-2023 16:44:15 to 17-01-2023 16:44: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 1563502 1563502 0 0.00 0 28447 6 0 0
doc_list_biud 1563502 1563502 0 0.00 0 32702 27 0 0
sp_add_invoice_to_stock 5258 5115 143 2.71 22 190051 18484 0 143
sp_cancel_adding_invoice 5777 5777 0 0.00 4 75842 791 0 0
sp_cancel_client_order 1808 1808 0 0.00 4 13598 376 0 0
sp_cancel_customer_reserve 567260 567260 0 0.00 0 22494 59 0 0
sp_cancel_pay_from_customer 660 660 0 0.00 3 5458 106 0 0
sp_cancel_pay_to_supplier 435 435 0 0.00 2 4723 87 0 0
sp_cancel_supplier_invoice 6432 6423 9 0.13 2 25200 852 0 9
sp_cancel_supplier_order 408 408 0 0.00 9 1081239 106754 0 0
sp_cancel_write_off 10510 10510 0 0.00 1 10584 109 0 0
sp_client_order 20497 20497 0 0.00 3 26115 366 0 0
sp_customer_reserve 472589 472589 0 0.00 0 56778 183 0 0
sp_fill_shopping_cart 31621 31621 0 0.00 1 12079 40 0 0
sp_fill_shopping_cart_clo_res 472584 472584 0 0.00 0 21572 14 0 0
sp_get_clo_for_invoice 5110 5110 0 0.00 0 40562 307 0 0
sp_kill_cost_storno 17382 17382 0 0.00 0 19956 56 0 0
sp_kill_qstorno_ret_qs2qd 1151861 1151861 0 0.00 0 25183 32 0 0
sp_kill_qty_storno 607432 607432 0 0.00 0 32700 70 0 0
sp_lock_selected_doc 21092 21092 0 0.00 0 6586 5 0 0
sp_make_cost_storno 28424 28424 0 0.00 0 28445 59 0 0
sp_multiply_rows_for_pdistr 28424 28424 0 0.00 0 11153 28 0 0
sp_multiply_rows_for_qdistr 20497 20497 0 0.00 0 14666 141 0 0
sp_pay_from_customer 7215 7215 0 0.00 4 13372 114 0 0
sp_pay_to_supplier 5963 5963 0 0.00 4 12791 143 0 0
sp_payment_common 13178 13178 0 0.00 1 11086 32 0 0
sp_qd_handle_on_cancel_clo 1808 1808 0 0.00 0 13592 340 0 0
sp_qd_handle_on_invoice_upd_sts 10887 10887 0 0.00 0 32700 342 0 0
sp_qd_handle_on_reserve_upd_sts 10136 10136 0 0.00 0 21977 61 0 0
sp_reserve_write_off 10136 10136 0 0.00 6 28468 193 0 0
sp_supplier_invoice 5595 5595 0 0.00 6 89590 3383 0 0
sp_supplier_order 5530 5530 0 0.00 5 32986 1157 0 0
srv_aggregate_perf_data 99 99 0 0.00 1915 578451 22229 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 881 105769 43598 0 0
srv_find_qd_qs_mism 1078333 1078333 0 0.00 0 16329 6 0 0
srv_make_invnt_saldo 565 565 0 0.00 32 147429 1378 0 0
srv_make_money_saldo 787 787 0 0.00 1 40920 96 0 0
srv_recalc_idx_stat 4 4 0 0.00 177277 439038 342342 0 0
t$perf-abend:sp_add_invoice_to_stock 143 143 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5117 5117 0 0.00 0 14621 202 0 0
t$perf-norm:sp_cancel_adding_invoice 407 407 0 0.00 0 3469 122 0 0
t$perf-norm:sp_cancel_client_order 1808 1808 0 0.00 0 7582 17 0 0
t$perf-norm:sp_cancel_customer_reserve 696 696 0 0.00 0 1210 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 660 660 0 0.00 0 2451 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 435 435 0 0.00 0 1739 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 393 393 0 0.00 0 1673 21 0 0
t$perf-norm:sp_cancel_supplier_order 408 408 0 0.00 0 19739 1699 0 0
t$perf-norm:sp_cancel_write_off 1039 1039 0 0.00 0 10333 21 0 0
t$perf-norm:sp_client_order 20497 20497 0 0.00 0 9322 19 0 0
t$perf-norm:sp_customer_reserve 931 931 0 0.00 0 1645 8 0 0
t$perf-norm:sp_pay_from_customer 7215 7215 0 0.00 0 13129 11 0 0
t$perf-norm:sp_pay_to_supplier 5964 5964 0 0.00 0 9137 16 0 0
t$perf-norm:sp_reserve_write_off 10136 10136 0 0.00 0 7562 14 0 0
t$perf-norm:sp_supplier_invoice 5595 5595 0 0.00 0 8221 24 0 0
t$perf-norm:sp_supplier_order 5530 5530 0 0.00 0 4846 21 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 300 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 322 6 0 0
t$perf-norm:srv_make_invnt_saldo 565 565 0 0.00 0 6746 39 0 0
t$perf-norm:srv_make_money_saldo 787 787 0 0.00 0 1625 24 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 3 0 0
v_add_invoice_to_stock 10506 10220 286 2.72 0 9518 20 0 286
v_all_customers 37476 37476 0 0.00 0 26 1 0 0
v_all_suppliers 30998 30998 0 0.00 0 19 1 0 0
v_all_wares 40992 40992 0 0.00 0 26 1 0 0
v_cancel_adding_invoice 814 814 0 0.00 0 125 6 0 0
v_cancel_client_order 3616 3616 0 0.00 0 10368 9 0 0
v_cancel_customer_prepayment 1320 1320 0 0.00 0 2824 10 0 0
v_cancel_customer_reserve 1392 1392 0 0.00 0 5382 10 0 0
v_cancel_payment_to_supplier 870 870 0 0.00 0 2780 14 0 0
v_cancel_supplier_invoice 804 786 18 2.23 0 2652 17 0 18
v_cancel_supplier_order 816 816 0 0.00 0 5504 22 0 0
v_cancel_write_off 2078 2078 0 0.00 0 4753 16 0 0
v_max_id_clo_ord 5530 5530 0 0.00 0 350 1 0 0
v_max_id_clo_res 931 931 0 0.00 0 4329 16 0 0
v_max_id_ord_sup 5595 5595 0 0.00 0 4149 5 0 0
v_max_non_paid_invoice 1589 1589 0 0.00 0 2225 5 0 0
v_max_non_paid_realizn 3755 3755 0 0.00 0 2044 4 0 0
v_min_id_clo_ord 5530 5530 0 0.00 0 8559 9 0 0
v_min_id_clo_res 931 931 0 0.00 0 3895 7 0 0
v_min_id_ord_sup 5595 5595 0 0.00 0 4167 7 0 0
v_min_non_paid_invoice 5963 5963 0 0.00 0 7161 22 0 0
v_min_non_paid_realizn 7215 7215 0 0.00 0 10986 26 0 0
v_our_firm 10436 10436 0 0.00 0 26 1 0 0
v_reserve_write_off 20272 20272 0 0.00 0 4918 8 0 0
x_lock_depdocs_on_canc_invoice 11807 11807 0 0.00 0 15261 126 0 0
x_lock_depdocs_on_canc_sup_ord 408 408 0 0.00 0 5599 386 0 0
x_make_qstorno_1000_1200 5530 5530 0 0.00 1 32861 1076 0 0
x_make_qstorno_1200_2000 5595 5595 0 0.00 3 78987 3241 0 0
x_make_qstorno_2100_3300 472589 472589 0 0.00 0 56777 167 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-01-2023 16:44:19 to 17-01-2023 16:44: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
14:12:34 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:13:35 30839 8192000000 9899953760 10209583104 978100688 10.666 100 87 174 0 1006327104 6055872 22170544
14:15:13 22787 8192000000 9944721952 10266034176 1025140784 11.122 100 89 178 0 1055928896 8112992 22675120
14:16:37 17107 8192000000 9978726736 10306834432 1056945936 11.427 100 87 174 0 1088260272 9252752 22061584
14:18:02 9173 8192000000 10035205264 10382016512 1125159104 12.076 100 76 151 0 1153147488 10540240 17448144
14:19:15 8527 8192000000 10084924800 10443722752 1173103728 12.526 100 82 164 0 1204946336 13007456 18835152
14:20:24 4199 8192000000 10114290992 10482880512 1209257008 12.862 100 70 140 0 1237568576 12831600 15479968
14:21:31 1500 8192000000 10162809488 10535174144 1251349088 13.251 100 87 173 2 1282744976 13203632 18192256
14:22:34 1755 8192000000 10193297328 10575253504 1275616240 13.473 100 82 164 2 1307466944 13766640 18084064
14:23:37 10771 8192000000 10194903088 10585731072 1291193952 13.615 100 63 124 2 1314516432 8987584 14334896
14:24:49 15308 8192000000 10182658288 10591170560 1296862288 13.667 100 30 60 0 1310111216 6237808 7011120
14:26:05 3980 8192000000 10198052272 10606489600 1306289136 13.752 100 23 44 0 1317638992 6816704 4533152
14:27:11 18596 8192000000 10202558416 10618957824 1318070624 13.859 100 25 49 0 1329329376 6233264 5025488
14:28:33 82640 8192000000 10226387232 10643468288 1330821296 13.975 100 21 40 0 1340637216 5264400 4551520
14:30:58 9324 8192000000 10229963616 10655723520 1341038432 14.067 100 20 40 1 1355726976 10170272 4518272
14:32:08 4503 8192000000 10212676240 10631475200 1316242320 13.843 99 19 38 0 1326175568 5837968 4095280
14:33:13 7921 8192000000 10187953904 10602610688 1284329088 13.553 98 17 35 0 1293851264 5485984 4036192
14:34:29 13993 8192000000 10137559872 10541924352 1164914288 12.449 90 20 40 0 1174542224 5082784 4545152
14:35:43 7290 8192000000 10140402800 10539708416 1234411184 13.095 98 28 56 0 1246440592 5650640 6378768
14:36:51 7376 8192000000 10136669136 10539565056 1225283584 13.011 98 17 34 2 1234262656 5226800 3752272
14:38:03 12299 8192000000 10172401824 10576482304 1274013088 13.458 100 28 56 0 1290572128 10140704 6418336
14:39:44 8288 8192000000 10173776528 10582818816 1280324016 13.516 100 29 54 0 1292465984 5701792 6440176
14:40:54 9205 8192000000 10184722624 10594230272 1293210960 13.633 100 13 26 1 1300770144 5025840 2533344
14:42:05 7303 8192000000 10201867344 10611806208 1307563008 13.764 100 24 46 0 1318477104 5446176 5467920
14:43:17 4318 8192000000 10233390848 10641690624 1330031680 13.967 100 51 102 0 1348899536 7217056 11650800
14:44:27 55308 8192000000 10252119312 10667892736 1348952384 14.138 100 41 80 1 1368019168 9891200 9175584
14:46:25 54521 8192000000 10262844464 10686414848 1357974272 14.219 100 26 52 0 1378658128 14592320 6091536
14:48:29 1308 8192000000 10292850928 10703765504 1376203152 14.383 100 57 115 0 1403966288 14524880 13238256
14:49:34 14541 8192000000 10301400496 10713587712 1372709872 14.351 100 55 105 0 1404687696 19720000 12257824
14:50:53 3379 8192000000 10306200336 10725265408 1375809760 14.379 99 54 106 1 1406941808 19071904 12060144
14:52:03 3143 8192000000 10290206608 10708647936 1373103168 14.355 100 53 106 1 1405706432 20316832 12286432
14:53:12 4201 8192000000 10314784528 10741669888 1391887104 14.523 100 51 98 1 1427643152 24079744 11676304
14:54:18 11419 8192000000 10303048880 10725244928 1380176240 14.418 100 55 108 0 1412024112 18928976 12918896
14:55:36 3852 8192000000 10323771120 10743812096 1394394576 14.545 100 57 113 1 1429667088 21863168 13409344
14:56:43 2354 8192000000 10339284064 10755690496 1402074944 14.613 100 72 138 2 1445728096 26347280 17305872
14:57:49 2068 8192000000 10344104272 10757709824 1388296832 14.491 99 68 137 0 1445833568 40994400 16542336
14:58:58 1040 8192000000 10336603664 10747109376 1392701088 14.530 100 64 121 2 1440985888 33004928 15279872
15:00:04 2798 8192000000 10365779456 10773008384 1406499264 14.653 100 76 149 0 1465248912 40705920 18043728
15:01:12 2288 8192000000 10346417376 10753331200 1377210848 14.392 98 65 130 2 1437242176 43330304 16701024
15:02:21 4963 8192000000 10357220000 10763460608 1391922208 14.523 99 74 148 1 1459681008 48716816 19041984
15:03:38 880 8192000000 10375751552 10779181056 1403419856 14.625 100 79 157 0 1478808240 55231744 20156640
15:04:43 16102 8192000000 10350207136 10770640896 1381596464 14.431 99 45 90 1 1447506384 53383760 12526160
15:06:07 10313 8192000000 10364178080 10786377728 1401531968 14.609 100 52 103 0 1465814048 50249904 14032176
15:07:23 27132 8192000000 10341193664 10773049344 1382992304 14.443 100 41 81 0 1453263088 58580576 11690208
15:09:11 24636 8192000000 10323838672 10764570624 1359574624 14.234 99 29 56 0 1420850624 53198448 8077552
15:10:44 21477 8192000000 10288470912 10744848384 1339518704 14.053 98 12 24 0 1386330256 43354656 3456896
15:12:11 39892 8192000000 10275558944 10714554368 1332915008 13.993 97 18 37 2 1347121584 10288432 3918144
15:13:52 73098 8192000000 10278177728 10733228032 1355553568 14.197 99 22 40 1 1376301840 15631680 5116592
15:16:05 93883 8192000000 10290002928 10745421824 1338336320 14.042 97 11 22 0 1357797360 16984784 2476256
15:18:39 35112 8192000000 10276454304 10712629248 1353265200 14.177 98 46 90 0 1373756048 9716560 10774288
15:20:15 101042 8192000000 10300353088 10740879360 1379713136 14.414 100 33 66 0 1399422640 11365808 8343696
15:23:26 34365 8192000000 10304935904 10759888896 1394738704 14.548 100 25 50 1 1415714976 15653056 5323216
15:25:03 50085 8192000000 10310109936 10762100736 1382102592 14.435 99 29 59 0 1402614944 13845296 6667056
15:27:03 48395 8192000000 10303124816 10758856704 1382591312 14.440 99 16 33 0 1397739712 11278656 3869744
15:28:52 74394 8192000000 10297472288 10755407872 1387920400 14.487 99 20 40 0 1403336544 10706608 4709536
15:32:05 60473 8192000000 10291849408 10762432512 1389299360 14.500 99 14 28 1 1405080736 12683760 3097616
15:34:06 70897 8192000000 10280176384 10740568064 1328914080 13.957 95 18 36 0 1348173312 15313600 3945632
15:36:25 62063 8192000000 10291783136 10766082048 1374254048 14.365 99 8 16 0 1386313248 9745712 2313488
15:38:39 61013 8192000000 10293903168 10761322496 1380284368 14.419 99 25 50 0 1404301712 18285040 5732304
15:40:44 53921 8192000000 10311974608 10764173312 1370627760 14.333 98 24 48 1 1390107360 13676128 5803472
15:42:38 76657 8192000000 10297171904 10763702272 1364777472 14.280 98 10 20 0 1384502928 17323008 2402448
15:44:56 83969 8192000000 10283436992 10740695040 1348933664 14.138 97 6 12 0 1363228368 13495616 799088
15:47:27 44394 8192000000 10279310832 10722615296 1315108832 13.832 96 34 68 0 1332414400 10181712 7123856
15:49:12 33613 8192000000 10290553712 10727989248 1337687776 14.037 97 42 86 0 1358071520 11610592 8773152
15:50:47 62408 8192000000 10263093680 10715561984 1326972032 13.940 97 34 68 0 1344284112 10223408 7088672
15:53:31 25712 8192000000 10250011168 10698231808 1312194432 13.806 96 19 39 0 1327346064 10526640 4624992
15:55:08 63790 8192000000 10265142528 10716274688 1350732848 14.154 99 9 18 0 1364054192 11357984 1963360
15:57:13 67227 8192000000 10266305024 10727370752 1366527360 14.296 100 20 40 1 1384069984 12470960 5071664
15:59:41 66248 8192000000 10258252544 10716499968 1333375568 13.998 98 22 42 0 1349487072 11354848 4756656
16:01:47 56483 8192000000 10273021040 10730569728 1337036464 14.031 98 13 26 0 1361247120 20653232 3557424
16:03:57 70614 8192000000 10290487520 10743365632 1367435392 14.304 99 21 42 0 1388732640 15910144 5387104
16:06:08 78608 8192000000 10292302544 10751946752 1360061248 14.238 98 21 42 0 1378353856 13693552 4599056
16:08:27 41414 8192000000 10308639776 10758074368 1391137696 14.516 100 36 72 0 1411623488 12748528 7737264
16:10:17 47461 8192000000 10286419280 10748764160 1359081920 14.229 98 21 42 0 1373199728 9781952 4335856
16:12:33 39086 8192000000 10323319456 10765000704 1394526544 14.546 100 33 65 0 1410562064 9637888 6397632
16:14:17 76160 8192000000 10298550160 10760765440 1400661360 14.601 100 25 50 0 1419261376 13038384 5561632
16:16:34 33977 8192000000 10288102016 10745466880 1381917040 14.434 99 24 48 0 1396794112 9632560 5244512
16:18:14 60891 8192000000 10300563184 10766237696 1403238144 14.624 100 11 22 0 1417375536 11018448 3118944
16:20:15 66011 8192000000 10281307264 10757619712 1356405440 14.205 97 9 18 0 1374756832 16249440 2101952
16:22:21 37123 8192000000 10316788640 10762129408 1386045072 14.471 99 35 68 0 1408318320 14071168 8202080
16:24:02 61116 8192000000 10309560640 10769678336 1386001456 14.470 99 12 24 0 1406276656 17037840 3237360
16:26:17 50804 8192000000 10293344944 10741211136 1334062848 14.004 96 28 56 0 1350708512 10574608 6071056
16:28:19 96474 8192000000 10286112800 10742788096 1332129776 13.986 96 18 36 0 1346285712 10346336 3809600
16:30:56 69901 8192000000 10282780800 10727768064 1281741520 13.529 92 24 48 0 1297375040 10514048 5119472
16:33:09 105769 8192000000 10256345584 10703458304 1326390624 13.935 97 24 48 0 1339526352 7436528 5699200
16:36:02 21841 8192000000 10246964432 10698571776 1345960064 14.111 99 29 58 0 1361530352 9189200 6381088
16:37:26 48603 8192000000 10251189424 10719428608 1351224192 14.158 99 4 8 0 1362156208 10208688 723328
16:39:15 80953 8192000000 10256224848 10716160000 1346077728 14.112 99 8 16 0 1358412304 10386016 1948560
16:42:11 35782 8192000000 9267907632 9563103232 418724944 4.862 32 4 8 0 429514752 10344560 445248

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 17-01-2023 16:44:25 to 17-01-2023 16:44:41.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 143 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 17-01-2023 16:44:42 to 17-01-2023 16:44: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          243876
MON$OLDEST_ACTIVE               244182
MON$OLDEST_SNAPSHOT             244182
MON$NEXT_TRANSACTION            244182
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                       2048480
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.33654 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33654 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33654 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33654 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'

17.01.23 16:44:45. 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
2023-01-17 16:44:47 No severe PSQL-related problems occured
17.01.23 16:44:48. End of report.