Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.01.2021 10:27
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 16579588 kB
MemAvailable 31255608 kB
Buffers 0 kB
Cached 14969976 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-08 10:26:54
DTS_END                         2021-01-08 10:26:54.5660
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/46396
TRN_ID                          244021
ATT_ID                          628
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: 8672.30 21699 1040676
customer order: creation 181.24 345 21749
customer order: refuse 15.92 361 1910
order to supplier: creation 48.18 1452 5782
order to supplier: removal 3.01 110200 361
invoice (draft): creation 48.37 3756 5804
invoice (draft): removal 47.23 875 5668
invoice accept: apply 42.48 17765 5097
invoice accept: cancel 42.13 930 5056
customer reserve: creation 3866.63 176 463995
customer reserve: removal 4081.18 64 489742
realization accept: apply 87.23 207 10468
realization accept: cancel 74.50 131 8940
payment from customer: creation 62.28 116 7474
payment from customer: removal 6.00 66 720
payment to supplier: creation 51.18 130 6141
payment to supplier: removal 3.43 109 411
service: total inventory turnovers 4.03 3656 484
service: total monetary turnovers 6.30 518 756
service: aggregate perf. data 0.95 15328 114
service: refresh index statistics 0.03 277793 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-01-2021 10:27:54 to 08-01-2021 10:27:56.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2912
TEST_TIME 2 3131
TEST_TIME 3 3234
TEST_TIME 4 4328
TEST_TIME 5 4108
TEST_TIME 6 3890
TEST_TIME 7 4405
TEST_TIME 8 4545
TEST_TIME 9 5540
TEST_TIME 10 5346
TEST_TIME 11 5626
TEST_TIME 12 6037
TEST_TIME 13 6165
TEST_TIME 14 6312
TEST_TIME 15 6538
TEST_TIME 16 6460
TEST_TIME 17 6546
TEST_TIME 18 6583
TEST_TIME 19 6767
TEST_TIME 20 7057
TEST_TIME 21 7397
TEST_TIME 22 7328
TEST_TIME 23 7782
TEST_TIME 24 7853
TEST_TIME 25 8004
TEST_TIME 26 8369
TEST_TIME 27 8550
TEST_TIME 28 8654
TEST_TIME 29 9346
TEST_TIME 30 9245
TEST_TIME 31 9863
TEST_TIME 32 9764
TEST_TIME 33 9616
TEST_TIME 34 9750
TEST_TIME 35 9829
TEST_TIME 36 9696
TEST_TIME 37 9556
TEST_TIME 38 9530
TEST_TIME 39 9614
TEST_TIME 40 9507
TEST_TIME 41 9609
TEST_TIME 42 9563
TEST_TIME 43 9352
TEST_TIME 44 9192
TEST_TIME 45 9248
TEST_TIME 46 9183
TEST_TIME 47 9144
TEST_TIME 48 9171
TEST_TIME 49 9123
TEST_TIME 50 9085
TEST_TIME 51 9081
TEST_TIME 52 9041
TEST_TIME 53 8958
TEST_TIME 54 8827
TEST_TIME 55 8798
TEST_TIME 56 8893
TEST_TIME 57 8840
TEST_TIME 58 8919
TEST_TIME 59 8931
TEST_TIME 60 8966
TEST_TIME 61 9043
TEST_TIME 62 9046
TEST_TIME 63 9094
TEST_TIME 64 9132
TEST_TIME 65 9139
TEST_TIME 66 9151
TEST_TIME 67 9110
TEST_TIME 68 9212
TEST_TIME 69 9312
TEST_TIME 70 9313
TEST_TIME 71 9188
TEST_TIME 72 9123
TEST_TIME 73 9043
TEST_TIME 74 8991
TEST_TIME 75 8901
TEST_TIME 76 8825
TEST_TIME 77 9045
TEST_TIME 78 8974
TEST_TIME 79 8977
TEST_TIME 80 9056
TEST_TIME 81 9013
TEST_TIME 82 8998
TEST_TIME 83 9013
TEST_TIME 84 9036
TEST_TIME 85 9040
TEST_TIME 86 9056
TEST_TIME 87 9058
TEST_TIME 88 9140
TEST_TIME 89 9091
TEST_TIME 90 8997
TEST_TIME 91 8962
TEST_TIME 92 8904
TEST_TIME 93 8855
TEST_TIME 94 8834
TEST_TIME 95 8823
TEST_TIME 96 8807
TEST_TIME 97 8822
TEST_TIME 98 8794
TEST_TIME 99 8867
TEST_TIME 100 8883
TEST_TIME 101 8839
TEST_TIME 102 8840
TEST_TIME 103 8838
TEST_TIME 104 8849
TEST_TIME 105 8844
TEST_TIME 106 8877
TEST_TIME 107 8932
TEST_TIME 108 8900
TEST_TIME 109 8860
TEST_TIME 110 8805
TEST_TIME 111 8782
TEST_TIME 112 8745
TEST_TIME 113 8695
TEST_TIME 114 8665
TEST_TIME 115 8695
TEST_TIME 116 8699
TEST_TIME 117 8703
TEST_TIME 118 8685
TEST_TIME 119 8695
TEST_TIME 120 8673

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 08-01-2021 10:27:58 to 08-01-2021 10:28:01.

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 1470900 1470900 0 0.00 0 21493 7 0 0
doc_list_biud 1470900 1470900 0 0.00 0 36504 28 0 0
sp_add_invoice_to_stock 5221 5097 124 2.37 36 158541 17765 0 124
sp_cancel_adding_invoice 5056 5056 0 0.00 6 79974 930 0 0
sp_cancel_client_order 1910 1910 0 0.00 4 9617 362 0 0
sp_cancel_customer_reserve 489742 489742 0 0.00 1 36294 64 0 0
sp_cancel_pay_from_customer 720 720 0 0.00 3 2419 67 0 0
sp_cancel_pay_to_supplier 411 411 0 0.00 3 5746 110 0 0
sp_cancel_supplier_invoice 5680 5668 12 0.21 3 25478 876 0 12
sp_cancel_supplier_order 361 361 0 0.00 18 1104623 110200 0 0
sp_cancel_write_off 8940 8940 0 0.00 1 18753 131 0 0
sp_client_order 21749 21749 0 0.00 4 17992 345 0 0
sp_customer_reserve 463995 463995 0 0.00 0 43382 177 0 0
sp_fill_shopping_cart 33316 33316 0 0.00 1 18184 45 0 0
sp_fill_shopping_cart_clo_res 463992 463992 0 0.00 0 18018 13 0 0
sp_get_clo_for_invoice 5094 5094 0 0.00 0 28915 341 0 0
sp_kill_cost_storno 15127 15127 0 0.00 0 5265 60 0 0
sp_kill_qstorno_ret_qs2qd 994453 994453 0 0.00 0 36289 35 0 0
sp_kill_qty_storno 527239 527239 0 0.00 0 36504 76 0 0
sp_lock_selected_doc 21603 21603 0 0.00 0 8914 7 0 0
sp_make_cost_storno 29177 29177 0 0.00 0 17093 59 0 0
sp_multiply_rows_for_pdistr 29177 29177 0 0.00 0 7993 31 0 0
sp_multiply_rows_for_qdistr 21749 21749 0 0.00 0 11435 168 0 0
sp_pay_from_customer 7474 7474 0 0.00 4 17478 117 0 0
sp_pay_to_supplier 6141 6141 0 0.00 5 19768 131 0 0
sp_payment_common 13615 13615 0 0.00 1 19743 25 0 0
sp_qd_handle_on_cancel_clo 1910 1910 0 0.00 0 9610 330 0 0
sp_qd_handle_on_invoice_upd_sts 10150 10150 0 0.00 0 36504 373 0 0
sp_qd_handle_on_reserve_upd_sts 10468 10468 0 0.00 0 19671 70 0 0
sp_reserve_write_off 10468 10468 0 0.00 7 23952 207 0 0
sp_supplier_invoice 5804 5804 0 0.00 6 95903 3757 0 0
sp_supplier_order 5782 5782 0 0.00 5 41294 1453 0 0
srv_aggregate_perf_data 114 114 0 0.00 2919 267802 15329 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1094 129348 42089 0 0
srv_find_qd_qs_mism 991705 991705 0 0.00 0 21490 6 0 0
srv_make_invnt_saldo 484 484 0 0.00 49 140984 3656 0 0
srv_make_money_saldo 756 756 0 0.00 1 22462 519 0 0
srv_recalc_idx_stat 4 4 0 0.00 57993 451263 277793 0 0
t$perf-abend:sp_add_invoice_to_stock 124 124 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5097 5097 0 0.00 0 10654 170 0 0
t$perf-norm:sp_cancel_adding_invoice 449 449 0 0.00 0 18504 136 0 0
t$perf-norm:sp_cancel_client_order 1910 1910 0 0.00 0 5317 14 0 0
t$perf-norm:sp_cancel_customer_reserve 745 745 0 0.00 0 2605 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 720 720 0 0.00 0 11872 31 0 0
t$perf-norm:sp_cancel_pay_to_supplier 411 411 0 0.00 0 3365 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 441 441 0 0.00 0 3045 24 0 0
t$perf-norm:sp_cancel_supplier_order 361 361 0 0.00 0 25985 1561 0 0
t$perf-norm:sp_cancel_write_off 1004 1004 0 0.00 0 1234 6 0 0
t$perf-norm:sp_client_order 21749 21749 0 0.00 0 10750 21 0 0
t$perf-norm:sp_customer_reserve 1059 1059 0 0.00 0 3879 17 0 0
t$perf-norm:sp_pay_from_customer 7474 7474 0 0.00 0 17943 17 0 0
t$perf-norm:sp_pay_to_supplier 6141 6141 0 0.00 0 7138 11 0 0
t$perf-norm:sp_reserve_write_off 10468 10468 0 0.00 0 7760 11 0 0
t$perf-norm:sp_supplier_invoice 5804 5804 0 0.00 0 17506 22 0 0
t$perf-norm:sp_supplier_order 5782 5782 0 0.00 0 7055 20 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 2647 33 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 80 3 0 0
t$perf-norm:srv_make_invnt_saldo 484 484 0 0.00 0 1488 16 0 0
t$perf-norm:srv_make_money_saldo 756 756 0 0.00 0 5476 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10436 10188 248 2.37 0 7649 21 0 248
v_all_customers 39294 39294 0 0.00 0 17 1 0 0
v_all_suppliers 31652 31652 0 0.00 0 20 1 0 0
v_all_wares 43498 43498 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 898 898 0 0.00 0 1897 8 0 0
v_cancel_client_order 3820 3820 0 0.00 0 2926 5 0 0
v_cancel_customer_prepayment 1440 1440 0 0.00 0 1650 6 0 0
v_cancel_customer_reserve 1490 1490 0 0.00 0 5303 11 0 0
v_cancel_payment_to_supplier 822 822 0 0.00 0 5365 14 0 0
v_cancel_supplier_invoice 906 882 24 2.64 0 5120 24 0 24
v_cancel_supplier_order 722 722 0 0.00 0 1529 9 0 0
v_cancel_write_off 2008 2008 0 0.00 0 2745 17 0 0
v_max_id_clo_ord 5781 5781 0 0.00 0 1658 2 0 0
v_max_id_clo_res 1059 1059 0 0.00 0 1652 12 0 0
v_max_id_ord_sup 5786 5786 0 0.00 0 10814 12 0 0
v_max_non_paid_invoice 1882 1882 0 0.00 0 709 2 0 0
v_max_non_paid_realizn 4134 4134 0 0.00 0 2535 3 0 0
v_min_id_clo_ord 5781 5781 0 0.00 0 1263 6 0 0
v_min_id_clo_res 1059 1059 0 0.00 0 1919 5 0 0
v_min_id_ord_sup 5786 5786 0 0.00 0 8234 7 0 0
v_min_non_paid_invoice 6141 6141 0 0.00 0 19736 19 0 0
v_min_non_paid_realizn 7474 7474 0 0.00 0 8360 15 0 0
v_our_firm 10884 10884 0 0.00 0 117 1 0 0
v_reserve_write_off 20936 20936 0 0.00 0 7229 7 0 0
x_lock_depdocs_on_canc_invoice 10283 10283 0 0.00 0 11275 114 0 0
x_lock_depdocs_on_canc_sup_ord 361 361 0 0.00 0 15282 413 0 0
x_make_qstorno_1000_1200 5782 5782 0 0.00 2 41258 1369 0 0
x_make_qstorno_1200_2000 5804 5804 0 0.00 3 95872 3583 0 0
x_make_qstorno_2100_3300 463995 463995 0 0.00 0 43381 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-01-2021 10:28:02 to 08-01-2021 10:28:06.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
07:56:58 8 8192000000 8798843424 8810254336 4138000 0.050 2 1 2 0 4237472 63616 35856
07:58:00 25888 8192000000 9726563344 10023866368 803611472 8.933 100 83 166 0 830901616 7222336 20067808
07:59:30 22556 8192000000 9773591472 10081652736 848926560 9.389 100 89 178 0 882429552 12653216 20849776
08:00:57 15906 8192000000 9806203808 10121834496 869911776 9.599 100 83 166 2 910082528 20913088 19257664
08:02:17 12088 8192000000 9872243488 10204200960 921757392 10.113 100 72 144 0 978850224 41354048 15738784
08:03:35 9625 8192000000 9911592928 10260271104 965015616 10.538 100 79 159 0 1032711680 50773184 16922880
08:04:48 6910 8192000000 9953275264 10309632000 1000286608 10.881 100 76 152 3 1065990272 49919648 15784016
08:05:57 4207 8192000000 10003014832 10363977728 1022486384 11.096 100 94 190 1 1119857424 78413248 18957792
08:07:04 2190 8192000000 10019264560 10386472960 1043618976 11.299 100 92 184 1 1130066336 67912064 18535296
08:08:08 2061 8192000000 10041302752 10413088768 1059523760 11.452 100 86 174 1 1152089120 74870032 17695328
08:09:12 4607 8192000000 10001326928 10393366528 1065198464 11.506 100 37 72 2 1120557136 48211440 7147232
08:10:17 8478 8192000000 9991793840 10385133568 1081693040 11.664 100 41 78 5 1113488320 22625552 9169728
08:11:26 3481 8192000000 10004545712 10398941184 1082423888 11.671 100 39 77 2 1119011632 28137440 8450304
08:12:33 45825 8192000000 9993898608 10395598848 1086810560 11.712 100 56 114 1 1110767200 11189024 12767616
08:14:27 3620 8192000000 10004313184 10418671616 1089854448 11.741 100 43 85 2 1129231872 29947504 9429920
08:15:31 12912 8192000000 9992495072 10399268864 1076488096 11.614 99 19 36 2 1099152032 18454064 4209872
08:16:45 7496 8192000000 9978978016 10375049216 1039752368 11.262 96 24 48 2 1072504864 27337904 5414592
08:17:54 38941 8192000000 9980941328 10374979584 1041046528 11.275 97 49 98 1 1071697872 19165520 11485824
08:19:37 8931 8192000000 9959894176 10359341056 1020804256 11.080 98 31 62 1 1061459104 34165008 6489840
08:20:46 6408 8192000000 9919483376 10299305984 987862752 10.761 97 38 76 2 1015904112 19457280 8584080
08:21:54 8421 8192000000 9938341824 10326360064 1022842896 11.099 100 33 66 1 1046663152 16481936 7338320
08:23:02 52560 8192000000 9941328528 10345406464 1037372880 11.239 100 36 72 1 1064747408 18936208 8438320
08:24:56 5654 8192000000 9963828256 10360381440 1042375984 11.287 99 47 92 2 1071242256 18778320 10087952
08:26:04 9924 8192000000 9974735472 10376331264 1065909824 11.513 100 47 94 1 1096817536 20135888 10771824
08:27:20 15689 8192000000 10018304544 10414129152 1095464944 11.795 100 43 81 0 1128370064 24210688 8694432
08:28:44 55965 8192000000 10067778160 10471600128 1110991712 11.942 100 42 81 2 1165874656 46175280 8707664
08:30:44 42591 8192000000 10073585744 10482241536 1122242832 12.048 100 29 56 2 1177178640 48648320 6287488
08:32:30 12017 8192000000 10121448752 10529038336 1134620864 12.165 100 46 92 1 1229821616 85320288 9880464
08:33:55 7386 8192000000 10156244576 10560647168 1141349232 12.228 100 57 112 2 1263179552 109101456 12728864
08:35:12 2203 8192000000 10164395504 10578288640 1154178016 12.349 100 74 147 4 1278441808 108817376 15446416
08:36:18 13010 8192000000 10163832560 10572431360 1157356384 12.378 100 59 117 1 1267299584 97968304 11974896
08:37:35 7062 8192000000 10177839088 10582355968 1158868960 12.393 100 67 132 3 1282522992 109733296 13920736
08:38:49 1711 8192000000 10174449344 10588737536 1161677168 12.419 100 63 126 1 1284984992 108993440 14314384
08:39:58 6216 8192000000 10214434096 10620751872 1164562064 12.446 100 69 139 1 1315997488 136304992 15130432
08:41:11 5240 8192000000 10223580912 10643062784 1153981376 12.347 99 66 132 0 1326159056 157955232 14222448
08:42:23 7334 8192000000 10198139248 10607026176 1150935472 12.318 100 46 89 0 1295177344 134193408 10048464
08:43:36 3306 8192000000 10263222064 10664148992 1149653728 12.306 100 73 146 2 1352598096 186446640 16497728
08:44:47 6857 8192000000 10283979456 10687807488 1137514224 12.192 99 70 140 1 1375496464 222219008 15763232
08:46:03 2160 8192000000 10313685568 10717372416 1151236992 12.321 100 78 156 0 1409763808 241109264 17417552
08:47:09 9482 8192000000 10314620688 10743926784 1150395360 12.313 99 50 100 0 1412352048 250251840 11704848
08:48:25 1092 8192000000 10352717792 10749927424 1145890368 12.271 100 87 174 1 1451619008 285643280 20085360
08:49:33 4171 8192000000 10422989344 10836979712 1153772416 12.345 100 77 154 0 1514881600 343392464 17716720
08:50:39 8055 8192000000 10355537856 10792017920 1123116672 12.056 99 37 74 1 1450315920 317600576 9598672
08:51:50 6106 8192000000 10373320112 10796507136 1110850528 11.940 98 50 98 2 1473121104 350310976 11959600
08:52:56 18945 8192000000 10328670096 10757013504 1135934416 12.177 100 27 53 2 1443940080 300673152 7332512
08:54:20 41313 8192000000 10275124400 10732244992 1108428064 11.918 99 26 52 1 1389797248 274642368 6726816
08:56:08 30526 8192000000 10179206208 10655924224 1123816832 12.063 100 15 30 0 1267592304 140131552 3643920
08:57:41 68824 8192000000 10040496320 10461683712 1090199952 11.745 97 12 24 0 1184094592 90821952 3072688
09:00:04 54770 8192000000 10085657200 10542940160 1113157152 11.962 98 15 30 3 1143396704 27275264 2964288
09:01:59 62821 8192000000 10062628080 10473537536 1101043440 11.848 97 25 50 1 1130227616 23094576 6089600
09:04:05 74029 8192000000 10070847616 10544648192 1134883312 12.167 99 32 63 2 1206790672 64184784 7722576
09:06:21 13729 8192000000 10068866464 10499772416 1126229680 12.086 99 32 63 2 1158646512 25244944 7171888
09:07:36 129293 8192000000 10095508640 10531446784 1121080960 12.037 98 11 22 0 1154886624 31593024 2212640
09:11:11 50002 8192000000 10070739600 10526363648 1147028896 12.282 100 14 28 1 1180775616 30676864 3069856
09:13:12 13477 8192000000 10100094448 10527465472 1148368048 12.294 100 58 114 2 1197283488 35484864 13430576
09:14:27 32524 8192000000 10078578640 10514558976 1143403792 12.248 100 28 53 2 1171175616 22072624 5699200
09:16:00 47061 8192000000 10091777824 10549751808 1129025120 12.112 98 20 40 2 1184077616 50763456 4289040
09:17:52 47355 8192000000 10077044896 10514178048 1105340336 11.888 97 19 38 1 1132660064 23760832 3558896
09:19:43 80791 8192000000 10096651104 10542923776 1108228272 11.916 98 23 46 2 1155336592 41758560 5349760
09:22:05 42096 8192000000 10050849088 10572546048 1093481280 11.776 97 7 14 0 1153340592 58337632 1521680
09:23:50 43787 8192000000 10064164944 10507657216 1102707712 11.863 98 19 38 0 1167414064 60524528 4181824
09:26:40 45087 8192000000 10076192400 10543935488 1087774880 11.721 96 24 49 2 1149004176 55921584 5307712
09:28:26 44381 8192000000 10105129616 10570903552 1115871056 11.988 98 18 36 1 1168654544 49237472 3546016
09:30:10 51963 8192000000 10082166384 10519822336 1074541968 11.595 95 13 25 1 1140296512 63089664 2664880
09:32:11 21667 8192000000 10131302288 10555109376 1107167200 11.906 98 51 104 0 1197867408 80070496 10629712
09:33:38 66880 8192000000 10110397488 10582626304 1087053872 11.715 97 19 38 1 1188230144 97249728 3926544
09:35:52 129348 8192000000 10022730848 10450874368 1089697856 11.740 98 15 30 0 1120826336 28031344 3097136
09:39:11 70226 8192000000 10051587680 10492047360 1071712320 11.568 97 10 20 1 1121108848 47322720 2073808
09:41:21 63482 8192000000 10123160848 10578157568 1096030016 11.800 99 10 20 2 1203107184 104980624 2096544
09:43:48 86235 8192000000 10121828128 10612723712 1121005008 12.036 100 22 44 1 1194938336 69225936 4707392
09:46:17 68078 8192000000 10061787968 10496507904 1089500992 11.738 97 16 32 1 1131121456 38003648 3616816
09:48:28 62295 8192000000 10076393328 10535030784 1118259632 12.011 99 17 34 3 1178261536 56494576 3507328
09:50:33 80946 8192000000 10077193056 10531713024 1086012064 11.705 96 20 40 0 1145770720 55421472 4337184
09:52:57 32028 8192000000 10122593568 10562473984 1127001152 12.093 100 28 56 2 1182659440 50055664 5602624
09:54:29 97713 8192000000 10062471328 10539782144 1115781824 11.987 99 8 15 1 1191177680 73858576 1537280
09:57:12 36586 8192000000 10052395744 10521657344 1133803536 12.157 100 24 48 3 1174358496 35365072 5189888
09:58:49 47129 8192000000 10065599008 10532356096 1141141360 12.226 100 11 22 0 1173538992 30406256 1991376
10:01:16 55894 8192000000 10088193136 10552180736 1128031392 12.103 99 17 33 2 1167413920 36031392 3351136
10:03:13 48890 8192000000 10093493040 10554417152 1138338800 12.200 99 20 40 1 1191008480 48318640 4351040
10:05:04 68094 8192000000 10118602560 10617618432 1149137456 12.301 100 31 62 1 1211227536 55738304 6351776
10:07:33 75401 8192000000 10093536576 10558435328 1098156816 11.820 96 28 56 3 1154190080 50271344 5761920
10:10:08 47885 8192000000 10075864416 10502807552 1117446848 12.003 98 29 57 2 1152510320 29555440 5508032
10:12:02 51567 8192000000 10144443216 10655064064 1119545392 12.023 98 11 22 0 1197087920 75257712 2284816
10:14:35 18213 8192000000 10098335152 10515820544 1134752496 12.166 100 50 100 1 1171373824 26091904 10529424
10:15:55 112228 8192000000 10034959680 10496864256 1060800528 11.464 93 16 32 0 1082360032 18521968 3037536
10:18:51 48769 8192000000 10053525648 10504609792 1114706368 11.977 99 8 16 2 1158608256 42101152 1800736
10:20:40 69008 8192000000 10036667632 10526924800 1092057504 11.762 97 10 20 1 1153732528 59084752 2590272
10:22:54 50581 8192000000 10048305552 10497445888 1065816976 11.512 95 14 28 0 1102576000 33714912 3044112
10:24:45 18182 8192000000 10043450784 10474819584 1100463616 11.842 100 50 100 2 1142768192 31640864 10663712
10:26:07 40060 8192000000 10074299472 10496880640 1093098304 11.772 98 30 60 1 1155098304 55897168 6102832

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 08-01-2021 10:28:08 to 08-01-2021 10:28:23.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 124 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-01-2021 10:28:24 to 08-01-2021 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          238436
MON$OLDEST_ACTIVE               244191
MON$OLDEST_SNAPSHOT             244191
MON$NEXT_TRANSACTION            244191
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                       2104000
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             821
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2319 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2319 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2319 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.2319 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 ---
:27: 
:28: mscs-test-161.budzdorov.ru    Fri Jan  8 10:27:01 2021
:29:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:30: 
--- end of diff output ---

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

08.01.21 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
2021-01-08 10:28:30 No severe PSQL-related problems occured
08.01.21 10:28:31. End of report.