Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.10.2020 19: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 17573212 kB
MemAvailable 31555756 kB
Buffers 0 kB
Cached 14158376 kB
SwapCached 3064 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-29 19:26:59
DTS_END                         2020-10-29 19:26:59.3060
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(56:17)
IP                              ::1/55870
TRN_ID                          248116
ATT_ID                          636
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: 9197.26 20256 1103671
customer order: creation 180.50 348 21660
customer order: refuse 14.83 305 1780
order to supplier: creation 47.83 1244 5740
order to supplier: removal 3.24 111080 389
invoice (draft): creation 48.06 3264 5767
invoice (draft): removal 51.05 794 6126
invoice accept: apply 42.65 17418 5118
invoice accept: cancel 45.93 846 5512
customer reserve: creation 3960.33 172 475240
customer reserve: removal 4492.95 64 539154
realization accept: apply 87.61 167 10513
realization accept: cancel 84.38 114 10126
payment from customer: creation 63.20 117 7584
payment from customer: removal 6.11 76 733
payment to supplier: creation 52.03 132 6244
payment to supplier: removal 3.83 77 459
service: total inventory turnovers 4.97 978 597
service: total monetary turnovers 6.72 26 806
service: aggregate perf. data 0.99 15090 119
service: refresh index statistics 0.03 252799 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 29-10-2020 19:28:25 to 29-10-2020 19:28:28.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 754
TEST_TIME 2 1484
TEST_TIME 3 2756
TEST_TIME 4 3682
TEST_TIME 5 3848
TEST_TIME 6 4260
TEST_TIME 7 4583
TEST_TIME 8 4993
TEST_TIME 9 4961
TEST_TIME 10 5531
TEST_TIME 11 5621
TEST_TIME 12 5637
TEST_TIME 13 5643
TEST_TIME 14 5875
TEST_TIME 15 5920
TEST_TIME 16 5942
TEST_TIME 17 6077
TEST_TIME 18 6363
TEST_TIME 19 6607
TEST_TIME 20 6783
TEST_TIME 21 7393
TEST_TIME 22 7541
TEST_TIME 23 7682
TEST_TIME 24 8173
TEST_TIME 25 8373
TEST_TIME 26 8551
TEST_TIME 27 8555
TEST_TIME 28 9705
TEST_TIME 29 10175
TEST_TIME 30 10122
TEST_TIME 31 10190
TEST_TIME 32 10148
TEST_TIME 33 9993
TEST_TIME 34 9768
TEST_TIME 35 9658
TEST_TIME 36 9434
TEST_TIME 37 9437
TEST_TIME 38 9653
TEST_TIME 39 9658
TEST_TIME 40 9602
TEST_TIME 41 9609
TEST_TIME 42 9658
TEST_TIME 43 9597
TEST_TIME 44 9548
TEST_TIME 45 9505
TEST_TIME 46 9437
TEST_TIME 47 9382
TEST_TIME 48 9335
TEST_TIME 49 9248
TEST_TIME 50 9220
TEST_TIME 51 9153
TEST_TIME 52 9152
TEST_TIME 53 9127
TEST_TIME 54 9188
TEST_TIME 55 9202
TEST_TIME 56 9139
TEST_TIME 57 9263
TEST_TIME 58 9316
TEST_TIME 59 9388
TEST_TIME 60 9423
TEST_TIME 61 9417
TEST_TIME 62 9544
TEST_TIME 63 9566
TEST_TIME 64 9619
TEST_TIME 65 9573
TEST_TIME 66 9475
TEST_TIME 67 9416
TEST_TIME 68 9393
TEST_TIME 69 9396
TEST_TIME 70 9369
TEST_TIME 71 9299
TEST_TIME 72 9275
TEST_TIME 73 9161
TEST_TIME 74 9240
TEST_TIME 75 9256
TEST_TIME 76 9246
TEST_TIME 77 9287
TEST_TIME 78 9265
TEST_TIME 79 9295
TEST_TIME 80 9336
TEST_TIME 81 9311
TEST_TIME 82 9326
TEST_TIME 83 9366
TEST_TIME 84 9430
TEST_TIME 85 9450
TEST_TIME 86 9507
TEST_TIME 87 9474
TEST_TIME 88 9429
TEST_TIME 89 9417
TEST_TIME 90 9376
TEST_TIME 91 9383
TEST_TIME 92 9387
TEST_TIME 93 9330
TEST_TIME 94 9303
TEST_TIME 95 9302
TEST_TIME 96 9296
TEST_TIME 97 9305
TEST_TIME 98 9341
TEST_TIME 99 9338
TEST_TIME 100 9365
TEST_TIME 101 9404
TEST_TIME 102 9397
TEST_TIME 103 9363
TEST_TIME 104 9393
TEST_TIME 105 9389
TEST_TIME 106 9399
TEST_TIME 107 9413
TEST_TIME 108 9434
TEST_TIME 109 9465
TEST_TIME 110 9387
TEST_TIME 111 9332
TEST_TIME 112 9302
TEST_TIME 113 9286
TEST_TIME 114 9267
TEST_TIME 115 9260
TEST_TIME 116 9219
TEST_TIME 117 9173
TEST_TIME 118 9199
TEST_TIME 119 9195
TEST_TIME 120 9199

