Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.10.2020 13: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 16776548 kB
MemAvailable 31588072 kB
Buffers 0 kB
Cached 14932712 kB
SwapCached 3048 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-24 13:26:52
DTS_END                         2020-10-24 13:26:52.6910
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(67:17)
IP                              ::1/40630
TRN_ID                          240419
ATT_ID                          653
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: 9092.98 17978 1091158
customer order: creation 176.68 386 21202
customer order: refuse 15.06 328 1807
order to supplier: creation 47.45 1321 5694
order to supplier: removal 3.44 108343 413
invoice (draft): creation 46.82 3166 5618
invoice (draft): removal 50.86 943 6103
invoice accept: apply 41.96 17318 5035
invoice accept: cancel 45.33 801 5439
customer reserve: creation 3898.36 168 467803
customer reserve: removal 4469.90 67 536388
realization accept: apply 85.52 183 10262
realization accept: cancel 81.85 98 9822
payment from customer: creation 59.93 112 7191
payment from customer: removal 6.02 104 722
payment to supplier: creation 49.53 112 5944
payment to supplier: removal 3.35 81 402
service: total inventory turnovers 3.79 3216 455
service: total monetary turnovers 6.21 490 745
service: aggregate perf. data 0.91 12501 109
service: refresh index statistics 0.03 209825 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-10-2020 13:28:50 to 24-10-2020 13:28:52.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2638
TEST_TIME 2 2455
TEST_TIME 3 3806
TEST_TIME 4 4365
TEST_TIME 5 4902
TEST_TIME 6 5250
TEST_TIME 7 5121
TEST_TIME 8 5315
TEST_TIME 9 5419
TEST_TIME 10 5520
TEST_TIME 11 5542
TEST_TIME 12 6102
TEST_TIME 13 6433
TEST_TIME 14 6569
TEST_TIME 15 7040
TEST_TIME 16 7311
TEST_TIME 17 7648
TEST_TIME 18 7773
TEST_TIME 19 7746
TEST_TIME 20 7817
TEST_TIME 21 7778
TEST_TIME 22 8132
TEST_TIME 23 8257
TEST_TIME 24 8796
TEST_TIME 25 9128
TEST_TIME 26 10635
TEST_TIME 27 10836
TEST_TIME 28 10818
TEST_TIME 29 10668
TEST_TIME 30 10650
TEST_TIME 31 10628
TEST_TIME 32 10789
TEST_TIME 33 10655
TEST_TIME 34 10565
TEST_TIME 35 10844
TEST_TIME 36 10862
TEST_TIME 37 10750
TEST_TIME 38 10645
TEST_TIME 39 10455
TEST_TIME 40 10222
TEST_TIME 41 10131
TEST_TIME 42 10153
TEST_TIME 43 10182
TEST_TIME 44 10105
TEST_TIME 45 10036
TEST_TIME 46 10037
TEST_TIME 47 9963
TEST_TIME 48 9871
TEST_TIME 49 9857
TEST_TIME 50 9719
TEST_TIME 51 9716
TEST_TIME 52 9698
TEST_TIME 53 9749
TEST_TIME 54 9730
TEST_TIME 55 9746
TEST_TIME 56 9720
TEST_TIME 57 9740
TEST_TIME 58 9750
TEST_TIME 59 9751
TEST_TIME 60 9724
TEST_TIME 61 9710
TEST_TIME 62 9857
TEST_TIME 63 9797
TEST_TIME 64 9812
TEST_TIME 65 9711
TEST_TIME 66 9609
TEST_TIME 67 9573
TEST_TIME 68 9530
TEST_TIME 69 9463
TEST_TIME 70 9381
TEST_TIME 71 9412
TEST_TIME 72 9363
TEST_TIME 73 9356
TEST_TIME 74 9385
TEST_TIME 75 9347
TEST_TIME 76 9423
TEST_TIME 77 9365
TEST_TIME 78 9400
TEST_TIME 79 9439
TEST_TIME 80 9485
TEST_TIME 81 9514
TEST_TIME 82 9477
TEST_TIME 83 9382
TEST_TIME 84 9336
TEST_TIME 85 9323
TEST_TIME 86 9295
TEST_TIME 87 9303
TEST_TIME 88 9304
TEST_TIME 89 9277
TEST_TIME 90 9384
TEST_TIME 91 9361
TEST_TIME 92 9399
TEST_TIME 93 9371
TEST_TIME 94 9330
TEST_TIME 95 9373
TEST_TIME 96 9456
TEST_TIME 97 9461
TEST_TIME 98 9519
TEST_TIME 99 9502
TEST_TIME 100 9432
TEST_TIME 101 9370
TEST_TIME 102 9307
TEST_TIME 103 9266
TEST_TIME 104 9265
TEST_TIME 105 9212
TEST_TIME 106 9215
TEST_TIME 107 9211
TEST_TIME 108 9183
TEST_TIME 109 9196
TEST_TIME 110 9168
TEST_TIME 111 9181
TEST_TIME 112 9232
TEST_TIME 113 9253
TEST_TIME 114 9265
TEST_TIME 115 9300
TEST_TIME 116 9291
TEST_TIME 117 9220
TEST_TIME 118 9183
TEST_TIME 119 9138
TEST_TIME 120 9098

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 24-10-2020 13:28:54 to 24-10-2020 13:28:57.

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 1525226 1525226 0 0.00 0 15154 6 0 0
doc_list_biud 1525225 1525225 0 0.00 0 26771 30 0 0
sp_add_invoice_to_stock 5130 5035 95 1.85 40 191660 17318 0 95
sp_cancel_adding_invoice 5439 5439 0 0.00 5 90220 802 0 0
sp_cancel_client_order 1807 1807 0 0.00 4 10714 329 0 0
sp_cancel_customer_reserve 536388 536388 0 0.00 1 26776 67 0 0
sp_cancel_pay_from_customer 722 722 0 0.00 3 6955 105 0 0
sp_cancel_pay_to_supplier 402 402 0 0.00 3 6595 82 0 0
sp_cancel_supplier_invoice 6111 6103 8 0.13 1 12485 944 0 8
sp_cancel_supplier_order 413 413 0 0.00 13 1361022 108344 0 0
sp_cancel_write_off 9822 9822 0 0.00 1 11589 98 0 0
sp_client_order 21202 21202 0 0.00 4 30884 386 0 0
sp_customer_reserve 467803 467803 0 0.00 0 71086 168 0 0
sp_fill_shopping_cart 32499 32499 0 0.00 1 15883 40 0 0
sp_fill_shopping_cart_clo_res 467797 467797 0 0.00 0 26571 18 0 0
sp_get_clo_for_invoice 5025 5025 0 0.00 0 34958 399 0 0
sp_kill_cost_storno 16385 16385 0 0.00 0 5785 49 0 0
sp_kill_qstorno_ret_qs2qd 1089114 1089114 0 0.00 0 21249 36 0 0
sp_kill_qty_storno 575255 575255 0 0.00 0 26770 78 0 0
sp_lock_selected_doc 21086 21086 0 0.00 0 8336 6 0 0
sp_make_cost_storno 28419 28419 0 0.00 0 15151 53 0 0
sp_multiply_rows_for_pdistr 28418 28418 0 0.00 0 10323 30 0 0
sp_multiply_rows_for_qdistr 21202 21202 0 0.00 0 12458 199 0 0
sp_pay_from_customer 7191 7191 0 0.00 4 11194 112 0 0
sp_pay_to_supplier 5944 5944 0 0.00 4 10651 112 0 0
sp_payment_common 13135 13135 0 0.00 1 7510 18 0 0
sp_qd_handle_on_cancel_clo 1807 1807 0 0.00 0 10708 284 0 0
sp_qd_handle_on_invoice_upd_sts 10461 10461 0 0.00 0 25965 336 0 0
sp_qd_handle_on_reserve_upd_sts 10261 10261 0 0.00 0 17994 51 0 0
sp_reserve_write_off 10262 10262 0 0.00 7 18011 183 0 0
sp_supplier_invoice 5618 5618 0 0.00 6 78512 3166 0 0
sp_supplier_order 5694 5694 0 0.00 5 31160 1322 0 0
srv_aggregate_perf_data 109 109 0 0.00 2717 134627 12502 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 872 210641 49671 0 0
srv_find_qd_qs_mism 1043121 1043121 0 0.00 0 13350 5 0 0
srv_make_invnt_saldo 455 455 0 0.00 45 137294 3217 0 0
srv_make_money_saldo 745 745 0 0.00 1 13214 491 0 0
srv_recalc_idx_stat 4 4 0 0.00 178088 254337 209826 0 0
t$perf-abend:sp_add_invoice_to_stock 95 95 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5035 5035 0 0.00 0 22600 169 0 0
t$perf-norm:sp_cancel_adding_invoice 416 416 0 0.00 0 3546 82 0 0
t$perf-norm:sp_cancel_client_order 1807 1807 0 0.00 0 1085 10 0 0
t$perf-norm:sp_cancel_customer_reserve 694 694 0 0.00 0 9240 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 722 722 0 0.00 0 1481 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 402 402 0 0.00 0 196 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 415 415 0 0.00 0 473 11 0 0
t$perf-norm:sp_cancel_supplier_order 413 413 0 0.00 0 25361 1479 0 0
t$perf-norm:sp_cancel_write_off 934 934 0 0.00 0 3389 11 0 0
t$perf-norm:sp_client_order 21203 21203 0 0.00 0 11683 17 0 0
t$perf-norm:sp_customer_reserve 1039 1039 0 0.00 0 1243 5 0 0
t$perf-norm:sp_pay_from_customer 7191 7191 0 0.00 0 3783 8 0 0
t$perf-norm:sp_pay_to_supplier 5944 5944 0 0.00 0 4905 10 0 0
t$perf-norm:sp_reserve_write_off 10262 10262 0 0.00 0 17602 13 0 0
t$perf-norm:sp_supplier_invoice 5618 5618 0 0.00 0 8898 17 0 0
t$perf-norm:sp_supplier_order 5694 5694 0 0.00 0 9464 16 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 158 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 166 5 0 0
t$perf-norm:srv_make_invnt_saldo 455 455 0 0.00 0 1325 8 0 0
t$perf-norm:srv_make_money_saldo 745 745 0 0.00 0 3382 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 8 4 0 0
v_add_invoice_to_stock 10234 10044 190 1.85 0 7009 24 0 190
v_all_customers 38438 38438 0 0.00 0 19 1 0 0
v_all_suppliers 31182 31182 0 0.00 0 14 1 0 0
v_all_wares 42402 42402 0 0.00 0 77 1 0 0
v_cancel_adding_invoice 832 832 0 0.00 0 6776 14 0 0
v_cancel_client_order 3614 3614 0 0.00 0 6407 9 0 0
v_cancel_customer_prepayment 1444 1444 0 0.00 0 6863 12 0 0
v_cancel_customer_reserve 1388 1388 0 0.00 0 5572 13 0 0
v_cancel_payment_to_supplier 804 804 0 0.00 0 4512 8 0 0
v_cancel_supplier_invoice 846 830 16 1.89 0 2801 16 0 16
v_cancel_supplier_order 826 826 0 0.00 0 2895 7 0 0
v_cancel_write_off 1868 1868 0 0.00 0 4745 16 0 0
v_max_id_clo_ord 5693 5693 0 0.00 0 1465 2 0 0
v_max_id_clo_res 1039 1039 0 0.00 0 1301 8 0 0
v_max_id_ord_sup 5605 5605 0 0.00 0 5248 8 0 0
v_max_non_paid_invoice 1651 1651 0 0.00 0 3151 4 0 0
v_max_non_paid_realizn 3886 3886 0 0.00 0 5598 3 0 0
v_min_id_clo_ord 5693 5693 0 0.00 0 5401 5 0 0
v_min_id_clo_res 1039 1039 0 0.00 0 4410 11 0 0
v_min_id_ord_sup 5605 5605 0 0.00 0 8147 11 0 0
v_min_non_paid_invoice 5944 5944 0 0.00 0 3459 8 0 0
v_min_non_paid_realizn 7191 7191 0 0.00 0 5844 12 0 0
v_our_firm 10574 10574 0 0.00 0 111 1 0 0
v_reserve_write_off 20522 20522 0 0.00 0 6824 10 0 0
x_lock_depdocs_on_canc_invoice 11127 11127 0 0.00 0 34424 109 0 0
x_lock_depdocs_on_canc_sup_ord 413 413 0 0.00 0 6040 287 0 0
x_make_qstorno_1000_1200 5694 5694 0 0.00 2 31102 1254 0 0
x_make_qstorno_1200_2000 5618 5618 0 0.00 3 78501 3002 0 0
x_make_qstorno_2100_3300 467803 467803 0 0.00 0 71064 148 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-10-2020 13:28:58 to 24-10-2020 13:29:02.

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:56 2 8192000000 8796633056 8807174144 3163600 0.038 1 1 2 0 3261296 61840 35856
10:57:58 27843 8192000000 9725086160 10024222720 794678784 8.842 100 85 170 1 823036304 7664608 20692912
10:59:30 21618 8192000000 9766515024 10075947008 840776592 9.308 100 86 172 0 874159360 12658160 20724608
11:00:56 12150 8192000000 9799527280 10119688192 861582864 9.516 100 81 163 0 911767600 31012256 19172480
11:02:18 13681 8192000000 9869664112 10206003200 918672912 10.083 100 74 146 3 973728528 39411456 15644160
11:03:34 6151 8192000000 9905238784 10254192640 958842512 10.478 100 72 144 0 1026762736 52492704 15427520
11:04:43 5479 8192000000 9966590272 10326327296 995473232 10.835 100 77 154 2 1073962208 62564624 15924352
11:05:51 9015 8192000000 9981457264 10353754112 1024638384 11.117 100 70 140 0 1099608320 61028464 13941472
11:07:02 2057 8192000000 10027993216 10396127232 1059372384 11.450 100 93 186 0 1135512016 57460880 18678752
11:08:04 8090 8192000000 9989593120 10383949824 1068458272 11.537 100 50 98 3 1120950096 42031328 10460496
11:09:16 6691 8192000000 9999087936 10397478912 1083696880 11.683 100 35 70 0 1113273584 22318992 7257712
11:10:24 4359 8192000000 10028599856 10419814400 1093509296 11.776 100 56 111 3 1146613248 41679264 11424688
11:11:29 58662 8192000000 10000666384 10413195264 1096179536 11.801 100 35 69 1 1127884640 24108224 7596880
11:13:28 6279 8192000000 10012496976 10421575680 1099726560 11.835 100 40 79 3 1131004352 22341904 8935888
11:14:38 3055 8192000000 10030933120 10422341632 1103457664 11.870 100 56 112 3 1135211888 18755840 12998384
11:15:41 4687 8192000000 10007747920 10408165376 1098083760 11.819 100 40 80 2 1115102288 7679008 9339520
11:16:47 36038 8192000000 9994080800 10406977536 1070841856 11.560 99 27 54 2 1101991856 25299184 5850816
11:18:27 6196 8192000000 9947398480 10334019584 1025982032 11.130 97 45 87 2 1050804320 16187680 8634608
11:19:34 3882 8192000000 9912846944 10286989312 994665568 10.827 97 39 78 2 1016052672 12634000 8753104
11:20:39 5498 8192000000 9924822400 10299256832 994886384 10.829 98 43 86 1 1022297616 17624576 9786656
11:21:50 49387 8192000000 9927043408 10313363456 1006196912 10.939 98 23 45 2 1026538688 15599552 4742224
11:23:47 12685 8192000000 9961129088 10349551616 1043995920 11.303 100 28 56 2 1080746400 30021296 6729184
11:25:00 6959 8192000000 9982422400 10373345280 1055821344 11.416 100 41 80 3 1081315728 16417088 9077296
11:26:09 9582 8192000000 9981706304 10379972608 1073601664 11.586 100 29 58 1 1093347488 12748928 6996896
11:27:28 55359 8192000000 10016997520 10418216960 1088818672 11.731 100 37 72 1 1119615072 23333712 7462688
11:29:41 24968 8192000000 10051750944 10464333824 1108611968 11.919 100 39 78 3 1160139168 43437072 8090128
11:31:14 7979 8192000000 10120167488 10516094976 1127122560 12.094 100 62 122 0 1218830112 78443376 13264176
11:32:29 7537 8192000000 10141004880 10545496064 1137677008 12.194 100 51 102 4 1250754464 102032032 11045424
11:33:45 8575 8192000000 10164055120 10569986048 1149514576 12.305 100 53 105 1 1273641184 112174992 11951616
11:35:00 23872 8192000000 10212385408 10631348224 1161105296 12.414 100 54 107 0 1321054864 147767056 12182512
11:36:34 6758 8192000000 10256527264 10663555072 1163916880 12.440 100 60 119 1 1361928752 184154288 13857584
11:37:46 5287 8192000000 10278877456 10686734336 1167404416 12.473 100 62 123 1 1386525936 205029648 14091872
11:38:57 871 8192000000 10250158256 10652889088 1151891760 12.327 99 82 163 0 1365345600 194609456 18844384
11:40:04 1855 8192000000 10307400048 10712633344 1187066176 12.656 100 78 155 1 1416493120 212024848 17402096
11:41:14 5505 8192000000 10334437248 10752958464 1171922080 12.515 99 66 132 1 1437628640 250428608 15277952
11:42:25 1446 8192000000 10373271504 10766102528 1170241376 12.499 100 91 181 0 1471942288 281064544 20636368
11:43:27 11653 8192000000 10386019152 10803027968 1164841712 12.449 100 46 92 0 1479752336 303216224 11694400
11:44:48 7823 8192000000 10376996192 10799067136 1153799184 12.345 100 41 82 1 1480104288 316126544 10178560
11:46:00 6942 8192000000 10379203184 10816917504 1155359808 12.360 100 38 76 1 1489704400 325137888 9206704
11:47:15 4258 8192000000 10419066688 10833727488 1151176000 12.321 100 57 112 2 1515890784 350833840 13880944
11:48:37 10960 8192000000 10433977408 10862813184 1149317888 12.303 100 44 88 2 1532431984 372064736 11049360
11:50:02 12618 8192000000 10453368832 10888073216 1129795840 12.119 100 44 88 2 1551517008 409425904 12295264
11:51:16 22120 8192000000 10355399952 10852564992 1111816896 11.950 99 23 46 1 1477816128 359005200 6994032
11:52:40 27822 8192000000 10127370528 10689855488 1107982880 11.913 99 14 28 0 1331432064 219493872 3955312
11:55:08 24269 8192000000 10122294640 10573062144 1102513504 11.861 100 11 22 0 1214353264 109391568 2448192
11:56:50 55434 8192000000 10065875152 10479448064 1065983008 11.514 96 17 34 0 1153049568 83819504 3247056
11:58:51 103963 8192000000 10093455568 10531287040 1070934288 11.561 95 10 20 0 1120122880 47239776 1948816
12:01:35 128594 8192000000 10046303408 10491322368 1078157552 11.630 96 8 15 1 1135204368 55386736 1660080
12:04:45 210641 8192000000 10096123392 10535407616 1110359744 11.936 98 30 59 2 1166869936 49837584 6672608
12:09:21 27385 8192000000 10072180720 10527838208 1128465088 12.107 100 37 74 2 1210023904 72733328 8825488
12:11:00 37736 8192000000 10079542224 10525364224 1143908432 12.252 100 20 40 1 1191985392 43870864 4206096
12:12:40 54395 8192000000 10098888800 10547601408 1151547568 12.324 100 20 40 1 1201981952 46061120 4373264
12:14:38 43408 8192000000 10117482288 10570706944 1131202096 12.133 98 13 26 1 1203311088 69202992 2906000
12:16:25 55932 8192000000 10126593600 10578952192 1138523648 12.202 100 13 26 1 1221391504 79769040 3098816
12:18:24 60316 8192000000 10044680096 10492956672 1103570400 11.872 97 12 22 1 1188653552 82211744 2871408
12:20:27 37735 8192000000 10096228736 10555547648 1141309696 12.228 100 26 52 0 1207873024 61309024 5254304
12:22:12 35571 8192000000 10067555440 10508595200 1135680192 12.175 100 28 53 3 1163992112 22627792 5684128
12:23:51 59826 8192000000 10077427776 10548367360 1110779664 11.940 98 22 43 1 1165603936 49749856 5074416
12:25:53 56425 8192000000 10089295888 10523660288 1110279872 11.935 97 16 32 0 1152900592 39306368 3314352
12:27:51 52019 8192000000 10063433200 10552008704 1111865232 11.950 98 13 27 1 1207191120 92699664 2626224
12:29:55 44432 8192000000 10096904416 10541182976 1093821056 11.779 97 39 78 0 1149257104 47945552 7490496
12:31:43 84251 8192000000 10074467344 10508533760 1115988768 11.989 100 32 64 1 1182400032 59807360 6603904
12:34:07 55451 8192000000 10032913024 10501582848 1073468400 11.585 96 8 16 0 1122449280 47138592 1842288
12:36:11 55180 8192000000 10049358896 10495840256 1085275728 11.698 97 19 38 3 1126705184 37347072 4082384
12:38:10 35867 8192000000 10035700096 10461642752 1051855680 11.378 95 19 39 2 1084992624 28603568 4533376
12:40:03 44508 8192000000 10044955424 10495557632 1067245904 11.526 97 18 37 0 1116013568 45028048 3739616
12:42:18 50360 8192000000 10044134944 10501799936 1077529328 11.624 98 22 44 0 1150676512 68633856 4513328
12:44:11 61824 8192000000 10080813712 10532515840 1071962368 11.571 97 24 48 3 1139130736 62556976 4611392
12:46:17 42383 8192000000 10085074400 10555564032 1100650016 11.844 99 40 79 0 1204242752 95266496 8326240
12:48:00 93854 8192000000 10053007728 10501775360 1097138624 11.810 98 23 46 0 1160268960 58312448 4817888
12:52:12 104411 8192000000 10143256464 10653581312 1135737248 12.175 100 17 35 1 1194122816 54791904 3593664
12:54:57 82350 8192000000 10103404416 10582659072 1123587632 12.061 99 11 22 0 1204818416 78609504 2621280
12:57:24 106332 8192000000 10072460992 10543628288 1096536400 11.805 96 14 28 1 1136038208 36926448 2575360
13:00:14 41417 8192000000 10099112576 10529505280 1109304176 11.926 97 45 89 0 1137290352 18465504 9520672
13:01:56 83816 8192000000 10127175968 10623340544 1129762736 12.119 99 8 16 0 1225951456 94387936 1800784
13:04:20 92882 8192000000 10074757632 10513391616 1128196976 12.104 99 11 22 1 1165076592 34676544 2203072
13:06:59 142062 8192000000 10092584784 10551820288 1126399408 12.087 98 25 50 1 1184311504 52459392 5452704
13:10:34 40293 8192000000 10080440384 10584887296 1144609616 12.259 100 8 16 1 1190171856 43880064 1682176
13:12:14 52172 8192000000 10101466032 10568105984 1154628560 12.353 100 24 48 1 1220676384 60979616 5068208
13:14:15 36166 8192000000 10076048464 10512769024 1096957984 11.809 95 40 80 0 1149191504 42836608 9396912
13:15:57 49257 8192000000 10126746992 10571223040 1106432128 11.899 96 16 32 1 1184437024 74459664 3545232
13:17:51 81304 8192000000 10097136512 10618417152 1081071840 11.658 95 17 34 0 1191544848 106437104 4035904
13:20:14 63143 8192000000 10072246512 10583363584 1083323312 11.679 96 15 30 0 1159738608 73143472 3271824
13:22:30 127889 8192000000 10063247472 10517372928 1112035440 11.952 99 27 54 1 1162072672 44515232 5522000
13:25:54 60424 8192000000 10020508016 10476621824 1071552144 11.567 95 9 18 0 1124710256 51149888 2008224

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 24-10-2020 13:29:04 to 24-10-2020 13:29:19.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 95 v_add_invoice_to_stock 335544517
user_exc 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-10-2020 13:29:20 to 24-10-2020 13:29:22.

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          239926
MON$OLDEST_ACTIVE               240673
MON$OLDEST_SNAPSHOT             240673
MON$NEXT_TRANSACTION            240673
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                       2118272
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             791
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 ---
:28: 
:29: mscs-test-161.budzdorov.ru    Sat Oct 24 13:27:21 2020
:30:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:31: 
--- end of diff output ---

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

24.10.20 13:29:23. 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-24 13:29:25 No severe PSQL-related problems occured
24.10.20 13:29:26. End of report.