Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.12.2020 04: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 16609396 kB
MemAvailable 31435676 kB
Buffers 20 kB
Cached 14913700 kB
SwapCached 3704 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-12-10 04:26:53
DTS_END                         2020-12-10 04:26:53.5560
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_FROM_CUSTOMER(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/37994
TRN_ID                          231803
ATT_ID                          602
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: 9070.22 19759 1088426
customer order: creation 167.11 431 20053
customer order: refuse 13.61 311 1633
order to supplier: creation 44.13 1300 5295
order to supplier: removal 3.28 107945 394
invoice (draft): creation 45.06 3237 5407
invoice (draft): removal 53.39 768 6407
invoice accept: apply 39.39 14476 4727
invoice accept: cancel 48.13 893 5776
customer reserve: creation 3654.64 139 438557
customer reserve: removal 4712.46 62 565495
realization accept: apply 81.55 184 9786
realization accept: cancel 84.23 107 10107
payment from customer: creation 56.85 122 6822
payment from customer: removal 5.71 81 685
payment to supplier: creation 47.08 126 5650
payment to supplier: removal 3.34 80 401
service: total inventory turnovers 3.71 3017 445
service: total monetary turnovers 5.69 330 683
service: aggregate perf. data 0.83 13622 99
service: refresh index statistics 0.03 247953 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 10-12-2020 04:28:13 to 10-12-2020 04:28:16.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2283
TEST_TIME 2 3085
TEST_TIME 3 3557
TEST_TIME 4 3452
TEST_TIME 5 3480
TEST_TIME 6 3701
TEST_TIME 7 3998
TEST_TIME 8 4197
TEST_TIME 9 4583
TEST_TIME 10 4940
TEST_TIME 11 5157
TEST_TIME 12 5586
TEST_TIME 13 6211
TEST_TIME 14 7372
TEST_TIME 15 7442
TEST_TIME 16 8031
TEST_TIME 17 8551
TEST_TIME 18 8907
TEST_TIME 19 8866
TEST_TIME 20 9103
TEST_TIME 21 10489
TEST_TIME 22 10865
TEST_TIME 23 10744
TEST_TIME 24 10945
TEST_TIME 25 11042
TEST_TIME 26 11031
TEST_TIME 27 10901
TEST_TIME 28 10820
TEST_TIME 29 10781
TEST_TIME 30 10764
TEST_TIME 31 10855
TEST_TIME 32 10762
TEST_TIME 33 10742
TEST_TIME 34 10894
TEST_TIME 35 10590
TEST_TIME 36 10391
TEST_TIME 37 10230
TEST_TIME 38 10258
TEST_TIME 39 10169
TEST_TIME 40 10043
TEST_TIME 41 10004
TEST_TIME 42 9966
TEST_TIME 43 9839
TEST_TIME 44 9769
TEST_TIME 45 9772
TEST_TIME 46 9662
TEST_TIME 47 9656
TEST_TIME 48 9648
TEST_TIME 49 9568
TEST_TIME 50 9572
TEST_TIME 51 9632
TEST_TIME 52 9700
TEST_TIME 53 9758
TEST_TIME 54 9875
TEST_TIME 55 9814
TEST_TIME 56 9756
TEST_TIME 57 9816
TEST_TIME 58 9767
TEST_TIME 59 9689
TEST_TIME 60 9586
TEST_TIME 61 9492
TEST_TIME 62 9420
TEST_TIME 63 9344
TEST_TIME 64 9304
TEST_TIME 65 9334
TEST_TIME 66 9306
TEST_TIME 67 9247
TEST_TIME 68 9248
TEST_TIME 69 9305
TEST_TIME 70 9275
TEST_TIME 71 9261
TEST_TIME 72 9237
TEST_TIME 73 9354
TEST_TIME 74 9397
TEST_TIME 75 9404
TEST_TIME 76 9510
TEST_TIME 77 9448
TEST_TIME 78 9417
TEST_TIME 79 9404
TEST_TIME 80 9335
TEST_TIME 81 9273
TEST_TIME 82 9285
TEST_TIME 83 9242
TEST_TIME 84 9257
TEST_TIME 85 9227
TEST_TIME 86 9195
TEST_TIME 87 9196
TEST_TIME 88 9183
TEST_TIME 89 9216
TEST_TIME 90 9215
TEST_TIME 91 9389
TEST_TIME 92 9335
TEST_TIME 93 9359
TEST_TIME 94 9482
TEST_TIME 95 9554
TEST_TIME 96 9469
TEST_TIME 97 9384
TEST_TIME 98 9330
TEST_TIME 99 9296
TEST_TIME 100 9246
TEST_TIME 101 9207
TEST_TIME 102 9223
TEST_TIME 103 9196
TEST_TIME 104 9165
TEST_TIME 105 9176
TEST_TIME 106 9138
TEST_TIME 107 9114
TEST_TIME 108 9113
TEST_TIME 109 9120
TEST_TIME 110 9265
TEST_TIME 111 9315
TEST_TIME 112 9306
TEST_TIME 113 9302
TEST_TIME 114 9247
TEST_TIME 115 9179
TEST_TIME 116 9136
TEST_TIME 117 9100
TEST_TIME 118 9099
TEST_TIME 119 9063
TEST_TIME 120 9072

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 10-12-2020 04:28:17 to 10-12-2020 04:28:21.

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 1496128 1496128 0 0.00 0 14417 5 0 0
doc_list_biud 1496128 1496128 0 0.00 0 74996 29 0 0
sp_add_invoice_to_stock 4836 4727 109 2.25 46 163905 14476 0 109
sp_cancel_adding_invoice 5776 5776 0 0.00 6 106744 893 0 0
sp_cancel_client_order 1633 1633 0 0.00 5 9374 311 0 0
sp_cancel_customer_reserve 565495 565495 0 0.00 1 74999 62 0 0
sp_cancel_pay_from_customer 685 685 0 0.00 3 4585 81 0 0
sp_cancel_pay_to_supplier 401 401 0 0.00 3 9646 81 0 0
sp_cancel_supplier_invoice 6419 6407 12 0.18 2 13082 769 0 12
sp_cancel_supplier_order 394 394 0 0.00 8 1186774 107946 0 0
sp_cancel_write_off 10107 10107 0 0.00 1 17427 108 0 0
sp_client_order 20053 20053 0 0.00 4 74413 432 0 0
sp_customer_reserve 438557 438557 0 0.00 0 42878 140 0 0
sp_fill_shopping_cart 30742 30742 0 0.00 1 14307 43 0 0
sp_fill_shopping_cart_clo_res 438551 438551 0 0.00 0 22972 18 0 0
sp_get_clo_for_invoice 4721 4721 0 0.00 0 28846 404 0 0
sp_kill_cost_storno 16969 16969 0 0.00 0 7228 50 0 0
sp_kill_qstorno_ret_qs2qd 1147898 1147898 0 0.00 0 74992 33 0 0
sp_kill_qty_storno 604317 604317 0 0.00 0 74996 71 0 0
sp_lock_selected_doc 19950 19950 0 0.00 0 9610 7 0 0
sp_make_cost_storno 26977 26977 0 0.00 0 13542 57 0 0
sp_multiply_rows_for_pdistr 26977 26977 0 0.00 0 10691 31 0 0
sp_multiply_rows_for_qdistr 20053 20053 0 0.00 0 74374 254 0 0
sp_pay_from_customer 6822 6822 0 0.00 4 15087 122 0 0
sp_pay_to_supplier 5650 5650 0 0.00 5 20056 127 0 0
sp_payment_common 12472 12472 0 0.00 1 7763 20 0 0
sp_qd_handle_on_cancel_clo 1633 1633 0 0.00 0 9313 286 0 0
sp_qd_handle_on_invoice_upd_sts 10495 10495 0 0.00 0 28411 324 0 0
sp_qd_handle_on_reserve_upd_sts 9786 9786 0 0.00 0 15033 51 0 0
sp_reserve_write_off 9786 9786 0 0.00 7 20798 185 0 0
sp_supplier_invoice 5407 5407 0 0.00 9 108158 3237 0 0
sp_supplier_order 5295 5295 0 0.00 6 69448 1301 0 0
srv_aggregate_perf_data 99 99 0 0.00 2978 171730 13623 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 1583 140527 52478 0 0
srv_find_qd_qs_mism 1043453 1043453 0 0.00 0 12050 4 0 0
srv_make_invnt_saldo 445 445 0 0.00 62 98660 3017 0 0
srv_make_money_saldo 683 683 0 0.00 1 10112 330 0 0
srv_recalc_idx_stat 4 4 0 0.00 98030 361030 247953 0 0
t$perf-abend:sp_add_invoice_to_stock 109 109 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 4727 4727 0 0.00 0 7241 131 0 0
t$perf-norm:sp_cancel_adding_invoice 384 384 0 0.00 1 1760 69 0 0
t$perf-norm:sp_cancel_client_order 1633 1633 0 0.00 0 2289 10 0 0
t$perf-norm:sp_cancel_customer_reserve 641 641 0 0.00 0 584 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 685 685 0 0.00 0 2102 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 401 401 0 0.00 0 65 1 0 0
t$perf-norm:sp_cancel_supplier_invoice 399 399 0 0.00 0 7359 37 0 0
t$perf-norm:sp_cancel_supplier_order 394 394 0 0.00 0 26191 1546 0 0
t$perf-norm:sp_cancel_write_off 908 908 0 0.00 0 424 4 0 0
t$perf-norm:sp_client_order 20053 20053 0 0.00 0 14522 17 0 0
t$perf-norm:sp_customer_reserve 958 958 0 0.00 0 1280 4 0 0
t$perf-norm:sp_pay_from_customer 6822 6822 0 0.00 0 3788 8 0 0
t$perf-norm:sp_pay_to_supplier 5650 5650 0 0.00 0 7843 8 0 0
t$perf-norm:sp_reserve_write_off 9786 9786 0 0.00 0 8075 9 0 0
t$perf-norm:sp_supplier_invoice 5407 5407 0 0.00 0 5919 13 0 0
t$perf-norm:sp_supplier_order 5295 5295 0 0.00 0 7630 17 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 355 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 599 18 0 0
t$perf-norm:srv_make_invnt_saldo 445 445 0 0.00 0 422 6 0 0
t$perf-norm:srv_make_money_saldo 683 683 0 0.00 0 843 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 18 7 0 0
v_add_invoice_to_stock 9656 9438 218 2.25 0 13528 22 0 218
v_all_customers 36448 36448 0 0.00 0 20 1 0 0
v_all_suppliers 29474 29474 0 0.00 0 129 1 0 0
v_all_wares 40102 40102 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 768 768 0 0.00 0 2244 12 0 0
v_cancel_client_order 3266 3266 0 0.00 0 3525 7 0 0
v_cancel_customer_prepayment 1370 1370 0 0.00 0 4579 14 0 0
v_cancel_customer_reserve 1282 1282 0 0.00 0 4933 14 0 0
v_cancel_payment_to_supplier 802 802 0 0.00 0 284 3 0 0
v_cancel_supplier_invoice 822 798 24 2.91 0 4449 20 0 24
v_cancel_supplier_order 788 788 0 0.00 0 1545 5 0 0
v_cancel_write_off 1816 1816 0 0.00 0 1320 12 0 0
v_max_id_clo_ord 5289 5289 0 0.00 0 301 1 0 0
v_max_id_clo_res 958 958 0 0.00 0 9176 32 0 0
v_max_id_ord_sup 5402 5402 0 0.00 0 3757 6 0 0
v_max_non_paid_invoice 1604 1604 0 0.00 0 869 3 0 0
v_max_non_paid_realizn 3618 3618 0 0.00 0 1389 2 0 0
v_min_id_clo_ord 5289 5289 0 0.00 0 9478 10 0 0
v_min_id_clo_res 958 958 0 0.00 0 1009 4 0 0
v_min_id_ord_sup 5402 5402 0 0.00 0 8666 8 0 0
v_min_non_paid_invoice 5650 5650 0 0.00 0 6515 12 0 0
v_min_non_paid_realizn 6822 6822 0 0.00 0 7759 13 0 0
v_our_firm 10062 10062 0 0.00 0 15 1 0 0
v_reserve_write_off 19572 19572 0 0.00 0 16421 8 0 0
x_lock_depdocs_on_canc_invoice 11784 11784 0 0.00 0 11412 90 0 0
x_lock_depdocs_on_canc_sup_ord 394 394 0 0.00 0 20326 331 0 0
x_make_qstorno_1000_1200 5295 5295 0 0.00 2 69442 1228 0 0
x_make_qstorno_1200_2000 5407 5407 0 0.00 3 108129 3071 0 0
x_make_qstorno_2100_3300 438557 438557 0 0.00 0 42877 120 0 0

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

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
01:56:57 5 8192000000 8796641776 8807108608 3168704 0.038 1 1 2 0 3266400 61840 35856
01:57:58 31123 8192000000 9727508416 10026795008 805601472 8.953 100 86 172 0 834342528 7847744 20893312
01:59:38 27895 8192000000 9778701408 10085859328 841774000 9.318 100 85 169 2 874990160 13341584 19874576
02:01:10 16260 8192000000 9812129152 10129498112 869679216 9.597 100 84 167 2 918989568 30147296 19163056
02:02:28 16650 8192000000 9866666656 10199781376 911739808 10.015 100 80 160 2 980217696 51138256 17339632
02:03:48 6929 8192000000 9931436208 10279501824 956249744 10.452 100 78 156 4 1043006816 69770144 16986928
02:04:59 4833 8192000000 9959319456 10312122368 999666768 10.875 100 84 168 2 1070195440 52986720 17541952
02:06:06 9475 8192000000 9983416752 10351468544 1026953392 11.139 100 83 166 2 1109047360 65221296 16872672
02:07:18 1405 8192000000 10037083664 10404220928 1046349744 11.326 100 89 178 0 1150332352 85745920 18236688
02:08:20 1610 8192000000 10032274704 10404225024 1068041920 11.533 100 92 185 2 1138719456 51463152 19214384
02:09:22 5910 8192000000 10034027376 10434551808 1072338672 11.574 100 37 74 1 1126844496 47199920 7305904
02:10:33 6370 8192000000 10001556832 10398953472 1079560608 11.643 100 42 84 2 1117880800 28533504 9786688
02:11:46 8328 8192000000 9989742208 10391572480 1079763312 11.645 100 31 62 1 1101997072 15527600 6706160
02:12:55 69675 8192000000 10010875920 10422353920 1088877568 11.732 100 35 70 1 1128032464 32269088 6885808
02:15:05 10707 8192000000 10011097776 10420301824 1091397888 11.756 100 35 70 1 1126493856 27845088 7250880
02:16:16 7825 8192000000 10005693872 10411618304 1077617728 11.625 99 30 61 0 1104475712 20481072 6376912
02:17:25 5401 8192000000 9970968256 10357858304 1022763168 11.099 96 32 65 3 1048509328 18915904 6830256
02:18:33 41876 8192000000 9934244432 10335277056 987200928 10.754 94 25 50 1 1012133392 19146448 5786016
02:20:15 7710 8192000000 9929232608 10309820416 1001185200 10.890 98 41 81 2 1028866960 19040224 8641536
02:21:23 9561 8192000000 9940257184 10323128320 993160400 10.812 97 31 63 2 1016712000 16799568 6752032
02:22:34 7103 8192000000 9938116000 10313273344 1024178864 11.112 99 22 44 0 1050779232 21333488 5266880
02:23:43 48043 8192000000 9960007152 10364293120 1048562896 11.347 100 28 54 4 1081441696 26643152 6235648
02:25:34 5151 8192000000 9961094320 10360709120 1060279008 11.459 100 38 79 2 1087844128 18883424 8681696
02:26:41 10158 8192000000 9999448448 10413699072 1082766000 11.674 100 25 50 0 1107280464 18998448 5516016
02:28:08 41216 8192000000 10048869504 10456346624 1104090496 11.876 100 31 60 1 1152547184 42035136 6421552
02:30:00 22937 8192000000 10111903072 10515812352 1129288304 12.115 100 36 72 0 1212216048 74543472 8384272
02:31:44 21303 8192000000 10181852784 10588069888 1149206464 12.302 100 47 94 1 1291882320 131967360 10708496
02:33:12 6346 8192000000 10209460672 10619113472 1147713984 12.288 100 46 89 1 1321986656 164125408 10147264
02:34:20 6816 8192000000 10275448320 10670399488 1159873072 12.402 100 71 143 1 1374472272 198137392 16461808
02:35:32 4786 8192000000 10294627008 10712559616 1171807888 12.514 100 59 118 0 1405219248 219676144 13735216
02:36:44 2444 8192000000 10366807952 10764148736 1176451408 12.557 99 75 150 1 1452750144 258501248 17797488
02:37:49 2179 8192000000 10367827328 10773098496 1173711280 12.532 100 87 172 2 1471002240 276774400 20516560
02:38:59 3247 8192000000 10397896352 10835701760 1187407840 12.659 100 80 160 0 1498866256 292025312 19433104
02:40:07 1581 8192000000 10385357680 10790035456 1184479568 12.632 100 90 178 1 1480454640 275770112 20204960
02:41:11 10744 8192000000 10345421344 10764365824 1151080032 12.320 99 43 85 1 1443576944 282270768 10226144
02:42:28 9903 8192000000 10354179296 10801668096 1169788480 12.495 100 36 71 1 1466353424 287753616 8811328
02:43:43 4944 8192000000 10347947456 10801569792 1148129712 12.292 99 43 84 2 1439057984 280513856 10414416
02:44:54 25122 8192000000 10277750992 10756120576 1126426448 12.088 100 27 53 1 1406889792 273157792 7305552
02:46:20 30695 8192000000 10308952032 10765225984 1126559312 12.089 100 25 50 3 1420291344 286860912 6871120
02:47:59 50301 8192000000 10070049184 10579808256 1070627904 11.558 96 12 24 0 1184367888 110847696 2892288
02:49:51 28474 8192000000 10087136128 10545688576 1095278464 11.793 99 13 23 4 1190779984 92660240 2841280
02:51:25 77658 8192000000 10030113424 10505474048 1085129872 11.696 97 8 17 1 1133007616 46154800 1722944
02:53:48 41474 8192000000 10032366016 10479337472 1084686512 11.692 98 32 64 3 1115785184 24152256 6946416
02:55:31 127016 8192000000 10063481216 10538618880 1023789888 11.109 92 6 12 0 1059153616 34649856 713872
02:58:39 61662 8192000000 10119180448 10554941440 1121967136 12.046 100 22 44 1 1198143728 71092176 5084416
03:00:42 130498 8192000000 10045661296 10483539968 1107607664 11.910 99 14 28 0 1146174544 35623424 2943456
03:03:54 14012 8192000000 10078796576 10505502720 1122253456 12.048 100 63 125 1 1169725760 34443584 13028720
03:05:12 60994 8192000000 10053602816 10502545408 1120359472 12.030 99 15 30 2 1151023152 26927104 3736576
03:07:14 26560 8192000000 10078620320 10516508672 1132047568 12.141 100 17 33 1 1178845936 43254480 3543888
03:08:40 32972 8192000000 10072497088 10520850432 1133080336 12.150 100 20 40 2 1172895616 35620976 4194304
03:10:14 77890 8192000000 10088725824 10554310656 1129458608 12.116 99 16 32 1 1183384960 50441040 3485312
03:13:31 43647 8192000000 10094995360 10532630528 1138219488 12.199 100 14 28 0 1177135216 36056432 2859296
03:15:15 34103 8192000000 10114128320 10554339328 1137170016 12.189 99 29 57 0 1190183120 47316928 5696176
03:16:50 34761 8192000000 10146784992 10584301568 1132542736 12.145 98 29 56 1 1208300288 69549712 6207840
03:18:39 70349 8192000000 10144026528 10609905664 1133515216 12.154 99 20 39 1 1268265984 129910592 4840176
03:20:52 51333 8192000000 10082205472 10539167744 1117572640 12.004 98 17 34 0 1165605792 44268944 3764208
03:23:00 86556 8192000000 10090898496 10561626112 1095359120 11.794 96 28 53 5 1202186672 100600656 6226896
03:25:41 110602 8192000000 10040518144 10492514304 1103987424 11.875 99 26 52 0 1157679344 48160144 5531776
03:28:40 84649 8192000000 10069537792 10533490688 1106907088 11.903 98 8 16 0 1151658576 42793616 1957872
03:31:05 50962 8192000000 10057651280 10523099136 1099308960 11.831 98 21 44 3 1171250448 67668112 4273376
03:32:59 50973 8192000000 10063485520 10479706112 1032440528 11.192 92 22 43 0 1067294592 30457024 4397040
03:34:51 60493 8192000000 10045048176 10502598656 1047400720 11.336 94 27 53 1 1117913360 65196576 5316064
03:36:53 56003 8192000000 10069533280 10504421376 1079638352 11.644 98 36 72 0 1131206368 44409136 7158880
03:38:49 64179 8192000000 10069968336 10540761088 1096520880 11.805 99 21 42 0 1209449952 108328736 4600336
03:40:57 43580 8192000000 10094751328 10552393728 1092864320 11.770 98 21 42 0 1166861168 68844128 5152720
03:42:41 59860 8192000000 10033182192 10455556096 1053515184 11.394 94 19 36 2 1100620256 43427344 3677728
03:44:42 140527 8192000000 10053937920 10512478208 1095192512 11.792 99 24 48 1 1149486528 49021888 5272128
03:48:02 66326 8192000000 10039868288 10536845312 1105539600 11.890 100 8 16 0 1157884960 50639552 1705808
03:50:09 62790 8192000000 10037154224 10472243200 1080533440 11.653 97 17 34 0 1112625344 28276992 3814912
03:52:12 52899 8192000000 10105456144 10545577984 1114412048 11.974 99 24 48 5 1181153760 61484752 5256960
03:54:05 46723 8192000000 10145316144 10588696576 1133802352 12.157 100 22 44 1 1219366912 80998896 4565664
03:55:52 56355 8192000000 10149251632 10616299520 1120172000 12.029 98 20 40 5 1231092896 106091696 4829200
03:58:03 53799 8192000000 10119160992 10549415936 1143082864 12.245 100 19 39 0 1180947808 34586464 3278480
04:00:44 70238 8192000000 10118226848 10660573184 1140538272 12.221 99 16 32 1 1231687888 88214048 2935568
04:02:55 118164 8192000000 10088982864 10554122240 1157669856 12.381 100 19 39 1 1193749152 32012064 4067232
04:05:54 108603 8192000000 10097015600 10586894336 1128813232 12.110 97 10 20 0 1191241504 60175040 2253232
04:08:51 74919 8192000000 10101856944 10548072448 1148252880 12.293 99 28 56 1 1184946896 30557680 6136336
04:11:25 79511 8192000000 10123488704 10562699264 1111891232 11.950 95 21 42 0 1159159664 42974704 4293728
04:13:46 73491 8192000000 10205185824 10670018560 1162603968 12.428 99 26 51 2 1294331408 125512768 6214672
04:16:06 86526 8192000000 10124583104 10639282176 1125034992 12.075 97 9 18 0 1217912848 90943456 1934400
04:19:40 100144 8192000000 10100215424 10551975936 1128114464 12.104 98 35 70 2 1176825504 41341136 7369904
04:22:45 55787 8192000000 10109469776 10563481600 1118746816 12.015 97 10 20 1 1187341264 66433312 2161136
04:24:41 38189 8192000000 10099656384 10595524608 1136733296 12.185 99 7 14 1 1182694656 44548096 1413264
04:26:20 37768 8192000000 9191810624 9491472384 322374880 3.786 29 4 8 0 332877344 9721040 781424

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 10-12-2020 04:28:28 to 10-12-2020 04:28:42.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 109 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 10-12-2020 04:28:43 to 10-12-2020 04:28:45.

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          232108
MON$OLDEST_ACTIVE               232109
MON$OLDEST_SNAPSHOT             232109
MON$NEXT_TRANSACTION            232109
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                       2120576
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             791
MON$NEXT_STATEMENT              53


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

10.12.20 04:28:46. 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-12-10 04:28:49 No severe PSQL-related problems occured
10.12.20 04:28:50. End of report.