Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.10.2020 22:28
Common Performance Final Results
  1. Hardware and OS info
  2. DB and 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, full
  3. Ratio "Versions / Records" for tables
  4. Database Validation Results
  5. New in firebird.log while test was run
  6. 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 e0e7b46250ba4f9795ceb55e0face73f
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 2020-09-25 13:42
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.00
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 17642324 kB
MemAvailable 31596616 kB
Buffers 0 kB
Cached 14089744 kB
SwapCached 3816 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.7
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 2020-10-22 22:26:59
DTS_END                         2020-10-22 22:26:59.2290
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(56:17)
IP                              ::1/37762
TRN_ID                          250464
ATT_ID                          665
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: 9544.16 19359 1145299
customer order: creation 180.58 339 21670
customer order: refuse 15.72 283 1886
order to supplier: creation 48.00 1232 5760
order to supplier: removal 3.73 85502 447
invoice (draft): creation 48.23 3079 5787
invoice (draft): removal 54.73 780 6567
invoice accept: apply 43.87 15516 5264
invoice accept: cancel 49.66 703 5959
customer reserve: creation 3994.33 157 479320
customer reserve: removal 4792.96 51 575155
realization accept: apply 88.08 149 10569
realization accept: cancel 86.27 98 10352
payment from customer: creation 62.98 86 7558
payment from customer: removal 6.18 76 742
payment to supplier: creation 51.96 94 6235
payment to supplier: removal 3.59 107 431
service: total inventory turnovers 5.20 953 624
service: total monetary turnovers 7.17 20 860
service: aggregate perf. data 0.91 19498 109
service: refresh index statistics 0.03 258458 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 22-10-2020 22:28:28 to 22-10-2020 22:28:31.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2043
TEST_TIME 2 2739
TEST_TIME 3 4028
TEST_TIME 4 5038
TEST_TIME 5 4887
TEST_TIME 6 4881
TEST_TIME 7 5085
TEST_TIME 8 5275
TEST_TIME 9 5486
TEST_TIME 10 6027
TEST_TIME 11 6459
TEST_TIME 12 6409
TEST_TIME 13 6531
TEST_TIME 14 6602
TEST_TIME 15 6813
TEST_TIME 16 7830
TEST_TIME 17 7926
TEST_TIME 18 8505
TEST_TIME 19 9178
TEST_TIME 20 9747
TEST_TIME 21 10549
TEST_TIME 22 11778
TEST_TIME 23 11770
TEST_TIME 24 11523
TEST_TIME 25 11313
TEST_TIME 26 11136
TEST_TIME 27 10975
TEST_TIME 28 10992
TEST_TIME 29 10779
TEST_TIME 30 10557
TEST_TIME 31 10394
TEST_TIME 32 10425
TEST_TIME 33 10210
TEST_TIME 34 10262
TEST_TIME 35 10168
TEST_TIME 36 9987
TEST_TIME 37 9940
TEST_TIME 38 9923
TEST_TIME 39 10007
TEST_TIME 40 10054
TEST_TIME 41 9946
TEST_TIME 42 10080
TEST_TIME 43 9961
TEST_TIME 44 10088
TEST_TIME 45 9950
TEST_TIME 46 10008
TEST_TIME 47 10018
TEST_TIME 48 10030
TEST_TIME 49 10211
TEST_TIME 50 10197
TEST_TIME 51 10016
TEST_TIME 52 9943
TEST_TIME 53 9881
TEST_TIME 54 9834
TEST_TIME 55 9783
TEST_TIME 56 9813
TEST_TIME 57 9788
TEST_TIME 58 9705
TEST_TIME 59 9634
TEST_TIME 60 9585
TEST_TIME 61 9687
TEST_TIME 62 9730
TEST_TIME 63 9715
TEST_TIME 64 9785
TEST_TIME 65 9777
TEST_TIME 66 9773
TEST_TIME 67 9746
TEST_TIME 68 9740
TEST_TIME 69 9770
TEST_TIME 70 9764
TEST_TIME 71 9814
TEST_TIME 72 9803
TEST_TIME 73 9870
TEST_TIME 74 9879
TEST_TIME 75 9794
TEST_TIME 76 9732
TEST_TIME 77 9672
TEST_TIME 78 9639
TEST_TIME 79 9656
TEST_TIME 80 9606
TEST_TIME 81 9584
TEST_TIME 82 9513
TEST_TIME 83 9523
TEST_TIME 84 9523
TEST_TIME 85 9513
TEST_TIME 86 9580
TEST_TIME 87 9620
TEST_TIME 88 9583
TEST_TIME 89 9623
TEST_TIME 90 9655
TEST_TIME 91 9710
TEST_TIME 92 9684
TEST_TIME 93 9717
TEST_TIME 94 9720
TEST_TIME 95 9797
TEST_TIME 96 9777
TEST_TIME 97 9697
TEST_TIME 98 9664
TEST_TIME 99 9629
TEST_TIME 100 9617
TEST_TIME 101 9604
TEST_TIME 102 9553
TEST_TIME 103 9503
TEST_TIME 104 9545
TEST_TIME 105 9527
TEST_TIME 106 9507
TEST_TIME 107 9511
TEST_TIME 108 9550
TEST_TIME 109 9535
TEST_TIME 110 9554
TEST_TIME 111 9566
TEST_TIME 112 9549
TEST_TIME 113 9561
TEST_TIME 114 9635
TEST_TIME 115 9646
TEST_TIME 116 9685
TEST_TIME 117 9663
TEST_TIME 118 9595
TEST_TIME 119 9576
TEST_TIME 120 9544

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 22-10-2020 22:28:32 to 22-10-2020 22:28:35.

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 1590837 1590837 0 0.00 0 17470 5 0 0
doc_list_biud 1590837 1590837 0 0.00 0 51612 24 0 0
sp_add_invoice_to_stock 5453 5264 189 3.46 35 184319 15516 0 189
sp_cancel_adding_invoice 5959 5959 0 0.00 4 76693 704 0 0
sp_cancel_client_order 1886 1886 0 0.00 3 11996 284 0 0
sp_cancel_customer_reserve 575155 575155 0 0.00 1 51613 52 0 0
sp_cancel_pay_from_customer 742 742 0 0.00 3 8403 77 0 0
sp_cancel_pay_to_supplier 431 431 0 0.00 3 8121 107 0 0
sp_cancel_supplier_invoice 6581 6567 14 0.21 2 19555 780 0 14
sp_cancel_supplier_order 447 447 0 0.00 11 1049423 85503 0 0
sp_cancel_write_off 10352 10352 0 0.00 1 21911 98 0 0
sp_client_order 21670 21670 0 0.00 3 43883 340 0 0
sp_customer_reserve 479320 479320 0 0.00 0 52927 157 0 0
sp_fill_shopping_cart 33208 33208 0 0.00 1 18742 33 0 0
sp_fill_shopping_cart_clo_res 479318 479318 0 0.00 0 23716 12 0 0
sp_get_clo_for_invoice 5262 5262 0 0.00 0 29281 272 0 0
sp_kill_cost_storno 17484 17484 0 0.00 0 6222 43 0 0
sp_kill_qstorno_ret_qs2qd 1167676 1167676 0 0.00 0 51610 29 0 0
sp_kill_qty_storno 616197 616197 0 0.00 0 51612 61 0 0
sp_lock_selected_doc 22031 22031 0 0.00 0 8749 7 0 0
sp_make_cost_storno 29624 29624 0 0.00 0 8970 38 0 0
sp_multiply_rows_for_pdistr 29624 29624 0 0.00 0 9652 24 0 0
sp_multiply_rows_for_qdistr 21670 21670 0 0.00 0 43871 166 0 0
sp_pay_from_customer 7558 7558 0 0.00 4 12296 86 0 0
sp_pay_to_supplier 6235 6235 0 0.00 5 13002 95 0 0
sp_payment_common 13793 13793 0 0.00 1 12287 23 0 0
sp_qd_handle_on_cancel_clo 1886 1886 0 0.00 0 8676 250 0 0
sp_qd_handle_on_invoice_upd_sts 11221 11221 0 0.00 0 30087 268 0 0
sp_qd_handle_on_reserve_upd_sts 10569 10569 0 0.00 0 17967 53 0 0
sp_reserve_write_off 10569 10569 0 0.00 6 17989 150 0 0
sp_supplier_invoice 5787 5787 0 0.00 6 128267 3079 0 0
sp_supplier_order 5760 5760 0 0.00 5 49552 1233 0 0
srv_aggregate_perf_data 109 109 0 0.00 2819 304028 19498 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1575 152142 53184 0 0
srv_find_qd_qs_mism 1096039 1096039 0 0.00 0 17470 5 0 0
srv_make_invnt_saldo 624 624 0 0.00 41 34637 954 0 0
srv_make_money_saldo 860 860 0 0.00 0 3889 21 0 0
srv_recalc_idx_stat 4 4 0 0.00 160544 494041 258459 0 0
t$perf-abend:sp_add_invoice_to_stock 189 189 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5264 5264 0 0.00 0 32747 135 0 0
t$perf-norm:sp_cancel_adding_invoice 439 439 0 0.00 0 11750 95 0 0
t$perf-norm:sp_cancel_client_order 1886 1886 0 0.00 0 5192 14 0 0
t$perf-norm:sp_cancel_customer_reserve 768 768 0 0.00 0 3845 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 742 742 0 0.00 0 1967 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 431 431 0 0.00 0 1289 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 440 440 0 0.00 0 3355 20 0 0
t$perf-norm:sp_cancel_supplier_order 447 447 0 0.00 0 42503 1209 0 0
t$perf-norm:sp_cancel_write_off 1047 1047 0 0.00 0 3639 9 0 0
t$perf-norm:sp_client_order 21670 21670 0 0.00 0 18356 15 0 0
t$perf-norm:sp_customer_reserve 1088 1088 0 0.00 0 292 3 0 0
t$perf-norm:sp_pay_from_customer 7558 7558 0 0.00 0 8457 8 0 0
t$perf-norm:sp_pay_to_supplier 6235 6235 0 0.00 0 3514 9 0 0
t$perf-norm:sp_reserve_write_off 10569 10569 0 0.00 0 27417 12 0 0
t$perf-norm:sp_supplier_invoice 5787 5787 0 0.00 0 14564 18 0 0
t$perf-norm:sp_supplier_order 5760 5760 0 0.00 0 4331 12 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 392 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 1121 32 0 0
t$perf-norm:srv_make_invnt_saldo 624 624 0 0.00 0 23429 53 0 0
t$perf-norm:srv_make_money_saldo 860 860 0 0.00 0 2487 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10902 10524 378 3.46 0 10096 19 0 378
v_all_customers 39434 39434 0 0.00 0 32 0 0 0
v_all_suppliers 32024 32024 0 0.00 0 22 1 0 0
v_all_wares 43340 43340 0 0.00 0 44 1 0 0
v_cancel_adding_invoice 878 878 0 0.00 0 2645 9 0 0
v_cancel_client_order 3772 3772 0 0.00 0 6105 7 0 0
v_cancel_customer_prepayment 1484 1484 0 0.00 0 3392 8 0 0
v_cancel_customer_reserve 1536 1536 0 0.00 0 8160 14 0 0
v_cancel_payment_to_supplier 862 862 0 0.00 0 4834 20 0 0
v_cancel_supplier_invoice 908 880 28 3.08 0 526 12 0 28
v_cancel_supplier_order 894 894 0 0.00 0 4379 8 0 0
v_cancel_write_off 2094 2094 0 0.00 0 5482 15 0 0
v_max_id_clo_ord 5760 5760 0 0.00 0 425 1 0 0
v_max_id_clo_res 1088 1088 0 0.00 0 2668 20 0 0
v_max_id_ord_sup 5778 5778 0 0.00 0 4396 3 0 0
v_max_non_paid_invoice 1761 1761 0 0.00 0 511 2 0 0
v_max_non_paid_realizn 4029 4029 0 0.00 0 5786 5 0 0
v_min_id_clo_ord 5760 5760 0 0.00 0 2209 5 0 0
v_min_id_clo_res 1088 1088 0 0.00 0 7591 16 0 0
v_min_id_ord_sup 5778 5778 0 0.00 0 7980 9 0 0
v_min_non_paid_invoice 6235 6235 0 0.00 0 5361 12 0 0
v_min_non_paid_realizn 7558 7558 0 0.00 0 12279 18 0 0
v_our_firm 10964 10964 0 0.00 0 15 1 0 0
v_reserve_write_off 21138 21138 0 0.00 0 6743 5 0 0
x_lock_depdocs_on_canc_invoice 12086 12086 0 0.00 0 21200 106 0 0
x_lock_depdocs_on_canc_sup_ord 447 447 0 0.00 0 7819 286 0 0
x_make_qstorno_1000_1200 5760 5760 0 0.00 1 49502 1174 0 0
x_make_qstorno_1200_2000 5787 5787 0 0.00 3 127963 2952 0 0
x_make_qstorno_2100_3300 479320 479320 0 0.00 0 52925 143 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-10-2020 22:28:36 to 22-10-2020 22:28:40.

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
19:57:02 1 8192000000 8798422528 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
19:58:03 31369 8192000000 10392175344 10714808320 1434606160 14.902 100 93 186 0 1476353056 11130384 30616512
19:59:42 21668 8192000000 10455274864 10791272448 1505642784 15.525 100 85 170 1 1542147056 8681200 27823072
20:01:07 13872 8192000000 10502073344 10849492992 1559668752 15.993 100 74 148 0 1593008448 9998928 23340768
20:02:26 7523 8192000000 10591149360 10955665408 1649319680 16.759 100 72 144 1 1682041648 10932144 21789824
20:03:36 6194 8192000000 10660101552 11039739904 1728002016 17.419 100 72 144 0 1761090432 12183088 20905328
20:04:44 9271 8192000000 10715535280 11102199808 1778854880 17.840 100 66 132 1 1810246800 13346688 18045232
20:05:57 2187 8192000000 10774472032 11156606976 1833791712 18.290 100 83 164 2 1869682608 12662992 23227904
20:07:00 1383 8192000000 10815316304 11205419008 1870703056 18.590 100 90 180 1 1909760768 13647936 25409776
20:08:03 2848 8192000000 10819168208 11213561856 1877791296 18.647 100 75 153 2 1909952528 9027216 23134016
20:09:07 6844 8192000000 10796740960 11219206144 1885943488 18.713 100 36 72 2 1904071792 7761648 10366656
20:10:14 10288 8192000000 10812461216 11242684416 1902588016 18.847 100 25 50 4 1914192480 5164928 6439536
20:11:28 46733 8192000000 10825811824 11259957248 1916732832 18.961 100 50 102 0 1937880480 6191648 14956000
20:13:15 7349 8192000000 10821254000 11263987712 1917305440 18.965 100 21 40 2 1928760080 5198048 6256592
20:14:22 9308 8192000000 10828528400 11265699840 1920136752 18.988 100 22 44 1 1931770288 4756992 6876544
20:15:34 9509 8192000000 10799993856 11240714240 1858605248 18.492 97 19 38 0 1869865792 5248080 6012464
20:16:44 47488 8192000000 10743811024 11177074688 1765475696 17.730 94 20 40 2 1775903264 4681856 5745712
20:18:34 8676 8192000000 10692967856 11107799040 1676864624 16.991 93 24 48 2 1689474896 5672480 6937792
20:19:45 10503 8192000000 10692475792 11102638080 1706949920 17.243 95 17 34 0 1716919328 5132960 4836448
20:20:55 14734 8192000000 10751475968 11150110720 1820834480 18.185 100 54 107 3 1841049712 5123920 15091312
20:22:39 9818 8192000000 10757722656 11180355584 1837482656 18.320 100 32 64 2 1856798224 10510560 8805008
20:23:49 13370 8192000000 10765024176 11188248576 1848473664 18.410 100 27 54 2 1862630352 6117200 8039488
20:25:04 7060 8192000000 10770646720 11199832064 1857671040 18.484 100 20 38 1 1868855200 5639760 5544400
20:26:14 84098 8192000000 10818436496 11234709504 1877072272 18.641 100 32 64 1 1891623824 5080640 9470912
20:28:49 10907 8192000000 10853894064 11275460608 1923435056 19.014 100 49 100 0 1950405008 12763680 14206272
20:30:02 6574 8192000000 10883080912 11303641088 1940075792 19.147 100 46 92 1 1967699488 15295408 12328288
20:31:13 16100 8192000000 10893004336 11320160256 1942679440 19.168 99 45 87 0 1969959088 15072768 12206880
20:32:35 13879 8192000000 10934615040 11361013760 1982650240 19.486 100 58 116 0 2022917824 23613520 16654064
20:33:56 15282 8192000000 10941654304 11378540544 1992546304 19.564 100 42 83 0 2033448960 28257856 12644800
20:35:18 5121 8192000000 10962882592 11390492672 2004110176 19.655 100 56 112 1 2051162480 29743120 17309184
20:36:33 4446 8192000000 10975331296 11409891328 2012169248 19.719 100 55 112 1 2063051968 34150752 16731968
20:37:42 5551 8192000000 10968520960 11398893568 2004840512 19.661 100 54 105 1 2055815328 35053472 15921344
20:38:50 3121 8192000000 10954362208 11373813760 1975640160 19.430 99 72 139 2 2038611904 40304032 22667712
20:39:58 1575 8192000000 11007447936 11422908416 2018635712 19.769 100 83 164 2 2089012080 44305344 26071024
20:41:05 9132 8192000000 10941388544 11361083392 1950682992 19.232 100 70 140 0 2029213344 54874288 23656064
20:42:21 2239 8192000000 10931065328 11344138240 1931400656 19.078 99 73 143 0 2013495408 59246336 22848416
20:43:27 8918 8192000000 10944174192 11378794496 1960307264 19.308 100 57 114 0 2039735312 60516512 18911536
20:44:43 18304 8192000000 10909751792 11351191552 1929088784 19.060 99 41 83 1 2002952896 59222944 14641168
20:46:02 31480 8192000000 10901859664 11355750400 1919819824 18.985 99 27 53 0 1980663248 50821136 10022288
20:47:37 47514 8192000000 10907191008 11380965376 1936916352 19.122 100 14 28 0 1991405776 48979200 5510224
20:49:26 26286 8192000000 10848204688 11296616448 1909209072 18.900 99 26 52 1 1924534208 7570928 7754208
20:50:54 68785 8192000000 10854265264 11321597952 1930959600 19.075 100 17 34 1 1943732368 7738208 5034560
20:53:04 112628 8192000000 10852037552 11323527168 1887255248 18.724 97 15 30 0 1900137392 9113360 3768784
20:55:57 103445 8192000000 10864241248 11322130432 1900030272 18.827 98 23 46 0 1916671840 9305872 7335696
20:58:41 64217 8192000000 10869157184 11325673472 1915371696 18.950 98 12 24 3 1926459328 8139088 2948544
21:00:51 29552 8192000000 10879689024 11326373888 1917534896 18.967 98 37 74 2 1934995424 6837504 10623024
21:02:25 53629 8192000000 10896472768 11359145984 1952824976 19.249 99 16 32 3 1965810944 8776784 4209184
21:04:29 56428 8192000000 10884198144 11344269312 1928699264 19.056 98 10 20 0 1946910032 15710560 2500208
21:06:27 59778 8192000000 10936487200 11386200064 1985103440 19.505 100 23 43 0 2002615568 11542480 5969648
21:08:28 71309 8192000000 10911404816 11370708992 1930464992 19.071 97 15 27 2 1946574480 12944448 3165040
21:10:44 75490 8192000000 10925844400 11386183680 1949165760 19.220 98 18 36 0 1967981952 13663536 5152656
21:13:07 83627 8192000000 10916725664 11385217024 1948213392 19.212 98 20 40 2 1964136304 10549600 5373312
21:15:31 103017 8192000000 10893429408 11360583680 1896346448 18.797 95 13 25 1 1924691152 24082720 4261984
21:18:19 57823 8192000000 10900248560 11354910720 1914979536 18.947 97 25 50 1 1930206048 8480112 6746400
21:20:24 12617 8192000000 10899260944 11358146560 1963700688 19.335 100 29 58 0 1983295328 11356192 8238448
21:21:40 72623 8192000000 10881418192 11366424576 1950015440 19.227 99 11 22 0 1965062400 11945856 3101104
21:23:56 97702 8192000000 10877861600 11343802368 1914444384 18.942 96 20 41 0 1929266288 9055232 5766672
21:27:41 52039 8192000000 10884949312 11332956160 1836736016 18.314 93 18 36 2 1852158864 10313136 5109712
21:29:34 32561 8192000000 10921376368 11366547456 1946077392 19.195 99 36 72 3 1964816000 8034944 10703664
21:31:07 54547 8192000000 10877902256 11342000128 1885462944 18.709 96 26 51 1 1902520480 10358064 6699472
21:33:02 20435 8192000000 10866498144 11307560960 1898150736 18.811 98 26 52 2 1912389584 7609792 6629056
21:34:23 29027 8192000000 10843848768 11283877888 1857988064 18.487 97 44 88 0 1884640224 14725968 11926192
21:35:57 59549 8192000000 10870344144 11327062016 1929245792 19.061 100 22 44 1 1950048960 14483120 6320048
21:38:08 152142 8192000000 10856603168 11326836736 1806909696 18.071 93 15 30 1 1824226464 13684544 3632224
21:41:40 139677 8192000000 10873880144 11327807488 1903081440 18.851 98 14 28 0 1915125056 7917872 4125744
21:45:00 55543 8192000000 10848225632 11329916928 1923565056 19.015 99 9 18 0 1933830704 7806464 2459184
21:46:56 52578 8192000000 10854280560 11325722624 1895067248 18.787 97 11 21 0 1909869536 11504752 3297536
21:48:49 65691 8192000000 10853792736 11335614464 1923533168 19.015 99 7 14 0 1935658720 9451696 2673856
21:51:00 57856 8192000000 10883583488 11343499264 1900556448 18.831 97 15 30 0 1914621360 9765456 4299456
21:52:58 90951 8192000000 10909311568 11354619904 1941209632 19.156 99 22 44 1 1969095168 21513376 6372160
21:55:29 92076 8192000000 10893567424 11373223936 1921697824 19.000 97 15 30 0 1940228704 14237520 4293360
21:58:04 85358 8192000000 10921669904 11382865920 1932939152 19.090 97 21 42 2 1948407424 9879296 5588976
22:00:31 62498 8192000000 10955004032 11414228992 2003829936 19.653 100 18 36 1 2029296080 20520880 4945264
22:02:35 66926 8192000000 10924335552 11397574656 2002764080 19.645 100 13 26 1 2014179024 7389536 4025408
22:04:54 73187 8192000000 10927938304 11388477440 1950936896 19.234 97 18 36 0 1966115776 10215184 4963696
22:07:07 74163 8192000000 10899618704 11394678784 1951050048 19.235 98 5 10 0 1967537216 15176032 1311136
22:09:21 75035 8192000000 10873651808 11354755072 1915268384 18.949 96 22 44 2 1931550960 9852816 6429760
22:11:47 19427 8192000000 10864454784 11331289088 1892534576 18.766 96 32 62 2 1907770768 6645104 8591088
22:13:17 54218 8192000000 10886947424 11358085120 1954848864 19.265 100 31 61 3 1978904672 15573760 8482048
22:15:12 73975 8192000000 10878939488 11339968512 1891271504 18.756 97 13 26 0 1903881648 9380432 3229712
22:17:26 84511 8192000000 10844216368 11304607744 1769787104 17.765 92 27 54 1 1792035472 14274608 7973760
22:20:02 76343 8192000000 10857401264 11325607936 1872211664 18.602 97 9 18 1 1896285408 21472352 2601392
22:23:41 101177 8192000000 10864568288 11322753024 1901814768 18.841 98 29 58 0 1919627024 9311056 8501200
22:26:33 9449 8192000000 10851777120 11297976320 1899461056 18.822 98 51 104 4 1919423872 6497344 13465472

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 22-10-2020 22:28:42 to 22-10-2020 22:28:57.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 189 v_add_invoice_to_stock 335544517
user_exc 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 22-10-2020 22:28:58 to 22-10-2020 22:28:59.

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          249729
MON$OLDEST_ACTIVE               250572
MON$OLDEST_SNAPSHOT             250572
MON$NEXT_TRANSACTION            250572
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                       2012032
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.7.33379 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.7.33379 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.7.33379 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.7.33379 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 ---
:28: 
:29: mscs-test-161.budzdorov.ru    Thu Oct 22 22:27:01 2020
:30:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:31: 
:32: 
:33: mscs-test-161.budzdorov.ru    Thu Oct 22 22:27:02 2020
:34:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:35: 
:36: 
:37: mscs-test-161.budzdorov.ru    Thu Oct 22 22:27:08 2020
:38:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:39: 
--- end of diff output ---

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

22.10.20 22:29:00. 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
2020-10-22 22:29:03 No severe PSQL-related problems occured
22.10.20 22:29:04. End of report.