Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.10.2020 13:29
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 17017340 kB
MemAvailable 31573928 kB
Buffers 0 kB
Cached 14694860 kB
SwapCached 3044 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 4.0.0
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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-25 13:26:48
DTS_END                         2020-10-25 13:26:48.5450
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(67:17)
IP                              ::1/56994
TRN_ID                          245945
ATT_ID                          695
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: 8754.88 25405 1050585
customer order: creation 170.95 398 20514
customer order: refuse 14.62 327 1754
order to supplier: creation 46.23 1453 5547
order to supplier: removal 3.30 98106 396
invoice (draft): creation 46.76 3642 5611
invoice (draft): removal 47.43 846 5691
invoice accept: apply 42.27 17915 5072
invoice accept: cancel 42.58 907 5110
customer reserve: creation 3843.66 178 461239
customer reserve: removal 4213.83 61 505660
realization accept: apply 84.78 177 10173
realization accept: cancel 69.33 121 8320
payment from customer: creation 59.18 129 7102
payment from customer: removal 5.72 73 686
payment to supplier: creation 49.18 123 5902
payment to supplier: removal 3.81 90 457
service: total inventory turnovers 3.72 3340 446
service: total monetary turnovers 6.56 478 787
service: aggregate perf. data 0.95 12763 114
service: refresh index statistics 0.03 366979 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 25-10-2020 13:29:28 to 25-10-2020 13:29:30.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2716
TEST_TIME 2 2941
TEST_TIME 3 2991
TEST_TIME 4 3040
TEST_TIME 5 3564
TEST_TIME 6 4093
TEST_TIME 7 4472
TEST_TIME 8 4660
TEST_TIME 9 4963
TEST_TIME 10 5084
TEST_TIME 11 5455
TEST_TIME 12 5680
TEST_TIME 13 5960
TEST_TIME 14 6513
TEST_TIME 15 6937
TEST_TIME 16 7421
TEST_TIME 17 7899
TEST_TIME 18 8000
TEST_TIME 19 8488
TEST_TIME 20 8492
TEST_TIME 21 8771
TEST_TIME 22 8791
TEST_TIME 23 9233
TEST_TIME 24 10059
TEST_TIME 25 9976
TEST_TIME 26 9886
TEST_TIME 27 9636
TEST_TIME 28 9990
TEST_TIME 29 10336
TEST_TIME 30 10161
TEST_TIME 31 10005
TEST_TIME 32 9937
TEST_TIME 33 9962
TEST_TIME 34 9922
TEST_TIME 35 9947
TEST_TIME 36 9980
TEST_TIME 37 9983
TEST_TIME 38 10015
TEST_TIME 39 10103
TEST_TIME 40 9876
TEST_TIME 41 9687
TEST_TIME 42 9644
TEST_TIME 43 9580
TEST_TIME 44 9496
TEST_TIME 45 9514
TEST_TIME 46 9559
TEST_TIME 47 9476
TEST_TIME 48 9425
TEST_TIME 49 9352
TEST_TIME 50 9385
TEST_TIME 51 9509
TEST_TIME 52 9484
TEST_TIME 53 9448
TEST_TIME 54 9386
TEST_TIME 55 9630
TEST_TIME 56 9613
TEST_TIME 57 9576
TEST_TIME 58 9544
TEST_TIME 59 9523
TEST_TIME 60 9447
TEST_TIME 61 9360
TEST_TIME 62 9276
TEST_TIME 63 9265
TEST_TIME 64 9175
TEST_TIME 65 9115
TEST_TIME 66 9066
TEST_TIME 67 8997
TEST_TIME 68 8979
TEST_TIME 69 8983
TEST_TIME 70 9095
TEST_TIME 71 9063
TEST_TIME 72 9086
TEST_TIME 73 9081
TEST_TIME 74 9101
TEST_TIME 75 9163
TEST_TIME 76 9198
TEST_TIME 77 9194
TEST_TIME 78 9172
TEST_TIME 79 9183
TEST_TIME 80 9231
TEST_TIME 81 9252
TEST_TIME 82 9299
TEST_TIME 83 9267
TEST_TIME 84 9186
TEST_TIME 85 9122
TEST_TIME 86 9091
TEST_TIME 87 9047
TEST_TIME 88 9025
TEST_TIME 89 8995
TEST_TIME 90 8983
TEST_TIME 91 8971
TEST_TIME 92 8992
TEST_TIME 93 8961
TEST_TIME 94 8938
TEST_TIME 95 8977
TEST_TIME 96 8966
TEST_TIME 97 9042
TEST_TIME 98 9038
TEST_TIME 99 8999
TEST_TIME 100 9030
TEST_TIME 101 9003
TEST_TIME 102 8941
TEST_TIME 103 8904
TEST_TIME 104 8848
TEST_TIME 105 8809
TEST_TIME 106 8796
TEST_TIME 107 8802
TEST_TIME 108 8768
TEST_TIME 109 8749
TEST_TIME 110 8747
TEST_TIME 111 8818
TEST_TIME 112 8862
TEST_TIME 113 8856
TEST_TIME 114 8905
TEST_TIME 115 8899
TEST_TIME 116 8881
TEST_TIME 117 8896
TEST_TIME 118 8855
TEST_TIME 119 8795
TEST_TIME 120 8756

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 25-10-2020 13:29:32 to 25-10-2020 13:29: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 1478445 1478445 0 0.00 0 17222 7 0 0
doc_list_biud 1478442 1478442 0 0.00 0 117351 27 0 0
sp_add_invoice_to_stock 5199 5072 127 2.44 39 200550 17916 0 127
sp_cancel_adding_invoice 5110 5110 0 0.00 5 73295 908 0 0
sp_cancel_client_order 1754 1754 0 0.00 4 11096 328 0 0
sp_cancel_customer_reserve 505660 505660 0 0.00 1 88814 62 0 0
sp_cancel_pay_from_customer 686 686 0 0.00 3 1557 73 0 0
sp_cancel_pay_to_supplier 457 457 0 0.00 2 4389 90 0 0
sp_cancel_supplier_invoice 5714 5691 23 0.40 2 117356 847 0 23
sp_cancel_supplier_order 396 396 0 0.00 7 913224 98106 0 0
sp_cancel_write_off 8320 8320 0 0.00 1 6923 122 0 0
sp_client_order 20514 20514 0 0.00 4 23809 398 0 0
sp_customer_reserve 461239 461239 0 0.00 0 77214 179 0 0
sp_fill_shopping_cart 31663 31663 0 0.00 1 35575 51 0 0
sp_fill_shopping_cart_clo_res 461237 461237 0 0.00 0 29824 17 0 0
sp_get_clo_for_invoice 5069 5069 0 0.00 0 40443 400 0 0
sp_kill_cost_storno 14573 14573 0 0.00 0 8793 58 0 0
sp_kill_qstorno_ret_qs2qd 1025727 1025727 0 0.00 0 117350 33 0 0
sp_kill_qty_storno 542173 542173 0 0.00 0 117351 73 0 0
sp_lock_selected_doc 21191 21191 0 0.00 0 8584 5 0 0
sp_make_cost_storno 28246 28246 0 0.00 0 16882 52 0 0
sp_multiply_rows_for_pdistr 28246 28246 0 0.00 0 8787 33 0 0
sp_multiply_rows_for_qdistr 20512 20512 0 0.00 0 23801 198 0 0
sp_pay_from_customer 7102 7102 0 0.00 4 14079 130 0 0
sp_pay_to_supplier 5902 5902 0 0.00 5 12039 123 0 0
sp_payment_common 13004 13004 0 0.00 1 7833 21 0 0
sp_qd_handle_on_cancel_clo 1754 1754 0 0.00 0 11071 284 0 0
sp_qd_handle_on_invoice_upd_sts 10179 10179 0 0.00 0 31357 354 0 0
sp_qd_handle_on_reserve_upd_sts 10173 10173 0 0.00 0 14375 55 0 0
sp_reserve_write_off 10173 10173 0 0.00 7 14407 177 0 0
sp_supplier_invoice 5611 5611 0 0.00 6 117504 3642 0 0
sp_supplier_order 5547 5547 0 0.00 5 42783 1453 0 0
srv_aggregate_perf_data 114 114 0 0.00 3098 114269 12764 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1414 141615 47102 0 0
srv_find_qd_qs_mism 1003240 1003240 0 0.00 0 13149 7 0 0
srv_make_invnt_saldo 446 446 0 0.00 67 103399 3341 0 0
srv_make_money_saldo 787 787 0 0.00 1 17686 478 0 0
srv_recalc_idx_stat 4 4 0 0.00 198520 498789 366979 0 0
t$perf-abend:sp_add_invoice_to_stock 127 127 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 23 23 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5072 5072 0 0.00 0 9067 146 0 0
t$perf-norm:sp_cancel_adding_invoice 437 437 0 0.00 0 2404 86 0 0
t$perf-norm:sp_cancel_client_order 1754 1754 0 0.00 0 5387 15 0 0
t$perf-norm:sp_cancel_customer_reserve 739 739 0 0.00 0 2684 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 686 686 0 0.00 0 496 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 457 457 0 0.00 0 439 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 420 420 0 0.00 0 1116 17 0 0
t$perf-norm:sp_cancel_supplier_order 396 396 0 0.00 0 23638 1497 0 0
t$perf-norm:sp_cancel_write_off 1060 1060 0 0.00 0 1324 6 0 0
t$perf-norm:sp_client_order 20514 20514 0 0.00 0 11873 17 0 0
t$perf-norm:sp_customer_reserve 1057 1057 0 0.00 0 599 3 0 0
t$perf-norm:sp_pay_from_customer 7102 7102 0 0.00 0 2137 6 0 0
t$perf-norm:sp_pay_to_supplier 5902 5902 0 0.00 0 9198 12 0 0
t$perf-norm:sp_reserve_write_off 10173 10173 0 0.00 0 6053 10 0 0
t$perf-norm:sp_supplier_invoice 5611 5611 0 0.00 0 4152 16 0 0
t$perf-norm:sp_supplier_order 5547 5547 0 0.00 0 7818 17 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 503 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 70 2 0 0
t$perf-norm:srv_make_invnt_saldo 446 446 0 0.00 0 629 8 0 0
t$perf-norm:srv_make_money_saldo 787 787 0 0.00 0 498 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 11 4 0 0
v_add_invoice_to_stock 10392 10138 254 2.44 0 9179 18 0 254
v_all_customers 36956 36956 0 0.00 0 70 1 0 0
v_all_suppliers 30524 30524 0 0.00 0 99 1 0 0
v_all_wares 41016 41016 0 0.00 0 27 1 0 0
v_cancel_adding_invoice 874 874 0 0.00 0 821 7 0 0
v_cancel_client_order 3508 3508 0 0.00 0 6386 6 0 0
v_cancel_customer_prepayment 1372 1372 0 0.00 0 712 6 0 0
v_cancel_customer_reserve 1478 1478 0 0.00 0 627 4 0 0
v_cancel_payment_to_supplier 914 914 0 0.00 0 688 6 0 0
v_cancel_supplier_invoice 886 840 46 5.19 0 3102 17 0 46
v_cancel_supplier_order 792 792 0 0.00 0 556 5 0 0
v_cancel_write_off 2120 2120 0 0.00 0 4041 15 0 0
v_max_id_clo_ord 5543 5543 0 0.00 0 732 2 0 0
v_max_id_clo_res 1057 1057 0 0.00 0 5794 22 0 0
v_max_id_ord_sup 5610 5610 0 0.00 0 4560 4 0 0
v_max_non_paid_invoice 1793 1793 0 0.00 0 812 3 0 0
v_max_non_paid_realizn 3949 3949 0 0.00 0 2203 4 0 0
v_min_id_clo_ord 5543 5543 0 0.00 0 4314 11 0 0
v_min_id_clo_res 1057 1057 0 0.00 0 4277 11 0 0
v_min_id_ord_sup 5610 5610 0 0.00 0 25011 14 0 0
v_min_non_paid_invoice 5902 5902 0 0.00 0 7829 13 0 0
v_min_non_paid_realizn 7102 7102 0 0.00 0 6930 15 0 0
v_our_firm 10366 10366 0 0.00 0 14 1 0 0
v_reserve_write_off 20346 20346 0 0.00 0 5962 6 0 0
x_lock_depdocs_on_canc_invoice 10381 10381 0 0.00 0 36973 114 0 0
x_lock_depdocs_on_canc_sup_ord 396 396 0 0.00 0 14182 323 0 0
x_make_qstorno_1000_1200 5547 5547 0 0.00 2 37425 1346 0 0
x_make_qstorno_1200_2000 5611 5611 0 0.00 2 114217 3457 0 0
x_make_qstorno_2100_3300 461239 461239 0 0.00 0 77213 159 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-10-2020 13:29:36 to 25-10-2020 13:29: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
10:56:52 2 8192000000 8796633056 8807174144 3163600 0.038 1 1 2 0 3261296 61840 35856
10:57:53 27587 8192000000 9731858736 10031542272 808463728 8.982 100 85 170 1 836108096 7524032 20120336
10:59:37 15030 8192000000 9767684432 10078334976 842246896 9.322 100 84 168 1 876041952 14182480 19612576
11:00:58 20960 8192000000 9840287472 10163232768 893643728 9.835 100 85 170 0 948993328 36179456 19170144
11:02:23 14937 8192000000 9902790144 10241277952 926091456 10.156 100 82 164 1 1002460416 58311296 18057664
11:03:42 9569 8192000000 9939867248 10287865856 966090848 10.549 100 77 154 0 1048497808 66192784 16214176
11:04:53 3701 8192000000 9972811920 10333691904 1018151328 11.054 100 84 168 2 1091663536 56176880 17335328
11:05:58 4776 8192000000 10009752912 10373279744 1032147152 11.189 100 79 155 3 1118024384 70277936 15599296
11:07:04 1373 8192000000 10037493280 10407354368 1063576848 11.491 100 93 186 1 1145540128 62385888 19577392
11:08:06 25732 8192000000 9971719968 10370478080 1069282400 11.545 100 37 75 0 1118692592 41563328 7846864
11:09:34 7821 8192000000 9975477008 10373365760 1074913728 11.599 100 17 33 2 1091306672 12848880 3544064
11:10:42 6174 8192000000 9998531664 10396405760 1083688976 11.683 100 29 58 2 1118296464 28701312 5906176
11:11:50 60061 8192000000 9990261456 10391031808 1085777904 11.702 100 26 51 2 1113523488 21936448 5809136
11:13:50 5896 8192000000 10023848208 10426941440 1099622192 11.834 100 45 90 2 1139396352 29964944 9809216
11:14:59 8793 8192000000 10008128656 10421542912 1085636944 11.701 99 24 48 4 1115657328 25025728 4994656
11:16:09 8369 8192000000 10000085072 10408849408 1080490544 11.652 100 31 62 2 1108322096 20491024 7340528
11:17:19 64494 8192000000 9953792992 10351149056 980643936 10.690 92 32 64 2 1008621024 21700400 6276688
11:19:26 7863 8192000000 9908866400 10275799040 982038560 10.704 97 35 70 2 1021498960 31572448 7887952
11:20:34 7640 8192000000 9929757520 10312224768 1000306880 10.882 97 28 54 2 1020672688 14093616 6272192
11:21:50 5100 8192000000 9948535600 10322812928 1031620048 11.184 100 34 66 2 1054523040 15145456 7757536
11:23:04 19006 8192000000 9938840432 10337951744 1038787024 11.253 100 38 73 2 1061983120 14266592 8929504
11:24:25 11144 8192000000 9978448368 10374213632 1050814720 11.368 100 38 75 4 1087326480 27044976 9466784
11:25:41 4919 8192000000 9974158480 10361278464 1061982928 11.475 100 52 104 4 1084654320 10530256 12141136
11:26:48 26393 8192000000 9982684800 10369400832 1069533696 11.548 100 24 47 3 1087554672 12011888 6009088
11:28:17 109574 8192000000 10049202704 10461757440 1096566496 11.805 100 37 73 3 1164569296 60290720 7712080
11:31:13 21179 8192000000 10108844336 10507116544 1089381728 11.737 98 44 88 1 1187214496 88476160 9356608
11:32:40 11347 8192000000 10125592032 10529406976 1122000880 12.046 100 42 82 1 1236354000 105105568 9247552
11:33:58 12347 8192000000 10145165920 10550620160 1135923760 12.177 100 44 88 0 1261108320 115393792 9790768
11:35:16 8238 8192000000 10176529584 10583158784 1145397104 12.266 100 52 104 1 1292773920 135728848 11647968
11:36:28 17696 8192000000 10235327072 10635776000 1158221824 12.387 100 60 118 1 1333906240 161455248 14229168
11:37:51 4746 8192000000 10260265984 10656473088 1158080240 12.385 99 71 140 1 1362175712 189178336 14917136
11:39:01 3586 8192000000 10307446432 10706948096 1161820928 12.420 100 84 167 3 1412089872 232651504 17617440
11:40:08 1979 8192000000 10297914672 10699276288 1143889392 12.252 100 91 180 2 1399998960 235900992 20208576
11:41:14 2728 8192000000 10319525456 10709307392 1126485968 12.088 100 86 169 0 1412534912 267455024 18593920
11:42:23 3037 8192000000 10289308000 10694901760 1123772544 12.063 100 78 157 2 1384999680 243540128 17687008
11:43:33 1733 8192000000 10286303872 10678128640 1142061824 12.235 100 77 152 2 1395900096 236656960 17181312
11:44:37 9987 8192000000 10290220256 10705575936 1141168400 12.227 100 44 88 0 1398723824 247556400 9999024
11:45:57 13502 8192000000 10252778352 10666663936 1127200784 12.095 99 37 74 2 1338362192 202820080 8341328
11:47:18 12243 8192000000 10244738304 10675732480 1098296000 11.821 98 28 56 0 1332518960 226789888 7433072
11:48:32 14072 8192000000 10292135264 10727362560 1117006800 11.999 99 25 50 0 1385772480 261884768 6880912
11:49:54 1413 8192000000 10211035456 10645708800 1122564912 12.051 100 69 142 1 1325066256 185618832 16882512
11:50:59 18759 8192000000 10148480432 10577846272 1129866928 12.120 100 14 28 0 1257536640 124310992 3358720
11:52:29 46190 8192000000 10162958288 10583789568 1129674144 12.118 100 32 65 1 1261359792 124082960 7602688
11:54:22 26575 8192000000 10161213376 10663034880 1128390560 12.106 100 12 24 0 1271560336 138856272 4313504
11:55:49 55449 8192000000 10060441680 10499018752 1119966608 12.027 99 15 30 0 1155865792 32106384 3792800
11:57:47 74930 8192000000 10087609936 10535378944 1109686688 11.929 97 24 47 3 1177168176 62235408 5246080
12:00:15 85789 8192000000 10075388416 10550693888 1101582064 11.853 97 18 36 0 1167450160 61887296 3980800
12:02:46 104320 8192000000 10113703264 10574655488 1121516304 12.041 99 15 30 0 1165503712 40809552 3177856
12:06:04 141615 8192000000 10100081472 10523979776 1120739712 12.034 98 38 77 3 1178825920 49277824 8808384
12:10:40 64737 8192000000 10111407408 10596700160 1126590944 12.089 98 12 24 2 1172310512 43407296 2312272
12:12:45 18149 8192000000 10054517776 10534514688 1126979136 12.093 99 5 10 0 1158502880 30746416 777328
12:14:05 40813 8192000000 10079923104 10534772736 1122992128 12.055 98 14 28 0 1144463984 18757744 2714112
12:15:45 43802 8192000000 10115756912 10582622208 1135177840 12.170 99 14 26 1 1219726592 81324016 3224736
12:17:31 50604 8192000000 10092619728 10554425344 1114271200 11.973 97 46 90 2 1160959648 36566560 10121888
12:19:29 34796 8192000000 10127229968 10552123392 1125114848 12.075 99 17 34 0 1198051600 68879632 4057120
12:21:08 68322 8192000000 10064531552 10552844288 1096349536 11.803 97 8 16 0 1173528144 75669712 1508896
12:23:17 26611 8192000000 10066452848 10486845440 1107838608 11.912 98 26 52 0 1136366976 22901888 5626480
12:24:44 17056 8192000000 10083938352 10510098432 1118306288 12.011 100 47 94 1 1172366160 44793248 9266624
12:26:02 46310 8192000000 10058888320 10494091264 1102376096 11.860 99 33 66 0 1152765824 43625424 6764304
12:27:49 97610 8192000000 10039506752 10508730368 1103171360 11.868 99 15 31 1 1164263344 57850000 3241984
12:31:46 51650 8192000000 10015389936 10458923008 1079839088 11.646 98 19 39 1 1103645056 19689280 4116688
12:33:40 81819 8192000000 10125119552 10596667392 1101915792 11.856 99 20 40 1 1208059616 101981248 4162576
12:36:22 55254 8192000000 10035776784 10501836800 1077466448 11.623 98 12 24 1 1113638464 33797376 2374640
12:38:29 50414 8192000000 10049453200 10497970176 1080103792 11.648 96 15 30 0 1113422240 30058000 3260448
12:40:20 41966 8192000000 10080475120 10515030016 1097039456 11.810 98 34 68 0 1146455520 42521760 6894304
12:42:04 66026 8192000000 10050080624 10482339840 1106526080 11.900 99 22 44 2 1154317088 43254544 4536464
12:44:13 53563 8192000000 10066735376 10501623808 1111673360 11.948 99 31 62 2 1174305728 56532352 6100016
12:46:12 35674 8192000000 10069992400 10537910272 1110809296 11.940 99 11 21 1 1163975904 51121296 2045312
12:48:12 125620 8192000000 10068745312 10594299904 1128115264 12.104 100 13 26 0 1187234288 56528752 2590272
12:51:42 73940 8192000000 10077788976 10522439680 1109355136 11.926 98 13 26 1 1141428096 29555776 2517184
12:54:08 48511 8192000000 10097400112 10587504640 1136998048 12.187 100 8 16 0 1223166832 84330704 1838080
12:56:30 80008 8192000000 10064663568 10533404672 1113383984 11.964 98 11 22 1 1170752624 54564256 2804384
12:58:51 32223 8192000000 10046845552 10481283072 1114468480 11.975 99 17 34 0 1149735600 30805328 4461792
13:00:31 65121 8192000000 10130584832 10621603840 1141590432 12.230 100 30 60 1 1225042368 76724192 6727744
13:03:11 108735 8192000000 10057427904 10538442752 1067974880 11.533 95 19 38 0 1133928240 62586464 3366896
13:06:33 56548 8192000000 10071721344 10490724352 1107324576 11.907 98 27 54 0 1152937664 40651504 4961584
13:08:35 90088 8192000000 10037335104 10484408320 1110315296 11.935 99 21 42 1 1162444800 48454032 3675472
13:11:17 51856 8192000000 10069662128 10527289344 1110141360 11.934 98 9 18 0 1146397392 34577056 1678976
13:13:10 41866 8192000000 10091969296 10547310592 1109149680 11.924 98 19 38 2 1177366192 64052560 4163952
13:15:00 54643 8192000000 10132942016 10562605056 1135773088 12.176 100 31 62 2 1240862416 97842016 7247312
13:16:59 102700 8192000000 10075190144 10564952064 1110506096 11.937 98 17 34 0 1191223296 76648960 4068240
13:19:49 70988 8192000000 10067465008 10549227520 1081912816 11.666 96 12 24 0 1154324368 70001040 2410512
13:22:13 20893 8192000000 10076485056 10494681088 1109614240 11.929 99 41 82 1 1144897776 25852608 9430928
13:23:34 38689 8192000000 10078507408 10495201280 1097171536 11.811 98 42 83 1 1151531776 46392240 7968000
13:25:16 83225 8192000000 10105811248 10555654144 1107673104 11.910 98 24 46 1 1200780112 87636800 5470208

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 127 v_add_invoice_to_stock 335544517
user_exc 23 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 25-10-2020 13:29:58 to 25-10-2020 13:30:00.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          244544
MON$OLDEST_ACTIVE               246057
MON$OLDEST_SNAPSHOT             246057
MON$NEXT_TRANSACTION            246057
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-30 07:30:08.6030 GMT
MON$PAGES                       2094784
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             806
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2235 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2235 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2235 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2235 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
on disk structure version 13.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 ---
:56: 
:57: mscs-test-161.budzdorov.ru    Sun Oct 25 13:26:55 2020
:58:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:59: 
:60: 
:61: mscs-test-161.budzdorov.ru    Sun Oct 25 13:26:55 2020
:62:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:63: 
:64: 
:65: mscs-test-161.budzdorov.ru    Sun Oct 25 13:27:31 2020
:66:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:67: 
--- end of diff output ---

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

25.10.20 13:30:01. There are 3 logs in /var/tmp/logs-oltp40 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-25 13:30:03 No severe PSQL-related problems occured
25.10.20 13:30:04. End of report.