Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.12.2021 19: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 16196576 kB
MemAvailable 31459272 kB
Buffers 0 kB
Cached 15487608 kB
SwapCached 0 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.9
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 2021-12-15 19:37:56
DTS_END                         2021-12-15 19:37:56.2920
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/38002
TRN_ID                          246006
ATT_ID                          731
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: 9665.11 24126 1159813
customer order: creation 177.09 328 21251
customer order: refuse 14.65 352 1758
order to supplier: creation 47.03 1296 5644
order to supplier: removal 3.57 115118 428
invoice (draft): creation 45.80 3292 5496
invoice (draft): removal 53.79 864 6455
invoice accept: apply 44.37 18261 5324
invoice accept: cancel 48.89 856 5867
customer reserve: creation 4066.18 181 487942
customer reserve: removal 4855.66 66 582679
realization accept: apply 85.75 224 10290
realization accept: cancel 88.79 125 10655
payment from customer: creation 61.35 158 7362
payment from customer: removal 5.86 108 703
payment to supplier: creation 50.44 183 6053
payment to supplier: removal 3.49 118 419
service: total inventory turnovers 4.53 1535 543
service: total monetary turnovers 6.96 42 835
service: aggregate perf. data 0.88 19738 105
service: refresh index statistics 0.03 319674 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-12-2021 19:39:39 to 15-12-2021 19:39:41.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3917
TEST_TIME 2 3672
TEST_TIME 3 3345
TEST_TIME 4 3281
TEST_TIME 5 3587
TEST_TIME 6 3733
TEST_TIME 7 4560
TEST_TIME 8 5028
TEST_TIME 9 5230
TEST_TIME 10 5835
TEST_TIME 11 6056
TEST_TIME 12 6475
TEST_TIME 13 6760
TEST_TIME 14 6900
TEST_TIME 15 6926
TEST_TIME 16 6724
TEST_TIME 17 6869
TEST_TIME 18 7329
TEST_TIME 19 7849
TEST_TIME 20 8010
TEST_TIME 21 8107
TEST_TIME 22 8137
TEST_TIME 23 8129
TEST_TIME 24 8141
TEST_TIME 25 8038
TEST_TIME 26 7921
TEST_TIME 27 8431
TEST_TIME 28 8651
TEST_TIME 29 9016
TEST_TIME 30 9765
TEST_TIME 31 10345
TEST_TIME 32 11265
TEST_TIME 33 11316
TEST_TIME 34 11185
TEST_TIME 35 10956
TEST_TIME 36 10953
TEST_TIME 37 11082
TEST_TIME 38 11038
TEST_TIME 39 10960
TEST_TIME 40 10784
TEST_TIME 41 10668
TEST_TIME 42 10555
TEST_TIME 43 10846
TEST_TIME 44 10677
TEST_TIME 45 10640
TEST_TIME 46 10559
TEST_TIME 47 10536
TEST_TIME 48 10630
TEST_TIME 49 10546
TEST_TIME 50 10409
TEST_TIME 51 10281
TEST_TIME 52 10235
TEST_TIME 53 10290
TEST_TIME 54 10242
TEST_TIME 55 10250
TEST_TIME 56 10130
TEST_TIME 57 10169
TEST_TIME 58 10118
TEST_TIME 59 10041
TEST_TIME 60 10183
TEST_TIME 61 10100
TEST_TIME 62 10016
TEST_TIME 63 9947
TEST_TIME 64 9853
TEST_TIME 65 9861
TEST_TIME 66 9873
TEST_TIME 67 9867
TEST_TIME 68 9917
TEST_TIME 69 9905
TEST_TIME 70 9917
TEST_TIME 71 9923
TEST_TIME 72 10015
TEST_TIME 73 9965
TEST_TIME 74 10115
TEST_TIME 75 10139
TEST_TIME 76 10117
TEST_TIME 77 10091
TEST_TIME 78 10185
TEST_TIME 79 10186
TEST_TIME 80 10126
TEST_TIME 81 10023
TEST_TIME 82 9999
TEST_TIME 83 10018
TEST_TIME 84 9944
TEST_TIME 85 9911
TEST_TIME 86 9889
TEST_TIME 87 9892
TEST_TIME 88 9859
TEST_TIME 89 9816
TEST_TIME 90 9787
TEST_TIME 91 9718
TEST_TIME 92 9803
TEST_TIME 93 9819
TEST_TIME 94 9828
TEST_TIME 95 9832
TEST_TIME 96 9797
TEST_TIME 97 9821
TEST_TIME 98 9900
TEST_TIME 99 9902
TEST_TIME 100 9917
TEST_TIME 101 9899
TEST_TIME 102 9915
TEST_TIME 103 9857
TEST_TIME 104 9936
TEST_TIME 105 9987
TEST_TIME 106 9970
TEST_TIME 107 9895
TEST_TIME 108 9848
TEST_TIME 109 9852
TEST_TIME 110 9806
TEST_TIME 111 9776
TEST_TIME 112 9742
TEST_TIME 113 9786
TEST_TIME 114 9736
TEST_TIME 115 9748
TEST_TIME 116 9731
TEST_TIME 117 9715
TEST_TIME 118 9652
TEST_TIME 119 9625
TEST_TIME 120 9669

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 15-12-2021 19:39:43 to 15-12-2021 19:39:46.

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 1610254 1610254 0 0.00 0 28014 7 0 0
doc_list_biud 1610254 1610254 0 0.00 0 50766 30 0 0
sp_add_invoice_to_stock 5447 5324 123 2.25 25 211135 18261 0 123
sp_cancel_adding_invoice 5867 5867 0 0.00 5 88088 857 0 0
sp_cancel_client_order 1758 1758 0 0.00 4 16820 353 0 0
sp_cancel_customer_reserve 582679 582679 0 0.00 1 50769 66 0 0
sp_cancel_pay_from_customer 703 703 0 0.00 3 21728 109 0 0
sp_cancel_pay_to_supplier 419 419 0 0.00 2 7585 119 0 0
sp_cancel_supplier_invoice 6463 6455 8 0.12 2 41378 864 0 8
sp_cancel_supplier_order 428 428 0 0.00 7 1182190 115118 0 0
sp_cancel_write_off 10655 10655 0 0.00 1 18171 125 0 0
sp_client_order 21251 21251 0 0.00 3 41274 329 0 0
sp_customer_reserve 487942 487942 0 0.00 0 120740 182 0 0
sp_fill_shopping_cart 32386 32386 0 0.00 1 17463 39 0 0
sp_fill_shopping_cart_clo_res 487937 487937 0 0.00 0 17731 14 0 0
sp_get_clo_for_invoice 5319 5319 0 0.00 0 27676 272 0 0
sp_kill_cost_storno 17644 17644 0 0.00 0 21724 64 0 0
sp_kill_qstorno_ret_qs2qd 1182896 1182896 0 0.00 0 41371 36 0 0
sp_kill_qty_storno 623451 623451 0 0.00 0 50765 77 0 0
sp_lock_selected_doc 21498 21498 0 0.00 0 7827 6 0 0
sp_make_cost_storno 29024 29024 0 0.00 0 26949 73 0 0
sp_multiply_rows_for_pdistr 29024 29024 0 0.00 0 23579 37 0 0
sp_multiply_rows_for_qdistr 21251 21251 0 0.00 0 14518 132 0 0
sp_pay_from_customer 7362 7362 0 0.00 4 22019 158 0 0
sp_pay_to_supplier 6053 6053 0 0.00 5 23204 184 0 0
sp_payment_common 13415 13415 0 0.00 1 20225 48 0 0
sp_qd_handle_on_cancel_clo 1758 1758 0 0.00 0 16814 318 0 0
sp_qd_handle_on_invoice_upd_sts 11186 11186 0 0.00 0 40370 356 0 0
sp_qd_handle_on_reserve_upd_sts 10290 10290 0 0.00 0 19839 62 0 0
sp_reserve_write_off 10290 10290 0 0.00 6 26746 225 0 0
sp_supplier_invoice 5496 5496 0 0.00 5 82859 3293 0 0
sp_supplier_order 5644 5644 0 0.00 5 40901 1296 0 0
srv_aggregate_perf_data 105 105 0 0.00 2618 258499 19739 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1560 182935 47933 0 0
srv_find_qd_qs_mism 1109587 1109587 0 0.00 0 14371 5 0 0
srv_make_invnt_saldo 543 543 0 0.00 62 142183 1536 0 0
srv_make_money_saldo 835 835 0 0.00 0 9773 43 0 0
srv_recalc_idx_stat 4 4 0 0.00 182912 502044 319674 0 0
t$perf-abend:sp_add_invoice_to_stock 123 123 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5325 5325 0 0.00 0 19814 193 0 0
t$perf-norm:sp_cancel_adding_invoice 460 460 0 0.00 1 3511 80 0 0
t$perf-norm:sp_cancel_client_order 1758 1758 0 0.00 0 1598 11 0 0
t$perf-norm:sp_cancel_customer_reserve 714 714 0 0.00 0 9381 25 0 0
t$perf-norm:sp_cancel_pay_from_customer 703 703 0 0.00 0 9005 22 0 0
t$perf-norm:sp_cancel_pay_to_supplier 419 419 0 0.00 0 656 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 411 411 0 0.00 0 1573 16 0 0
t$perf-norm:sp_cancel_supplier_order 428 428 0 0.00 0 32155 1694 0 0
t$perf-norm:sp_cancel_write_off 998 998 0 0.00 0 4679 9 0 0
t$perf-norm:sp_client_order 21251 21251 0 0.00 0 12442 20 0 0
t$perf-norm:sp_customer_reserve 1062 1062 0 0.00 0 301 4 0 0
t$perf-norm:sp_pay_from_customer 7362 7362 0 0.00 0 13473 11 0 0
t$perf-norm:sp_pay_to_supplier 6053 6053 0 0.00 0 9641 11 0 0
t$perf-norm:sp_reserve_write_off 10290 10290 0 0.00 0 16069 13 0 0
t$perf-norm:sp_supplier_invoice 5496 5496 0 0.00 0 10681 19 0 0
t$perf-norm:sp_supplier_order 5644 5644 0 0.00 0 10319 20 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 1593 36 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 7 1 0 0
t$perf-norm:srv_make_invnt_saldo 543 543 0 0.00 0 4245 36 0 0
t$perf-norm:srv_make_money_saldo 835 835 0 0.00 0 2694 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10880 10634 246 2.26 0 12122 26 0 246
v_all_customers 39034 39034 0 0.00 0 23 1 0 0
v_all_suppliers 30902 30902 0 0.00 0 21 1 0 0
v_all_wares 42502 42502 0 0.00 0 97 1 0 0
v_cancel_adding_invoice 920 920 0 0.00 0 3183 11 0 0
v_cancel_client_order 3516 3516 0 0.00 0 7050 9 0 0
v_cancel_customer_prepayment 1406 1406 0 0.00 0 3944 10 0 0
v_cancel_customer_reserve 1428 1428 0 0.00 0 5344 13 0 0
v_cancel_payment_to_supplier 838 838 0 0.00 0 7277 32 0 0
v_cancel_supplier_invoice 838 822 16 1.90 0 4550 25 0 16
v_cancel_supplier_order 856 856 0 0.00 0 4538 10 0 0
v_cancel_write_off 1996 1996 0 0.00 0 8123 30 0 0
v_max_id_clo_ord 5643 5643 0 0.00 0 4689 2 0 0
v_max_id_clo_res 1062 1062 0 0.00 0 685 9 0 0
v_max_id_ord_sup 5491 5491 0 0.00 0 3925 4 0 0
v_max_non_paid_invoice 1736 1736 0 0.00 0 130 2 0 0
v_max_non_paid_realizn 3757 3757 0 0.00 0 5860 11 0 0
v_min_id_clo_ord 5643 5643 0 0.00 0 2694 6 0 0
v_min_id_clo_res 1062 1062 0 0.00 0 6355 15 0 0
v_min_id_ord_sup 5491 5491 0 0.00 0 7459 6 0 0
v_min_non_paid_invoice 6053 6053 0 0.00 0 18873 32 0 0
v_min_non_paid_realizn 7362 7362 0 0.00 0 20129 40 0 0
v_our_firm 10678 10678 0 0.00 0 151 1 0 0
v_reserve_write_off 20580 20580 0 0.00 0 7411 11 0 0
x_lock_depdocs_on_canc_invoice 11911 11911 0 0.00 0 15705 134 0 0
x_lock_depdocs_on_canc_sup_ord 428 428 0 0.00 0 15553 449 0 0
x_make_qstorno_1000_1200 5644 5644 0 0.00 2 40891 1208 0 0
x_make_qstorno_1200_2000 5496 5496 0 0.00 2 82773 3160 0 0
x_make_qstorno_2100_3300 487942 487942 0 0.00 0 120739 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-12-2021 19:39:47 to 15-12-2021 19:39:51.

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:00 3 8192000000 8799065760 8810561536 4972256 0.060 2 1 2 0 5071856 56816 42784
17:09:01 27329 8192000000 9898055600 10208657408 973808512 10.624 100 81 161 0 1001897936 6555408 21534016
17:10:33 12441 8192000000 9949979744 10272481280 1031210064 11.180 100 85 170 0 1061037872 8554816 21272992
17:11:52 14308 8192000000 9984504368 10320609280 1068718352 11.540 100 85 170 0 1099908096 10314336 20875408
17:13:11 15145 8192000000 10037748240 10383147008 1119607504 12.023 100 85 170 0 1151767008 11716480 20443024
17:14:29 5935 8192000000 10085342592 10444296192 1166895632 12.468 100 82 164 4 1198874544 12626272 19352640
17:15:40 5397 8192000000 10124774976 10493480960 1210078000 12.870 100 76 152 0 1240147360 12934864 17134496
17:16:47 3446 8192000000 10162620080 10536075264 1246377440 13.205 100 90 177 1 1278867440 12834496 19655504
17:17:51 2055 8192000000 10189022784 10570022912 1274712832 13.465 100 90 177 2 1307148064 13420208 19015024
17:18:55 1040 8192000000 10214268144 10599370752 1296122992 13.660 100 91 182 0 1329689632 13292240 20274400
17:19:58 17652 8192000000 10194258368 10605944832 1302028688 13.714 100 47 94 1 1323197472 11055104 10113680
17:21:18 6570 8192000000 10203849408 10615554048 1313352048 13.816 100 33 65 2 1327076368 5603888 8120432
17:22:28 9521 8192000000 10206129472 10622640128 1318058096 13.859 100 22 44 0 1329424672 6526064 4840512
17:23:40 6561 8192000000 10206045920 10630135808 1326814000 13.938 100 24 48 2 1338405856 6476336 5115520
17:24:53 38339 8192000000 10216694064 10641780736 1326070336 13.932 100 24 48 0 1337205584 5445360 5689888
17:26:32 7239 8192000000 10219410416 10646343680 1332958336 13.994 100 21 43 1 1343357648 5551040 4848272
17:27:41 6149 8192000000 10218146832 10635137024 1321528752 13.891 100 33 66 1 1335905136 6561232 7815152
17:28:49 18179 8192000000 10146889376 10552471552 1171807872 12.514 91 12 24 1 1179218624 4711712 2699040
17:30:07 23550 8192000000 10138478288 10539720704 1189323552 12.677 94 42 81 0 1205196304 5072688 10800064
17:31:31 8505 8192000000 10136985696 10535636992 1225043632 13.008 98 28 56 1 1237570480 6084096 6442752
17:32:42 10351 8192000000 10152042656 10551590912 1214654640 12.912 96 23 45 0 1224811760 5097376 5059744
17:33:53 60144 8192000000 10180267248 10584817664 1282918880 13.540 100 27 54 3 1294135824 5433136 5783808
17:35:54 4398 8192000000 10185703696 10594975744 1291999536 13.622 100 26 52 0 1304052768 6253840 5799392
17:36:59 10066 8192000000 10207998032 10619432960 1308787008 13.775 100 39 78 1 1328954736 10840208 9327520
17:38:13 16017 8192000000 10227489040 10639605760 1329657632 13.964 100 45 90 2 1347304656 7308128 10338896
17:39:37 61358 8192000000 10249886752 10668990464 1339294416 14.051 99 31 60 2 1353696160 6424752 7976992
17:41:48 80545 8192000000 10267334560 10692403200 1366810080 14.298 100 33 66 0 1387050800 12205104 8035616
17:44:13 16010 8192000000 10304360064 10715549696 1379018144 14.408 100 72 142 1 1413303280 17577952 16707184
17:45:32 9128 8192000000 10301923952 10720358400 1386225872 14.472 100 58 114 0 1415485136 16030016 13229248
17:46:48 8572 8192000000 10326872480 10742562816 1385534528 14.466 99 79 158 0 1423817344 20146016 18136800
17:48:01 12073 8192000000 10327960720 10751782912 1404716880 14.637 100 58 115 1 1439250016 20916608 13616528
17:49:20 2939 8192000000 10339679648 10756046848 1403500160 14.626 100 80 158 0 1446464224 24259936 18704128
17:50:26 2825 8192000000 10334712768 10748731392 1396461584 14.563 100 84 167 1 1442543440 24749024 21332832
17:51:33 2542 8192000000 10309624192 10709893120 1362181744 14.257 98 85 170 2 1410605008 26962480 21460784
17:52:40 4972 8192000000 10328499504 10739527680 1381915952 14.434 100 70 137 1 1433671520 34206880 17548688
17:53:48 15616 8192000000 10330843072 10749452288 1380263872 14.419 100 45 90 1 1437624512 43964896 13395744
17:55:08 4660 8192000000 10360019872 10772512768 1388916960 14.496 99 76 152 0 1460835440 52032832 19885648
17:56:17 1563 8192000000 10374908336 10776899584 1392358480 14.527 100 93 185 0 1470129072 54626912 23143680
17:57:26 1559 8192000000 10365594080 10780106752 1405826992 14.647 100 74 148 1 1470945360 46271600 18846768
17:58:33 1942 8192000000 10345391040 10754670592 1380811488 14.424 100 73 147 0 1445154288 44523280 19819520
17:59:43 2153 8192000000 10374886112 10786222080 1403361488 14.625 100 83 164 0 1473406096 49390896 20653712
18:00:51 2745 8192000000 10393362144 10804658176 1417987760 14.755 100 76 152 0 1494003024 56966368 19048896
18:01:58 6199 8192000000 10396476064 10818150400 1416257776 14.740 99 54 107 1 1491758736 61153760 14347200
18:03:13 5158 8192000000 10406231520 10828836864 1423148720 14.801 100 53 106 0 1508829872 70198800 15482352
18:04:37 19454 8192000000 10377487824 10816024576 1398020096 14.577 100 35 70 1 1481348512 72815248 10513168
18:06:03 26004 8192000000 10370043760 10804338688 1383867888 14.451 100 32 64 2 1465934976 72465312 9601776
18:07:50 21379 8192000000 10333195024 10778025984 1351577936 14.162 99 23 44 3 1430864560 72588224 6698400
18:09:13 60721 8192000000 10272522432 10705346560 1281775056 13.529 93 15 28 0 1307958912 22924448 3259408
18:11:15 84966 8192000000 10304742128 10741161984 1368768752 14.316 99 28 56 1 1385235904 10006240 6460912
18:13:45 41958 8192000000 10295661168 10749001728 1381007760 14.426 100 24 48 2 1400924752 14820240 5096752
18:15:28 80987 8192000000 10289432400 10738466816 1328035456 13.949 96 6 12 0 1338450000 9161072 1253472
18:17:49 64655 8192000000 10309736112 10750332928 1358596128 14.225 98 37 70 1 1383817888 16577760 8644000
18:19:58 65626 8192000000 10296688336 10765848576 1380388784 14.420 100 11 22 0 1404393968 21197312 2807872
18:22:30 73343 8192000000 10291017104 10741321728 1361382928 14.250 98 22 42 0 1376115840 10206976 4525936
18:24:48 126825 8192000000 10306236752 10749005824 1338507728 14.044 96 11 22 1 1356656864 15555328 2593808
18:28:14 126541 8192000000 10296406608 10754691072 1342888512 14.083 96 27 54 0 1363284560 14089472 6306576
18:31:23 53644 8192000000 10289932560 10742874112 1312485536 13.809 94 36 72 0 1331726064 11119648 8120880
18:33:22 37454 8192000000 10304397408 10742321152 1381169792 14.427 100 65 131 0 1405273184 9351584 14751808
18:35:02 23064 8192000000 10279722224 10728480768 1354540768 14.188 98 25 49 0 1366849232 6640224 5668240
18:36:27 12060 8192000000 10288741584 10731577344 1363224224 14.266 99 38 77 3 1380642960 7517392 9901344
18:37:42 182935 8192000000 10262530640 10712002560 1265955552 13.385 92 8 16 0 1275928816 8556560 1416704
18:41:50 62316 8192000000 10274508560 10739253248 1370601024 14.332 100 24 48 3 1390598112 14330272 5666816
18:44:01 39944 8192000000 10267747024 10721624064 1340715360 14.064 97 14 29 0 1350863552 7206656 2941536
18:45:42 45200 8192000000 10277137600 10731700224 1349871520 14.146 98 12 24 1 1367492864 13626768 3994576
18:47:28 54692 8192000000 10295988672 10739843072 1366900176 14.299 99 33 66 0 1395555616 20807808 7847632
18:49:23 69271 8192000000 10301478256 10750382080 1375849920 14.379 99 23 46 1 1397440256 16354432 5235904
18:51:33 80680 8192000000 10289880240 10749050880 1353750224 14.181 98 11 21 2 1377699056 21153856 2794976
18:54:56 25064 8192000000 10303889840 10745057280 1376647936 14.387 100 43 86 0 1399319584 12412256 10259392
18:56:47 78598 8192000000 10276668864 10728128512 1343321024 14.087 97 29 58 0 1358192272 7512336 7358912
18:59:29 65486 8192000000 10274352752 10727354368 1345124640 14.104 98 31 61 0 1364290464 13123184 6042640
19:01:35 65642 8192000000 10271925456 10714955776 1319166832 13.869 96 37 75 0 1336385216 8723856 8494528
19:03:55 51104 8192000000 10269405904 10732142592 1366798512 14.298 100 11 20 2 1379061072 10073728 2188832
19:06:29 78416 8192000000 10266476272 10736824320 1367063328 14.301 100 6 12 0 1384529120 15999936 1465856
19:09:38 79598 8192000000 10287459872 10748813312 1356391120 14.205 98 13 26 0 1371804064 12907568 2505376
19:11:59 19434 8192000000 10297179488 10745372672 1365827600 14.290 98 49 97 0 1386343408 8735264 11780544
19:13:24 31352 8192000000 10279019024 10722086912 1340154016 14.059 98 32 64 0 1359093264 11822736 7116512
19:14:56 73110 8192000000 10299070128 10754793472 1378626240 14.404 100 18 36 1 1394777408 12635184 3515984
19:17:16 44711 8192000000 10301811840 10759356416 1377571824 14.395 99 24 48 2 1394379328 11499264 5308240
19:19:04 70811 8192000000 10288390464 10738262016 1321264752 13.888 95 17 34 1 1333525264 8923360 3337152
19:21:19 68499 8192000000 10281733408 10728591360 1300709504 13.702 94 10 20 1 1320415360 17560096 2145760
19:23:27 96413 8192000000 10274180704 10721656832 1347919248 14.129 97 27 54 0 1365713840 12354112 5440480
19:26:13 61579 8192000000 10287808928 10739765248 1334943296 14.012 96 33 65 1 1354978912 12180608 7855008
19:28:27 101903 8192000000 10268784864 10732359680 1322400416 13.898 96 13 24 1 1339692096 13970896 3320784
19:31:13 90416 8192000000 10266484288 10726232064 1350162912 14.149 99 13 26 2 1366836272 13480960 3192400
19:33:47 57248 8192000000 10266289920 10722430976 1344758384 14.100 98 17 34 0 1358940784 9672528 4509872
19:35:53 100038 8192000000 10263901952 10746318848 1346131424 14.113 98 16 32 0 1364340640 14839056 3370160

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 15-12-2021 19:39:53 to 15-12-2021 19:40:08.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 123 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 15-12-2021 19:40:09 to 15-12-2021 19:40:10.

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          245886
MON$OLDEST_ACTIVE               246114
MON$OLDEST_SNAPSHOT             246114
MON$NEXT_TRANSACTION            246114
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                       2085472
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33544 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33544 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33544 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33544 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 ---
:19: 
:20: mscs-test-161.budzdorov.ru    Wed Dec 15 19:38:07 2021
:21:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:22: 
:23: 
:24: mscs-test-161.budzdorov.ru    Wed Dec 15 19:38:07 2021
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:26: 
--- end of diff output ---

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

15.12.21 19:40:12. 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
2021-12-15 19:40:14 No severe PSQL-related problems occured
15.12.21 19:40:15. End of report.