Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.01.2021 13:28
Common Performance Final Results
  1. Hardware and OS info
  2. 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 was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. 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 16635868 kB
MemAvailable 31277204 kB
Buffers 0 kB
Cached 14975948 kB
SwapCached 4864 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 2021-01-16 13:27:05
DTS_END                         2021-01-16 13:26:55.8550
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/53396
TRN_ID                          234349
ATT_ID                          259
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: 8947.80 24640 1073736
customer order: creation 167.32 444 20078
customer order: refuse 14.83 364 1780
order to supplier: creation 45.58 1389 5469
order to supplier: removal 3.24 131364 389
invoice (draft): creation 45.13 3578 5416
invoice (draft): removal 49.27 1024 5912
invoice accept: apply 40.88 19947 4906
invoice accept: cancel 44.35 1069 5322
customer reserve: creation 3848.18 190 461782
customer reserve: removal 4408.18 75 528982
realization accept: apply 82.08 199 9850
realization accept: cancel 73.85 154 8862
payment from customer: creation 57.93 111 6951
payment from customer: removal 5.66 128 679
payment to supplier: creation 47.19 131 5663
payment to supplier: removal 3.34 99 401
service: total inventory turnovers 3.74 3710 449
service: total monetary turnovers 6.11 698 733
service: aggregate perf. data 0.90 15487 108
service: refresh index statistics 0.03 312632 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-01-2021 13:28:30 to 16-01-2021 13:28:32.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1288
TEST_TIME 2 2847
TEST_TIME 3 3859
TEST_TIME 4 4281
TEST_TIME 5 3972
TEST_TIME 6 4279
TEST_TIME 7 4443
TEST_TIME 8 4394
TEST_TIME 9 4828
TEST_TIME 10 5498
TEST_TIME 11 5567
TEST_TIME 12 6160
TEST_TIME 13 6250
TEST_TIME 14 6508
TEST_TIME 15 6550
TEST_TIME 16 7098
TEST_TIME 17 7276
TEST_TIME 18 7182
TEST_TIME 19 7213
TEST_TIME 20 7348
TEST_TIME 21 7564
TEST_TIME 22 7737
TEST_TIME 23 8097
TEST_TIME 24 8032
TEST_TIME 25 8208
TEST_TIME 26 8326
TEST_TIME 27 8812
TEST_TIME 28 8947
TEST_TIME 29 9078
TEST_TIME 30 9495
TEST_TIME 31 10421
TEST_TIME 32 10861
TEST_TIME 33 10903
TEST_TIME 34 10829
TEST_TIME 35 10574
TEST_TIME 36 10402
TEST_TIME 37 10139
TEST_TIME 38 10130
TEST_TIME 39 10188
TEST_TIME 40 10010
TEST_TIME 41 10014
TEST_TIME 42 9938
TEST_TIME 43 9944
TEST_TIME 44 9826
TEST_TIME 45 9765
TEST_TIME 46 9729
TEST_TIME 47 9632
TEST_TIME 48 9683
TEST_TIME 49 9628
TEST_TIME 50 9583
TEST_TIME 51 9575
TEST_TIME 52 9578
TEST_TIME 53 9570
TEST_TIME 54 9600
TEST_TIME 55 9678
TEST_TIME 56 9700
TEST_TIME 57 9753
TEST_TIME 58 9827
TEST_TIME 59 9822
TEST_TIME 60 9764
TEST_TIME 61 9654
TEST_TIME 62 9577
TEST_TIME 63 9525
TEST_TIME 64 9465
TEST_TIME 65 9410
TEST_TIME 66 9367
TEST_TIME 67 9282
TEST_TIME 68 9318
TEST_TIME 69 9336
TEST_TIME 70 9351
TEST_TIME 71 9323
TEST_TIME 72 9365
TEST_TIME 73 9335
TEST_TIME 74 9380
TEST_TIME 75 9490
TEST_TIME 76 9475
TEST_TIME 77 9508
TEST_TIME 78 9524
TEST_TIME 79 9579
TEST_TIME 80 9503
TEST_TIME 81 9416
TEST_TIME 82 9434
TEST_TIME 83 9389
TEST_TIME 84 9342
TEST_TIME 85 9318
TEST_TIME 86 9262
TEST_TIME 87 9253
TEST_TIME 88 9304
TEST_TIME 89 9262
TEST_TIME 90 9276
TEST_TIME 91 9277
TEST_TIME 92 9283
TEST_TIME 93 9260
TEST_TIME 94 9277
TEST_TIME 95 9303
TEST_TIME 96 9368
TEST_TIME 97 9330
TEST_TIME 98 9246
TEST_TIME 99 9195
TEST_TIME 100 9138
TEST_TIME 101 9118
TEST_TIME 102 9072
TEST_TIME 103 9059
TEST_TIME 104 9017
TEST_TIME 105 9011
TEST_TIME 106 9016
TEST_TIME 107 8995
TEST_TIME 108 9007
TEST_TIME 109 9116
TEST_TIME 110 9101
TEST_TIME 111 9116
TEST_TIME 112 9105
TEST_TIME 113 9119
TEST_TIME 114 9163
TEST_TIME 115 9118
TEST_TIME 116 9050
TEST_TIME 117 9013
TEST_TIME 118 8960
TEST_TIME 119 8933
TEST_TIME 120 8950

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 16-01-2021 13:28:33 to 16-01-2021 13:28: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 1500978 1500978 0 0.00 0 21640 7 0 0
doc_list_biud 1500978 1500978 0 0.00 0 77197 34 0 0
sp_add_invoice_to_stock 5088 4906 182 3.57 27 190663 19947 0 182
sp_cancel_adding_invoice 5322 5322 0 0.00 6 91044 1069 0 0
sp_cancel_client_order 1780 1780 0 0.00 5 9943 365 0 0
sp_cancel_customer_reserve 528982 528982 0 0.00 1 38695 76 0 0
sp_cancel_pay_from_customer 679 679 0 0.00 3 4639 128 0 0
sp_cancel_pay_to_supplier 401 401 0 0.00 3 4608 100 0 0
sp_cancel_supplier_invoice 5931 5912 19 0.32 3 77204 1024 0 19
sp_cancel_supplier_order 389 389 0 0.00 10 1168955 131365 0 0
sp_cancel_write_off 8862 8862 0 0.00 1 10909 155 0 0
sp_client_order 20078 20078 0 0.00 4 56674 444 0 0
sp_customer_reserve 461782 461782 0 0.00 0 83553 191 0 0
sp_fill_shopping_cart 30952 30952 0 0.00 1 18647 43 0 0
sp_fill_shopping_cart_clo_res 461777 461777 0 0.00 0 23434 19 0 0
sp_get_clo_for_invoice 4901 4901 0 0.00 0 64165 381 0 0
sp_kill_cost_storno 15264 15264 0 0.00 0 8942 75 0 0
sp_kill_qstorno_ret_qs2qd 1073127 1073127 0 0.00 0 77196 41 0 0
sp_kill_qty_storno 565998 565998 0 0.00 0 77197 89 0 0
sp_lock_selected_doc 20450 20450 0 0.00 0 10877 6 0 0
sp_make_cost_storno 27365 27365 0 0.00 0 9383 58 0 0
sp_multiply_rows_for_pdistr 27365 27365 0 0.00 0 7608 36 0 0
sp_multiply_rows_for_qdistr 20078 20078 0 0.00 0 18120 252 0 0
sp_pay_from_customer 6951 6951 0 0.00 4 13026 112 0 0
sp_pay_to_supplier 5663 5663 0 0.00 5 10832 131 0 0
sp_payment_common 12614 12614 0 0.00 1 8297 22 0 0
sp_qd_handle_on_cancel_clo 1780 1780 0 0.00 0 9884 338 0 0
sp_qd_handle_on_invoice_upd_sts 10223 10223 0 0.00 0 56106 476 0 0
sp_qd_handle_on_reserve_upd_sts 9850 9850 0 0.00 0 16931 58 0 0
sp_reserve_write_off 9850 9850 0 0.00 7 16957 199 0 0
sp_supplier_invoice 5416 5416 0 0.00 6 119794 3579 0 0
sp_supplier_order 5469 5469 0 0.00 6 81830 1390 0 0
srv_aggregate_perf_data 108 108 0 0.00 2834 223437 15488 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1144 121207 44201 0 0
srv_find_qd_qs_mism 1026646 1026646 0 0.00 0 21640 6 0 0
srv_make_invnt_saldo 449 449 0 0.00 51 100788 3711 0 0
srv_make_money_saldo 733 733 0 0.00 0 18130 699 0 0
srv_recalc_idx_stat 4 4 0 0.00 128728 582825 312633 0 0
t$perf-abend:sp_add_invoice_to_stock 182 182 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 19 19 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4906 4906 0 0.00 0 11568 197 0 0
t$perf-norm:sp_cancel_adding_invoice 390 390 0 0.00 0 2187 83 0 0
t$perf-norm:sp_cancel_client_order 1780 1780 0 0.00 0 32399 33 0 0
t$perf-norm:sp_cancel_customer_reserve 717 717 0 0.00 0 3552 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 679 679 0 0.00 0 1129 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 401 401 0 0.00 0 2820 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 385 385 0 0.00 0 1271 13 0 0
t$perf-norm:sp_cancel_supplier_order 389 389 0 0.00 0 23369 1862 0 0
t$perf-norm:sp_cancel_write_off 958 958 0 0.00 0 993 4 0 0
t$perf-norm:sp_client_order 20078 20078 0 0.00 0 7727 19 0 0
t$perf-norm:sp_customer_reserve 935 935 0 0.00 0 1119 6 0 0
t$perf-norm:sp_pay_from_customer 6951 6951 0 0.00 0 7466 10 0 0
t$perf-norm:sp_pay_to_supplier 5663 5663 0 0.00 0 7056 11 0 0
t$perf-norm:sp_reserve_write_off 9850 9850 0 0.00 0 6107 10 0 0
t$perf-norm:sp_supplier_invoice 5416 5416 0 0.00 0 2942 20 0 0
t$perf-norm:sp_supplier_order 5469 5469 0 0.00 0 5581 21 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 844 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 131 4 0 0
t$perf-norm:srv_make_invnt_saldo 449 449 0 0.00 0 2054 25 0 0
t$perf-norm:srv_make_money_saldo 733 733 0 0.00 0 572 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 111 29 0 0
v_add_invoice_to_stock 10166 9802 364 3.58 0 6861 17 0 364
v_all_customers 36184 36184 0 0.00 0 97 1 0 0
v_all_suppliers 29964 29964 0 0.00 0 27 1 0 0
v_all_wares 40152 40152 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 780 780 0 0.00 0 12473 28 0 0
v_cancel_client_order 3560 3560 0 0.00 0 436 3 0 0
v_cancel_customer_prepayment 1358 1358 0 0.00 0 3760 8 0 0
v_cancel_customer_reserve 1434 1434 0 0.00 0 635 4 0 0
v_cancel_payment_to_supplier 802 802 0 0.00 0 3394 16 0 0
v_cancel_supplier_invoice 808 770 38 4.70 0 1332 15 0 38
v_cancel_supplier_order 778 778 0 0.00 0 602 5 0 0
v_cancel_write_off 1916 1916 0 0.00 0 3828 15 0 0
v_max_id_clo_ord 5466 5466 0 0.00 0 102 1 0 0
v_max_id_clo_res 935 935 0 0.00 0 1859 13 0 0
v_max_id_ord_sup 5408 5408 0 0.00 0 6249 5 0 0
v_max_non_paid_invoice 1555 1555 0 0.00 0 1154 3 0 0
v_max_non_paid_realizn 3913 3913 0 0.00 0 4869 4 0 0
v_min_id_clo_ord 5466 5466 0 0.00 0 7583 13 0 0
v_min_id_clo_res 935 935 0 0.00 0 5177 14 0 0
v_min_id_ord_sup 5408 5408 0 0.00 0 18642 11 0 0
v_min_non_paid_invoice 5663 5663 0 0.00 0 8264 14 0 0
v_min_non_paid_realizn 6950 6950 0 0.00 0 8110 16 0 0
v_our_firm 10042 10042 0 0.00 0 22 1 0 0
v_reserve_write_off 19700 19700 0 0.00 0 5938 6 0 0
x_lock_depdocs_on_canc_invoice 10849 10849 0 0.00 0 14490 128 0 0
x_lock_depdocs_on_canc_sup_ord 389 389 0 0.00 0 17276 459 0 0
x_make_qstorno_1000_1200 5469 5469 0 0.00 2 81816 1289 0 0
x_make_qstorno_1200_2000 5416 5416 0 0.00 3 119764 3433 0 0
x_make_qstorno_2100_3300 461782 461782 0 0.00 0 83552 169 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-01-2021 13:28:38 to 16-01-2021 13:28: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:59 5 8192000000 8796638048 8807108608 3168016 0.038 1 1 2 0 3265632 61840 35776
10:58:00 17563 8192000000 9712191824 10005733376 791924304 8.814 100 82 163 0 819095472 7275488 19895680
10:59:23 20551 8192000000 9755457952 10057940992 830217776 9.201 100 89 178 0 862944960 11427584 21299600
11:00:57 25610 8192000000 9805382816 10126520320 874340736 9.643 100 85 170 0 916134912 22355264 19438912
11:02:25 14671 8192000000 9867026416 10198343680 915000512 10.047 100 81 161 3 973544128 40294768 18248848
11:03:43 7663 8192000000 9911111088 10253537280 953893728 10.429 100 81 162 0 1022465776 51094064 17477984
11:04:53 4922 8192000000 9954622128 10305622016 994246464 10.823 100 83 166 2 1066869360 55333488 17289408
11:06:00 4330 8192000000 9978078448 10341441536 1019845456 11.071 100 76 152 0 1100091632 65189776 15056400
11:07:06 3599 8192000000 10012712000 10374205440 1040069808 11.265 100 83 165 1 1126721264 70760976 15890480
11:08:12 1971 8192000000 10049984976 10417594368 1060274656 11.459 100 91 182 1 1162694192 83712384 18707152
11:09:14 1383 8192000000 10059704336 10432385024 1073735744 11.588 100 97 195 3 1160802208 68004320 19062144
11:10:16 14162 8192000000 9992686016 10394136576 1076634432 11.615 100 23 46 0 1137703392 56858800 4210160
11:11:32 5145 8192000000 10001463760 10395205632 1083548576 11.681 100 44 88 2 1114778016 21859456 9369984
11:12:38 10577 8192000000 10005698416 10416029696 1096955280 11.809 100 21 42 0 1131939072 30537296 4446496
11:13:49 17967 8192000000 10012629008 10414346240 1095978144 11.799 100 25 50 2 1116737760 14887296 5872320
11:15:07 23562 8192000000 10029068464 10414575616 1102833072 11.865 100 73 150 3 1128976944 10024320 16119552
11:16:31 7491 8192000000 10021469536 10423549952 1094973264 11.790 100 41 83 0 1130698352 26720432 9004656
11:17:40 7118 8192000000 9994798224 10398597120 1077502704 11.624 99 41 82 0 1096558832 9736768 9319360
11:18:48 6104 8192000000 9963927792 10352205824 1020650816 11.078 95 27 54 1 1040532864 13502000 6380048
11:19:59 34622 8192000000 9929182352 10307829760 902469136 9.923 85 29 58 2 926049856 17471200 6109520
11:21:34 8637 8192000000 9947876512 10328342528 992780544 10.808 97 39 77 2 1014042688 12298704 8963440
11:22:45 6907 8192000000 9968956432 10349973504 1016908704 11.042 99 43 85 2 1048150576 22434160 8807712
11:23:54 54930 8192000000 9969174528 10372595712 1040160816 11.266 100 25 51 0 1060942864 15308736 5473312
11:25:54 7175 8192000000 9980389744 10381127680 1055487808 11.413 100 36 69 2 1085938672 22960272 7490592
11:27:08 59916 8192000000 9992537072 10381848576 1067331472 11.527 100 40 80 1 1095379088 19609856 8437760
11:29:15 28078 8192000000 10072929088 10470559744 1088776320 11.731 100 58 116 1 1179653920 78749264 12128336
11:30:50 82878 8192000000 10098215264 10515841024 1101746448 11.854 100 43 86 1 1217077472 105144128 10186896
11:33:24 2398 8192000000 10122099792 10521640960 1113271888 11.963 100 49 98 0 1221986768 97743952 10970928
11:34:31 15342 8192000000 10158515024 10559459328 1121759680 12.044 100 55 109 2 1256184160 122850944 11573536
11:36:05 5980 8192000000 10169106016 10584674304 1137496352 12.192 100 59 116 1 1278123184 127149120 13477712
11:37:25 4564 8192000000 10245518064 10642624512 1156756624 12.373 100 76 151 0 1345654864 173225200 15673040
11:38:32 13456 8192000000 10231684272 10662453248 1156672224 12.372 100 50 100 1 1334789104 166935792 11181088
11:39:53 2431 8192000000 10232719568 10628235264 1144219872 12.255 99 89 177 0 1325605392 162125392 19260128
11:40:59 1141 8192000000 10282046288 10676809728 1159902480 12.402 100 87 168 0 1380497808 201252224 19343104
11:42:04 2336 8192000000 10317704944 10713186304 1154335552 12.350 100 81 163 0 1416266432 243316288 18614592
11:43:10 7105 8192000000 10261486000 10673180672 1160135760 12.405 100 73 146 1 1359462784 183357104 15969920
11:44:22 2399 8192000000 10309660176 10703499264 1161748144 12.420 100 87 169 1 1407499376 226146240 19604992
11:45:30 2107 8192000000 10370853712 10765729792 1166522688 12.464 100 88 173 1 1459376400 272957216 19896496
11:46:39 4149 8192000000 10383156528 10777473024 1145921296 12.271 99 87 172 0 1471593712 305663088 20009328
11:47:50 1738 8192000000 10405926384 10801602560 1156478176 12.370 99 89 180 3 1488201312 310077856 21645280
11:48:58 1503 8192000000 10457990624 10859806720 1187651552 12.662 100 89 178 1 1550190176 340977280 21561344
11:50:01 5212 8192000000 10451792048 10871455744 1191578864 12.698 100 54 108 1 1551656016 346752320 13324832
11:51:17 2161 8192000000 10498334496 10914734080 1176453520 12.557 100 84 167 0 1597596896 400893552 20249824
11:52:23 8606 8192000000 10487572624 10914865152 1177809152 12.570 100 41 81 0 1582795664 394095536 10890976
11:53:54 11333 8192000000 10429858896 10911375360 1176744256 12.560 100 31 62 1 1534013456 348967280 8301920
11:55:23 31926 8192000000 10449837840 10911051776 1134209920 12.161 98 25 50 0 1541193488 399959440 7024128
11:57:02 27025 8192000000 10206657616 10775605248 1135169328 12.170 99 17 34 0 1352097808 212499184 4429296
11:58:52 33413 8192000000 10092444096 10594582528 1147603728 12.287 100 6 12 0 1210282896 61604064 1075104
12:00:27 103490 8192000000 10122409840 10570448896 1129637920 12.118 98 22 42 1 1207358768 72986304 4734544
12:05:00 43517 8192000000 10089832672 10529214464 1113836016 11.969 98 37 74 1 1193318432 71912272 7570144
12:06:55 12865 8192000000 10099448736 10533244928 1126394848 12.087 99 42 85 1 1186937616 51832800 8709968
12:08:22 40947 8192000000 10108798576 10569555968 1124535392 12.070 98 43 86 1 1187265952 53915376 8815184
12:10:13 62638 8192000000 10102182080 10566918144 1145447840 12.267 100 18 36 0 1212816272 63915072 3453360
12:12:48 59360 8192000000 10093914480 10568105984 1145831840 12.270 100 20 41 1 1208125760 57631776 4662144
12:14:47 65456 8192000000 10096334640 10598854656 1138259776 12.199 99 9 18 0 1190889888 50937568 1692544
12:16:56 18902 8192000000 10098626880 10535804928 1131356192 12.134 99 56 107 1 1175239568 33006704 10876672
12:18:16 57816 8192000000 10129039808 10585292800 1148248048 12.293 100 22 44 1 1219513968 66643856 4622064
12:20:18 86434 8192000000 10134259792 10604732416 1110876208 11.941 97 16 32 0 1195791760 81747792 3167760
12:22:45 112922 8192000000 10102780112 10566258688 1118595568 12.014 98 19 38 0 1250634992 127692096 4347328
12:25:53 50057 8192000000 10071903264 10506342400 1068689984 11.540 94 35 70 0 1119086192 43890176 6506032
12:27:43 89238 8192000000 10060446896 10528346112 1113090656 11.962 99 26 52 1 1179378784 61026656 5261472
12:30:19 44747 8192000000 10048887472 10515357696 1132314256 12.143 100 9 18 0 1158068128 23812640 1941232
12:32:16 61328 8192000000 10018036656 10481995776 1044162128 11.305 93 5 11 0 1076615616 31466144 987344
12:34:18 77384 8192000000 10076486896 10562539520 1091029984 11.752 97 13 26 1 1180633408 86583760 3019664
12:36:36 40846 8192000000 10054555168 10486689792 1075067552 11.600 96 28 56 0 1130764944 50199088 5498304
12:38:22 49876 8192000000 10063174352 10497019904 1120369248 12.030 99 22 44 1 1152818768 28635536 3813984
12:40:13 42028 8192000000 10115671104 10598600704 1127861360 12.101 100 27 54 2 1204849312 71380336 5607616
12:42:05 51022 8192000000 10067266848 10513199104 1064470608 11.499 95 19 38 0 1133108688 64659888 3978192
12:43:59 93863 8192000000 10073825760 10583015424 1073148368 11.582 96 21 42 0 1119369200 41743008 4477824
12:46:34 116043 8192000000 10066113648 10583719936 1110652512 11.939 100 16 32 0 1203271952 89151280 3468160
12:49:44 63132 8192000000 10056143504 10507747328 1119016672 12.018 100 8 16 0 1157357504 36657392 1683440
12:51:47 58358 8192000000 10075901696 10519257088 1105727472 11.892 98 23 46 1 1147253152 36320608 5205072
12:53:50 69960 8192000000 10093331072 10586800128 1121439920 12.041 100 23 46 0 1205259584 78669760 5149904
12:56:02 70205 8192000000 10117795216 10589376512 1136490384 12.183 100 26 52 1 1207250288 65103248 5656656
12:58:12 45480 8192000000 10077639936 10514292736 1098879344 11.827 97 23 46 0 1141305152 37410400 5015408
13:00:02 52995 8192000000 10111729472 10558668800 1116116608 11.990 98 44 88 0 1184206256 58956416 9133232
13:02:01 46899 8192000000 10084028624 10574876672 1111162848 11.943 98 22 43 1 1199650144 84286512 4200784
13:03:53 67069 8192000000 10033693120 10474475520 1111692496 11.948 99 14 28 1 1151065008 35819168 3553344
13:06:28 51534 8192000000 10054413120 10513723392 1120206336 12.029 99 15 30 1 1156321904 32809472 3306096
13:09:43 54019 8192000000 10064545696 10504433664 1102551632 11.862 97 18 36 1 1133038848 27027584 3459632
13:11:38 30498 8192000000 10085070032 10533371904 1117402880 12.002 99 34 68 1 1192121072 67508928 7209264
13:13:13 65120 8192000000 10148615120 10590113792 1110040592 11.933 97 26 50 1 1203374992 88000656 5333744
13:15:24 80226 8192000000 10104836080 10576191488 1128855744 12.111 100 11 22 0 1190585744 59331680 2398320
13:17:44 54410 8192000000 10070844192 10522583040 1099118560 11.829 97 21 41 2 1140558400 37333552 4106288
13:19:45 67007 8192000000 10070632064 10562457600 1107701072 11.911 98 23 46 1 1185171792 73004400 4466320
13:21:54 121206 8192000000 10043976816 10493079552 1108224336 11.916 99 17 34 0 1147536960 36022032 3290592
13:25:00 48455 8192000000 10079365216 10530033664 1111744128 11.949 99 5 10 0 1173167728 60219568 1204032

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 16-01-2021 13:28:44 to 16-01-2021 13:28:59.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 182 v_add_invoice_to_stock 335544517
except 19 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-01-2021 13:28:59 to 16-01-2021 13:29:01.

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          236557
MON$OLDEST_ACTIVE               236558
MON$OLDEST_SNAPSHOT             236558
MON$NEXT_TRANSACTION            236558
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                       2096640
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             802
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2325 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2325 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2325 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.2325 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'

16.01.21 13:29:02. 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
2021-01-16 13:29:05 No severe PSQL-related problems occured
16.01.21 13:29:06. End of report.