Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.10.2020 13:29
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 16655388 kB
MemAvailable 31565304 kB
Buffers 0 kB
Cached 15054260 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 13:26:51
DTS_END                         2020-10-26 13:26:51.5770
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/44486
TRN_ID                          234056
ATT_ID                          590
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: 9231.22 18208 1107746
customer order: creation 169.78 398 20373
customer order: refuse 14.77 307 1772
order to supplier: creation 44.96 1430 5395
order to supplier: removal 3.34 108926 401
invoice (draft): creation 45.13 3510 5415
invoice (draft): removal 52.37 822 6284
invoice accept: apply 42.03 17278 5044
invoice accept: cancel 47.17 761 5660
customer reserve: creation 3888.02 169 466562
customer reserve: removal 4641.56 63 556987
realization accept: apply 79.93 181 9591
realization accept: cancel 78.39 120 9407
payment from customer: creation 58.13 130 6976
payment from customer: removal 5.73 76 687
payment to supplier: creation 46.18 143 5541
payment to supplier: removal 3.47 104 416
service: total inventory turnovers 3.76 3283 451
service: total monetary turnovers 5.68 538 682
service: aggregate perf. data 0.82 16690 98
service: refresh index statistics 0.03 209226 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 26-10-2020 13:29:30 to 26-10-2020 13:29:32.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4201
TEST_TIME 2 5582
TEST_TIME 3 5413
TEST_TIME 4 4999
TEST_TIME 5 5351
TEST_TIME 6 5975
TEST_TIME 7 6001
TEST_TIME 8 6430
TEST_TIME 9 6385
TEST_TIME 10 6498
TEST_TIME 11 6452
TEST_TIME 12 6397
TEST_TIME 13 6751
TEST_TIME 14 7048
TEST_TIME 15 7034
TEST_TIME 16 7105
TEST_TIME 17 7092
TEST_TIME 18 7829
TEST_TIME 19 7991
TEST_TIME 20 8099
TEST_TIME 21 8222
TEST_TIME 22 8786
TEST_TIME 23 8826
TEST_TIME 24 8903
TEST_TIME 25 9349
TEST_TIME 26 9314
TEST_TIME 27 9862
TEST_TIME 28 10179
TEST_TIME 29 10700
TEST_TIME 30 10573
TEST_TIME 31 10561
TEST_TIME 32 10526
TEST_TIME 33 10559
TEST_TIME 34 10436
TEST_TIME 35 10410
TEST_TIME 36 10542
TEST_TIME 37 10480
TEST_TIME 38 10463
TEST_TIME 39 10288
TEST_TIME 40 10043
TEST_TIME 41 9969
TEST_TIME 42 9819
TEST_TIME 43 9833
TEST_TIME 44 9766
TEST_TIME 45 9806
TEST_TIME 46 9793
TEST_TIME 47 9678
TEST_TIME 48 9581
TEST_TIME 49 9470
TEST_TIME 50 9454
TEST_TIME 51 9625
TEST_TIME 52 9634
TEST_TIME 53 9599
TEST_TIME 54 9641
TEST_TIME 55 9649
TEST_TIME 56 9607
TEST_TIME 57 9637
TEST_TIME 58 9627
TEST_TIME 59 9626
TEST_TIME 60 9854
TEST_TIME 61 9819
TEST_TIME 62 9836
TEST_TIME 63 9859
TEST_TIME 64 9731
TEST_TIME 65 9656
TEST_TIME 66 9579
TEST_TIME 67 9503
TEST_TIME 68 9493
TEST_TIME 69 9439
TEST_TIME 70 9446
TEST_TIME 71 9407
TEST_TIME 72 9404
TEST_TIME 73 9403
TEST_TIME 74 9417
TEST_TIME 75 9455
TEST_TIME 76 9457
TEST_TIME 77 9440
TEST_TIME 78 9463
TEST_TIME 79 9465
TEST_TIME 80 9562
TEST_TIME 81 9491
TEST_TIME 82 9383
TEST_TIME 83 9342
TEST_TIME 84 9279
TEST_TIME 85 9255
TEST_TIME 86 9251
TEST_TIME 87 9243
TEST_TIME 88 9240
TEST_TIME 89 9229
TEST_TIME 90 9190
TEST_TIME 91 9226
TEST_TIME 92 9201
TEST_TIME 93 9216
TEST_TIME 94 9249
TEST_TIME 95 9336
TEST_TIME 96 9334
TEST_TIME 97 9351
TEST_TIME 98 9395
TEST_TIME 99 9447
TEST_TIME 100 9414
TEST_TIME 101 9384
TEST_TIME 102 9313
TEST_TIME 103 9272
TEST_TIME 104 9246
TEST_TIME 105 9189
TEST_TIME 106 9148
TEST_TIME 107 9150
TEST_TIME 108 9129
TEST_TIME 109 9128
TEST_TIME 110 9150
TEST_TIME 111 9194
TEST_TIME 112 9157
TEST_TIME 113 9141
TEST_TIME 114 9172
TEST_TIME 115 9140
TEST_TIME 116 9192
TEST_TIME 117 9334
TEST_TIME 118 9353
TEST_TIME 119 9301
TEST_TIME 120 9235

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 26-10-2020 13:29:34 to 26-10-2020 13:29:37.

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 1538086 1538086 0 0.00 0 27380 6 0 0
doc_list_biud 1538084 1538084 0 0.00 0 64755 29 0 0
sp_add_invoice_to_stock 5174 5044 130 2.51 32 148855 17279 0 130
sp_cancel_adding_invoice 5660 5660 0 0.00 4 49614 761 0 0
sp_cancel_client_order 1772 1772 0 0.00 5 8495 307 0 0
sp_cancel_customer_reserve 556987 556987 0 0.00 1 64756 63 0 0
sp_cancel_pay_from_customer 687 687 0 0.00 3 4873 77 0 0
sp_cancel_pay_to_supplier 416 416 0 0.00 3 5001 104 0 0
sp_cancel_supplier_invoice 6292 6284 8 0.12 2 58553 822 0 8
sp_cancel_supplier_order 401 401 0 0.00 11 989444 108927 0 0
sp_cancel_write_off 9407 9407 0 0.00 1 15476 121 0 0
sp_client_order 20373 20373 0 0.00 4 17546 398 0 0
sp_customer_reserve 466562 466562 0 0.00 0 72176 170 0 0
sp_fill_shopping_cart 31170 31170 0 0.00 1 19896 44 0 0
sp_fill_shopping_cart_clo_res 466554 466554 0 0.00 0 20778 18 0 0
sp_get_clo_for_invoice 5036 5036 0 0.00 0 23356 388 0 0
sp_kill_cost_storno 16170 16170 0 0.00 0 5800 52 0 0
sp_kill_qstorno_ret_qs2qd 1130066 1130066 0 0.00 0 64741 34 0 0
sp_kill_qty_storno 595137 595137 0 0.00 0 64754 73 0 0
sp_lock_selected_doc 20316 20316 0 0.00 0 7833 4 0 0
sp_make_cost_storno 27144 27144 0 0.00 0 20142 64 0 0
sp_multiply_rows_for_pdistr 27143 27143 0 0.00 0 10544 32 0 0
sp_multiply_rows_for_qdistr 20373 20373 0 0.00 0 16059 218 0 0
sp_pay_from_customer 6976 6976 0 0.00 4 17133 130 0 0
sp_pay_to_supplier 5541 5541 0 0.00 5 17813 144 0 0
sp_payment_common 12517 12517 0 0.00 1 16997 31 0 0
sp_qd_handle_on_cancel_clo 1772 1772 0 0.00 0 8488 284 0 0
sp_qd_handle_on_invoice_upd_sts 10695 10695 0 0.00 0 21641 342 0 0
sp_qd_handle_on_reserve_upd_sts 9591 9591 0 0.00 0 25839 48 0 0
sp_reserve_write_off 9591 9591 0 0.00 7 28035 181 0 0
sp_supplier_invoice 5415 5415 0 0.00 6 57690 3510 0 0
sp_supplier_order 5395 5395 0 0.00 6 101626 1430 0 0
srv_aggregate_perf_data 98 98 0 0.00 2235 180433 16691 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1284 154254 48827 0 0
srv_find_qd_qs_mism 1059811 1059811 0 0.00 0 27379 5 0 0
srv_make_invnt_saldo 451 451 0 0.00 32 96370 3284 0 0
srv_make_money_saldo 682 682 0 0.00 1 16806 539 0 0
srv_recalc_idx_stat 4 4 0 0.00 57780 335437 209226 0 0
t$perf-abend:sp_add_invoice_to_stock 130 130 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 5044 5044 0 0.00 0 10001 167 0 0
t$perf-norm:sp_cancel_adding_invoice 377 377 0 0.00 0 6996 113 0 0
t$perf-norm:sp_cancel_client_order 1772 1772 0 0.00 0 3294 14 0 0
t$perf-norm:sp_cancel_customer_reserve 710 710 0 0.00 0 1585 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 687 687 0 0.00 0 867 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 416 416 0 0.00 0 235 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 392 392 0 0.00 0 506 11 0 0
t$perf-norm:sp_cancel_supplier_order 401 401 0 0.00 0 21331 1316 0 0
t$perf-norm:sp_cancel_write_off 937 937 0 0.00 0 2270 6 0 0
t$perf-norm:sp_client_order 20373 20373 0 0.00 0 7171 17 0 0
t$perf-norm:sp_customer_reserve 1044 1044 0 0.00 0 2145 6 0 0
t$perf-norm:sp_pay_from_customer 6976 6976 0 0.00 0 7758 9 0 0
t$perf-norm:sp_pay_to_supplier 5541 5541 0 0.00 0 4411 9 0 0
t$perf-norm:sp_reserve_write_off 9591 9591 0 0.00 0 7242 8 0 0
t$perf-norm:sp_supplier_invoice 5415 5415 0 0.00 0 6997 17 0 0
t$perf-norm:sp_supplier_order 5395 5395 0 0.00 0 7171 15 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 1011 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 40 2 0 0
t$perf-norm:srv_make_invnt_saldo 451 451 0 0.00 0 714 6 0 0
t$perf-norm:srv_make_money_saldo 682 682 0 0.00 0 2698 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 3 0 0
v_add_invoice_to_stock 10326 10066 260 2.51 0 7151 19 0 260
v_all_customers 37378 37378 0 0.00 0 103 1 0 0
v_all_suppliers 29024 29024 0 0.00 0 14 1 0 0
v_all_wares 40744 40744 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 754 754 0 0.00 0 3236 10 0 0
v_cancel_client_order 3544 3544 0 0.00 0 2962 5 0 0
v_cancel_customer_prepayment 1374 1374 0 0.00 0 677 4 0 0
v_cancel_customer_reserve 1420 1420 0 0.00 0 180 3 0 0
v_cancel_payment_to_supplier 832 832 0 0.00 0 4986 16 0 0
v_cancel_supplier_invoice 800 784 16 2.00 0 2773 21 0 16
v_cancel_supplier_order 802 802 0 0.00 0 3195 9 0 0
v_cancel_write_off 1874 1874 0 0.00 0 6591 18 0 0
v_max_id_clo_ord 5391 5391 0 0.00 0 423 1 0 0
v_max_id_clo_res 1044 1044 0 0.00 0 2217 15 0 0
v_max_id_ord_sup 5407 5407 0 0.00 0 2619 4 0 0
v_max_non_paid_invoice 1827 1827 0 0.00 0 4080 6 0 0
v_max_non_paid_realizn 3670 3670 0 0.00 0 5445 6 0 0
v_min_id_clo_ord 5391 5391 0 0.00 0 13424 15 0 0
v_min_id_clo_res 1044 1044 0 0.00 0 2971 7 0 0
v_min_id_ord_sup 5407 5407 0 0.00 0 4869 7 0 0
v_min_non_paid_invoice 5541 5541 0 0.00 0 12967 20 0 0
v_min_non_paid_realizn 6976 6976 0 0.00 0 9013 19 0 0
v_our_firm 9978 9978 0 0.00 0 14 1 0 0
v_reserve_write_off 19182 19182 0 0.00 0 3437 6 0 0
x_lock_depdocs_on_canc_invoice 11552 11552 0 0.00 0 17259 102 0 0
x_lock_depdocs_on_canc_sup_ord 401 401 0 0.00 0 27409 406 0 0
x_make_qstorno_1000_1200 5395 5395 0 0.00 2 101591 1344 0 0
x_make_qstorno_1200_2000 5415 5415 0 0.00 3 57656 3344 0 0
x_make_qstorno_2100_3300 466562 466562 0 0.00 0 68884 149 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-10-2020 13:29:38 to 26-10-2020 13:29:42.

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:55 2 8192000000 8796633056 8807174144 3163600 0.038 1 1 2 0 3261296 61840 35856
10:57:56 23309 8192000000 9721226080 10020872192 799709056 8.893 100 86 172 0 829017280 8159312 21148912
10:59:27 18589 8192000000 9768458864 10076835840 836172976 9.261 100 93 186 0 875763744 17263904 22326864
11:01:00 15583 8192000000 9816448384 10135822336 880386720 9.704 100 88 176 2 923575184 22797760 20390704
11:02:18 14514 8192000000 9885618912 10219405312 916330544 10.060 100 83 164 0 993089344 58896736 17862064
11:03:38 7955 8192000000 9921199328 10267308032 956933232 10.459 100 76 152 0 1027300976 54750384 15617360
11:04:50 2571 8192000000 9980779584 10334822400 1003416224 10.912 100 85 170 2 1090866224 70136016 17313984
11:05:54 2143 8192000000 10010435344 10375299072 1025360960 11.124 100 86 172 0 1127996720 85243680 17392080
11:06:58 2118 8192000000 10035487168 10407911424 1058236256 11.440 100 85 166 2 1155453792 79440112 17777424
11:08:00 34199 8192000000 10014377680 10397601792 1065748432 11.511 100 64 127 2 1120868144 41252448 13867264
11:09:35 8347 8192000000 9990561744 10387316736 1073138880 11.582 100 39 76 1 1100956816 19372656 8445280
11:10:44 7679 8192000000 9980975024 10387816448 1078399920 11.632 100 25 50 3 1102534432 18708912 5425600
11:11:52 14279 8192000000 9982674032 10397327360 1087156304 11.716 100 29 57 2 1111628320 18392016 6080000
11:13:07 19702 8192000000 10015684480 10443083776 1091473024 11.757 100 40 79 2 1138050656 38261824 8315808
11:14:27 9170 8192000000 10026619104 10455240704 1094176416 11.782 100 29 58 0 1142055648 41937152 5942080
11:15:36 7850 8192000000 10007874288 10404241408 1084669632 11.692 99 44 86 1 1118567872 24375776 9522464
11:16:46 8171 8192000000 10004410112 10405724160 1067027792 11.524 99 37 74 4 1095725792 20155872 8542128
11:18:03 63361 8192000000 9937364592 10337951744 995980112 10.840 95 32 63 3 1025303344 22461856 6861376
11:20:06 9978 8192000000 9940247728 10330099712 1001368576 10.892 97 24 48 1 1023517152 16882672 5265904
11:21:18 10768 8192000000 9931020864 10321575936 1002181472 10.900 98 25 50 0 1033915888 26318272 5416144
11:22:31 9268 8192000000 9961370224 10351222784 1031938928 11.187 100 48 95 1 1058788832 16368272 10481632
11:23:41 60563 8192000000 9958616768 10351132672 1048310496 11.344 100 35 70 0 1078285696 22393040 7582160
11:25:41 4222 8192000000 9981230560 10364026880 1065267744 11.507 100 56 108 4 1094318640 16869744 12181152
11:26:47 12310 8192000000 10008146992 10398855168 1083184416 11.678 100 26 53 1 1107841056 19300128 5356512
11:28:06 19876 8192000000 10067235456 10463154176 1101474080 11.852 100 50 100 3 1167463872 54729104 11260688
11:29:28 49737 8192000000 10068598688 10476027904 1111134400 11.943 100 34 68 1 1177084928 57773408 8177120
11:31:23 10797 8192000000 10112145312 10509004800 1120931424 12.036 100 59 118 1 1227200896 93921232 12348240
11:32:37 7724 8192000000 10111759008 10540314624 1130718624 12.128 100 46 92 1 1229122720 88424304 9979792
11:33:48 9336 8192000000 10165563200 10572300288 1150545152 12.315 100 66 131 1 1266962752 102775088 13642512
11:35:03 8793 8192000000 10152263968 10556018688 1145045168 12.263 99 54 108 2 1262163328 105480368 11637792
11:36:15 5946 8192000000 10201046208 10613186560 1163078592 12.432 100 51 101 4 1300652816 126080384 11493840
11:37:27 3548 8192000000 10229435632 10633383936 1166436800 12.464 100 54 107 0 1321412800 143123584 11852416
11:38:32 3379 8192000000 10249493664 10657132544 1160503696 12.408 100 62 124 3 1354158944 179465024 14190224
11:39:44 3378 8192000000 10272940768 10674032640 1164118624 12.442 100 71 140 0 1370033792 190674128 15241040
11:40:51 4959 8192000000 10259780960 10675294208 1153196240 12.339 99 42 84 1 1351843328 189138704 9508384
11:42:02 4821 8192000000 10288601168 10695544832 1149958256 12.309 100 69 137 1 1385676416 220182544 15535616
11:43:18 1298 8192000000 10312934928 10720247808 1159243856 12.396 100 80 159 2 1413781392 236483488 18054048
11:44:22 2050 8192000000 10371652368 10790191104 1162734992 12.429 100 76 152 0 1479672272 299007216 17930064
11:45:32 2565 8192000000 10310871376 10710437888 1136546560 12.183 99 68 129 3 1403641472 251583184 15511728
11:46:37 1278 8192000000 10345034832 10748104704 1141541664 12.230 99 76 146 1 1439456672 281768528 16146480
11:47:42 3701 8192000000 10358684240 10767101952 1142442048 12.238 100 61 119 3 1457677920 301009312 14226560
11:48:51 13512 8192000000 10342050112 10799144960 1144555376 12.258 100 37 75 2 1447487344 293383680 9548288
11:50:30 17319 8192000000 10373460064 10816651264 1123635488 12.061 99 39 76 2 1466555760 332939488 9980784
11:51:53 50375 8192000000 10297689296 10715516928 1120976928 12.036 100 24 47 1 1403028512 276268016 5783568
11:53:48 18521 8192000000 10201191904 10687619072 1112258304 11.954 100 17 34 3 1324745408 208272816 4214288
11:55:08 58102 8192000000 10053875712 10486439936 1118849008 12.016 100 15 30 0 1174419872 52461856 3109008
11:57:08 90559 8192000000 10083404880 10500947968 1093737520 11.778 96 26 51 1 1128210608 29319552 5153536
11:59:38 95004 8192000000 10091106448 10514010112 1119519088 12.022 99 25 50 1 1150358912 25312512 5527312
12:02:17 76476 8192000000 10089812304 10568204288 1141914880 12.234 100 10 20 0 1205110544 61091936 2103728
12:04:37 154253 8192000000 10068892960 10504511488 1124662448 12.071 99 28 54 0 1177354016 48046752 4644816
12:08:23 37232 8192000000 10133675152 10568417280 1128408736 12.106 98 33 66 0 1194791328 59005504 7377088
12:10:15 53073 8192000000 10111481520 10601820160 1131013584 12.131 99 14 28 0 1213151168 78315824 3821760
12:12:08 54581 8192000000 10110453104 10558193664 1138022240 12.197 99 8 16 0 1183201824 43517360 1662224
12:14:07 41111 8192000000 10161080752 10612506624 1148741888 12.298 100 22 44 3 1243604768 89981376 4881504
12:15:52 63200 8192000000 10192527280 10678996992 1152269600 12.331 100 21 42 1 1288109856 131097312 4742944
12:17:55 75878 8192000000 10095898704 10547671040 1142246096 12.237 99 14 28 0 1205822896 60134256 3442544
12:20:12 61840 8192000000 10092568080 10543751168 1130371296 12.125 98 16 32 1 1168740640 35012752 3356592
12:22:17 11774 8192000000 10089172528 10508574720 1133773024 12.157 99 63 127 0 1171609680 24491776 13344880
12:23:35 66240 8192000000 10089310928 10563067904 1103302128 11.869 97 23 46 2 1148229792 40478832 4448832
12:25:49 87301 8192000000 10088362560 10585014272 1097200352 11.811 96 14 28 0 1160694864 60824704 2669808
12:28:20 43413 8192000000 10087387120 10575622144 1124481040 12.069 99 11 22 0 1197239728 70856448 1902240
12:31:57 67033 8192000000 10050176736 10502201344 1089138688 11.734 96 18 36 0 1142192448 49427360 3626400
12:34:09 48986 8192000000 10068258640 10547277824 1116686192 11.996 99 8 16 0 1161126320 42910656 1529472
12:36:06 114514 8192000000 10086683552 10575101952 1065346544 11.508 94 22 43 3 1139328592 69469472 4512576
12:39:05 54849 8192000000 10077236272 10526289920 1079908576 11.647 97 22 44 0 1149194080 64510704 4774800
12:41:01 67318 8192000000 10071080224 10522644480 1075105456 11.601 97 23 46 0 1150065872 70371376 4589040
12:43:13 50459 8192000000 10078708432 10503700480 1092406352 11.766 98 32 64 1 1164339632 65517056 6416224
12:45:05 14954 8192000000 10072769984 10475073536 1089378240 11.737 98 57 114 1 1151766064 50801824 11586000
12:46:20 153139 8192000000 10054803696 10496229376 1079638528 11.644 98 18 36 2 1120595040 37570304 3386208
12:49:56 106549 8192000000 10053934528 10512609280 1065087056 11.505 97 8 16 0 1138702720 72058640 1557024
12:53:14 81705 8192000000 10040780016 10491846656 1084955184 11.695 98 12 24 0 1137504560 49825232 2724144
12:55:39 45952 8192000000 10081752000 10500698112 1117860496 12.007 100 39 78 0 1176015584 49795856 8359232
12:57:26 88455 8192000000 10118761936 10562035712 1129879184 12.120 100 18 36 1 1200854640 67443760 3531696
13:00:06 57919 8192000000 10094522176 10607484928 1133431104 12.154 99 15 30 1 1202071536 65368352 3272080
13:02:19 51129 8192000000 10094063040 10552197120 1137249312 12.190 100 17 34 0 1186270544 45758752 3262480
13:04:24 124475 8192000000 10074416288 10547175424 1116423184 11.993 98 9 18 0 1161584512 43452512 1708816
13:07:30 111722 8192000000 10107806368 10560790528 1124625424 12.071 98 39 76 2 1176703232 44663008 7414800
13:10:22 39647 8192000000 10055199888 10542546944 1113265264 11.963 98 6 12 0 1172200320 57359136 1575920
13:12:08 71752 8192000000 10065486032 10535501824 1126831280 12.091 99 11 22 0 1160867520 31729440 2306800
13:14:22 52911 8192000000 10103650544 10548846592 1139093312 12.207 100 25 51 1 1219859968 74275136 6491520
13:16:16 37797 8192000000 10095775088 10536230912 1118245920 12.010 98 26 52 1 1190727184 66591152 5890112
13:17:59 33551 8192000000 10087726176 10518343680 1127359104 12.096 99 18 36 0 1154265856 22861840 4044912
13:19:44 73050 8192000000 10164360992 10646085632 1153128352 12.339 99 25 50 0 1262417024 103872896 5415776
13:21:57 74538 8192000000 10207311824 10703859712 1137995168 12.197 98 35 70 2 1299574352 154327872 7251312
13:24:12 79555 8192000000 10082850672 10566729728 1107816624 11.912 97 9 18 0 1147962752 38498640 1647488

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 26-10-2020 13:29:44 to 26-10-2020 13:29:59.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 130 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 26-10-2020 13:30:00 to 26-10-2020 13:30:02.

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          234358
MON$OLDEST_ACTIVE               234359
MON$OLDEST_SNAPSHOT             234359
MON$NEXT_TRANSACTION            234359
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                       2133504
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             774
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 ---
--- end of diff output ---

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

26.10.20 13:30:03. 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 13:30:05 No severe PSQL-related problems occured
26.10.20 13:30:07. End of report.