Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.01.2023 19: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 15232924 kB
MemAvailable 30421796 kB
Buffers 0 kB
Cached 16506308 kB
SwapCached 2596 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-18 19:38:37
DTS_END                         2023-01-18 19:38:37.2820
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/50954
TRN_ID                          245179
ATT_ID                          669
EXC_UNIT                        2


Test workload details

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

Indexes for heavy-loaded tables

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

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 9467.92 17199 1136150
customer order: creation 179.21 350 21505
customer order: refuse 15.01 318 1801
order to supplier: creation 47.25 1194 5670
order to supplier: removal 3.63 95724 435
invoice (draft): creation 46.63 3795 5596
invoice (draft): removal 53.38 728 6405
invoice accept: apply 42.88 17489 5146
invoice accept: cancel 48.48 740 5817
customer reserve: creation 3962.30 174 475476
customer reserve: removal 4768.99 57 572279
realization accept: apply 85.18 179 10221
realization accept: cancel 83.29 110 9995
payment from customer: creation 59.96 126 7195
payment from customer: removal 5.76 83 691
payment to supplier: creation 49.88 129 5985
payment to supplier: removal 3.69 114 443
service: total inventory turnovers 4.62 1023 554
service: total monetary turnovers 6.77 32 812
service: aggregate perf. data 1.00 17261 120
service: refresh index statistics 0.03 204344 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-01-2023 19:41:09 to 18-01-2023 19:41:11.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 972
TEST_TIME 2 2019
TEST_TIME 3 4050
TEST_TIME 4 4414
TEST_TIME 5 4331
TEST_TIME 6 4354
TEST_TIME 7 4659
TEST_TIME 8 4884
TEST_TIME 9 4892
TEST_TIME 10 5577
TEST_TIME 11 6222
TEST_TIME 12 6008
TEST_TIME 13 6139
TEST_TIME 14 6358
TEST_TIME 15 6581
TEST_TIME 16 6531
TEST_TIME 17 6928
TEST_TIME 18 6975
TEST_TIME 19 7248
TEST_TIME 20 7162
TEST_TIME 21 7567
TEST_TIME 22 8570
TEST_TIME 23 8812
TEST_TIME 24 9212
TEST_TIME 25 9267
TEST_TIME 26 9403
TEST_TIME 27 9600
TEST_TIME 28 10231
TEST_TIME 29 10380
TEST_TIME 30 10278
TEST_TIME 31 10348
TEST_TIME 32 10280
TEST_TIME 33 10150
TEST_TIME 34 10068
TEST_TIME 35 10004
TEST_TIME 36 10137
TEST_TIME 37 10095
TEST_TIME 38 10189
TEST_TIME 39 10234
TEST_TIME 40 10093
TEST_TIME 41 9900
TEST_TIME 42 9912
TEST_TIME 43 9785
TEST_TIME 44 9778
TEST_TIME 45 9727
TEST_TIME 46 9613
TEST_TIME 47 9603
TEST_TIME 48 9693
TEST_TIME 49 9782
TEST_TIME 50 9761
TEST_TIME 51 9682
TEST_TIME 52 9571
TEST_TIME 53 9405
TEST_TIME 54 9591
TEST_TIME 55 9472
TEST_TIME 56 9703
TEST_TIME 57 9680
TEST_TIME 58 9724
TEST_TIME 59 9708
TEST_TIME 60 9823
TEST_TIME 61 9797
TEST_TIME 62 9887
TEST_TIME 63 9828
TEST_TIME 64 9866
TEST_TIME 65 9910
TEST_TIME 66 9948
TEST_TIME 67 9967
TEST_TIME 68 10025
TEST_TIME 69 10059
TEST_TIME 70 10009
TEST_TIME 71 9878
TEST_TIME 72 9885
TEST_TIME 73 9817
TEST_TIME 74 9850
TEST_TIME 75 9859
TEST_TIME 76 9792
TEST_TIME 77 9714
TEST_TIME 78 9637
TEST_TIME 79 9555
TEST_TIME 80 9562
TEST_TIME 81 9630
TEST_TIME 82 9753
TEST_TIME 83 9754
TEST_TIME 84 9748
TEST_TIME 85 9793
TEST_TIME 86 9781
TEST_TIME 87 9768
TEST_TIME 88 9754
TEST_TIME 89 9798
TEST_TIME 90 9821
TEST_TIME 91 9906
TEST_TIME 92 9917
TEST_TIME 93 9942
TEST_TIME 94 9856
TEST_TIME 95 9843
TEST_TIME 96 9775
TEST_TIME 97 9839
TEST_TIME 98 9776
TEST_TIME 99 9758
TEST_TIME 100 9713
TEST_TIME 101 9668
TEST_TIME 102 9633
TEST_TIME 103 9612
TEST_TIME 104 9577
TEST_TIME 105 9546
TEST_TIME 106 9547
TEST_TIME 107 9554
TEST_TIME 108 9652
TEST_TIME 109 9673
TEST_TIME 110 9655
TEST_TIME 111 9649
TEST_TIME 112 9702
TEST_TIME 113 9707
TEST_TIME 114 9697
TEST_TIME 115 9691
TEST_TIME 116 9655
TEST_TIME 117 9594
TEST_TIME 118 9564
TEST_TIME 119 9542
TEST_TIME 120 9470

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 18-01-2023 19:41:13 to 18-01-2023 19:41:16.

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 1578187 1578187 0 0.00 0 17897 6 0 0
doc_list_biud 1578187 1578187 0 0.00 0 50177 26 0 0
sp_add_invoice_to_stock 5278 5146 132 2.50 41 218155 17489 0 132
sp_cancel_adding_invoice 5817 5817 0 0.00 4 66471 740 0 0
sp_cancel_client_order 1801 1801 0 0.00 4 10779 319 0 0
sp_cancel_customer_reserve 572279 572279 0 0.00 0 50178 58 0 0
sp_cancel_pay_from_customer 691 691 0 0.00 3 8232 84 0 0
sp_cancel_pay_to_supplier 443 443 0 0.00 2 7774 115 0 0
sp_cancel_supplier_invoice 6414 6405 9 0.14 2 28279 728 0 9
sp_cancel_supplier_order 435 435 0 0.00 6 1046578 95725 0 0
sp_cancel_write_off 9995 9995 0 0.00 1 19892 110 0 0
sp_client_order 21505 21505 0 0.00 3 21323 351 0 0
sp_customer_reserve 475476 475476 0 0.00 0 41105 174 0 0
sp_fill_shopping_cart 32748 32748 0 0.00 1 17228 35 0 0
sp_fill_shopping_cart_clo_res 475467 475467 0 0.00 0 17569 12 0 0
sp_get_clo_for_invoice 5137 5137 0 0.00 0 29084 248 0 0
sp_kill_cost_storno 16946 16946 0 0.00 0 6841 53 0 0
sp_kill_qstorno_ret_qs2qd 1161393 1161393 0 0.00 0 50175 31 0 0
sp_kill_qty_storno 612090 612090 0 0.00 0 50177 67 0 0
sp_lock_selected_doc 21284 21284 0 0.00 0 8460 7 0 0
sp_make_cost_storno 28538 28538 0 0.00 0 9386 46 0 0
sp_multiply_rows_for_pdistr 28538 28538 0 0.00 0 17887 28 0 0
sp_multiply_rows_for_qdistr 21505 21505 0 0.00 0 11968 157 0 0
sp_pay_from_customer 7195 7195 0 0.00 3 18284 126 0 0
sp_pay_to_supplier 5985 5985 0 0.00 4 16816 129 0 0
sp_payment_common 13180 13180 0 0.00 1 7139 27 0 0
sp_qd_handle_on_cancel_clo 1801 1801 0 0.00 0 10773 278 0 0
sp_qd_handle_on_invoice_upd_sts 10954 10954 0 0.00 0 23477 322 0 0
sp_qd_handle_on_reserve_upd_sts 10221 10221 0 0.00 0 21044 55 0 0
sp_reserve_write_off 10221 10221 0 0.00 6 21064 180 0 0
sp_supplier_invoice 5596 5596 0 0.00 6 115567 3796 0 0
sp_supplier_order 5670 5670 0 0.00 4 40790 1194 0 0
srv_aggregate_perf_data 120 120 0 0.00 2191 124363 17262 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1970 100766 44975 0 0
srv_find_qd_qs_mism 1087990 1087990 0 0.00 0 17641 6 0 0
srv_make_invnt_saldo 554 554 0 0.00 42 40023 1023 0 0
srv_make_money_saldo 812 812 0 0.00 1 3429 32 0 0
srv_recalc_idx_stat 4 4 0 0.00 137706 249655 204344 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 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5146 5146 0 0.00 0 13806 164 0 0
t$perf-norm:sp_cancel_adding_invoice 415 415 0 0.00 0 5725 107 0 0
t$perf-norm:sp_cancel_client_order 1801 1801 0 0.00 0 5788 20 0 0
t$perf-norm:sp_cancel_customer_reserve 724 724 0 0.00 0 6763 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 691 691 0 0.00 0 9064 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 443 443 0 0.00 0 17560 57 0 0
t$perf-norm:sp_cancel_supplier_invoice 419 419 0 0.00 0 2212 13 0 0
t$perf-norm:sp_cancel_supplier_order 435 435 0 0.00 0 27019 1627 0 0
t$perf-norm:sp_cancel_write_off 998 998 0 0.00 0 10064 18 0 0
t$perf-norm:sp_client_order 21505 21505 0 0.00 0 15479 18 0 0
t$perf-norm:sp_customer_reserve 984 984 0 0.00 0 9059 18 0 0
t$perf-norm:sp_pay_from_customer 7195 7195 0 0.00 0 6546 10 0 0
t$perf-norm:sp_pay_to_supplier 5985 5985 0 0.00 0 19144 13 0 0
t$perf-norm:sp_reserve_write_off 10221 10221 0 0.00 0 7846 14 0 0
t$perf-norm:sp_supplier_invoice 5596 5596 0 0.00 0 6781 21 0 0
t$perf-norm:sp_supplier_order 5670 5670 0 0.00 0 8571 16 0 0
t$perf-norm:srv_aggregate_perf_data 120 120 0 0.00 0 80 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 45 1 0 0
t$perf-norm:srv_make_invnt_saldo 554 554 0 0.00 0 1223 20 0 0
t$perf-norm:srv_make_money_saldo 812 812 0 0.00 0 681 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10538 10274 264 2.50 0 20643 26 0 264
v_all_customers 39010 39010 0 0.00 0 20 0 0 0
v_all_suppliers 31134 31134 0 0.00 0 24 1 0 0
v_all_wares 43010 43010 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 830 830 0 0.00 0 2078 10 0 0
v_cancel_client_order 3602 3602 0 0.00 0 9175 13 0 0
v_cancel_customer_prepayment 1382 1382 0 0.00 0 8220 12 0 0
v_cancel_customer_reserve 1448 1448 0 0.00 0 15917 17 0 0
v_cancel_payment_to_supplier 886 886 0 0.00 0 7641 22 0 0
v_cancel_supplier_invoice 856 838 18 2.10 0 9165 37 0 18
v_cancel_supplier_order 870 870 0 0.00 0 983 5 0 0
v_cancel_write_off 1996 1996 0 0.00 0 19745 34 0 0
v_max_id_clo_ord 5670 5670 0 0.00 0 739 1 0 0
v_max_id_clo_res 984 984 0 0.00 0 11014 28 0 0
v_max_id_ord_sup 5573 5573 0 0.00 0 17216 6 0 0
v_max_non_paid_invoice 1661 1661 0 0.00 0 1689 4 0 0
v_max_non_paid_realizn 3885 3885 0 0.00 0 3526 4 0 0
v_min_id_clo_ord 5670 5670 0 0.00 0 8390 11 0 0
v_min_id_clo_res 984 984 0 0.00 0 2485 9 0 0
v_min_id_ord_sup 5573 5573 0 0.00 0 8746 13 0 0
v_min_non_paid_invoice 5985 5985 0 0.00 0 4039 17 0 0
v_min_non_paid_realizn 7195 7195 0 0.00 0 4930 22 0 0
v_our_firm 10620 10620 0 0.00 0 27 1 0 0
v_reserve_write_off 20442 20442 0 0.00 0 19812 11 0 0
x_lock_depdocs_on_canc_invoice 11803 11803 0 0.00 0 16975 107 0 0
x_lock_depdocs_on_canc_sup_ord 435 435 0 0.00 0 13566 345 0 0
x_make_qstorno_1000_1200 5670 5670 0 0.00 1 40769 1123 0 0
x_make_qstorno_1200_2000 5596 5596 0 0.00 3 110458 3651 0 0
x_make_qstorno_2100_3300 475476 475476 0 0.00 0 36100 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-01-2023 19:41:17 to 18-01-2023 19:41:21.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
17:08:41 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:42 29271 8192000000 9902767168 10210869248 984803312 10.731 100 86 172 0 1013461600 6219792 22438496
17:11:15 17643 8192000000 9940463904 10260443136 1022362464 11.095 100 85 170 0 1052121824 7875264 21884096
17:12:36 15621 8192000000 9975623616 10309058560 1061334112 11.469 100 79 158 0 1090576512 9508512 19733888
17:13:57 10735 8192000000 10026869312 10373402624 1115476592 11.984 100 83 167 0 1146823856 11322112 20025152
17:15:23 6493 8192000000 10082186800 10436694016 1161298432 12.415 100 82 162 0 1193141664 13398416 18444816
17:16:34 2843 8192000000 10129113040 10494873600 1218986912 12.952 100 76 150 0 1248690640 12889104 16814624
17:17:39 1841 8192000000 10155755792 10529996800 1244988128 13.192 100 76 149 0 1273903120 12033808 16881184
17:18:42 1535 8192000000 10181254752 10569117696 1278810784 13.502 100 83 164 0 1308081008 11047728 18222496
17:19:45 16881 8192000000 10211036240 10598862848 1304060656 13.732 100 76 151 2 1331544032 10466960 17016416
17:21:03 10078 8192000000 10206708352 10611449856 1309597040 13.782 100 27 52 0 1321093232 5868800 5627392
17:22:14 5893 8192000000 10196184304 10611253248 1316192704 13.842 100 23 46 0 1327602544 6697536 4712304
17:23:22 9348 8192000000 10207581728 10623365120 1318480304 13.863 100 14 29 0 1332069712 10653472 2935936
17:24:32 60692 8192000000 10233068144 10642128896 1327877072 13.948 100 34 66 2 1340289920 4957040 7455808
17:26:34 6700 8192000000 10216159840 10634366976 1315689488 13.838 99 24 48 0 1327991056 7265952 5035616
17:27:42 9265 8192000000 10230319568 10649214976 1319351376 13.871 99 20 40 0 1337751232 14433024 3966832
17:28:52 42268 8192000000 10179128896 10590576640 1210351552 12.872 92 27 54 0 1221990240 4842112 6796576
17:30:35 7190 8192000000 10159440560 10561896448 1229970224 13.054 97 30 58 1 1242342016 5867600 6504192
17:31:44 4344 8192000000 10146560976 10546982912 1243400112 13.178 99 27 53 0 1255305376 5348208 6557056
17:32:50 15410 8192000000 10158115360 10559418368 1266433360 13.389 100 33 66 2 1278847680 5401328 7012992
17:34:06 18984 8192000000 10171712096 10580516864 1279609520 13.509 100 19 38 0 1289856304 5730784 4516000
17:35:26 5733 8192000000 10187221760 10600026112 1297235360 13.670 100 21 42 0 1307312304 5371312 4705632
17:36:36 7826 8192000000 10207477200 10624716800 1318128864 13.860 100 15 29 0 1326242560 5293136 2820560
17:37:44 34816 8192000000 10228424144 10644631552 1326103152 13.932 100 19 37 0 1335614304 5200448 4310704
17:39:22 73580 8192000000 10252160960 10669031424 1348543296 14.134 100 38 76 0 1364770112 7265696 8961120
17:41:49 23228 8192000000 10278143856 10701594624 1374936160 14.371 100 35 68 0 1392422480 9656160 7830160
17:43:15 26380 8192000000 10286694336 10711318528 1384176320 14.454 100 41 82 0 1404686624 11218800 9291504
17:44:49 28193 8192000000 10305874912 10735419392 1396762912 14.566 100 41 82 0 1422339968 15776656 9800400
17:46:21 8515 8192000000 10311349232 10733060096 1392361024 14.527 99 50 100 1 1422199744 17686960 12151760
17:47:34 15959 8192000000 10337552896 10763239424 1410650000 14.690 100 48 95 0 1446616384 24520496 11445888
17:48:56 19058 8192000000 10357516128 10784227328 1425956192 14.825 100 49 99 0 1465244400 26214880 13073328
17:50:23 4753 8192000000 10341981024 10765578240 1415736672 14.735 100 54 108 0 1450921952 21726064 13459216
17:51:43 7670 8192000000 10361276016 10793189376 1428718672 14.850 100 42 83 0 1469179024 29988832 10471520
17:52:55 2816 8192000000 10365058256 10787368960 1425469056 14.821 100 70 140 0 1470311072 27253024 17588992
17:54:06 10720 8192000000 10348601968 10774142976 1414830672 14.727 100 54 108 0 1457289616 27998384 14460560
17:55:22 2561 8192000000 10341356016 10753581056 1379544448 14.412 99 72 135 0 1435773024 39251600 16976976
17:56:30 5933 8192000000 10335149568 10749263872 1379696656 14.414 99 56 112 0 1430307184 35921424 14689104
17:57:44 3331 8192000000 10337352112 10747514880 1377420608 14.393 99 68 133 0 1436562384 42741776 16400000
17:58:54 1963 8192000000 10354343664 10760011776 1376192352 14.382 100 86 171 1 1452341392 52599840 23549200
18:00:02 3390 8192000000 10336519776 10753597440 1373161696 14.355 100 67 130 2 1444131296 53275184 17694416
18:01:07 7445 8192000000 10313379088 10744872960 1370075216 14.328 100 33 66 0 1421267968 42103232 9089520
18:02:31 21983 8192000000 10294931760 10730663936 1363670960 14.270 100 25 51 0 1404530176 34106144 6753072
18:03:56 36732 8192000000 10279695456 10720227328 1344124720 14.095 99 33 64 0 1384222672 31981024 8116928
18:05:56 27279 8192000000 10268244512 10722291712 1347814928 14.128 99 14 28 0 1379889280 27988576 4085776
18:07:26 80629 8192000000 10267227456 10692960256 1296354624 13.662 96 15 31 0 1309717888 9563136 3800128
18:09:47 82295 8192000000 10281501728 10731196416 1341197184 14.068 98 14 28 0 1360256096 16874464 2184448
18:12:12 78389 8192000000 10288659536 10726313984 1349608752 14.144 99 21 43 1 1363189120 8889216 4691152
18:14:37 49499 8192000000 10287656448 10728308736 1367081200 14.301 99 9 16 0 1378363488 9586128 1696160
18:16:35 75562 8192000000 10294563472 10738499584 1360041280 14.238 98 30 60 0 1376016656 8583712 7391664
18:18:53 73568 8192000000 10311267648 10746908672 1385896480 14.469 100 39 78 1 1406156032 10354976 9904576
18:21:07 75116 8192000000 10267121312 10709647360 1354367264 14.187 98 17 34 0 1364682480 6584768 3730448
18:23:24 17977 8192000000 10313929776 10751287296 1383480080 14.448 99 47 91 0 1404721328 9699456 11541792
18:24:48 28502 8192000000 10329025040 10769997824 1406176624 14.650 100 41 82 0 1432542816 17358768 9007424
18:26:24 42777 8192000000 10310309072 10777935872 1393024192 14.533 99 15 30 0 1415294544 18353856 3916496
18:28:07 49946 8192000000 10323106464 10788802560 1415331280 14.731 100 18 36 1 1434857456 15021360 4504816
18:29:58 49922 8192000000 10313531088 10780741632 1410352272 14.687 100 12 24 0 1429729648 16507232 2870144
18:32:01 41951 8192000000 10314704096 10791538688 1395681312 14.557 99 18 36 0 1422093520 21953008 4459200
18:34:00 61879 8192000000 10313558560 10789662720 1397110384 14.569 99 29 58 1 1417664560 13920880 6633296
18:36:02 20403 8192000000 10315319056 10772131840 1396437136 14.563 99 21 43 1 1414304160 12886768 4980256
18:37:33 99509 8192000000 10313474992 10775453696 1359950352 14.237 96 12 24 0 1375242912 12783040 2509520
18:40:13 73389 8192000000 10286013536 10736656384 1291483600 13.618 92 18 37 0 1303528368 7885312 4159456
18:42:27 25645 8192000000 10290467968 10731397120 1341187584 14.068 97 29 56 1 1356029792 7718592 7123616
18:43:54 89195 8192000000 10288396080 10751668224 1321555808 13.891 96 10 20 1 1339123344 15418704 2148832
18:46:24 64632 8192000000 10273834768 10720788480 1302348320 13.717 95 14 28 0 1317638464 12430880 2859264
18:48:55 98260 8192000000 10237970144 10684514304 1309324512 13.780 97 26 50 2 1321233136 6099184 5809440
18:51:35 52532 8192000000 10266970768 10709471232 1352139728 14.167 100 38 75 0 1375655168 15369760 8145680
18:53:31 76567 8192000000 10250663776 10704314368 1293601904 13.637 95 7 14 0 1303848176 8734912 1511360
18:55:48 45263 8192000000 10280496608 10728087552 1360351584 14.241 99 18 36 1 1380534096 15476768 4705744
18:57:38 75861 8192000000 10288190672 10747351040 1375638928 14.378 100 18 36 0 1398195744 17954944 4601872
18:59:57 37814 8192000000 10270586208 10734309376 1357817872 14.218 99 13 26 1 1374769136 13729520 3221744
19:01:38 37923 8192000000 10279928400 10727034880 1347788928 14.128 98 27 54 0 1361689440 7917120 5983392
19:03:16 51028 8192000000 10278676944 10738192384 1372515760 14.350 100 11 22 0 1384851664 9778032 2557872
19:05:16 34176 8192000000 10279060592 10727931904 1340306080 14.060 97 29 58 0 1356125520 9627920 6191520
19:07:53 29875 8192000000 10293540016 10742583296 1367165552 14.302 99 37 74 1 1386200432 11024784 8010096
19:09:34 69702 8192000000 10302204768 10749788160 1379440496 14.412 99 14 29 0 1391601296 9497504 2663296
19:11:44 100765 8192000000 10315791696 10769367040 1400762544 14.602 100 18 36 0 1415707536 10841376 4103616
19:14:47 88225 8192000000 10279648896 10759688192 1359308416 14.231 97 12 24 1 1373703856 12006400 2389040
19:17:38 46224 8192000000 10304190016 10774495232 1399280528 14.589 100 8 16 1 1413704992 12773120 1651344
19:19:42 78558 8192000000 10305892768 10773745664 1392540416 14.529 99 9 18 0 1409692576 14855168 2296992
19:22:23 55800 8192000000 10311650544 10771537920 1390280720 14.508 99 17 35 0 1412134032 17571088 4282224
19:24:42 84823 8192000000 10315385104 10783334400 1365099072 14.283 97 22 44 0 1392677728 22202464 5376192
19:27:17 44905 8192000000 10283854400 10741575680 1326907328 13.939 95 14 28 1 1339969520 10429056 2633136
19:29:11 13420 8192000000 10308469184 10752004096 1377375600 14.393 99 43 86 0 1396565344 9795696 9394048
19:30:27 60471 8192000000 10285542688 10741428224 1353008640 14.175 98 16 32 0 1371667088 15235440 3423008
19:32:29 20419 8192000000 10274351280 10711199744 1330125584 13.968 98 49 99 1 1351568560 10999392 10443584
19:33:50 14414 8192000000 10284205536 10726903808 1361960432 14.255 100 40 80 0 1384059232 13235680 8863120
19:35:06 89538 8192000000 10247648464 10690936832 1316424240 13.844 97 29 58 0 1334590672 11518048 6648384
19:37:36 59488 8192000000 10257903072 10717360128 1337731488 14.037 98 11 22 0 1354806992 14848752 2226752

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 18-01-2023 19:41:23 to 18-01-2023 19:41:38.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 132 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:02, from 18-01-2023 19:41:39 to 18-01-2023 19:41:41.

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          245483
MON$OLDEST_ACTIVE               245484
MON$OLDEST_SNAPSHOT             245484
MON$NEXT_TRANSACTION            245484
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                       2042336
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'

18.01.23 19:41:42. 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-18 19:41:45 No severe PSQL-related problems occured
18.01.23 19:41:46. End of report.