Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.10.2020 07:35
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 16595984 kB
MemAvailable 31525500 kB
Buffers 0 kB
Cached 15038800 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 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-23 07:35:21
DTS_END                         2020-10-23 07:35:21.4080
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_MAKE_INVNT_SALDO(40:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(67:17)
IP                              ::1/48718
TRN_ID                          239230
ATT_ID                          646
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: 9396.63 20298 1127595
customer order: creation 177.08 392 21250
customer order: refuse 15.43 353 1851
order to supplier: creation 47.08 1258 5649
order to supplier: removal 3.31 116919 397
invoice (draft): creation 46.81 3419 5617
invoice (draft): removal 51.68 945 6201
invoice accept: apply 43.26 16610 5191
invoice accept: cancel 47.01 912 5641
customer reserve: creation 4036.52 159 484382
customer reserve: removal 4624.17 66 554900
realization accept: apply 86.73 173 10408
realization accept: cancel 84.28 121 10114
payment from customer: creation 61.68 99 7401
payment from customer: removal 6.26 75 751
payment to supplier: creation 50.42 109 6050
payment to supplier: removal 3.75 80 450
service: total inventory turnovers 3.95 3366 474
service: total monetary turnovers 6.31 536 757
service: aggregate perf. data 0.88 18204 106
service: refresh index statistics 0.04 242161 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 23-10-2020 07:36:16 to 23-10-2020 07:36:19.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5864
TEST_TIME 2 5277
TEST_TIME 3 6131
TEST_TIME 4 6409
TEST_TIME 5 5867
TEST_TIME 6 5722
TEST_TIME 7 6043
TEST_TIME 8 6635
TEST_TIME 9 6612
TEST_TIME 10 7055
TEST_TIME 11 7498
TEST_TIME 12 7538
TEST_TIME 13 7802
TEST_TIME 14 7853
TEST_TIME 15 8196
TEST_TIME 16 8171
TEST_TIME 17 8446
TEST_TIME 18 8993
TEST_TIME 19 8943
TEST_TIME 20 8920
TEST_TIME 21 9350
TEST_TIME 22 9411
TEST_TIME 23 9686
TEST_TIME 24 9747
TEST_TIME 25 10019
TEST_TIME 26 10628
TEST_TIME 27 10938
TEST_TIME 28 11293
TEST_TIME 29 11390
TEST_TIME 30 11149
TEST_TIME 31 11089
TEST_TIME 32 10970
TEST_TIME 33 10965
TEST_TIME 34 10925
TEST_TIME 35 10835
TEST_TIME 36 10752
TEST_TIME 37 10885
TEST_TIME 38 10886
TEST_TIME 39 10730
TEST_TIME 40 10836
TEST_TIME 41 10868
TEST_TIME 42 10839
TEST_TIME 43 10615
TEST_TIME 44 10440
TEST_TIME 45 10369
TEST_TIME 46 10254
TEST_TIME 47 10192
TEST_TIME 48 10190
TEST_TIME 49 10146
TEST_TIME 50 10163
TEST_TIME 51 10087
TEST_TIME 52 10127
TEST_TIME 53 10032
TEST_TIME 54 9999
TEST_TIME 55 9967
TEST_TIME 56 9910
TEST_TIME 57 9924
TEST_TIME 58 9888
TEST_TIME 59 9854
TEST_TIME 60 9971
TEST_TIME 61 9983
TEST_TIME 62 9971
TEST_TIME 63 9967
TEST_TIME 64 9911
TEST_TIME 65 9957
TEST_TIME 66 9977
TEST_TIME 67 10038
TEST_TIME 68 10002
TEST_TIME 69 10109
TEST_TIME 70 9974
TEST_TIME 71 9878
TEST_TIME 72 9868
TEST_TIME 73 9773
TEST_TIME 74 9716
TEST_TIME 75 9656
TEST_TIME 76 9607
TEST_TIME 77 9630
TEST_TIME 78 9561
TEST_TIME 79 9546
TEST_TIME 80 9594
TEST_TIME 81 9607
TEST_TIME 82 9646
TEST_TIME 83 9628
TEST_TIME 84 9641
TEST_TIME 85 9714
TEST_TIME 86 9723
TEST_TIME 87 9759
TEST_TIME 88 9695
TEST_TIME 89 9617
TEST_TIME 90 9570
TEST_TIME 91 9487
TEST_TIME 92 9460
TEST_TIME 93 9424
TEST_TIME 94 9412
TEST_TIME 95 9408
TEST_TIME 96 9385
TEST_TIME 97 9399
TEST_TIME 98 9385
TEST_TIME 99 9444
TEST_TIME 100 9479
TEST_TIME 101 9426
TEST_TIME 102 9447
TEST_TIME 103 9457
TEST_TIME 104 9455
TEST_TIME 105 9564
TEST_TIME 106 9545
TEST_TIME 107 9459
TEST_TIME 108 9386
TEST_TIME 109 9347
TEST_TIME 110 9349
TEST_TIME 111 9343
TEST_TIME 112 9335
TEST_TIME 113 9304
TEST_TIME 114 9305
TEST_TIME 115 9272
TEST_TIME 116 9248
TEST_TIME 117 9244
TEST_TIME 118 9256
TEST_TIME 119 9357
TEST_TIME 120 9402

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 23-10-2020 07:36:20 to 23-10-2020 07:36:24.

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 1574432 1574432 0 0.00 0 23661 6 0 0
doc_list_biud 1574431 1574431 0 0.00 0 85885 30 0 0
sp_add_invoice_to_stock 5361 5191 170 3.17 39 158139 16610 0 170
sp_cancel_adding_invoice 5641 5641 0 0.00 5 72118 913 0 0
sp_cancel_client_order 1851 1851 0 0.00 4 15215 353 0 0
sp_cancel_customer_reserve 554900 554900 0 0.00 1 47390 67 0 0
sp_cancel_pay_from_customer 751 751 0 0.00 3 4903 75 0 0
sp_cancel_pay_to_supplier 450 450 0 0.00 3 4269 81 0 0
sp_cancel_supplier_invoice 6207 6201 6 0.09 2 85901 945 0 6
sp_cancel_supplier_order 397 397 0 0.00 10 971364 116919 0 0
sp_cancel_write_off 10114 10114 0 0.00 1 12778 121 0 0
sp_client_order 21250 21250 0 0.00 4 20716 393 0 0
sp_customer_reserve 484382 484382 0 0.00 0 150202 160 0 0
sp_fill_shopping_cart 32504 32504 0 0.00 1 30631 44 0 0
sp_fill_shopping_cart_clo_res 484377 484377 0 0.00 0 29405 16 0 0
sp_get_clo_for_invoice 5174 5174 0 0.00 0 50533 422 0 0
sp_kill_cost_storno 16956 16956 0 0.00 0 8876 57 0 0
sp_kill_qstorno_ret_qs2qd 1126512 1126512 0 0.00 0 85879 36 0 0
sp_kill_qty_storno 594686 594686 0 0.00 0 85885 78 0 0
sp_lock_selected_doc 21647 21647 0 0.00 0 5011 3 0 0
sp_make_cost_storno 29033 29033 0 0.00 0 14220 47 0 0
sp_multiply_rows_for_pdistr 29033 29033 0 0.00 0 9351 29 0 0
sp_multiply_rows_for_qdistr 21250 21250 0 0.00 0 20706 216 0 0
sp_pay_from_customer 7401 7401 0 0.00 4 14322 99 0 0
sp_pay_to_supplier 6050 6050 0 0.00 5 9361 109 0 0
sp_payment_common 13451 13451 0 0.00 1 9322 17 0 0
sp_qd_handle_on_cancel_clo 1851 1851 0 0.00 0 15205 303 0 0
sp_qd_handle_on_invoice_upd_sts 10815 10815 0 0.00 0 22637 358 0 0
sp_qd_handle_on_reserve_upd_sts 10408 10408 0 0.00 0 26061 55 0 0
sp_reserve_write_off 10408 10408 0 0.00 7 26081 174 0 0
sp_supplier_invoice 5617 5617 0 0.00 7 104268 3420 0 0
sp_supplier_order 5649 5649 0 0.00 6 21882 1259 0 0
srv_aggregate_perf_data 106 106 0 0.00 2842 270654 18205 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1203 179976 51137 0 0
srv_find_qd_qs_mism 1077241 1077241 0 0.00 0 23660 5 0 0
srv_make_invnt_saldo 474 474 0 0.00 32 93915 3367 0 0
srv_make_money_saldo 757 757 0 0.00 1 18352 537 0 0
srv_recalc_idx_stat 5 5 0 0.00 186216 307847 242161 0 0
t$perf-abend:sp_add_invoice_to_stock 170 170 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5191 5191 0 0.00 0 11497 170 0 0
t$perf-norm:sp_cancel_adding_invoice 462 462 0 0.00 0 4866 86 0 0
t$perf-norm:sp_cancel_client_order 1851 1851 0 0.00 0 2592 10 0 0
t$perf-norm:sp_cancel_customer_reserve 701 701 0 0.00 0 2976 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 751 751 0 0.00 0 3503 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 450 450 0 0.00 0 606 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 422 422 0 0.00 0 2166 26 0 0
t$perf-norm:sp_cancel_supplier_order 397 397 0 0.00 0 18272 1583 0 0
t$perf-norm:sp_cancel_write_off 1033 1033 0 0.00 0 2025 7 0 0
t$perf-norm:sp_client_order 21250 21250 0 0.00 0 9544 20 0 0
t$perf-norm:sp_customer_reserve 1019 1019 0 0.00 0 3644 6 0 0
t$perf-norm:sp_pay_from_customer 7401 7401 0 0.00 0 5296 9 0 0
t$perf-norm:sp_pay_to_supplier 6050 6050 0 0.00 0 3162 8 0 0
t$perf-norm:sp_reserve_write_off 10408 10408 0 0.00 0 5571 9 0 0
t$perf-norm:sp_supplier_invoice 5617 5617 0 0.00 0 6874 21 0 0
t$perf-norm:sp_supplier_order 5649 5649 0 0.00 0 9556 16 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 678 23 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 271 7 0 0
t$perf-norm:srv_make_invnt_saldo 474 474 0 0.00 0 2915 15 0 0
t$perf-norm:srv_make_money_saldo 757 757 0 0.00 0 2001 9 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 66 15 0 0
v_add_invoice_to_stock 10684 10344 340 3.18 0 4522 17 0 340
v_all_customers 38568 38568 0 0.00 0 15 1 0 0
v_all_suppliers 31052 31052 0 0.00 0 20 1 0 0
v_all_wares 42496 42496 0 0.00 0 24 1 0 0
v_cancel_adding_invoice 924 924 0 0.00 0 2806 12 0 0
v_cancel_client_order 3702 3702 0 0.00 0 4371 11 0 0
v_cancel_customer_prepayment 1502 1502 0 0.00 0 4768 10 0 0
v_cancel_customer_reserve 1402 1402 0 0.00 0 4134 9 0 0
v_cancel_payment_to_supplier 900 900 0 0.00 0 174 2 0 0
v_cancel_supplier_invoice 856 844 12 1.40 0 2978 20 0 12
v_cancel_supplier_order 794 794 0 0.00 0 1231 6 0 0
v_cancel_write_off 2066 2066 0 0.00 0 3896 17 0 0
v_max_id_clo_ord 5649 5649 0 0.00 0 1088 1 0 0
v_max_id_clo_res 1019 1019 0 0.00 0 5255 14 0 0
v_max_id_ord_sup 5607 5607 0 0.00 0 2294 4 0 0
v_max_non_paid_invoice 1780 1780 0 0.00 0 734 3 0 0
v_max_non_paid_realizn 4055 4055 0 0.00 0 497 2 0 0
v_min_id_clo_ord 5649 5649 0 0.00 0 6922 9 0 0
v_min_id_clo_res 1019 1019 0 0.00 0 2779 9 0 0
v_min_id_ord_sup 5607 5607 0 0.00 0 5701 10 0 0
v_min_non_paid_invoice 6050 6050 0 0.00 0 7366 11 0 0
v_min_non_paid_realizn 7401 7401 0 0.00 0 4484 10 0 0
v_our_firm 10620 10620 0 0.00 0 22 1 0 0
v_reserve_write_off 20816 20816 0 0.00 0 4799 7 0 0
x_lock_depdocs_on_canc_invoice 11420 11420 0 0.00 0 29591 119 0 0
x_lock_depdocs_on_canc_sup_ord 397 397 0 0.00 0 3204 320 0 0
x_make_qstorno_1000_1200 5649 5649 0 0.00 2 21857 1179 0 0
x_make_qstorno_1200_2000 5617 5617 0 0.00 3 104261 3249 0 0
x_make_qstorno_2100_3300 484382 484382 0 0.00 0 150188 142 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-10-2020 07:36:25 to 23-10-2020 07:36:29.

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
05:05:25 2 8192000000 8796633056 8807174144 3163600 0.038 1 1 2 0 3261296 61840 35856
05:06:26 27491 8192000000 9716609808 10014769152 794788864 8.843 100 82 164 1 822413712 7685440 19939408
05:08:02 19558 8192000000 9768146976 10071187456 834438864 9.244 100 91 182 0 868144816 12110768 21595184
05:09:31 20365 8192000000 9794514576 10113171456 865924928 9.559 100 81 162 0 908964336 24774128 18265280
05:10:53 9622 8192000000 9852372224 10183491584 913900224 10.036 100 82 164 0 963853696 32076992 17876480
05:12:05 5787 8192000000 9941122896 10283597824 958083328 10.470 100 88 176 1 1050257424 73782944 18391152
05:13:16 4161 8192000000 9966905936 10319548416 995238464 10.832 100 89 178 1 1081283440 67597120 18447856
05:14:24 2765 8192000000 9987552960 10351017984 1025771520 11.128 100 82 164 0 1099535584 57031360 16732704
05:15:28 2705 8192000000 10051976768 10421268480 1051432272 11.374 100 87 175 0 1164752048 95827280 17492496
05:16:33 12454 8192000000 10035611808 10411675648 1064013312 11.495 100 66 132 2 1150806944 73312416 13481216
05:17:46 2612 8192000000 9996916688 10387771392 1074233952 11.592 100 41 81 2 1130315056 47172528 8908576
05:18:49 17213 8192000000 9990036304 10401460224 1083137088 11.677 100 44 88 2 1114470672 20387264 10946320
05:20:47 5271 8192000000 10017774064 10419675136 1089531232 11.738 100 42 83 1 1140726224 41933056 9261936
05:21:53 6255 8192000000 10000094032 10404429824 1091246400 11.755 100 35 69 0 1119033008 19892256 7894352
05:23:00 17180 8192000000 10007385008 10421846016 1083649200 11.682 99 22 44 0 1119449840 30970320 4830320
05:24:18 53509 8192000000 10005807008 10432692224 1097897968 11.818 100 25 48 0 1129622528 26950384 4774176
05:26:17 11496 8192000000 9995924640 10407452672 1056997312 11.428 98 33 66 3 1094675472 30031152 7647008
05:27:30 4844 8192000000 9949866096 10330398720 1015582704 11.029 97 45 84 3 1051886032 27097504 9205824
05:28:35 4915 8192000000 9933302112 10307637248 996679216 10.846 98 42 86 1 1030335104 25195856 8460032
05:29:41 48608 8192000000 9903226832 10281562112 941596672 10.309 92 39 79 0 973089184 23273408 8219104
05:31:34 4907 8192000000 9939843152 10318127104 1012390640 10.998 98 47 96 3 1038565904 15893920 10281344
05:32:41 7437 8192000000 9972428064 10361851904 1047446592 11.336 100 42 84 1 1073264208 15597376 10220240
05:33:53 6944 8192000000 9972778688 10365919232 1059972544 11.456 100 35 69 0 1087434080 19385728 8075808
05:35:05 62438 8192000000 9989439552 10385727488 1073942384 11.590 100 30 59 1 1096314368 16387504 5984480
05:37:13 6534 8192000000 10073212608 10467254272 1105633056 11.891 100 64 128 1 1190383472 71586048 13164368
05:38:21 20864 8192000000 10086493824 10485751808 1112120832 11.952 100 47 93 1 1194597200 72716656 9759712
05:39:45 16613 8192000000 10097009808 10497572864 1132432944 12.144 100 43 86 2 1205511888 63994096 9084848
05:41:06 14049 8192000000 10155601472 10560139264 1138196528 12.199 100 51 101 1 1270633344 121735808 10701008
05:42:32 9234 8192000000 10207620528 10605072384 1146947312 12.281 100 67 131 1 1309352736 148246496 14158928
05:43:43 10927 8192000000 10193912576 10607181824 1149697248 12.307 100 55 110 1 1305996576 144172288 12127040
05:44:59 9128 8192000000 10231834624 10653880320 1163086800 12.432 100 60 120 0 1330184592 153658656 13439136
05:46:11 7897 8192000000 10248875664 10660614144 1172284288 12.518 100 60 116 4 1360169760 174720368 13165104
05:47:23 5785 8192000000 10247985312 10663976960 1179494080 12.585 100 64 126 1 1352538416 158508816 14535520
05:48:42 1194 8192000000 10282024128 10683387904 1160358720 12.407 99 75 150 0 1378127696 200629488 17139488
05:49:50 1901 8192000000 10306067296 10699870208 1141712320 12.232 98 85 169 1 1392479712 232313024 18454368
05:50:57 7276 8192000000 10314435136 10725416960 1171040896 12.507 100 54 105 0 1414200144 230664240 12495008
05:52:09 2642 8192000000 10343429632 10748624896 1167013024 12.469 100 85 167 1 1445659664 259685184 18961456
05:53:18 3051 8192000000 10374227840 10767257600 1150042384 12.310 100 79 156 0 1464129472 296199344 17887744
05:54:26 8106 8192000000 10308067792 10715631616 1138523376 12.202 99 56 108 2 1402889424 252054800 12311248
05:55:40 3032 8192000000 10331889696 10730610688 1118984352 12.017 100 61 120 5 1427060640 293444592 14631696
05:56:48 17945 8192000000 10366570464 10781720576 1149035440 12.300 100 40 80 2 1471590480 311899232 10655808
05:58:10 26588 8192000000 10388170416 10832486400 1154330448 12.350 100 33 66 0 1481052416 317763168 8958800
05:59:39 13597 8192000000 10369895520 10801061888 1122811280 12.054 99 27 54 1 1464779952 334222928 7745744
06:00:56 18680 8192000000 10348802592 10778079232 1125799184 12.082 100 32 65 0 1435925072 301573296 8552592
06:02:16 33672 8192000000 10176013136 10673651712 1110320464 11.935 99 32 65 5 1312426672 194375728 7730480
06:04:13 66239 8192000000 10051307056 10506158080 1095366224 11.794 98 5 10 0 1134674784 37807296 1501264
06:06:21 64650 8192000000 10074115024 10519064576 1099727824 11.835 98 21 39 1 1131982592 27586464 4668304
06:08:32 86965 8192000000 10085582176 10546814976 1131487136 12.135 100 24 48 0 1196951184 60779584 4684464
06:11:00 36020 8192000000 10082556176 10519093248 1142284624 12.237 100 14 28 1 1164631536 19322784 3024128
06:12:36 58603 8192000000 10085807408 10584211456 1134911552 12.168 99 5 10 1 1183132544 47562800 658192
06:14:35 52156 8192000000 10105037456 10556256256 1138108448 12.198 99 23 44 2 1206325568 63890384 4326736
06:16:34 126179 8192000000 10113008144 10563727360 1125339424 12.077 98 7 14 0 1169675312 42842976 1492912
06:20:30 114726 8192000000 10108002464 10544001024 1141429760 12.229 99 40 79 0 1210752576 59927088 9395728
06:23:27 16680 8192000000 10085995248 10531004416 1139357136 12.209 100 37 73 2 1189091984 41295936 8438912
06:24:48 41143 8192000000 10073161632 10528829440 1117521536 12.004 98 29 57 0 1178693616 55332512 5839568
06:26:45 66748 8192000000 10082903024 10568855552 1137000544 12.187 100 11 22 0 1197486080 58069264 2416272
06:29:04 179976 8192000000 10123033936 10636578816 1093581632 11.777 96 21 41 3 1184116784 85956944 4578208
06:33:05 62643 8192000000 10088754688 10533875712 1090175968 11.744 96 9 18 0 1135620688 43459248 1985472
06:35:08 109061 8192000000 10046950480 10490208256 1080096832 11.648 96 14 28 1 1126722624 43686864 2938928
06:38:07 33037 8192000000 10077264208 10536767488 1071655568 11.568 95 50 101 1 1130575344 48052800 10866976
06:39:52 58168 8192000000 10054258368 10491662336 1078799744 11.636 96 27 55 1 1124453664 40402976 5250944
06:41:56 154401 8192000000 10088058832 10561576960 1066091760 11.515 94 11 22 0 1128821712 60707072 2022880
06:45:30 95213 8192000000 10067840640 10518253568 1118068128 12.009 99 15 30 0 1164016160 43161808 2786224
06:48:07 94047 8192000000 10067107440 10545057792 1119507840 12.022 100 14 28 0 1156008288 33558592 2941856
06:51:04 33426 8192000000 10050479936 10479652864 1099224784 11.830 98 19 38 1 1129253920 25996352 4032784
06:52:38 67545 8192000000 10085842016 10513108992 1079801456 11.646 95 24 48 1 1128240960 43219808 5219696
06:54:48 73009 8192000000 10075794512 10532290560 1085382528 11.699 97 19 38 0 1172939200 83635440 3921232
06:57:07 32998 8192000000 10077135520 10532356096 1107963360 11.913 99 33 66 1 1165655904 50996912 6695632
06:58:44 34868 8192000000 10123219344 10546741248 1116004784 11.989 99 33 67 2 1190101728 67503200 6593744
07:00:20 78232 8192000000 10076498192 10541297664 1125461792 12.079 100 23 46 1 1194978160 64918256 4598112
07:02:43 122295 8192000000 10064423344 10509516800 1110271056 11.935 98 12 24 0 1166326112 53421712 2633344
07:05:53 73427 8192000000 10053374784 10497769472 1115782672 11.987 99 11 22 0 1141916144 23855952 2277520
07:09:22 41691 8192000000 10087126752 10532999168 1119930736 12.026 99 34 68 2 1180901584 53625632 7345216
07:11:12 32608 8192000000 10114606192 10565132288 1133866736 12.158 99 33 65 2 1215999968 74860416 7272816
07:12:56 65085 8192000000 10143796208 10597855232 1112383632 11.955 97 22 44 0 1185956352 68882704 4690016
07:15:01 75860 8192000000 10065165328 10499534848 1096580240 11.805 96 13 26 0 1123960528 24873040 2507248
07:17:18 64487 8192000000 10089670176 10561470464 1127053040 12.094 99 27 54 1 1198626048 66364272 5208736
07:19:23 47779 8192000000 10111434720 10543079424 1115350000 11.983 98 24 48 4 1197235344 77281776 4603568
07:21:18 145969 8192000000 10057324720 10495848448 1103689952 11.873 97 11 22 0 1141390368 35434528 2265888
07:25:15 82600 8192000000 10062631568 10528776192 1099252512 11.831 97 8 16 1 1140872304 40065440 1554352
07:27:39 54107 8192000000 10072329280 10504585216 1109633552 11.929 98 35 69 0 1153590592 36700672 7256368
07:30:07 42753 8192000000 10119814992 10554023936 1135831376 12.176 100 27 54 1 1194139792 52461344 5847072
07:32:00 92663 8192000000 10189938256 10638667776 1110972960 11.942 97 24 48 0 1237142064 120594416 5574688
07:34:33 40085 8192000000 10121798800 10730262528 1131353488 12.134 100 10 20 0 1265288208 131423600 2511120

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 23-10-2020 07:36:31 to 23-10-2020 07:36:45.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 170 v_add_invoice_to_stock 335544517
user_exc 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-10-2020 07:36:46 to 23-10-2020 07:36:48.

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          239226
MON$OLDEST_ACTIVE               239386
MON$OLDEST_SNAPSHOT             239386
MON$NEXT_TRANSACTION            239386
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                       2134656
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             789
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2234 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2234 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2234 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.2234 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Fri Oct 23 07:35:25 2020
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Fri Oct 23 07:35:43 2020
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
--- end of diff output ---

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

23.10.20 07:36:49. 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-23 07:36:52 No severe PSQL-related problems occured
23.10.20 07:36:53. End of report.