Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.10.2020 10:27
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 15747540 kB
MemAvailable 31532212 kB
Buffers 0 kB
Cached 15866420 kB
SwapCached 5152 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-20 10:26:54
DTS_END                         2020-10-20 10:26:54.6810
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(67:17)
IP                              ::1/58856
TRN_ID                          220215
ATT_ID                          581
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: 8751.17 23789 1050140
customer order: creation 167.73 461 20128
customer order: refuse 14.62 309 1754
order to supplier: creation 44.73 1282 5368
order to supplier: removal 3.10 126835 372
invoice (draft): creation 43.79 3754 5255
invoice (draft): removal 48.72 877 5846
invoice accept: apply 40.17 18785 4821
invoice accept: cancel 44.27 957 5312
customer reserve: creation 3713.73 184 445647
customer reserve: removal 4344.04 73 521285
realization accept: apply 81.27 160 9752
realization accept: cancel 82.58 133 9909
payment from customer: creation 56.52 100 6782
payment from customer: removal 5.48 77 658
payment to supplier: creation 46.63 142 5596
payment to supplier: removal 3.55 73 426
service: total inventory turnovers 3.54 3485 425
service: total monetary turnovers 5.66 554 679
service: aggregate perf. data 1.01 11967 121
service: refresh index statistics 0.03 305565 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-10-2020 10:28:09 to 20-10-2020 10:28:11.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2115
TEST_TIME 2 4845
TEST_TIME 3 4778
TEST_TIME 4 4828
TEST_TIME 5 5123
TEST_TIME 6 5275
TEST_TIME 7 5547
TEST_TIME 8 5825
TEST_TIME 9 6053
TEST_TIME 10 6282
TEST_TIME 11 6148
TEST_TIME 12 6474
TEST_TIME 13 6802
TEST_TIME 14 7236
TEST_TIME 15 7217
TEST_TIME 16 7244
TEST_TIME 17 7733
TEST_TIME 18 7986
TEST_TIME 19 8494
TEST_TIME 20 8331
TEST_TIME 21 8869
TEST_TIME 22 8849
TEST_TIME 23 9049
TEST_TIME 24 9573
TEST_TIME 25 10040
TEST_TIME 26 10684
TEST_TIME 27 10463
TEST_TIME 28 10494
TEST_TIME 29 10134
TEST_TIME 30 10350
TEST_TIME 31 10132
TEST_TIME 32 10008
TEST_TIME 33 9873
TEST_TIME 34 9722
TEST_TIME 35 9587
TEST_TIME 36 9455
TEST_TIME 37 9333
TEST_TIME 38 9292
TEST_TIME 39 9212
TEST_TIME 40 9107
TEST_TIME 41 9077
TEST_TIME 42 9037
TEST_TIME 43 8918
TEST_TIME 44 8863
TEST_TIME 45 8895
TEST_TIME 46 8924
TEST_TIME 47 9094
TEST_TIME 48 9203
TEST_TIME 49 9210
TEST_TIME 50 9274
TEST_TIME 51 9188
TEST_TIME 52 9353
TEST_TIME 53 9373
TEST_TIME 54 9225
TEST_TIME 55 9095
TEST_TIME 56 8994
TEST_TIME 57 8908
TEST_TIME 58 8989
TEST_TIME 59 8916
TEST_TIME 60 8919
TEST_TIME 61 8957
TEST_TIME 62 8931
TEST_TIME 63 8945
TEST_TIME 64 8920
TEST_TIME 65 8958
TEST_TIME 66 8921
TEST_TIME 67 8979
TEST_TIME 68 8988
TEST_TIME 69 9026
TEST_TIME 70 9058
TEST_TIME 71 9101
TEST_TIME 72 9179
TEST_TIME 73 9212
TEST_TIME 74 9178
TEST_TIME 75 9059
TEST_TIME 76 9072
TEST_TIME 77 9020
TEST_TIME 78 8954
TEST_TIME 79 8906
TEST_TIME 80 8955
TEST_TIME 81 8915
TEST_TIME 82 8899
TEST_TIME 83 8952
TEST_TIME 84 8940
TEST_TIME 85 8986
TEST_TIME 86 8982
TEST_TIME 87 9036
TEST_TIME 88 9026
TEST_TIME 89 9107
TEST_TIME 90 9138
TEST_TIME 91 9050
TEST_TIME 92 8982
TEST_TIME 93 8975
TEST_TIME 94 8928
TEST_TIME 95 8872
TEST_TIME 96 8868
TEST_TIME 97 8910
TEST_TIME 98 8909
TEST_TIME 99 8902
TEST_TIME 100 8939
TEST_TIME 101 8937
TEST_TIME 102 8960
TEST_TIME 103 8918
TEST_TIME 104 8890
TEST_TIME 105 8978
TEST_TIME 106 9045
TEST_TIME 107 9079
TEST_TIME 108 9068
TEST_TIME 109 9014
TEST_TIME 110 8940
TEST_TIME 111 8940
TEST_TIME 112 8909
TEST_TIME 113 8856
TEST_TIME 114 8818
TEST_TIME 115 8817
TEST_TIME 116 8792
TEST_TIME 117 8759
TEST_TIME 118 8749
TEST_TIME 119 8760
TEST_TIME 120 8756

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 20-10-2020 10:28:13 to 20-10-2020 10:28:16.

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 1461911 1461911 0 0.00 0 26162 7 0 0
doc_list_biud 1461910 1461910 0 0.00 0 103371 32 0 0
sp_add_invoice_to_stock 4943 4821 122 2.46 30 179900 18786 0 122
sp_cancel_adding_invoice 5312 5312 0 0.00 5 88187 957 0 0
sp_cancel_client_order 1754 1754 0 0.00 4 8484 310 0 0
sp_cancel_customer_reserve 521285 521285 0 0.00 1 72141 74 0 0
sp_cancel_pay_from_customer 658 658 0 0.00 3 3736 77 0 0
sp_cancel_pay_to_supplier 426 426 0 0.00 3 3183 73 0 0
sp_cancel_supplier_invoice 5856 5846 10 0.17 2 103386 877 0 10
sp_cancel_supplier_order 372 372 0 0.00 7 1169726 126835 0 0
sp_cancel_write_off 9909 9909 0 0.00 1 26256 134 0 0
sp_client_order 20128 20128 0 0.00 3 22699 461 0 0
sp_customer_reserve 445647 445647 0 0.00 0 102344 185 0 0
sp_fill_shopping_cart 30738 30738 0 0.00 1 19937 44 0 0
sp_fill_shopping_cart_clo_res 445638 445638 0 0.00 0 26391 19 0 0
sp_get_clo_for_invoice 4812 4812 0 0.00 0 47522 469 0 0
sp_kill_cost_storno 16305 16305 0 0.00 0 26151 69 0 0
sp_kill_qstorno_ret_qs2qd 1058697 1058697 0 0.00 0 103369 39 0 0
sp_kill_qty_storno 559042 559042 0 0.00 0 103370 83 0 0
sp_lock_selected_doc 20242 20242 0 0.00 0 7347 4 0 0
sp_make_cost_storno 26942 26942 0 0.00 0 18748 58 0 0
sp_multiply_rows_for_pdistr 26942 26942 0 0.00 0 10297 31 0 0
sp_multiply_rows_for_qdistr 20128 20128 0 0.00 0 18954 274 0 0
sp_pay_from_customer 6782 6782 0 0.00 4 13130 100 0 0
sp_pay_to_supplier 5596 5596 0 0.00 5 14631 142 0 0
sp_payment_common 12378 12378 0 0.00 1 11964 22 0 0
sp_qd_handle_on_cancel_clo 1754 1754 0 0.00 0 8477 278 0 0
sp_qd_handle_on_invoice_upd_sts 10124 10124 0 0.00 0 26400 374 0 0
sp_qd_handle_on_reserve_upd_sts 9752 9752 0 0.00 0 8874 44 0 0
sp_reserve_write_off 9752 9752 0 0.00 6 17613 160 0 0
sp_supplier_invoice 5255 5255 0 0.00 6 109815 3755 0 0
sp_supplier_order 5368 5368 0 0.00 5 77351 1283 0 0
srv_aggregate_perf_data 121 121 0 0.00 2453 105138 11967 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1959 153181 45642 0 0
srv_find_qd_qs_mism 1003752 1003752 0 0.00 0 25537 6 0 0
srv_make_invnt_saldo 425 425 0 0.00 43 109453 3486 0 0
srv_make_money_saldo 679 679 0 0.00 2 18796 555 0 0
srv_recalc_idx_stat 4 4 0 0.00 115124 489292 305565 0 0
t$perf-abend:sp_add_invoice_to_stock 122 122 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4821 4821 0 0.00 0 15170 166 0 0
t$perf-norm:sp_cancel_adding_invoice 411 411 0 0.00 0 5481 113 0 0
t$perf-norm:sp_cancel_client_order 1754 1754 0 0.00 0 4046 12 0 0
t$perf-norm:sp_cancel_customer_reserve 678 678 0 0.00 0 3150 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 658 658 0 0.00 0 4594 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 426 426 0 0.00 0 1112 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 408 408 0 0.00 0 6808 37 0 0
t$perf-norm:sp_cancel_supplier_order 372 372 0 0.00 0 22699 1556 0 0
t$perf-norm:sp_cancel_write_off 972 972 0 0.00 0 2989 9 0 0
t$perf-norm:sp_client_order 20128 20128 0 0.00 0 8454 15 0 0
t$perf-norm:sp_customer_reserve 962 962 0 0.00 0 1429 7 0 0
t$perf-norm:sp_pay_from_customer 6782 6782 0 0.00 0 5921 9 0 0
t$perf-norm:sp_pay_to_supplier 5596 5596 0 0.00 0 4629 8 0 0
t$perf-norm:sp_reserve_write_off 9752 9752 0 0.00 0 7495 9 0 0
t$perf-norm:sp_supplier_invoice 5255 5255 0 0.00 0 12109 16 0 0
t$perf-norm:sp_supplier_order 5368 5368 0 0.00 0 7795 19 0 0
t$perf-norm:srv_aggregate_perf_data 121 121 0 0.00 0 233 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 855 21 0 0
t$perf-norm:srv_make_invnt_saldo 425 425 0 0.00 0 929 9 0 0
t$perf-norm:srv_make_money_saldo 679 679 0 0.00 0 379 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 3 0 0
v_add_invoice_to_stock 9866 9622 244 2.47 0 3407 15 0 244
v_all_customers 36968 36968 0 0.00 0 21 1 0 0
v_all_suppliers 29340 29340 0 0.00 0 21 1 0 0
v_all_wares 40256 40256 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 822 822 0 0.00 0 1522 9 0 0
v_cancel_client_order 3508 3508 0 0.00 0 3394 8 0 0
v_cancel_customer_prepayment 1316 1316 0 0.00 0 3290 9 0 0
v_cancel_customer_reserve 1356 1356 0 0.00 0 3644 7 0 0
v_cancel_payment_to_supplier 852 852 0 0.00 0 3162 8 0 0
v_cancel_supplier_invoice 836 816 20 2.39 0 3732 19 0 20
v_cancel_supplier_order 744 744 0 0.00 0 608 6 0 0
v_cancel_write_off 1944 1944 0 0.00 0 3340 15 0 0
v_max_id_clo_ord 5366 5366 0 0.00 0 330 1 0 0
v_max_id_clo_res 962 962 0 0.00 0 3561 13 0 0
v_max_id_ord_sup 5244 5244 0 0.00 0 7337 5 0 0
v_max_non_paid_invoice 1536 1536 0 0.00 0 346 2 0 0
v_max_non_paid_realizn 3362 3362 0 0.00 0 4211 4 0 0
v_min_id_clo_ord 5366 5366 0 0.00 0 2944 5 0 0
v_min_id_clo_res 962 962 0 0.00 0 2183 8 0 0
v_min_id_ord_sup 5244 5244 0 0.00 0 6940 12 0 0
v_min_non_paid_invoice 5596 5596 0 0.00 0 11959 19 0 0
v_min_non_paid_realizn 6782 6782 0 0.00 0 6377 15 0 0
v_our_firm 10128 10128 0 0.00 0 19 1 0 0
v_reserve_write_off 19504 19504 0 0.00 0 3605 5 0 0
x_lock_depdocs_on_canc_invoice 10750 10750 0 0.00 0 20250 115 0 0
x_lock_depdocs_on_canc_sup_ord 372 372 0 0.00 0 14501 401 0 0
x_make_qstorno_1000_1200 5368 5368 0 0.00 2 77322 1210 0 0
x_make_qstorno_1200_2000 5255 5255 0 0.00 3 104383 3580 0 0
x_make_qstorno_2100_3300 445647 445647 0 0.00 0 102342 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-10-2020 10:28:17 to 20-10-2020 10:28:21.

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
07:56:58 2 8192000000 8796624000 8807108608 3156752 0.038 1 1 2 0 3254112 61504 35856
07:57:59 23481 8192000000 9721030880 10017742848 794444480 8.840 100 87 175 0 823489504 7587936 21457088
07:59:28 19642 8192000000 9762170064 10071388160 839680880 9.297 100 82 163 2 871359504 12496960 19181664
08:00:52 18406 8192000000 9798208960 10112757760 868397536 9.584 100 90 180 0 906443856 17434096 20612224
08:02:13 9812 8192000000 9854331936 10186874880 908530256 9.983 100 78 156 3 968382480 42836576 17015648
08:03:26 10328 8192000000 9910369648 10252431360 939328448 10.286 100 78 154 0 1017422784 62125056 15969280
08:04:42 3884 8192000000 9949959456 10301616128 983289744 10.716 100 79 158 0 1060251104 60777136 16184224
08:05:49 3870 8192000000 10000516096 10363805696 1007395648 10.950 100 83 164 1 1112187760 88479680 16312432
08:06:58 4870 8192000000 10008660992 10378477568 1021097680 11.083 100 66 130 1 1125065760 90889664 13078416
08:08:09 4019 8192000000 9991143424 10364801024 1041539664 11.279 100 68 136 3 1105849632 50304592 14005376
08:09:18 3253 8192000000 10012838304 10387361792 1056565776 11.424 100 76 152 0 1125390784 53837232 14987776
08:10:22 2907 8192000000 10030380176 10400399360 1068928704 11.542 100 79 156 0 1139144928 54699056 15517168
08:11:25 5613 8192000000 9984144272 10393010176 1070272400 11.555 100 22 44 2 1114840640 40235760 4332480
08:12:34 8914 8192000000 9985463472 10384818176 1080604928 11.653 100 23 45 1 1109974272 25075392 4293952
08:13:43 9655 8192000000 9987198688 10403549184 1087222368 11.716 100 25 50 1 1111075552 18475344 5377840
08:14:56 67952 8192000000 9997250352 10417213440 1093673280 11.778 100 40 77 2 1116597968 14467792 8456896
08:17:07 6223 8192000000 10021609568 10428346368 1094900464 11.789 100 33 65 1 1134812464 33256304 6655696
08:18:13 2618 8192000000 10028414784 10416775168 1103495760 11.871 100 60 120 2 1127834960 11441104 12898096
08:19:17 6029 8192000000 10009216400 10404261888 1086884128 11.713 100 33 63 3 1118156608 24700080 6572400
08:20:24 54887 8192000000 9947930288 10348552192 950859056 10.400 88 18 35 1 963114624 8869968 3385600
08:22:26 7590 8192000000 9910017712 10295656448 981745120 10.701 97 36 72 3 1020138976 30746192 7647664
08:23:36 8860 8192000000 9928605616 10304036864 1006009472 10.937 99 37 72 1 1025341760 10671552 8660736
08:24:47 66743 8192000000 9951981136 10345881600 1036746304 11.233 100 20 40 0 1064860416 24226128 3887984
08:26:56 28258 8192000000 9977349264 10367983616 1066158208 11.515 100 36 70 1 1090150336 16351808 7640320
08:28:28 3502 8192000000 10030412816 10422833152 1082533312 11.672 100 68 135 2 1143060400 45216384 15310704
08:29:35 21568 8192000000 10076971456 10477383680 1107757344 11.911 100 61 120 2 1186653200 65806752 13089104
08:31:05 10178 8192000000 10082819136 10478743552 1124856000 12.073 100 49 98 0 1194833264 59528256 10449008
08:32:19 5141 8192000000 10143066640 10535575552 1134938880 12.168 100 60 115 1 1234360016 86350656 13070480
08:33:29 4754 8192000000 10184087600 10585370624 1145152304 12.264 100 65 126 1 1285701184 127127984 13420896
08:34:40 7696 8192000000 10218922960 10618228736 1154854864 12.355 100 65 130 3 1324502048 155982336 13664848
08:35:55 7987 8192000000 10290225776 10690826240 1161812464 12.420 100 70 137 3 1391298240 214558272 14927504
08:37:09 2281 8192000000 10311133152 10707529728 1166275296 12.462 100 87 173 0 1412027472 226723072 19029104
08:38:17 5201 8192000000 10324715504 10742493184 1171198752 12.508 100 71 137 1 1437905440 250664704 16041984
08:39:27 4560 8192000000 10361214800 10768527360 1177384800 12.566 100 86 168 0 1459126048 262515424 19225824
08:40:39 4705 8192000000 10399620080 10806153216 1192808912 12.709 100 79 157 0 1498241328 286273120 19159296
08:41:49 3108 8192000000 10420128016 10826260480 1192453888 12.706 100 83 160 2 1517386640 306731792 18200960
08:42:56 1957 8192000000 10470306368 10877566976 1179760128 12.588 99 81 161 1 1563379088 364417008 19201952
08:44:07 8365 8192000000 10435748400 10867101696 1191509680 12.697 100 72 144 0 1546392320 337596080 17286560
08:45:20 8357 8192000000 10480546160 10910728192 1197114112 12.750 100 68 136 0 1571099168 357037312 16947744
08:46:35 15682 8192000000 10478511072 10919145472 1177164704 12.564 98 40 80 1 1555218112 367599024 10454384
08:48:04 14576 8192000000 10431100528 10883354624 1179400192 12.585 100 32 64 0 1515955888 328361200 8194496
08:49:53 32048 8192000000 10358499136 10873839616 1140599856 12.221 99 25 50 0 1505194048 357764128 6830064
08:51:40 10659 8192000000 10238485600 10680369152 1130755952 12.128 99 31 62 0 1336526096 197691680 8078464
08:52:53 24880 8192000000 10106759888 10513829888 1120858144 12.035 99 66 130 2 1190765072 55956176 13950752
08:54:25 101964 8192000000 10094694016 10519666688 1074421440 11.594 96 28 55 2 1180335840 100117760 5796640
08:57:19 69606 8192000000 10031186272 10471403520 1098060640 11.819 97 30 59 3 1121180368 17186048 5933680
08:59:29 35816 8192000000 10039837568 10478399488 1117732080 12.006 99 10 21 0 1149191712 29854544 1605088
09:01:08 102329 8192000000 10085992544 10527199232 1107753072 11.911 97 19 37 0 1169717440 57961872 4002496
09:03:54 74146 8192000000 10062160928 10535948288 1095495520 11.795 97 24 47 3 1140837760 40515568 4826672
09:06:43 49507 8192000000 10044164224 10523709440 1094900240 11.789 97 14 28 0 1149130208 51590368 2639600
09:08:47 47707 8192000000 10081347632 10521501696 1107442816 11.908 98 18 36 1 1167756176 56694176 3619184
09:10:35 55789 8192000000 10099519520 10547531776 1106780736 11.902 98 22 43 0 1205966208 94537968 4647504
09:12:32 91422 8192000000 10076172800 10581434368 1094447984 11.785 97 17 33 0 1164287248 66111344 3727920
09:15:14 56117 8192000000 10092543936 10553966592 1127897792 12.102 100 29 58 1 1199908320 66122912 5887616
09:18:23 78868 8192000000 10063857104 10541129728 1113949488 11.970 99 13 26 1 1202778304 86111744 2717072
09:20:46 85570 8192000000 10098412928 10545090560 1132524560 12.145 100 23 46 2 1219758384 82837072 4396752
09:23:17 40156 8192000000 10126336656 10577756160 1133746800 12.157 100 10 20 0 1220252240 84361936 2143504
09:25:07 34161 8192000000 10084934064 10535735296 1142299728 12.237 100 7 14 0 1193718992 49799152 1620112
09:26:48 88693 8192000000 10085986560 10561458176 1131748784 12.138 98 16 32 1 1165293936 30371440 3173712
09:29:18 60585 8192000000 10102875904 10537050112 1152401488 12.332 100 17 35 2 1182383360 26774896 3206976
09:31:23 57063 8192000000 10104000416 10548527104 1118139264 12.009 97 19 38 0 1164693296 43109280 3444752
09:33:21 61550 8192000000 10099172464 10557415424 1099370544 11.832 95 22 44 0 1152517552 49007152 4139856
09:35:27 51366 8192000000 10160749984 10627883008 1127050288 12.094 98 37 72 1 1202214560 67845616 7318656
09:38:15 40557 8192000000 10113559584 10572214272 1107245360 11.906 98 25 48 1 1172057152 60112368 4699424
09:40:04 153181 8192000000 10079203024 10539933696 1114067456 11.971 99 16 32 0 1186654480 69088864 3498160
09:43:50 44941 8192000000 10059978576 10539442176 1123373232 12.059 100 8 16 0 1163454320 38243104 1837984
09:47:14 33008 8192000000 10074185904 10504634368 1135181168 12.170 100 24 50 2 1178674416 38362320 5130928
09:48:57 70437 8192000000 10123112608 10598219776 1125098112 12.075 99 16 32 0 1201222160 72817760 3306288
09:51:15 55259 8192000000 10100891200 10544435200 1095009728 11.790 98 31 61 0 1171414368 69731104 6673536
09:53:37 57384 8192000000 10097898400 10519740416 1101743984 11.854 98 28 56 1 1158756672 50814064 6198624
09:55:39 95100 8192000000 10089012320 10531319808 1110983072 11.942 99 16 32 0 1157895376 43711824 3200480
09:58:15 73944 8192000000 10040062048 10505523200 1112297328 11.954 99 15 30 1 1151890704 37018272 2575104
10:00:29 37174 8192000000 10082480480 10567503872 1117692400 12.005 100 6 12 0 1169088320 50377312 1018608
10:02:08 72195 8192000000 10120612928 10550202368 1103946480 11.875 97 30 60 2 1181934224 71551136 6436608
10:04:25 53083 8192000000 10098950624 10540879872 1121118240 12.038 99 23 46 1 1176424096 49997760 5308096
10:06:23 52824 8192000000 10078000096 10551357440 1123207664 12.057 99 17 35 0 1171333056 44404624 3720768
10:08:25 49627 8192000000 10085682160 10527657984 1127613280 12.099 99 14 28 0 1177249600 46826896 2809424
10:10:19 29472 8192000000 10143184752 10575400960 1145404176 12.266 100 16 32 0 1227723248 78927904 3391168
10:11:55 81560 8192000000 10102153440 10569961472 1127779664 12.100 99 19 38 0 1200563904 69155984 3628256
10:14:23 89413 8192000000 10090643888 10548129792 1138678096 12.203 99 23 46 1 1208900432 65733536 4488800
10:18:09 61062 8192000000 10062920896 10512367616 1123977840 12.065 98 10 23 0 1142239280 16731712 1529728
10:20:16 62377 8192000000 10096927152 10559041536 1148745824 12.298 100 9 18 0 1197896368 47202528 1948016
10:22:18 66339 8192000000 10083019744 10530373632 1105178560 11.887 96 23 46 0 1168176560 57983376 5014624
10:24:36 33826 8192000000 10129487664 10584952832 1152227024 12.330 100 28 56 0 1226673984 67476256 6970704
10:26:12 49109 8192000000 9383402272 9706786816 478295744 5.516 42 16 31 3 549111808 67566016 3250048

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 20-10-2020 10:28:23 to 20-10-2020 10:28:37.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 122 v_add_invoice_to_stock 335544517
user_exc 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 20-10-2020 10:28:38 to 20-10-2020 10:28:40.

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          217464
MON$OLDEST_ACTIVE               223612
MON$OLDEST_SNAPSHOT             223612
MON$NEXT_TRANSACTION            223612
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                       2061088
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             759
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2228 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2228 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2228 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.2228 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 ---
:735: 
:736: mscs-test-161.budzdorov.ru    Tue Oct 20 10:27:06 2020
:737:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:738: 
:739: 
:740: mscs-test-161.budzdorov.ru    Tue Oct 20 10:27:07 2020
:741:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:742: 
:743: 
:744: mscs-test-161.budzdorov.ru    Tue Oct 20 10:27:18 2020
:745:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:746: 
--- end of diff output ---

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

20.10.20 10:28:41. 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-20 10:28:44 No severe PSQL-related problems occured
20.10.20 10:28:45. End of report.