Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.07.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 15987240 kB
MemAvailable 31059036 kB
Buffers 0 kB
Cached 15758112 kB
SwapCached 2552 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.11
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-07-27 01:38:24
DTS_END                         2022-07-27 01:38:24.8030
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/49558
TRN_ID                          255399
ATT_ID                          696
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: 9335.73 18980 1120287
customer order: creation 189.31 333 22717
customer order: refuse 15.63 350 1876
order to supplier: creation 48.97 1241 5876
order to supplier: removal 3.55 79011 426
invoice (draft): creation 50.63 3199 6076
invoice (draft): removal 49.78 663 5974
invoice accept: apply 45.52 16423 5462
invoice accept: cancel 44.83 807 5379
customer reserve: creation 4145.08 165 497409
customer reserve: removal 4423.33 50 530800
realization accept: apply 92.95 168 11154
realization accept: cancel 84.40 114 10128
payment from customer: creation 65.11 119 7813
payment from customer: removal 5.93 106 712
payment to supplier: creation 53.79 119 6455
payment to supplier: removal 3.82 74 458
service: total inventory turnovers 5.26 919 631
service: total monetary turnovers 6.88 47 825
service: aggregate perf. data 0.93 14811 112
service: refresh index statistics 0.03 260880 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-07-2022 01:40:03 to 27-07-2022 01:40:05.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2436
TEST_TIME 2 3002
TEST_TIME 3 2995
TEST_TIME 4 4330
TEST_TIME 5 5092
TEST_TIME 6 5491
TEST_TIME 7 5483
TEST_TIME 8 5466
TEST_TIME 9 5805
TEST_TIME 10 5801
TEST_TIME 11 6229
TEST_TIME 12 6348
TEST_TIME 13 6470
TEST_TIME 14 6514
TEST_TIME 15 7429
TEST_TIME 16 7494
TEST_TIME 17 7831
TEST_TIME 18 8260
TEST_TIME 19 8352
TEST_TIME 20 8766
TEST_TIME 21 8842
TEST_TIME 22 8808
TEST_TIME 23 9302
TEST_TIME 24 9295
TEST_TIME 25 9549
TEST_TIME 26 9538
TEST_TIME 27 9865
TEST_TIME 28 10213
TEST_TIME 29 10156
TEST_TIME 30 10061
TEST_TIME 31 9985
TEST_TIME 32 10014
TEST_TIME 33 10129
TEST_TIME 34 10196
TEST_TIME 35 10037
TEST_TIME 36 10127
TEST_TIME 37 10112
TEST_TIME 38 9858
TEST_TIME 39 9924
TEST_TIME 40 9948
TEST_TIME 41 9791
TEST_TIME 42 9731
TEST_TIME 43 9641
TEST_TIME 44 9614
TEST_TIME 45 9784
TEST_TIME 46 9778
TEST_TIME 47 9704
TEST_TIME 48 9610
TEST_TIME 49 9642
TEST_TIME 50 9661
TEST_TIME 51 9649
TEST_TIME 52 9575
TEST_TIME 53 9724
TEST_TIME 54 9692
TEST_TIME 55 9778
TEST_TIME 56 9782
TEST_TIME 57 9764
TEST_TIME 58 9812
TEST_TIME 59 9848
TEST_TIME 60 9884
TEST_TIME 61 9922
TEST_TIME 62 9946
TEST_TIME 63 10028
TEST_TIME 64 9906
TEST_TIME 65 9824
TEST_TIME 66 9793
TEST_TIME 67 9720
TEST_TIME 68 9691
TEST_TIME 69 9687
TEST_TIME 70 9626
TEST_TIME 71 9644
TEST_TIME 72 9639
TEST_TIME 73 9547
TEST_TIME 74 9531
TEST_TIME 75 9599
TEST_TIME 76 9598
TEST_TIME 77 9570
TEST_TIME 78 9566
TEST_TIME 79 9588
TEST_TIME 80 9660
TEST_TIME 81 9683
TEST_TIME 82 9713
TEST_TIME 83 9702
TEST_TIME 84 9669
TEST_TIME 85 9670
TEST_TIME 86 9786
TEST_TIME 87 9755
TEST_TIME 88 9672
TEST_TIME 89 9618
TEST_TIME 90 9588
TEST_TIME 91 9567
TEST_TIME 92 9479
TEST_TIME 93 9531
TEST_TIME 94 9478
TEST_TIME 95 9504
TEST_TIME 96 9469
TEST_TIME 97 9542
TEST_TIME 98 9574
TEST_TIME 99 9536
TEST_TIME 100 9591
TEST_TIME 101 9559
TEST_TIME 102 9577
TEST_TIME 103 9545
TEST_TIME 104 9623
TEST_TIME 105 9607
TEST_TIME 106 9594
TEST_TIME 107 9644
TEST_TIME 108 9667
TEST_TIME 109 9634
TEST_TIME 110 9579
TEST_TIME 111 9584
TEST_TIME 112 9530
TEST_TIME 113 9532
TEST_TIME 114 9513
TEST_TIME 115 9485
TEST_TIME 116 9469
TEST_TIME 117 9413
TEST_TIME 118 9398
TEST_TIME 119 9398
TEST_TIME 120 9338

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 27-07-2022 01:40:06 to 27-07-2022 01:40:10.

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 1580163 1580163 0 0.00 0 23523 6 0 0
doc_list_biud 1580163 1580163 0 0.00 0 27965 22 0 0
sp_add_invoice_to_stock 5600 5462 138 2.46 40 216252 16423 0 138
sp_cancel_adding_invoice 5379 5379 0 0.00 4 70822 808 0 0
sp_cancel_client_order 1876 1876 0 0.00 4 14476 351 0 0
sp_cancel_customer_reserve 530800 530800 0 0.00 0 27968 50 0 0
sp_cancel_pay_from_customer 712 712 0 0.00 2 12222 107 0 0
sp_cancel_pay_to_supplier 458 458 0 0.00 2 3900 75 0 0
sp_cancel_supplier_invoice 5984 5974 10 0.16 1 20349 664 0 10
sp_cancel_supplier_order 426 426 0 0.00 12 794434 79012 0 0
sp_cancel_write_off 10128 10128 0 0.00 1 12734 114 0 0
sp_client_order 22717 22717 0 0.00 2 27543 333 0 0
sp_customer_reserve 497409 497409 0 0.00 0 59602 166 0 0
sp_fill_shopping_cart 34669 34669 0 0.00 1 12469 35 0 0
sp_fill_shopping_cart_clo_res 497404 497404 0 0.00 0 16173 12 0 0
sp_get_clo_for_invoice 5457 5457 0 0.00 0 23016 239 0 0
sp_kill_cost_storno 16677 16677 0 0.00 0 8941 51 0 0
sp_kill_qstorno_ret_qs2qd 1078128 1078128 0 0.00 0 27935 27 0 0
sp_kill_qty_storno 571194 571194 0 0.00 0 27964 61 0 0
sp_lock_selected_doc 22823 22823 0 0.00 0 8335 6 0 0
sp_make_cost_storno 30879 30879 0 0.00 0 19343 48 0 0
sp_multiply_rows_for_pdistr 30879 30879 0 0.00 0 10544 26 0 0
sp_multiply_rows_for_qdistr 22717 22717 0 0.00 0 11650 135 0 0
sp_pay_from_customer 7813 7813 0 0.00 4 28144 119 0 0
sp_pay_to_supplier 6455 6455 0 0.00 5 40460 120 0 0
sp_payment_common 14268 14268 0 0.00 1 40035 45 0 0
sp_qd_handle_on_cancel_clo 1876 1876 0 0.00 0 14468 320 0 0
sp_qd_handle_on_invoice_upd_sts 10836 10836 0 0.00 0 22126 328 0 0
sp_qd_handle_on_reserve_upd_sts 11154 11154 0 0.00 0 19672 64 0 0
sp_reserve_write_off 11154 11154 0 0.00 6 19700 168 0 0
sp_supplier_invoice 6076 6076 0 0.00 6 91447 3199 0 0
sp_supplier_order 5876 5876 0 0.00 5 42283 1242 0 0
srv_aggregate_perf_data 112 112 0 0.00 2872 114191 14811 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1947 115923 46098 0 0
srv_find_qd_qs_mism 1067960 1067960 0 0.00 0 23201 5 0 0
srv_make_invnt_saldo 631 631 0 0.00 31 25141 919 0 0
srv_make_money_saldo 825 825 0 0.00 1 18094 48 0 0
srv_recalc_idx_stat 4 4 0 0.00 151842 383312 260880 0 0
t$perf-abend:sp_add_invoice_to_stock 138 138 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5462 5462 0 0.00 0 11379 164 0 0
t$perf-norm:sp_cancel_adding_invoice 447 447 0 0.00 0 3123 118 0 0
t$perf-norm:sp_cancel_client_order 1876 1876 0 0.00 0 13451 23 0 0
t$perf-norm:sp_cancel_customer_reserve 761 761 0 0.00 0 8322 30 0 0
t$perf-norm:sp_cancel_pay_from_customer 712 712 0 0.00 0 1815 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 458 458 0 0.00 0 919 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 411 411 0 0.00 0 856 13 0 0
t$perf-norm:sp_cancel_supplier_order 426 426 0 0.00 0 17472 1417 0 0
t$perf-norm:sp_cancel_write_off 1121 1121 0 0.00 0 3885 7 0 0
t$perf-norm:sp_client_order 22717 22717 0 0.00 0 10375 16 0 0
t$perf-norm:sp_customer_reserve 1125 1125 0 0.00 0 4102 13 0 0
t$perf-norm:sp_pay_from_customer 7813 7813 0 0.00 0 15376 9 0 0
t$perf-norm:sp_pay_to_supplier 6455 6455 0 0.00 0 7781 11 0 0
t$perf-norm:sp_reserve_write_off 11154 11154 0 0.00 0 24868 14 0 0
t$perf-norm:sp_supplier_invoice 6076 6076 0 0.00 0 9441 16 0 0
t$perf-norm:sp_supplier_order 5876 5876 0 0.00 0 5543 17 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 834 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 399 9 0 0
t$perf-norm:srv_make_invnt_saldo 631 631 0 0.00 0 829 19 0 0
t$perf-norm:srv_make_money_saldo 825 825 0 0.00 0 1051 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 11190 10914 276 2.46 0 9980 18 0 276
v_all_customers 41306 41306 0 0.00 0 106 1 0 0
v_all_suppliers 32696 32696 0 0.00 0 175 1 0 0
v_all_wares 45434 45434 0 0.00 0 180 1 0 0
v_cancel_adding_invoice 894 894 0 0.00 0 864 8 0 0
v_cancel_client_order 3752 3752 0 0.00 0 4053 5 0 0
v_cancel_customer_prepayment 1424 1424 0 0.00 0 1749 7 0 0
v_cancel_customer_reserve 1522 1522 0 0.00 0 1258 6 0 0
v_cancel_payment_to_supplier 916 916 0 0.00 0 1357 6 0 0
v_cancel_supplier_invoice 842 822 20 2.37 0 461 12 0 20
v_cancel_supplier_order 852 852 0 0.00 0 4054 19 0 0
v_cancel_write_off 2242 2242 0 0.00 0 8510 16 0 0
v_max_id_clo_ord 5876 5876 0 0.00 0 577 1 0 0
v_max_id_clo_res 1125 1125 0 0.00 0 7108 16 0 0
v_max_id_ord_sup 6076 6076 0 0.00 0 7369 5 0 0
v_max_non_paid_invoice 2059 2059 0 0.00 0 1698 5 0 0
v_max_non_paid_realizn 4213 4213 0 0.00 0 3717 7 0 0
v_min_id_clo_ord 5876 5876 0 0.00 0 2965 8 0 0
v_min_id_clo_res 1125 1125 0 0.00 0 7372 19 0 0
v_min_id_ord_sup 6076 6076 0 0.00 0 5989 5 0 0
v_min_non_paid_invoice 6455 6455 0 0.00 0 40027 38 0 0
v_min_non_paid_realizn 7813 7813 0 0.00 0 17921 31 0 0
v_our_firm 11328 11328 0 0.00 0 16 1 0 0
v_reserve_write_off 22308 22308 0 0.00 0 8225 6 0 0
x_lock_depdocs_on_canc_invoice 10942 10942 0 0.00 0 16812 113 0 0
x_lock_depdocs_on_canc_sup_ord 426 426 0 0.00 0 7184 281 0 0
x_make_qstorno_1000_1200 5876 5876 0 0.00 1 42244 1169 0 0
x_make_qstorno_1200_2000 6076 6076 0 0.00 2 91374 3069 0 0
x_make_qstorno_2100_3300 497409 497409 0 0.00 0 59588 151 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-07-2022 01:40:11 to 27-07-2022 01:40:15.

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:28 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:29 26152 8192000000 9901199264 10213220352 978814032 10.673 100 77 154 0 1004722176 6003648 19904496
23:10:57 16621 8192000000 9944637488 10267029504 1026612880 11.136 100 89 178 0 1058047824 8638336 22796608
23:12:20 20749 8192000000 9977943296 10308968448 1058146624 11.439 100 88 175 0 1090216528 10472960 21596944
23:13:46 9939 8192000000 10026487312 10369859584 1107129584 11.905 100 92 184 0 1141373984 11898688 22345712
23:15:01 5484 8192000000 10067533536 10423074816 1153439360 12.342 100 76 150 0 1183302656 12114224 17749072
23:16:09 6236 8192000000 10113500912 10484805632 1204923728 12.822 100 76 152 2 1234803664 13032592 16847344
23:17:18 1982 8192000000 10164198640 10541367296 1250338112 13.241 100 81 162 1 1284465824 16871648 17256064
23:18:22 1474 8192000000 10183785984 10570231808 1276669824 13.483 100 77 151 0 1307160848 13483488 17007536
23:19:25 7777 8192000000 10210653840 10601893888 1302578032 13.719 100 73 147 1 1329886048 10604960 16703056
23:20:33 2321 8192000000 10208639488 10610290688 1312222704 13.806 100 57 109 0 1333544400 8185504 13136192
23:21:36 7795 8192000000 10221340144 10624536576 1325288576 13.925 100 41 80 1 1340669264 5615680 9765008
23:22:46 6777 8192000000 10235633632 10639085568 1326830848 13.939 100 57 111 1 1346255040 6732928 12691264
23:23:53 5648 8192000000 10216417056 10639290368 1331279984 13.979 100 17 35 2 1340582816 5243792 4059040
23:24:59 10821 8192000000 10221463968 10647662592 1336802720 14.029 100 21 41 0 1348122096 6219488 5099888
23:26:11 20266 8192000000 10227213952 10650648576 1337921088 14.039 100 51 102 0 1357605056 6347392 13336576
23:27:38 8500 8192000000 10233553680 10658414592 1345428928 14.106 100 19 40 0 1355681904 5730624 4522352
23:28:46 6897 8192000000 10208459664 10624499712 1318301184 13.861 100 13 26 0 1326386352 4982448 3102720
23:29:59 71653 8192000000 10148253488 10556223488 1153318352 12.341 89 27 54 1 1163907056 4739024 5849680
23:32:11 5045 8192000000 10139730448 10536353792 1214669376 12.912 97 24 48 0 1225894896 5701152 5524368
23:33:19 11169 8192000000 10150245136 10559770624 1254581328 13.280 99 22 44 1 1264823872 4925008 5317536
23:34:31 15202 8192000000 10183408624 10595221504 1294051600 13.641 100 20 40 0 1304114560 4842336 5220624
23:35:46 22824 8192000000 10184614784 10602008576 1297911552 13.676 100 19 36 0 1307077776 5400016 3766208
23:37:10 6614 8192000000 10198509776 10617344000 1308288912 13.771 100 27 54 0 1319941104 5340512 6311680
23:38:18 36780 8192000000 10239154848 10651025408 1333603664 14.000 100 19 38 0 1342193920 4434688 4155568
23:40:12 41333 8192000000 10262073440 10680664064 1360681360 14.243 100 43 86 1 1380038160 9544240 9812560
23:41:57 39675 8192000000 10262623696 10697846784 1367763024 14.307 100 26 52 1 1385243184 10922336 6557824
23:43:45 33387 8192000000 10287453920 10709360640 1363898640 14.272 99 41 82 0 1383176592 9705632 9572320
23:45:27 11866 8192000000 10309062816 10732449792 1394920064 14.550 100 54 106 2 1418733328 11302752 12510512
23:46:43 4340 8192000000 10330924416 10747551744 1406049280 14.649 100 62 124 0 1437943808 16762960 15131568
23:47:50 2882 8192000000 10330976944 10752057344 1408586448 14.671 100 69 137 0 1445083664 20606368 15890848
23:49:00 6459 8192000000 10344914736 10776010752 1420621440 14.778 100 61 119 2 1457956336 23274176 14060720
23:50:12 11499 8192000000 10327347696 10754052096 1397410320 14.572 99 47 94 1 1430666672 21574224 11682128
23:51:35 2830 8192000000 10315494480 10724675584 1370686464 14.333 98 72 137 0 1409358480 21644736 17027280
23:52:42 4533 8192000000 10336492576 10750939136 1385526272 14.466 99 68 134 2 1430124592 27675264 16923056
23:53:52 2980 8192000000 10300131872 10720509952 1377232192 14.392 100 53 106 1 1413807648 23289504 13285952
23:55:00 2197 8192000000 10311062400 10728296448 1375309584 14.375 100 66 131 2 1416531312 24721712 16500016
23:56:08 6171 8192000000 10274945472 10684145664 1323089504 13.905 98 51 101 3 1364383504 28039456 13254544
23:57:18 1947 8192000000 10318651712 10730524672 1377819504 14.397 100 78 153 0 1420163120 23957488 18386128
23:58:25 13935 8192000000 10276915936 10698567680 1346549024 14.116 99 37 73 1 1379583152 23258736 9775392
23:59:45 5608 8192000000 10283555536 10705481728 1357336960 14.213 100 47 92 0 1389312384 19830960 12144464
00:00:56 1965 8192000000 10304802720 10706620416 1357867680 14.218 100 75 145 1 1402188896 25293456 19027760
00:02:00 18241 8192000000 10300458672 10729791488 1370720496 14.334 100 33 63 1 1398308224 18826912 8760816
00:03:23 24949 8192000000 10289389696 10733547520 1373564384 14.359 100 34 68 0 1408051952 25478752 9008816
00:05:05 89551 8192000000 10252159840 10707939328 1309893376 13.785 97 15 30 0 1338309104 24495376 3920352
00:07:36 81145 8192000000 10247781760 10678910976 1290252368 13.607 95 15 33 0 1301392144 7659120 3480656
00:09:58 111656 8192000000 10287267632 10745110528 1361431840 14.250 99 7 13 0 1373402992 10985776 985376
00:12:51 42450 8192000000 10315106928 10742976512 1381501376 14.430 99 30 56 1 1395320432 7519696 6299360
00:14:35 83657 8192000000 10319043120 10780241920 1387012720 14.479 99 20 40 0 1416442496 24949216 4480560
00:17:04 99107 8192000000 10310599696 10776653824 1402578608 14.618 100 27 54 0 1422165056 13747040 5839408
00:20:07 37957 8192000000 10319976432 10779512832 1412612864 14.707 100 20 40 0 1429812032 11874224 5324944
00:21:49 63668 8192000000 10329455040 10793467904 1412439920 14.706 100 25 50 0 1442789312 24368848 5980544
00:24:21 27355 8192000000 10312728496 10777018368 1398295744 14.580 99 13 26 1 1419225040 17689600 3239696
00:25:49 37017 8192000000 10317777232 10773700608 1409540224 14.680 100 11 23 0 1422274544 10355920 2378400
00:27:27 35105 8192000000 10309638384 10779312128 1398713904 14.584 99 23 46 1 1412722608 9159104 4849600
00:29:03 36782 8192000000 10319000784 10780794880 1411696448 14.699 100 27 54 1 1426952016 8350416 6905152
00:30:43 47416 8192000000 10320311600 10783539200 1408173808 14.668 100 32 62 1 1428843856 13650144 7019904
00:32:30 66989 8192000000 10309580576 10782666752 1396366288 14.563 99 8 16 0 1407773056 9594672 1812096
00:34:38 51503 8192000000 10282873120 10744614912 1346416160 14.115 97 37 71 1 1366161712 11853920 7891632
00:36:38 51432 8192000000 10271038720 10716086272 1333902416 14.002 98 24 46 1 1353796192 14541984 5351792
00:38:31 70195 8192000000 10263514352 10712203264 1321337136 13.889 98 11 22 0 1340130688 16496352 2297200
00:40:42 60755 8192000000 10214870672 10655997952 1234067584 13.092 92 21 42 0 1249204368 10240992 4895792
00:42:43 93607 8192000000 10251659632 10689073152 1327250064 13.942 99 34 68 2 1344342208 8952816 8139328
00:45:19 28676 8192000000 10246846032 10670104576 1329207456 13.960 99 47 93 3 1347338336 7697808 10433072
00:46:49 46365 8192000000 10247741936 10703798272 1342096016 14.076 99 23 46 0 1358127472 10217472 5813984
00:48:56 59890 8192000000 10248912336 10702950400 1342784336 14.083 99 9 18 0 1354940736 9896240 2260160
00:51:06 74885 8192000000 10243862736 10692902912 1330893792 13.975 98 16 32 0 1344958592 9880016 4184784
00:54:01 51849 8192000000 10246215120 10694750208 1296480832 13.663 96 16 32 0 1308177888 7712672 3984384
00:55:58 78671 8192000000 10278880560 10730504192 1346229824 14.114 99 15 30 0 1367034816 17072336 3732656
00:58:25 44790 8192000000 10266143152 10720645120 1351370608 14.160 99 19 35 0 1365009568 9695808 3943152
01:00:10 79840 8192000000 10278893760 10739478528 1361910144 14.255 99 8 16 0 1375916400 12363328 1642928
01:02:41 28762 8192000000 10307925296 10750738432 1379238544 14.410 100 47 90 0 1403473776 14254832 9980400
01:04:12 115923 8192000000 10287126800 10742726656 1380437312 14.420 100 19 38 0 1400496656 15807408 4251936
01:07:30 47950 8192000000 10284655456 10746245120 1382941840 14.443 100 16 32 0 1397594016 10977712 3674464
01:10:01 53030 8192000000 10298304704 10766700544 1400638896 14.601 100 10 20 0 1412571552 9895104 2037552
01:12:10 21937 8192000000 10294235216 10755403776 1380313952 14.419 99 27 41 2 1390113168 6111328 3687888
01:13:32 73697 8192000000 10305007648 10758664192 1366995184 14.300 98 16 31 1 1386067824 16105072 2967568
01:15:46 91334 8192000000 10302118688 10744107008 1346316672 14.114 97 11 22 0 1358048944 9521312 2210960
01:18:19 77574 8192000000 10274764352 10729848832 1319607328 13.873 95 20 40 1 1336402272 11971728 4823216
01:20:37 69768 8192000000 10280070064 10726219776 1320841376 13.884 96 18 36 1 1337269632 12448816 3979440
01:22:48 49964 8192000000 10264703120 10706735104 1302316880 13.716 96 27 54 0 1320082640 11369664 6396096
01:24:44 28587 8192000000 10259783376 10699350016 1302032128 13.714 97 33 66 0 1320121696 10930448 7159120
01:26:25 58608 8192000000 10265002208 10703261696 1336940160 14.030 100 20 40 0 1354304176 13170304 4193712
01:28:30 87757 8192000000 10242824944 10687422464 1321494048 13.890 99 27 54 0 1343545040 16209712 5841280
01:32:24 49782 8192000000 10225518000 10687049728 1325928160 13.930 99 9 18 0 1334355120 6403360 2023600
01:35:04 65321 8192000000 10253602864 10708414464 1349267616 14.141 100 17 34 0 1361200448 8147456 3785376
01:37:20 58244 8192000000 10257219424 10714411008 1326804240 13.938 98 15 28 0 1342018448 11966544 3247664

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 27-07-2022 01:40:16 to 27-07-2022 01:40:31.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 138 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 27-07-2022 01:40:32 to 27-07-2022 01:40:34.

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          253817
MON$OLDEST_ACTIVE               255515
MON$OLDEST_SNAPSHOT             255515
MON$NEXT_TRANSACTION            255515
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                       2036256
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.33613 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33613 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33613 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33613 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 ---
:249: 
:250: mscs-test-161.budzdorov.ru    Wed Jul 27 01:38:25 2022
:251:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:252: 
:253: 
:254: mscs-test-161.budzdorov.ru    Wed Jul 27 01:38:26 2022
:255:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:256: 
:257: 
:258: mscs-test-161.budzdorov.ru    Wed Jul 27 01:38:28 2022
:259:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:260: 
:261: 
:262: mscs-test-161.budzdorov.ru    Wed Jul 27 01:38:31 2022
:263:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:264: 
--- end of diff output ---

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

27.07.22 01:40:35. 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-07-27 01:40:37 No severe PSQL-related problems occured
27.07.22 01:40:39. End of report.