Performance per minute, chart


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

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 1544003 1544003 0 0.00 0 35609 7 0 0
doc_list_biud 1544003 1544003 0 0.00 0 28703 28 0 0
sp_add_invoice_to_stock 5236 5118 118 2.25 35 220825 17418 0 118
sp_cancel_adding_invoice 5512 5512 0 0.00 4 55410 846 0 0
sp_cancel_client_order 1780 1780 0 0.00 5 12417 306 0 0
sp_cancel_customer_reserve 539154 539154 0 0.00 1 30852 64 0 0
sp_cancel_pay_from_customer 733 733 0 0.00 2 4254 76 0 0
sp_cancel_pay_to_supplier 459 459 0 0.00 3 4395 78 0 0
sp_cancel_supplier_invoice 6135 6126 9 0.14 2 23432 794 0 9
sp_cancel_supplier_order 389 389 0 0.00 6 871763 111080 0 0
sp_cancel_write_off 10126 10126 0 0.00 1 16410 115 0 0
sp_client_order 21660 21660 0 0.00 3 22077 348 0 0
sp_customer_reserve 475240 475240 0 0.00 0 57569 172 0 0
sp_fill_shopping_cart 33161 33161 0 0.00 1 23032 36 0 0
sp_fill_shopping_cart_clo_res 475239 475239 0 0.00 0 17715 14 0 0
sp_get_clo_for_invoice 5116 5116 0 0.00 0 26386 290 0 0
sp_kill_cost_storno 16830 16830 0 0.00 0 9160 56 0 0
sp_kill_qstorno_ret_qs2qd 1094949 1094949 0 0.00 0 28674 35 0 0
sp_kill_qty_storno 578716 578716 0 0.00 0 28703 74 0 0
sp_lock_selected_doc 21596 21596 0 0.00 0 8337 4 0 0
sp_make_cost_storno 29457 29457 0 0.00 0 35596 54 0 0
sp_multiply_rows_for_pdistr 29457 29457 0 0.00 0 16000 35 0 0
sp_multiply_rows_for_qdistr 21660 21660 0 0.00 0 21029 162 0 0
sp_pay_from_customer 7584 7584 0 0.00 4 18833 118 0 0
sp_pay_to_supplier 6244 6244 0 0.00 4 17483 132 0 0
sp_payment_common 13828 13828 0 0.00 2 18828 35 0 0
sp_qd_handle_on_cancel_clo 1780 1780 0 0.00 0 12409 283 0 0
sp_qd_handle_on_invoice_upd_sts 10628 10628 0 0.00 0 18143 317 0 0
sp_qd_handle_on_reserve_upd_sts 10513 10513 0 0.00 0 11826 45 0 0
sp_reserve_write_off 10513 10513 0 0.00 6 16030 167 0 0
sp_supplier_invoice 5767 5767 0 0.00 6 100359 3264 0 0
sp_supplier_order 5740 5740 0 0.00 5 29570 1244 0 0
srv_aggregate_perf_data 119 119 0 0.00 2723 155834 15090 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 2843 208291 43531 0 0
srv_find_qd_qs_mism 1053853 1053853 0 0.00 0 15823 6 0 0
srv_make_invnt_saldo 597 597 0 0.00 32 50786 979 0 0
srv_make_money_saldo 806 806 0 0.00 1 2417 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 161282 345967 252799 0 0
t$perf-abend:sp_add_invoice_to_stock 118 118 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5118 5118 0 0.00 0 11589 162 0 0
t$perf-norm:sp_cancel_adding_invoice 455 455 0 0.00 0 6598 83 0 0
t$perf-norm:sp_cancel_client_order 1780 1780 0 0.00 0 5158 21 0 0
t$perf-norm:sp_cancel_customer_reserve 674 674 0 0.00 0 294 4 0 0
t$perf-norm:sp_cancel_pay_from_customer 733 733 0 0.00 0 959 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 459 459 0 0.00 0 1624 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 446 446 0 0.00 0 2494 28 0 0
t$perf-norm:sp_cancel_supplier_order 389 389 0 0.00 0 24391 1797 0 0
t$perf-norm:sp_cancel_write_off 1031 1031 0 0.00 0 1492 6 0 0
t$perf-norm:sp_client_order 21660 21660 0 0.00 0 11555 17 0 0
t$perf-norm:sp_customer_reserve 1001 1001 0 0.00 0 6693 9 0 0
t$perf-norm:sp_pay_from_customer 7584 7584 0 0.00 0 2484 7 0 0
t$perf-norm:sp_pay_to_supplier 6244 6244 0 0.00 0 5820 10 0 0
t$perf-norm:sp_reserve_write_off 10513 10513 0 0.00 0 10208 10 0 0
t$perf-norm:sp_supplier_invoice 5767 5767 0 0.00 0 5278 17 0 0
t$perf-norm:sp_supplier_order 5740 5740 0 0.00 0 4398 16 0 0
t$perf-norm:srv_aggregate_perf_data 119 119 0 0.00 0 349 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 180 5 0 0
t$perf-norm:srv_make_invnt_saldo 597 597 0 0.00 0 1567 21 0 0
t$perf-norm:srv_make_money_saldo 806 806 0 0.00 0 1826 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 4 0 0
v_add_invoice_to_stock 10466 10230 236 2.25 0 8169 17 0 236
v_all_customers 39038 39038 0 0.00 0 12 1 0 0
v_all_suppliers 32038 32038 0 0.00 0 133 1 0 0
v_all_wares 43320 43320 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 910 910 0 0.00 0 3964 16 0 0
v_cancel_client_order 3560 3560 0 0.00 0 3863 6 0 0
v_cancel_customer_prepayment 1466 1466 0 0.00 0 4240 6 0 0
v_cancel_customer_reserve 1348 1348 0 0.00 0 692 3 0 0
v_cancel_payment_to_supplier 918 918 0 0.00 0 3654 6 0 0
v_cancel_supplier_invoice 910 892 18 1.97 0 763 13 0 18
v_cancel_supplier_order 778 778 0 0.00 0 2881 10 0 0
v_cancel_write_off 2062 2062 0 0.00 0 5100 18 0 0
v_max_id_clo_ord 5740 5740 0 0.00 0 228 1 0 0
v_max_id_clo_res 1001 1001 0 0.00 0 2156 10 0 0
v_max_id_ord_sup 5760 5760 0 0.00 0 3330 3 0 0
v_max_non_paid_invoice 1725 1725 0 0.00 0 715 3 0 0
v_max_non_paid_realizn 4186 4186 0 0.00 0 2929 6 0 0
v_min_id_clo_ord 5740 5740 0 0.00 0 13108 9 0 0
v_min_id_clo_res 1001 1001 0 0.00 0 622 2 0 0
v_min_id_ord_sup 5760 5760 0 0.00 0 4410 5 0 0
v_min_non_paid_invoice 6244 6244 0 0.00 0 17227 23 0 0
v_min_non_paid_realizn 7584 7584 0 0.00 0 7040 21 0 0
v_our_firm 11078 11078 0 0.00 0 109 1 0 0
v_reserve_write_off 21026 21026 0 0.00 0 4857 5 0 0
x_lock_depdocs_on_canc_invoice 11192 11192 0 0.00 0 13657 115 0 0
x_lock_depdocs_on_canc_sup_ord 389 389 0 0.00 0 6689 392 0 0
x_make_qstorno_1000_1200 5740 5740 0 0.00 2 29549 1169 0 0
x_make_qstorno_1200_2000 5767 5767 0 0.00 3 100336 3138 0 0
x_make_qstorno_2100_3300 475240 475240 0 0.00 0 57566 156 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-10-2020 19:28:33 to 29-10-2020 19:28:37.

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
16:57:03 1 8192000000 8798422528 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
16:58:03 19810 8192000000 10358295152 10676137984 1418914032 14.763 100 87 174 0 1453464592 5786912 28763648
16:59:27 21455 8192000000 10422881824 10753413120 1475209872 15.259 100 88 176 0 1512167936 8100432 28857632
17:00:57 21074 8192000000 10486699776 10830925824 1546975616 15.884 100 88 176 0 1584339344 10004144 27359584
17:02:29 13222 8192000000 10559216864 10917638144 1619765856 16.508 100 78 156 0 1653700176 11224880 22709440
17:03:53 10728 8192000000 10646727584 11013128192 1703081456 17.211 100 81 162 4 1739003728 13025760 22896512
17:05:06 6870 8192000000 10700651696 11081175040 1764756864 17.724 100 74 148 1 1798802592 12991696 21054032
17:06:15 7451 8192000000 10742093536 11128188928 1805419424 18.058 100 81 162 1 1841488496 13881776 22187296
17:07:25 2306 8192000000 10799754944 11185795072 1856493712 18.475 100 88 177 2 1893431520 12675152 24262656
17:08:28 23904 8192000000 10799686672 11207933952 1876524848 18.637 100 73 143 2 1906154272 8792880 20836544
17:09:56 14307 8192000000 10811768096 11220193280 1881580128 18.678 100 42 82 1 1899018128 5041168 12396832
17:11:12 6623 8192000000 10801198736 11229278208 1888445888 18.733 100 31 60 1 1903582688 6733024 8403776
17:12:19 5522 8192000000 10816704448 11245109248 1906508976 18.879 100 30 60 2 1920968848 6403120 8056752
17:13:26 7018 8192000000 10813339536 11248975872 1905537264 18.871 100 18 36 0 1916750096 6106816 5106016
17:14:34 56031 8192000000 10844493888 11270758400 1919809216 18.985 100 48 96 1 1938713440 4854912 14049312
17:16:33 6476 8192000000 10829679312 11257020416 1907278480 18.885 100 42 83 0 1926250864 7926064 11046320
17:17:40 10266 8192000000 10754022224 11178119168 1779986144 17.849 95 17 34 1 1789994912 5391376 4617392
17:18:56 19044 8192000000 10746954128 11172470784 1793708592 17.962 97 23 47 1 1805829520 5223760 6897168
17:20:17 7899 8192000000 10705884544 11124572160 1765782720 17.732 96 24 48 1 1778233312 5880064 6570528
17:21:26 5442 8192000000 10731600304 11145654272 1803170384 18.040 100 22 45 1 1815211648 5818496 6222768
17:22:34 6593 8192000000 10746443184 11169284096 1830069936 18.260 100 21 42 2 1842059488 6106016 5883536
17:23:41 60488 8192000000 10761377216 11195867136 1862169904 18.521 100 48 97 1 1886380496 10103200 14107392
17:25:42 7822 8192000000 10794093744 11223609344 1874513280 18.621 100 30 61 1 1889431824 6518240 8400304
17:26:52 48345 8192000000 10827673328 11246301184 1890416576 18.749 100 28 56 1 1903615552 4733952 8465024
17:28:51 15975 8192000000 10866060256 11286384640 1931213088 19.077 100 54 108 0 1959329936 13149072 14967776
17:30:12 64209 8192000000 10870302432 11305553920 1940652288 19.152 100 34 68 0 1963462480 12525648 10284544
17:32:24 11340 8192000000 10918144880 11331129344 1960132992 19.307 100 69 137 1 1993134768 14633760 18368016
17:33:38 10607 8192000000 10903667696 11333918720 1963661696 19.335 100 49 98 1 1997463616 18745248 15056672
17:34:56 15531 8192000000 10921458576 11349278720 1976356688 19.436 100 53 106 2 2010637680 18537440 15743552
17:36:21 6554 8192000000 10919937712 11349372928 1966498560 19.358 99 46 92 0 1997484032 16646304 14339168
17:37:34 5717 8192000000 10926963168 11351916544 1976824016 19.440 100 51 102 1 2010768224 18702352 15241856
17:38:46 4237 8192000000 10936025248 11368968192 1992201856 19.561 100 52 101 0 2028269664 20848720 15219088
17:39:57 2839 8192000000 10927556576 11341414400 1958510320 19.294 99 63 125 1 2004563840 25755264 20298256
17:41:06 3197 8192000000 10947048032 11358978048 1966274176 19.356 99 79 156 0 2021717216 31409264 24033776
17:42:14 5351 8192000000 10939917088 11370143744 1975355504 19.428 100 47 93 2 2025570240 35765392 14449344
17:43:26 3895 8192000000 10977412576 11388350464 1988911056 19.535 100 83 167 3 2059499952 44461184 26127712
17:44:35 8058 8192000000 10932663440 11346104320 1941748784 19.161 100 70 139 2 2014573856 49366560 23458512
17:45:49 4225 8192000000 10966589936 11373772800 1968174352 19.371 100 82 164 1 2045372240 51884928 25312960
17:46:59 5164 8192000000 10962308336 11375304704 1963055392 19.330 100 75 149 3 2041675872 55690832 22929648
17:48:08 9881 8192000000 10940102592 11372171264 1944743040 19.185 99 42 85 1 2013267760 54324256 14200464
17:49:22 8739 8192000000 10942385040 11376910336 1953047680 19.251 100 38 77 0 2023979152 57464752 13466720
17:50:39 9279 8192000000 10929543680 11375218688 1947255344 19.205 100 30 59 2 2011305616 53372288 10677984
17:51:55 17265 8192000000 10910934128 11361972224 1935355776 19.110 99 24 48 0 1986352608 42969328 8027504
17:53:54 26033 8192000000 10925514320 11381235712 1937375440 19.126 100 21 42 1 2007941776 62543760 8022576
17:55:21 72052 8192000000 10882208080 11332276224 1864365264 18.539 96 11 22 0 1892252256 24949600 2937392
17:57:34 105704 8192000000 10878334528 11330707456 1902245472 18.844 97 20 38 1 1919709488 11942320 5521696
18:00:21 19292 8192000000 10921500000 11350614016 1959591248 19.303 100 55 111 0 1995473088 20430720 15451120
18:01:56 81229 8192000000 10875862192 11330093056 1921003424 18.995 98 30 59 3 1948688704 18903760 8781520
18:04:55 17589 8192000000 10869476176 11334750208 1928816592 19.057 99 17 34 1 1947084416 13692496 4575328
18:06:32 44197 8192000000 10894752416 11350929408 1937414848 19.126 99 42 84 2 1971949776 22137008 12397920
18:08:20 55952 8192000000 10887035472 11345596416 1924810896 19.025 98 15 30 0 1939584864 10076656 4697312
18:10:18 21769 8192000000 10883486512 11357265920 1964912576 19.345 100 4 8 0 1973304000 7264368 1127056
18:11:40 26557 8192000000 10905969200 11361484800 1943635168 19.176 98 26 50 3 1960301680 9372576 7293936
18:13:11 68182 8192000000 10887524784 11370315776 1970777744 19.392 100 12 24 0 1983098240 9044944 3275552
18:15:20 90028 8192000000 10893261024 11378966528 1985118720 19.505 100 18 36 1 1998452688 7960400 5373568
18:17:50 41054 8192000000 10897756576 11371143168 1955382432 19.269 99 12 24 0 1965562272 6495280 3684560
18:19:37 46146 8192000000 10887408240 11367612416 1943663728 19.176 98 19 39 1 1963843696 14915312 5264656
18:21:25 24600 8192000000 10907848112 11380760576 1978136720 19.450 100 19 37 2 1994918384 11661776 5119888
18:22:50 28002 8192000000 10903840416 11370876928 1941414672 19.158 98 20 40 1 1963738128 16239440 6084016
18:24:22 37927 8192000000 10903706192 11359006720 1934673776 19.104 98 32 61 1 1955759008 12702720 8382512
18:26:03 36752 8192000000 10859874496 11319672832 1872653696 18.606 96 24 49 0 1890272624 11367584 6251344
18:27:44 52882 8192000000 10832505344 11306762240 1866088304 18.553 97 23 46 0 1886006240 13382784 6535152
18:29:37 51468 8192000000 10847473888 11312697344 1832164048 18.277 94 12 24 0 1843596528 8421968 3010512
18:32:14 83226 8192000000 10897814656 11345227776 1915199824 18.948 98 33 66 2 1931725760 7232720 9293216
18:34:39 30364 8192000000 10874780144 11338391552 1925994240 19.035 99 27 54 1 1948746208 14290672 8461296
18:36:11 54272 8192000000 10844224512 11315556352 1903009808 18.850 98 15 30 2 1917639104 10992160 3637136
18:38:05 46340 8192000000 10849822608 11318493184 1895259856 18.788 98 11 22 0 1907691424 9292976 3138592
18:39:52 66344 8192000000 10857962512 11344969728 1922256832 19.005 99 12 24 0 1942585968 16735296 3593840
18:42:05 45777 8192000000 10908004960 11362717696 1962921248 19.329 100 24 47 1 1979158944 8547584 7690112
18:43:54 32068 8192000000 10898252416 11344793600 1920967264 18.995 98 42 82 3 1941320560 8785280 11568016
18:45:26 49550 8192000000 10875867472 11339554816 1918983456 18.979 98 15 30 0 1932910448 9379584 4547408
18:47:16 19781 8192000000 10885009184 11322421248 1908328784 18.893 98 41 82 1 1929326416 9695680 11301952
18:48:39 91143 8192000000 10881844512 11370762240 1933570976 19.095 99 19 38 2 1964371472 25335152 5465344
18:51:11 59745 8192000000 10887234288 11348353024 1917572592 18.967 98 19 38 2 1939410720 16085248 5752880
18:54:07 79297 8192000000 10888933632 11358601216 1946485216 19.198 99 21 42 1 1964217440 11722176 6010048
18:56:37 25817 8192000000 10913638544 11363581952 1963150960 19.331 100 54 108 0 1993380080 15481008 14748112
18:58:05 69606 8192000000 10874101264 11357229056 1959933264 19.306 100 14 28 2 1973675008 9840160 3901584
19:00:15 45175 8192000000 10875768128 11350851584 1910281376 18.909 97 14 28 0 1918876784 5399152 3196256
19:02:00 75964 8192000000 10905305936 11373801472 1932797456 19.089 98 17 34 1 1949329472 11646624 4885392
19:04:23 75234 8192000000 10885488960 11365695488 1917126688 18.964 97 17 34 0 1934478768 12519664 4832416
19:06:40 70391 8192000000 10880421456 11351793664 1947737904 19.208 99 14 28 0 1962518464 10453760 4326800
19:08:51 56830 8192000000 10867675776 11324366848 1883491856 18.693 97 27 54 0 1901264400 10234672 7537872
19:10:50 208291 8192000000 10874239840 11333246976 1749225120 17.595 90 13 26 0 1773118688 20648512 3245056
19:15:19 102756 8192000000 10878616816 11346067456 1925052640 19.027 99 12 24 0 1948305792 20027968 3225184
19:18:14 57060 8192000000 10836235120 11309510656 1875589168 18.629 97 15 27 1 1885739008 6795056 3354784
19:20:12 49323 8192000000 10851621168 11332702208 1937364720 19.126 100 16 32 0 1951792368 10470208 3957440
19:22:01 42493 8192000000 10877749712 11338833920 1939617120 19.144 99 20 40 0 1955691344 10371136 5703088
19:23:47 68637 8192000000 10874699952 11356569600 1936519072 19.119 99 20 40 2 1957841664 15219712 6102880
19:25:58 59585 8192000000 10875091856 11360346112 1930043952 19.067 98 21 42 1 1949053712 12838880 6170880

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 29-10-2020 19:28:39 to 29-10-2020 19:28:54.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 118 v_add_invoice_to_stock 335544517
user_exc 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 29-10-2020 19:28:55 to 29-10-2020 19:28:57.

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          247114
MON$OLDEST_ACTIVE               248238
MON$OLDEST_SNAPSHOT             248238
MON$NEXT_TRANSACTION            248238
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                       2021888
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.33382 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.7.33382 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.7.33382 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.7.33382 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 ---
:15: 
:16: mscs-test-161.budzdorov.ru    Thu Oct 29 19:27:01 2020
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:18: 
:19: 
:20: mscs-test-161.budzdorov.ru    Thu Oct 29 19:27:03 2020
:21:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:22: 
:23: 
:24: mscs-test-161.budzdorov.ru    Thu Oct 29 19:27:04 2020
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:26: 
:27: 
:28: mscs-test-161.budzdorov.ru    Thu Oct 29 19:27:05 2020
:29:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:30: 
:31: 
:32: mscs-test-161.budzdorov.ru    Thu Oct 29 19:27:06 2020
:33:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:34: 
--- end of diff output ---

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

29.10.20 19:28:58. 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-29 19:29:00 No severe PSQL-related problems occured
29.10.20 19:29:02. End of report.