Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.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 16893624 kB
MemAvailable 31509456 kB
Buffers 0 kB
Cached 14758404 kB
SwapCached 3044 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.0
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2020-10-26 10:26:52
DTS_END                         2020-10-26 10:26:52.8690
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/42800
TRN_ID                          233014
ATT_ID                          638
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: 8994.08 20073 1079289
customer order: creation 169.09 418 20291
customer order: refuse 14.46 310 1735
order to supplier: creation 45.02 1303 5402
order to supplier: removal 3.45 115959 414
invoice (draft): creation 45.54 3104 5465
invoice (draft): removal 50.59 960 6071
invoice accept: apply 41.06 18903 4927
invoice accept: cancel 45.88 820 5505
customer reserve: creation 3788.37 186 454604
customer reserve: removal 4495.24 71 539429
realization accept: apply 81.13 171 9736
realization accept: cancel 89.01 111 10681
payment from customer: creation 57.63 113 6915
payment from customer: removal 5.64 91 677
payment to supplier: creation 47.28 126 5673
payment to supplier: removal 3.60 85 432
service: total inventory turnovers 3.79 3154 455
service: total monetary turnovers 6.22 508 746
service: aggregate perf. data 1.05 12434 126
service: refresh index statistics 0.04 242623 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 26-10-2020 10:27:55 to 26-10-2020 10:27:57.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5799
TEST_TIME 2 4158
TEST_TIME 3 5038
TEST_TIME 4 4824
TEST_TIME 5 4790
TEST_TIME 6 4547
TEST_TIME 7 4647
TEST_TIME 8 5088
TEST_TIME 9 5512
TEST_TIME 10 5795
TEST_TIME 11 5853
TEST_TIME 12 6151
TEST_TIME 13 6420
TEST_TIME 14 7054
TEST_TIME 15 7452
TEST_TIME 16 7855
TEST_TIME 17 8299
TEST_TIME 18 8305
TEST_TIME 19 8948
TEST_TIME 20 8756
TEST_TIME 21 8680
TEST_TIME 22 8784
TEST_TIME 23 8776
TEST_TIME 24 9413
TEST_TIME 25 9791
TEST_TIME 26 9942
TEST_TIME 27 10994
TEST_TIME 28 11182
TEST_TIME 29 11131
TEST_TIME 30 10878
TEST_TIME 31 10700
TEST_TIME 32 10715
TEST_TIME 33 10658
TEST_TIME 34 10532
TEST_TIME 35 10486
TEST_TIME 36 10379
TEST_TIME 37 10429
TEST_TIME 38 10253
TEST_TIME 39 10174
TEST_TIME 40 10013
TEST_TIME 41 9955
TEST_TIME 42 9990
TEST_TIME 43 9942
TEST_TIME 44 9872
TEST_TIME 45 9841
TEST_TIME 46 9788
TEST_TIME 47 9688
TEST_TIME 48 9702
TEST_TIME 49 10004
TEST_TIME 50 9928
TEST_TIME 51 9874
TEST_TIME 52 9991
TEST_TIME 53 10028
TEST_TIME 54 9976
TEST_TIME 55 10029
TEST_TIME 56 10031
TEST_TIME 57 9991
TEST_TIME 58 9847
TEST_TIME 59 9779
TEST_TIME 60 9688
TEST_TIME 61 9613
TEST_TIME 62 9556
TEST_TIME 63 9520
TEST_TIME 64 9584
TEST_TIME 65 9543
TEST_TIME 66 9543
TEST_TIME 67 9532
TEST_TIME 68 9505
TEST_TIME 69 9465
TEST_TIME 70 9487
TEST_TIME 71 9505
TEST_TIME 72 9676
TEST_TIME 73 9711
TEST_TIME 74 9729
TEST_TIME 75 9738
TEST_TIME 76 9624
TEST_TIME 77 9596
TEST_TIME 78 9512
TEST_TIME 79 9421
TEST_TIME 80 9379
TEST_TIME 81 9389
TEST_TIME 82 9427
TEST_TIME 83 9391
TEST_TIME 84 9351
TEST_TIME 85 9335
TEST_TIME 86 9324
TEST_TIME 87 9383
TEST_TIME 88 9323
TEST_TIME 89 9336
TEST_TIME 90 9335
TEST_TIME 91 9355
TEST_TIME 92 9275
TEST_TIME 93 9235
TEST_TIME 94 9201
TEST_TIME 95 9152
TEST_TIME 96 9149
TEST_TIME 97 9159
TEST_TIME 98 9117
TEST_TIME 99 9074
TEST_TIME 100 9158
TEST_TIME 101 9150
TEST_TIME 102 9140
TEST_TIME 103 9131
TEST_TIME 104 9105
TEST_TIME 105 9184
TEST_TIME 106 9215
TEST_TIME 107 9246
TEST_TIME 108 9225
TEST_TIME 109 9151
TEST_TIME 110 9115
TEST_TIME 111 9086
TEST_TIME 112 9084
TEST_TIME 113 9039
TEST_TIME 114 9014
TEST_TIME 115 9042
TEST_TIME 116 9043
TEST_TIME 117 9039
TEST_TIME 118 9008
TEST_TIME 119 9007
TEST_TIME 120 9000

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 26-10-2020 10:27:58 to 26-10-2020 10:28:02.

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 1501063 1501063 0 0.00 0 17499 7 0 0
doc_list_biud 1501062 1501062 0 0.00 0 90023 32 0 0
sp_add_invoice_to_stock 5017 4927 90 1.79 52 292761 18903 0 90
sp_cancel_adding_invoice 5505 5505 0 0.00 4 97060 820 0 0
sp_cancel_client_order 1735 1735 0 0.00 4 9137 311 0 0
sp_cancel_customer_reserve 539429 539429 0 0.00 1 78918 71 0 0
sp_cancel_pay_from_customer 677 677 0 0.00 3 5124 92 0 0
sp_cancel_pay_to_supplier 432 432 0 0.00 3 7077 86 0 0
sp_cancel_supplier_invoice 6077 6071 6 0.09 1 90040 961 0 6
sp_cancel_supplier_order 414 414 0 0.00 7 1192949 115959 0 0
sp_cancel_write_off 10681 10681 0 0.00 1 8728 112 0 0
sp_client_order 20291 20291 0 0.00 4 18425 419 0 0
sp_customer_reserve 454604 454604 0 0.00 0 86108 186 0 0
sp_fill_shopping_cart 31139 31139 0 0.00 1 16505 41 0 0
sp_fill_shopping_cart_clo_res 454593 454593 0 0.00 0 29946 23 0 0
sp_get_clo_for_invoice 4914 4914 0 0.00 0 40413 426 0 0
sp_kill_cost_storno 17295 17295 0 0.00 0 9684 53 0 0
sp_kill_qstorno_ret_qs2qd 1096024 1096024 0 0.00 0 90019 39 0 0
sp_kill_qty_storno 578484 578484 0 0.00 0 90022 82 0 0
sp_lock_selected_doc 20408 20408 0 0.00 0 6203 4 0 0
sp_make_cost_storno 27237 27237 0 0.00 0 15329 56 0 0
sp_multiply_rows_for_pdistr 27237 27237 0 0.00 0 9298 30 0 0
sp_multiply_rows_for_qdistr 20291 20291 0 0.00 0 12574 249 0 0
sp_pay_from_customer 6915 6915 0 0.00 4 12606 114 0 0
sp_pay_to_supplier 5673 5673 0 0.00 4 9962 126 0 0
sp_payment_common 12588 12588 0 0.00 1 8143 24 0 0
sp_qd_handle_on_cancel_clo 1735 1735 0 0.00 0 4776 279 0 0
sp_qd_handle_on_invoice_upd_sts 10418 10418 0 0.00 0 24428 349 0 0
sp_qd_handle_on_reserve_upd_sts 9736 9736 0 0.00 0 8156 47 0 0
sp_reserve_write_off 9736 9736 0 0.00 7 14236 171 0 0
sp_supplier_invoice 5465 5465 0 0.00 7 56837 3104 0 0
sp_supplier_order 5402 5402 0 0.00 5 97702 1303 0 0
srv_aggregate_perf_data 126 126 0 0.00 2644 107119 12434 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1335 117474 47858 0 0
srv_find_qd_qs_mism 1032939 1032939 0 0.00 0 17499 6 0 0
srv_make_invnt_saldo 455 455 0 0.00 44 91033 3155 0 0
srv_make_money_saldo 746 746 0 0.00 1 12622 508 0 0
srv_recalc_idx_stat 5 5 0 0.00 56930 359091 242623 0 0
t$perf-abend:sp_add_invoice_to_stock 90 90 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4927 4927 0 0.00 0 10364 144 0 0
t$perf-norm:sp_cancel_adding_invoice 409 409 0 0.00 0 4478 72 0 0
t$perf-norm:sp_cancel_client_order 1735 1735 0 0.00 0 5867 20 0 0
t$perf-norm:sp_cancel_customer_reserve 720 720 0 0.00 0 2342 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 677 677 0 0.00 0 378 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 432 432 0 0.00 0 1418 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 400 400 0 0.00 0 1539 12 0 0
t$perf-norm:sp_cancel_supplier_order 414 414 0 0.00 0 15987 1393 0 0
t$perf-norm:sp_cancel_write_off 972 972 0 0.00 0 1270 6 0 0
t$perf-norm:sp_client_order 20291 20291 0 0.00 0 6880 17 0 0
t$perf-norm:sp_customer_reserve 962 962 0 0.00 0 849 6 0 0
t$perf-norm:sp_pay_from_customer 6915 6915 0 0.00 0 4724 9 0 0
t$perf-norm:sp_pay_to_supplier 5673 5673 0 0.00 0 5504 7 0 0
t$perf-norm:sp_reserve_write_off 9736 9736 0 0.00 0 5716 9 0 0
t$perf-norm:sp_supplier_invoice 5465 5465 0 0.00 0 4214 16 0 0
t$perf-norm:sp_supplier_order 5402 5402 0 0.00 0 5564 13 0 0
t$perf-norm:srv_aggregate_perf_data 126 126 0 0.00 0 3113 33 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 53 1 0 0
t$perf-norm:srv_make_invnt_saldo 455 455 0 0.00 0 2840 17 0 0
t$perf-norm:srv_make_money_saldo 746 746 0 0.00 0 850 4 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 5 3 0 0
v_add_invoice_to_stock 10006 9826 180 1.79 0 6249 16 0 180
v_all_customers 37314 37314 0 0.00 0 14 1 0 0
v_all_suppliers 30008 30008 0 0.00 0 40 1 0 0
v_all_wares 40582 40582 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 818 818 0 0.00 0 2639 12 0 0
v_cancel_client_order 3470 3470 0 0.00 0 2258 5 0 0
v_cancel_customer_prepayment 1354 1354 0 0.00 0 3777 15 0 0
v_cancel_customer_reserve 1440 1440 0 0.00 0 4207 7 0 0
v_cancel_payment_to_supplier 864 864 0 0.00 0 802 5 0 0
v_cancel_supplier_invoice 812 800 12 1.47 0 1589 13 0 12
v_cancel_supplier_order 828 828 0 0.00 0 3035 13 0 0
v_cancel_write_off 1944 1944 0 0.00 0 2141 14 0 0
v_max_id_clo_ord 5392 5392 0 0.00 0 151 1 0 0
v_max_id_clo_res 962 962 0 0.00 0 2803 19 0 0
v_max_id_ord_sup 5456 5456 0 0.00 0 6141 5 0 0
v_max_non_paid_invoice 1517 1517 0 0.00 0 3090 4 0 0
v_max_non_paid_realizn 3516 3516 0 0.00 0 1210 3 0 0
v_min_id_clo_ord 5392 5392 0 0.00 0 3411 6 0 0
v_min_id_clo_res 962 962 0 0.00 0 8054 14 0 0
v_min_id_ord_sup 5456 5456 0 0.00 0 5875 6 0 0
v_min_non_paid_invoice 5673 5673 0 0.00 0 5791 13 0 0
v_min_non_paid_realizn 6915 6915 0 0.00 0 8140 21 0 0
v_our_firm 10066 10066 0 0.00 0 15 1 0 0
v_reserve_write_off 19472 19472 0 0.00 0 4210 7 0 0
x_lock_depdocs_on_canc_invoice 11176 11176 0 0.00 0 32192 109 0 0
x_lock_depdocs_on_canc_sup_ord 414 414 0 0.00 0 8516 321 0 0
x_make_qstorno_1000_1200 5402 5402 0 0.00 2 97688 1229 0 0
x_make_qstorno_1200_2000 5465 5465 0 0.00 4 56764 2950 0 0
x_make_qstorno_2100_3300 454604 454604 0 0.00 0 86107 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-10-2020 10:28:03 to 26-10-2020 10:28:07.

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:56 2 8192000000 8796633056 8807174144 3163600 0.038 1 1 2 0 3261296 61840 35856
07:57:58 22644 8192000000 9717079616 10016481280 795815632 8.854 100 83 166 0 823297184 7496144 19985408
07:59:25 23969 8192000000 9764310496 10071781376 834465184 9.244 100 89 178 0 868023824 12334272 21224368
08:00:52 23864 8192000000 9816595280 10136248320 871574656 9.616 100 87 174 0 922969712 31483232 19911824
08:02:19 15793 8192000000 9881692160 10217463808 923070384 10.126 100 78 154 2 988630416 48787728 16772304
08:03:38 7021 8192000000 9930097456 10279563264 964246096 10.531 100 77 154 2 1036575840 56108640 16221104
08:04:49 3365 8192000000 9984529840 10339758080 999095536 10.870 100 86 172 1 1101858864 85179376 17583952
08:05:57 2363 8192000000 9992176800 10358325248 1028335616 11.152 100 74 147 2 1098428608 56072624 14020368
08:07:01 1411 8192000000 10022693552 10395529216 1055388336 11.412 100 88 177 3 1137858352 64115552 18354464
08:08:05 24456 8192000000 10009385376 10391158784 1064347696 11.498 100 63 124 3 1121164736 44104528 12712512
08:09:30 2832 8192000000 9981155216 10381107200 1069208208 11.545 100 34 68 1 1098175872 21521504 7446160
08:10:34 20779 8192000000 9989976304 10379202560 1077863408 11.627 100 56 113 1 1101400976 10601712 12935856
08:11:56 6234 8192000000 9998296016 10401816576 1083150528 11.677 100 46 92 1 1123030304 31154032 8725744
08:13:05 5681 8192000000 10015911696 10400686080 1092657216 11.768 100 55 108 4 1118487696 13322832 12507648
08:14:13 8228 8192000000 9996330848 10402824192 1090967600 11.752 100 29 59 3 1124532736 27334160 6230976
08:15:22 65524 8192000000 9996515024 10409963520 1092510784 11.767 100 31 62 1 1140335264 41283200 6541280
08:17:29 9049 8192000000 10024882544 10430799872 1072485696 11.576 99 45 86 2 1121537504 39881632 9170176
08:18:40 6236 8192000000 9936558256 10307555328 978082176 10.666 93 40 80 2 1010281824 23443728 8755920
08:19:47 6167 8192000000 9950627888 10321494016 1010427872 10.980 97 30 60 1 1049893104 31977664 7487568
08:20:54 28185 8192000000 9917673840 10295611392 924906752 10.144 89 23 47 1 946131632 16279136 4945744
08:22:22 6197 8192000000 9945984960 10328047616 1019806864 11.070 99 52 104 2 1057305568 26858080 10640624
08:23:30 8798 8192000000 9973110832 10360672256 1035431296 11.221 99 31 62 1 1057141984 15124864 6585824
08:24:39 80352 8192000000 9963385536 10368131072 1057566944 11.433 100 26 51 4 1083191472 19689648 5934880
08:27:02 3906 8192000000 10013954608 10405793792 1085807680 11.703 100 60 118 0 1126965680 28926144 12231856
08:28:13 14779 8192000000 10050156208 10441043968 1101582368 11.853 100 54 107 2 1152494592 40326992 10585232
08:29:39 14084 8192000000 10078031712 10477768704 1122033744 12.046 100 45 89 2 1194194928 62674032 9487152
08:30:55 42173 8192000000 10116279168 10531684352 1131908176 12.139 100 29 58 1 1240893856 102326992 6658688
08:32:43 25240 8192000000 10169849840 10573393920 1148935936 12.300 100 44 88 2 1263069760 104188768 9945056
08:34:14 5688 8192000000 10226892592 10641743872 1164569152 12.446 100 58 116 2 1329558800 152127664 12861984
08:35:23 12267 8192000000 10246423328 10659176448 1170442640 12.501 100 61 122 3 1364254304 180155296 13656368
08:36:42 7357 8192000000 10288075712 10693541888 1166921648 12.468 100 59 118 1 1389873248 210227424 12724176
08:37:53 3274 8192000000 10299606496 10706063360 1170508624 12.502 100 74 148 1 1409452112 221004304 17939184
08:39:03 2577 8192000000 10293749488 10702626816 1165780624 12.457 100 71 141 2 1400062816 217738560 16543632
08:40:10 8713 8192000000 10308054880 10722590720 1154920576 12.356 100 72 139 1 1401479824 230566208 15993040
08:41:26 1335 8192000000 10316634192 10717020160 1154136240 12.348 100 86 172 1 1418698384 245320128 19242016
08:42:31 4356 8192000000 10292948160 10697711616 1143098640 12.245 99 53 106 0 1394684848 238759600 12826608
08:43:44 2763 8192000000 10297247024 10707324928 1138180096 12.198 100 79 154 1 1390730768 234183392 18367280
08:44:50 3827 8192000000 10330200736 10727981056 1139628976 12.212 100 87 174 1 1426144592 265899200 20616416
08:45:56 14853 8192000000 10288769872 10710413312 1126865824 12.092 98 31 62 0 1366655920 232064080 7726016
08:47:16 12150 8192000000 10370332816 10785214464 1143905488 12.252 100 38 76 1 1471270512 317751088 9613936
08:48:31 13397 8192000000 10395628480 10830946304 1110465296 11.937 98 37 74 0 1472806400 352101872 10239232
08:49:53 12001 8192000000 10386049376 10868240384 1119929040 12.026 100 37 74 1 1538945392 407990192 11026160
08:51:11 24122 8192000000 10308340336 10766405632 1115021184 11.980 100 22 44 2 1439667232 318292336 6353712
08:52:38 49119 8192000000 10238576512 10749329408 1094768320 11.788 99 14 28 0 1369977648 271203120 4006208
08:54:28 90387 8192000000 10085996272 10530787328 1107437776 11.908 99 17 34 2 1163933280 53298784 3196720
08:57:01 108085 8192000000 10127385072 10538807296 1094048368 11.781 98 39 77 3 1203160976 101150992 7961616
08:59:57 47690 8192000000 10081682736 10502897664 1107319968 11.907 98 50 100 1 1162494832 44985392 10189472
09:01:48 73207 8192000000 10100942560 10559381504 1108258352 11.916 98 27 55 0 1184572016 69928352 6385312
09:04:09 117472 8192000000 10088662048 10554769408 1111733904 11.949 98 17 34 0 1163917952 48567296 3616752
09:07:07 42992 8192000000 10100657520 10536509440 1105898912 11.894 98 15 31 0 1176697008 67089152 3708944
09:08:54 35557 8192000000 10092996960 10532143104 1133164576 12.151 100 12 24 0 1192611248 56924832 2521840
09:10:32 81503 8192000000 10067245504 10519269376 1121653856 12.043 99 11 22 1 1177195232 53778720 1762656
09:12:54 25207 8192000000 10122590800 10551615488 1145286304 12.265 100 39 79 1 1205129008 52064896 7777808
09:14:20 96500 8192000000 10099578912 10657894400 1138072672 12.197 99 13 26 1 1299098544 158010256 3015616
09:16:57 23166 8192000000 10125076544 10551799808 1153003904 12.338 100 26 50 1 1213437920 54506976 5927040
09:18:21 105620 8192000000 10089538576 10573885440 1159082528 12.395 100 12 24 1 1245364720 83652160 2630032
09:21:07 50408 8192000000 10115444656 10593411072 1134593216 12.165 98 17 34 2 1211305824 73243440 3469168
09:23:00 115954 8192000000 10081257376 10542870528 1130288672 12.124 99 28 54 2 1191247184 55390256 5568256
09:26:41 43486 8192000000 10065381920 10517700608 1129722032 12.119 99 8 16 0 1177548336 46191568 1634736
09:28:29 49958 8192000000 10099823632 10584395776 1138155808 12.198 100 10 21 0 1199092384 58945744 1990832
09:30:24 43178 8192000000 10072765200 10507571200 1081728240 11.664 95 23 44 1 1124580672 38297248 4555184
09:32:07 69567 8192000000 10087506128 10530963456 1109049168 11.923 98 25 50 0 1199963376 85393104 5521104
09:34:22 39667 8192000000 10128175872 10550927360 1116509264 11.994 98 30 60 3 1209922816 87529520 5884032
09:36:02 52911 8192000000 10209036320 10642722816 1133989376 12.159 99 28 56 1 1280889952 140815920 6084656
09:38:01 52640 8192000000 10127783824 10666487808 1137254144 12.190 100 16 33 0 1211277472 70803648 3219680
09:40:06 75250 8192000000 10086872608 10590875648 1101457456 11.851 97 21 42 2 1212949776 107005712 4486608
09:42:24 106127 8192000000 10043292688 10503356416 1105797984 11.893 98 16 32 1 1148060400 38984432 3277984
09:46:36 60403 8192000000 10077283632 10533199872 1097518608 11.814 98 16 32 2 1152827504 51989184 3319712
09:49:13 44455 8192000000 10032920800 10463047680 1066945680 11.523 96 28 54 4 1112038336 38892032 6200624
09:50:58 47531 8192000000 10089218736 10515222528 1102916928 11.865 99 40 80 2 1165099456 53071712 9110816
09:52:46 56999 8192000000 10063491280 10528800768 1115833744 11.988 100 22 44 0 1175953056 55402048 4717264
09:54:49 26010 8192000000 10080907280 10530693120 1110697424 11.939 99 34 68 1 1164794672 47258320 6838928
09:56:16 52162 8192000000 10102200592 10571526144 1128590720 12.108 100 35 70 1 1198046368 62847760 6607888
09:58:15 105992 8192000000 10076883840 10521374720 1105198400 11.887 98 41 82 0 1163772512 50468768 8105344
10:01:18 80980 8192000000 10120702256 10572730368 1142887376 12.243 100 14 28 0 1203634912 57757936 2989600
10:03:59 79488 8192000000 10116162112 10563010560 1134106880 12.160 99 15 30 0 1207077056 69821792 3148384
10:06:28 74507 8192000000 10074175552 10532298752 1111863760 11.950 98 14 28 1 1151768304 36977360 2927184
10:08:44 51864 8192000000 10107743840 10567507968 1134434800 12.163 100 16 32 0 1191143920 53476832 3232288
10:10:38 47456 8192000000 10082074736 10535772160 1118751600 12.015 98 23 45 0 1168461840 45620608 4089632
10:12:26 59841 8192000000 10107906752 10612453376 1126594032 12.089 99 11 22 0 1191099456 62259392 2246032
10:14:49 114241 8192000000 10072981264 10515632128 1115076560 11.980 99 35 70 5 1167985280 45879312 7029408
10:17:49 55993 8192000000 10082250912 10564464640 1129090080 12.113 100 8 16 0 1190557984 59858944 1608960
10:19:47 32063 8192000000 10082760320 10502414336 1092369504 11.765 97 36 72 0 1132886576 33002576 7514496
10:21:28 80810 8192000000 10067150800 10526793728 1106553184 11.900 98 17 33 1 1183001072 71919488 4528400
10:23:56 32876 8192000000 10074283584 10506211328 1106189696 11.896 98 16 32 2 1138258256 28974848 3093712
10:25:30 86790 8192000000 9580381376 9932009472 635922912 7.203 56 18 36 1 708602496 68863200 3816384

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

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

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

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          231341
MON$OLDEST_ACTIVE               233709
MON$OLDEST_SNAPSHOT             233709
MON$NEXT_TRANSACTION            233709
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                       2097536
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             775
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 ---
:83: 
:84: mscs-test-161.budzdorov.ru    Mon Oct 26 10:27:03 2020
:85:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:86: 
:87: 
:88: mscs-test-161.budzdorov.ru    Mon Oct 26 10:27:04 2020
:89:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:90: 
--- end of diff output ---

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

26.10.20 10:28:27. 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-26 10:28:30 No severe PSQL-related problems occured
26.10.20 10:28:31. End of report.