Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.02.2021 04:29
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 16329476 kB
MemAvailable 31178244 kB
Buffers 20 kB
Cached 15319864 kB
SwapCached 4780 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-02-13 04:27:33
DTS_END                         2021-02-13 04:26:55.7920
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/56284
TRN_ID                          233622
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: 9118.83 20428 1094259
customer order: creation 167.56 395 20107
customer order: refuse 13.95 315 1674
order to supplier: creation 44.35 1435 5322
order to supplier: removal 3.39 106587 407
invoice (draft): creation 44.61 3392 5353
invoice (draft): removal 53.08 811 6370
invoice accept: apply 41.20 17280 4944
invoice accept: cancel 47.58 839 5710
customer reserve: creation 3731.53 174 447783
customer reserve: removal 4679.11 61 561493
realization accept: apply 81.23 166 9748
realization accept: cancel 85.74 118 10289
payment from customer: creation 58.19 116 6983
payment from customer: removal 5.58 79 669
payment to supplier: creation 48.11 114 5773
payment to supplier: removal 3.15 84 378
service: total inventory turnovers 3.78 3385 453
service: total monetary turnovers 5.74 493 689
service: aggregate perf. data 0.91 13976 109
service: refresh index statistics 0.04 258744 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-02-2021 04:30:00 to 13-02-2021 04:30:02.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2705
TEST_TIME 2 3151
TEST_TIME 3 3454
TEST_TIME 4 3599
TEST_TIME 5 4118
TEST_TIME 6 4855
TEST_TIME 7 5673
TEST_TIME 8 5867
TEST_TIME 9 5850
TEST_TIME 10 5961
TEST_TIME 11 6303
TEST_TIME 12 6272
TEST_TIME 13 6836
TEST_TIME 14 7078
TEST_TIME 15 7208
TEST_TIME 16 7431
TEST_TIME 17 7637
TEST_TIME 18 7740
TEST_TIME 19 8435
TEST_TIME 20 8556
TEST_TIME 21 9032
TEST_TIME 22 9068
TEST_TIME 23 9306
TEST_TIME 24 9945
TEST_TIME 25 10159
TEST_TIME 26 10274
TEST_TIME 27 10715
TEST_TIME 28 11019
TEST_TIME 29 10920
TEST_TIME 30 10830
TEST_TIME 31 10681
TEST_TIME 32 10728
TEST_TIME 33 10847
TEST_TIME 34 10813
TEST_TIME 35 10732
TEST_TIME 36 10513
TEST_TIME 37 10388
TEST_TIME 38 10198
TEST_TIME 39 10238
TEST_TIME 40 10165
TEST_TIME 41 10078
TEST_TIME 42 9992
TEST_TIME 43 10078
TEST_TIME 44 9920
TEST_TIME 45 9817
TEST_TIME 46 9689
TEST_TIME 47 9776
TEST_TIME 48 9804
TEST_TIME 49 9798
TEST_TIME 50 9818
TEST_TIME 51 9836
TEST_TIME 52 9763
TEST_TIME 53 9798
TEST_TIME 54 9765
TEST_TIME 55 9820
TEST_TIME 56 9776
TEST_TIME 57 9819
TEST_TIME 58 9857
TEST_TIME 59 9896
TEST_TIME 60 9885
TEST_TIME 61 9846
TEST_TIME 62 9713
TEST_TIME 63 9673
TEST_TIME 64 9567
TEST_TIME 65 9467
TEST_TIME 66 9516
TEST_TIME 67 9455
TEST_TIME 68 9440
TEST_TIME 69 9489
TEST_TIME 70 9472
TEST_TIME 71 9514
TEST_TIME 72 9504
TEST_TIME 73 9545
TEST_TIME 74 9500
TEST_TIME 75 9482
TEST_TIME 76 9471
TEST_TIME 77 9504
TEST_TIME 78 9451
TEST_TIME 79 9453
TEST_TIME 80 9437
TEST_TIME 81 9553
TEST_TIME 82 9467
TEST_TIME 83 9368
TEST_TIME 84 9314
TEST_TIME 85 9251
TEST_TIME 86 9171
TEST_TIME 87 9135
TEST_TIME 88 9135
TEST_TIME 89 9109
TEST_TIME 90 9120
TEST_TIME 91 9121
TEST_TIME 92 9189
TEST_TIME 93 9216
TEST_TIME 94 9217
TEST_TIME 95 9222
TEST_TIME 96 9237
TEST_TIME 97 9279
TEST_TIME 98 9294
TEST_TIME 99 9343
TEST_TIME 100 9358
TEST_TIME 101 9295
TEST_TIME 102 9215
TEST_TIME 103 9201
TEST_TIME 104 9157
TEST_TIME 105 9092
TEST_TIME 106 9040
TEST_TIME 107 9100
TEST_TIME 108 9065
TEST_TIME 109 9057
TEST_TIME 110 9029
TEST_TIME 111 9021
TEST_TIME 112 9012
TEST_TIME 113 9085
TEST_TIME 114 9060
TEST_TIME 115 9109
TEST_TIME 116 9103
TEST_TIME 117 9119
TEST_TIME 118 9186
TEST_TIME 119 9196
TEST_TIME 120 9121
TEST_TIME 121 9046

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 13-02-2021 04:30:04 to 13-02-2021 04:30:07.

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 1508605 1508605 0 0.00 0 23626 6 0 0
doc_list_biud 1508602 1508602 0 0.00 0 27418 29 0 0
sp_add_invoice_to_stock 5082 4944 138 2.71 56 179515 17280 0 138
sp_cancel_adding_invoice 5710 5710 0 0.00 5 75586 839 0 0
sp_cancel_client_order 1674 1674 0 0.00 5 11662 315 0 0
sp_cancel_customer_reserve 561493 561493 0 0.00 1 22476 62 0 0
sp_cancel_pay_from_customer 669 669 0 0.00 3 11106 79 0 0
sp_cancel_pay_to_supplier 378 378 0 0.00 3 4613 84 0 0
sp_cancel_supplier_invoice 6386 6370 16 0.25 2 21305 811 0 16
sp_cancel_supplier_order 407 407 0 0.00 6 1029959 106587 0 0
sp_cancel_write_off 10289 10289 0 0.00 1 21927 118 0 0
sp_client_order 20107 20107 0 0.00 4 93568 396 0 0
sp_customer_reserve 447783 447783 0 0.00 0 93225 175 0 0
sp_fill_shopping_cart 30771 30771 0 0.00 1 16087 45 0 0
sp_fill_shopping_cart_clo_res 447781 447781 0 0.00 0 19477 18 0 0
sp_get_clo_for_invoice 4939 4939 0 0.00 0 26410 342 0 0
sp_kill_cost_storno 17046 17046 0 0.00 0 14770 55 0 0
sp_kill_qstorno_ret_qs2qd 1140052 1140052 0 0.00 0 22461 33 0 0
sp_kill_qty_storno 600627 600627 0 0.00 0 27417 71 0 0
sp_lock_selected_doc 20276 20276 0 0.00 0 11667 7 0 0
sp_make_cost_storno 27443 27443 0 0.00 0 18345 54 0 0
sp_multiply_rows_for_pdistr 27443 27443 0 0.00 0 16574 30 0 0
sp_multiply_rows_for_qdistr 20107 20107 0 0.00 0 89469 221 0 0
sp_pay_from_customer 6983 6983 0 0.00 4 17039 116 0 0
sp_pay_to_supplier 5773 5773 0 0.00 5 18076 115 0 0
sp_payment_common 12756 12756 0 0.00 1 16010 24 0 0
sp_qd_handle_on_cancel_clo 1674 1674 0 0.00 1 11654 283 0 0
sp_qd_handle_on_invoice_upd_sts 10648 10648 0 0.00 0 27417 327 0 0
sp_qd_handle_on_reserve_upd_sts 9746 9746 0 0.00 0 7197 45 0 0
sp_reserve_write_off 9748 9748 0 0.00 7 17731 167 0 0
sp_supplier_invoice 5353 5353 0 0.00 7 149923 3392 0 0
sp_supplier_order 5322 5322 0 0.00 6 44960 1436 0 0
srv_aggregate_perf_data 109 109 0 0.00 2676 248098 13976 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 3012 156266 48219 0 0
srv_find_qd_qs_mism 1047721 1047721 0 0.00 0 23625 6 0 0
srv_make_invnt_saldo 453 453 0 0.00 60 101436 3385 0 0
srv_make_money_saldo 689 689 0 0.00 1 13856 493 0 0
srv_recalc_idx_stat 5 5 0 0.00 111891 389384 258744 0 0
t$perf-abend:sp_add_invoice_to_stock 138 138 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4944 4944 0 0.00 0 12768 164 0 0
t$perf-norm:sp_cancel_adding_invoice 415 415 0 0.00 1 3001 84 0 0
t$perf-norm:sp_cancel_client_order 1674 1674 0 0.00 0 3369 15 0 0
t$perf-norm:sp_cancel_customer_reserve 660 660 0 0.00 0 5190 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 669 669 0 0.00 0 417 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 378 378 0 0.00 0 1498 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 400 400 0 0.00 0 1668 20 0 0
t$perf-norm:sp_cancel_supplier_order 407 407 0 0.00 0 24721 1379 0 0
t$perf-norm:sp_cancel_write_off 989 989 0 0.00 0 3046 14 0 0
t$perf-norm:sp_client_order 20107 20107 0 0.00 0 23645 19 0 0
t$perf-norm:sp_customer_reserve 957 957 0 0.00 0 3333 9 0 0
t$perf-norm:sp_pay_from_customer 6983 6983 0 0.00 0 11512 10 0 0
t$perf-norm:sp_pay_to_supplier 5773 5773 0 0.00 0 4575 8 0 0
t$perf-norm:sp_reserve_write_off 9748 9748 0 0.00 0 5872 12 0 0
t$perf-norm:sp_supplier_invoice 5353 5353 0 0.00 0 9463 23 0 0
t$perf-norm:sp_supplier_order 5322 5322 0 0.00 0 3669 11 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 521 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 62 2 0 0
t$perf-norm:srv_make_invnt_saldo 453 453 0 0.00 0 5978 23 0 0
t$perf-norm:srv_make_money_saldo 689 689 0 0.00 0 833 7 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 10152 9876 276 2.71 0 3974 15 0 276
v_all_customers 36828 36828 0 0.00 0 125 1 0 0
v_all_suppliers 29674 29674 0 0.00 0 19 1 0 0
v_all_wares 40214 40214 0 0.00 0 126 1 0 0
v_cancel_adding_invoice 830 830 0 0.00 0 407 6 0 0
v_cancel_client_order 3348 3348 0 0.00 0 3091 5 0 0
v_cancel_customer_prepayment 1338 1338 0 0.00 0 2179 6 0 0
v_cancel_customer_reserve 1320 1320 0 0.00 0 910 5 0 0
v_cancel_payment_to_supplier 756 756 0 0.00 0 2704 8 0 0
v_cancel_supplier_invoice 832 800 32 3.84 0 584 15 0 32
v_cancel_supplier_order 814 814 0 0.00 0 793 4 0 0
v_cancel_write_off 1978 1978 0 0.00 0 10595 20 0 0
v_max_id_clo_ord 5321 5321 0 0.00 0 3224 2 0 0
v_max_id_clo_res 957 957 0 0.00 0 1790 13 0 0
v_max_id_ord_sup 5343 5343 0 0.00 0 4416 4 0 0
v_max_non_paid_invoice 1600 1600 0 0.00 0 49 2 0 0
v_max_non_paid_realizn 3647 3647 0 0.00 0 3535 4 0 0
v_min_id_clo_ord 5321 5321 0 0.00 0 1693 4 0 0
v_min_id_clo_res 957 957 0 0.00 0 2029 4 0 0
v_min_id_ord_sup 5343 5343 0 0.00 0 9892 12 0 0
v_min_non_paid_invoice 5773 5773 0 0.00 0 13363 15 0 0
v_min_non_paid_realizn 6983 6983 0 0.00 0 15504 18 0 0
v_our_firm 10058 10058 0 0.00 0 15 1 0 0
v_reserve_write_off 19492 19492 0 0.00 0 3813 5 0 0
x_lock_depdocs_on_canc_invoice 11680 11680 0 0.00 0 38687 115 0 0
x_lock_depdocs_on_canc_sup_ord 407 407 0 0.00 0 11057 282 0 0
x_make_qstorno_1000_1200 5322 5322 0 0.00 2 44895 1360 0 0
x_make_qstorno_1200_2000 5353 5353 0 0.00 3 149787 3214 0 0
x_make_qstorno_2100_3300 447783 447783 0 0.00 0 93163 154 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-02-2021 04:30:08 to 13-02-2021 04:30:12.

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:59 9 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
01:58:00 33259 8192000000 9723099264 10020663296 795596976 8.852 100 91 182 0 826396672 8004096 22795600
01:59:41 16178 8192000000 9771615328 10077487104 844020208 9.340 100 89 178 1 877633952 12183440 21430304
02:01:00 18844 8192000000 9804295872 10120507392 870334752 9.603 100 86 172 0 915096944 24899168 19863024
02:02:25 12213 8192000000 9863757616 10197364736 916710304 10.064 100 78 156 1 972796416 39377808 16708304
02:03:39 9594 8192000000 9933416048 10276646912 968008624 10.567 100 78 156 3 1039349584 55254976 16085984
02:04:52 4861 8192000000 9966291840 10320232448 999504032 10.874 100 88 173 2 1089695840 71933008 18258800
02:05:59 2709 8192000000 10007276224 10366812160 1028404912 11.153 100 91 180 1 1117503664 71047408 18051344
02:07:04 1265 8192000000 10041025744 10406703104 1046359280 11.326 100 91 179 2 1153198976 88261616 18578080
02:08:06 1963 8192000000 10050742592 10419441664 1059632816 11.453 100 93 185 3 1161231056 82653216 18945024
02:09:08 9137 8192000000 9992922496 10390577152 1061028032 11.466 100 29 58 1 1098199856 31658272 5513552
02:10:19 7954 8192000000 9986240224 10382671872 1067572352 11.529 100 30 61 3 1103354256 29638928 6142976
02:11:27 14761 8192000000 9983165088 10378424320 1072893600 11.580 100 22 43 3 1097638192 19580544 5164048
02:12:43 22922 8192000000 9997029440 10420789248 1082870976 11.675 100 16 32 0 1123078752 37163184 3044592
02:14:39 4767 8192000000 10014515728 10414669824 1086065696 11.705 100 35 70 1 1113069264 19926752 7076816
02:15:45 10036 8192000000 10010687920 10410893312 1091778848 11.760 100 18 36 2 1114835776 18782256 4274672
02:17:02 12216 8192000000 9975686160 10378870784 1059518080 11.452 99 19 39 1 1088913792 25652544 3743168
02:18:17 25131 8192000000 9979250288 10364444672 1046370352 11.326 98 46 89 1 1072326960 15810320 10146288
02:19:42 5798 8192000000 9944144144 10333880320 1041821776 11.282 100 50 100 4 1064813056 12512096 10479184
02:20:51 7462 8192000000 9945563616 10332266496 1019758640 11.070 100 30 60 0 1060653408 34110464 6784304
02:21:59 5683 8192000000 9947071248 10331660288 1024125600 11.112 100 59 117 1 1053655312 16050544 13479168
02:23:07 56330 8192000000 9939069072 10346233856 1037155392 11.237 100 31 61 1 1066180864 21826176 7199296
02:25:04 5446 8192000000 9987803792 10373971968 1060403424 11.460 100 48 96 2 1096525040 25012208 11109408
02:26:10 5629 8192000000 10005857264 10401820672 1078317744 11.631 100 39 74 2 1112201264 25426864 8456656
02:27:21 24558 8192000000 10027275456 10431954944 1097734480 11.816 100 38 76 1 1130547232 24649424 8163328
02:28:53 76274 8192000000 10074394704 10491236352 1110433296 11.937 100 31 62 1 1178022672 60850656 6738720
02:31:41 8034 8192000000 10115936320 10525224960 1119500112 12.022 99 52 103 2 1216467712 85173856 11793744
02:32:54 4466 8192000000 10162830784 10564538368 1139391120 12.210 100 71 137 1 1262860384 109648944 13820320
02:34:02 13419 8192000000 10203735200 10608295936 1160662304 12.409 100 70 140 0 1310099872 133428704 16008864
02:35:25 10169 8192000000 10217406640 10632613888 1171272432 12.509 100 52 100 1 1332113376 149558176 11282768
02:36:41 4565 8192000000 10279564464 10694656000 1175332544 12.547 100 66 129 0 1381511504 192486880 13692080
02:37:49 6009 8192000000 10286526432 10703335424 1175131552 12.545 100 65 128 2 1397161472 207209056 14820864
02:39:04 3010 8192000000 10322823360 10744066048 1180232288 12.592 100 78 154 0 1421222128 224244816 16745024
02:40:09 3906 8192000000 10318358288 10741280768 1180498304 12.595 100 58 116 0 1418372528 224123904 13750320
02:41:16 4806 8192000000 10308352208 10727280640 1176733808 12.560 100 45 91 1 1416780864 229247664 10799392
02:42:24 5078 8192000000 10357462944 10779504640 1157705088 12.382 98 54 108 1 1448260976 277848976 12706912
02:43:35 13546 8192000000 10355012896 10782908416 1152675328 12.335 99 41 81 0 1456852688 294024768 10152592
02:45:02 12413 8192000000 10348994688 10773409792 1109621248 11.929 97 46 90 1 1432268160 310926864 11720048
02:46:18 19830 8192000000 10328718848 10776322048 1152052832 12.329 100 35 69 0 1436930368 276018384 8859152
02:47:41 10683 8192000000 10303294064 10775957504 1127244832 12.095 98 33 66 1 1386551120 250569520 8736768
02:49:04 11684 8192000000 10308760704 10741641216 1126878192 12.092 100 29 58 0 1409155648 275028352 7249104
02:50:20 19624 8192000000 10328172736 10759143424 1126718576 12.090 100 27 54 1 1426016384 292315936 6981872
02:51:42 45305 8192000000 10235838832 10672615424 1108754288 11.921 99 19 38 2 1340565120 226952400 4858432
02:53:39 31135 8192000000 10094735504 10640556032 1114655920 11.976 100 10 20 1 1279502800 162425632 2421248
02:55:18 34151 8192000000 10039812240 10487754752 1086478880 11.709 98 22 43 2 1147719008 56261568 4978560
02:56:52 62533 8192000000 10099113056 10513166336 1108601936 11.919 99 32 64 0 1170770608 56337632 5831040
02:58:57 115468 8192000000 10063172272 10510139392 1094182304 11.782 97 13 25 1 1139891952 43555216 2154432
03:01:55 123163 8192000000 10119563312 10583851008 1126311936 12.087 99 32 63 0 1213407456 79948384 7147136
03:05:44 48289 8192000000 10104093792 10540331008 1131729424 12.138 99 26 52 4 1189648592 52645968 5273200
03:07:32 39731 8192000000 10117946560 10574843904 1146750224 12.279 100 26 49 2 1209725440 57735440 5239776
03:09:13 49168 8192000000 10117771168 10603081728 1146906368 12.280 99 16 32 0 1193674544 43136448 3631728
03:11:02 156250 8192000000 10201565376 10684370944 1124968576 12.074 97 16 32 0 1236723584 107972752 3782256
03:15:14 53845 8192000000 10085522272 10568769536 1148586944 12.296 100 15 30 1 1220036816 68227584 3222288
03:17:11 53490 8192000000 10081048704 10519031808 1138237936 12.199 98 30 59 2 1165703232 21493936 5971360
03:19:05 32548 8192000000 10108444416 10569134080 1146990000 12.281 99 28 56 0 1214365216 61728896 5646320
03:20:41 68475 8192000000 10119255328 10601504768 1152435232 12.332 99 31 62 2 1264810688 105571264 6804192
03:23:03 58733 8192000000 10100546736 10552168448 1143597472 12.249 99 13 26 0 1176117840 30053712 2466656
03:25:02 81137 8192000000 10110032160 10581377024 1130853408 12.129 98 16 32 1 1194536080 60313968 3368704
03:27:51 118300 8192000000 10066273760 10540228608 1125342032 12.077 98 21 42 0 1172291456 42836656 4112768
03:30:51 54977 8192000000 10079013648 10540462080 1108794384 11.921 97 11 22 0 1154246736 43543264 1909088
03:32:46 65081 8192000000 10094633728 10547978240 1131244624 12.133 99 10 20 0 1183372144 50520192 1607328
03:34:52 58171 8192000000 10117181104 10575335424 1138249056 12.199 99 23 46 0 1211251328 68394944 4607328
03:36:52 61999 8192000000 10095196256 10579808256 1084533664 11.691 95 14 28 0 1157891760 70300608 3057488
03:38:58 50160 8192000000 10073229376 10517831680 1091805536 11.760 97 19 37 1 1155553696 59581856 4166304
03:40:50 48484 8192000000 10060551488 10510102528 1080657040 11.654 97 28 54 0 1121922496 36207472 5057984
03:43:23 92521 8192000000 10087399056 10528784384 1099537616 11.833 99 21 40 2 1151967568 48194624 4235328
03:46:53 41456 8192000000 10088876384 10538635264 1098738096 11.826 99 13 26 0 1180104416 78625360 2740960
03:48:39 68763 8192000000 10055183760 10556424192 1112300416 11.954 100 14 28 0 1150943504 35882144 2760944
03:51:04 32106 8192000000 10035953248 10479116288 1099650864 11.834 98 10 20 0 1134227072 32317680 2258528
03:52:39 76719 8192000000 10090780480 10561609728 1115896704 11.988 100 15 30 0 1181197808 61641328 3659776
03:56:04 42458 8192000000 10112323392 10546741248 1125119856 12.075 99 25 50 1 1221877200 90905632 5851712
03:57:47 62776 8192000000 10134533632 10642903040 1123415136 12.059 98 21 42 3 1227995904 99759120 4821648
03:59:55 80868 8192000000 10072221872 10526965760 1117908128 12.007 98 19 38 1 1171927152 49975616 4043408
04:02:17 84232 8192000000 10096362928 10596012032 1132456176 12.145 99 13 24 2 1203309552 68138816 2714560
04:04:52 26520 8192000000 10133734352 10585325568 1156940256 12.375 100 39 75 3 1194797936 29632400 8225280
04:06:39 39931 8192000000 10107206256 10561773568 1155140096 12.358 100 21 42 1 1213554480 54234432 4179952
04:08:20 68605 8192000000 10092135968 10570366976 1154900704 12.355 100 33 66 1 1209433584 48185696 6347184
04:10:37 59139 8192000000 10107719856 10578800640 1160174016 12.405 100 5 10 0 1197702640 36612384 916240
04:12:36 76027 8192000000 10127520800 10662989824 1157438896 12.379 99 11 22 0 1235322672 75631760 2252016
04:14:53 66502 8192000000 10090776912 10549637120 1131688624 12.137 98 24 48 1 1165200176 27877696 5633856
04:16:59 33507 8192000000 10140154576 10602889216 1152254320 12.331 99 21 42 1 1229095568 72236864 4604384
04:19:10 49767 8192000000 10086685072 10593329152 1123327392 12.058 99 24 48 0 1226614816 97921904 5365520
04:21:04 93750 8192000000 10073383968 10564411392 1099537040 11.833 96 15 30 0 1153229904 50568368 3124496
04:23:38 28313 8192000000 10155849648 10671022080 1134124624 12.160 100 19 38 0 1262710944 124567856 4018464
04:25:14 54527 8192000000 10055876208 10566094848 1123824112 12.063 100 8 16 0 1189460320 63660992 1975216

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 13-02-2021 04:30:14 to 13-02-2021 04:30:29.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 138 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 13-02-2021 04:30:30 to 13-02-2021 04:30:32.

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          233663
MON$OLDEST_ACTIVE               236278
MON$OLDEST_SNAPSHOT             236278
MON$NEXT_TRANSACTION            236278
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                       2118464
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                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             794
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

13.02.21 04:30:33. 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-02-13 04:30:35 No severe PSQL-related problems occured
13.02.21 04:30:37. End of report.