Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 01.04.2021 07:40
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 df9f488d08424df294c459d6c283e9c3
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 2021-03-04 16:07
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.26
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 16692904 kB
MemAvailable 31434024 kB
Buffers 0 kB
Cached 14925892 kB
SwapCached 0 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-04-01 07:39:40
DTS_END                         2021-04-01 07:39:40.6620
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/34454
TRN_ID                          232053
ATT_ID                          626
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: 8900.68 22978 1068082
customer order: creation 169.97 391 20396
customer order: refuse 14.99 353 1799
order to supplier: creation 45.63 1511 5475
order to supplier: removal 3.34 114894 401
invoice (draft): creation 45.03 3638 5403
invoice (draft): removal 48.75 1065 5850
invoice accept: apply 41.77 18507 5012
invoice accept: cancel 44.45 892 5334
customer reserve: creation 3845.02 181 461402
customer reserve: removal 4350.94 68 522113
realization accept: apply 83.45 205 10014
realization accept: cancel 80.08 141 9609
payment from customer: creation 58.73 121 7047
payment from customer: removal 5.78 96 694
payment to supplier: creation 48.51 118 5821
payment to supplier: removal 3.45 72 414
service: total inventory turnovers 3.80 3860 456
service: total monetary turnovers 6.01 610 721
service: aggregate perf. data 0.98 14685 117
service: refresh index statistics 0.03 298142 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 01-04-2021 07:40:38 to 01-04-2021 07:40:41.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4633
TEST_TIME 2 3488
TEST_TIME 3 4198
TEST_TIME 4 5358
TEST_TIME 5 4990
TEST_TIME 6 4843
TEST_TIME 7 5207
TEST_TIME 8 5351
TEST_TIME 9 5218
TEST_TIME 10 5434
TEST_TIME 11 5651
TEST_TIME 12 6075
TEST_TIME 13 6481
TEST_TIME 14 6585
TEST_TIME 15 6754
TEST_TIME 16 6738
TEST_TIME 17 6783
TEST_TIME 18 6898
TEST_TIME 19 6961
TEST_TIME 20 7059
TEST_TIME 21 7277
TEST_TIME 22 7443
TEST_TIME 23 7764
TEST_TIME 24 7719
TEST_TIME 25 7952
TEST_TIME 26 8188
TEST_TIME 27 8234
TEST_TIME 28 8622
TEST_TIME 29 8798
TEST_TIME 30 9287
TEST_TIME 31 9514
TEST_TIME 32 9658
TEST_TIME 33 9995
TEST_TIME 34 10046
TEST_TIME 35 10141
TEST_TIME 36 10188
TEST_TIME 37 10230
TEST_TIME 38 10111
TEST_TIME 39 10008
TEST_TIME 40 10066
TEST_TIME 41 10111
TEST_TIME 42 10075
TEST_TIME 43 9971
TEST_TIME 44 9781
TEST_TIME 45 9605
TEST_TIME 46 9590
TEST_TIME 47 9588
TEST_TIME 48 9576
TEST_TIME 49 9522
TEST_TIME 50 9572
TEST_TIME 51 9544
TEST_TIME 52 9462
TEST_TIME 53 9363
TEST_TIME 54 9323
TEST_TIME 55 9266
TEST_TIME 56 9199
TEST_TIME 57 9213
TEST_TIME 58 9256
TEST_TIME 59 9379
TEST_TIME 60 9393
TEST_TIME 61 9366
TEST_TIME 62 9388
TEST_TIME 63 9366
TEST_TIME 64 9354
TEST_TIME 65 9530
TEST_TIME 66 9521
TEST_TIME 67 9489
TEST_TIME 68 9518
TEST_TIME 69 9512
TEST_TIME 70 9519
TEST_TIME 71 9538
TEST_TIME 72 9572
TEST_TIME 73 9449
TEST_TIME 74 9385
TEST_TIME 75 9316
TEST_TIME 76 9255
TEST_TIME 77 9162
TEST_TIME 78 9184
TEST_TIME 79 9141
TEST_TIME 80 9104
TEST_TIME 81 9111
TEST_TIME 82 9087
TEST_TIME 83 9066
TEST_TIME 84 9020
TEST_TIME 85 9103
TEST_TIME 86 9124
TEST_TIME 87 9097
TEST_TIME 88 9136
TEST_TIME 89 9145
TEST_TIME 90 9163
TEST_TIME 91 9154
TEST_TIME 92 9173
TEST_TIME 93 9078
TEST_TIME 94 9028
TEST_TIME 95 9003
TEST_TIME 96 9003
TEST_TIME 97 9007
TEST_TIME 98 8979
TEST_TIME 99 8989
TEST_TIME 100 8978
TEST_TIME 101 9008
TEST_TIME 102 8995
TEST_TIME 103 8987
TEST_TIME 104 9043
TEST_TIME 105 9006
TEST_TIME 106 9049
TEST_TIME 107 9097
TEST_TIME 108 9094
TEST_TIME 109 9067
TEST_TIME 110 9030
TEST_TIME 111 8982
TEST_TIME 112 8943
TEST_TIME 113 8890
TEST_TIME 114 8862
TEST_TIME 115 8883
TEST_TIME 116 8862
TEST_TIME 117 8842
TEST_TIME 118 8844
TEST_TIME 119 8823
TEST_TIME 120 8901

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 01-04-2021 07:40:42 to 01-04-2021 07:40:45.

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 1494619 1494619 0 0.00 0 29474 7 0 0
doc_list_biud 1494619 1494619 0 0.00 0 29512 31 0 0
sp_add_invoice_to_stock 5177 5012 165 3.18 46 178668 18507 0 165
sp_cancel_adding_invoice 5334 5334 0 0.00 6 82707 893 0 0
sp_cancel_client_order 1799 1799 0 0.00 4 10079 354 0 0
sp_cancel_customer_reserve 522113 522113 0 0.00 1 26578 69 0 0
sp_cancel_pay_from_customer 694 694 0 0.00 3 7174 97 0 0
sp_cancel_pay_to_supplier 414 414 0 0.00 3 1539 73 0 0
sp_cancel_supplier_invoice 5867 5850 17 0.28 3 18382 1066 0 17
sp_cancel_supplier_order 401 401 0 0.00 9 1125187 114895 0 0
sp_cancel_write_off 9609 9609 0 0.00 1 29519 141 0 0
sp_client_order 20396 20396 0 0.00 4 25341 392 0 0
sp_customer_reserve 461402 461402 0 0.00 0 81560 181 0 0
sp_fill_shopping_cart 31265 31265 0 0.00 1 38813 47 0 0
sp_fill_shopping_cart_clo_res 461402 461402 0 0.00 0 28021 18 0 0
sp_get_clo_for_invoice 5012 5012 0 0.00 0 35618 372 0 0
sp_kill_cost_storno 16051 16051 0 0.00 0 5128 64 0 0
sp_kill_qstorno_ret_qs2qd 1060086 1060086 0 0.00 0 29511 38 0 0
sp_kill_qty_storno 560132 560132 0 0.00 0 29511 82 0 0
sp_lock_selected_doc 20772 20772 0 0.00 0 8415 6 0 0
sp_make_cost_storno 27894 27894 0 0.00 0 22007 65 0 0
sp_multiply_rows_for_pdistr 27894 27894 0 0.00 0 10050 36 0 0
sp_multiply_rows_for_qdistr 20396 20396 0 0.00 0 15826 201 0 0
sp_pay_from_customer 7047 7047 0 0.00 4 15045 122 0 0
sp_pay_to_supplier 5821 5821 0 0.00 5 21842 119 0 0
sp_payment_common 12868 12868 0 0.00 1 7454 27 0 0
sp_qd_handle_on_cancel_clo 1799 1799 0 0.00 0 10073 328 0 0
sp_qd_handle_on_invoice_upd_sts 10346 10346 0 0.00 0 29301 372 0 0
sp_qd_handle_on_reserve_upd_sts 10014 10014 0 0.00 0 19474 62 0 0
sp_reserve_write_off 10014 10014 0 0.00 7 19508 206 0 0
sp_supplier_invoice 5403 5403 0 0.00 6 101738 3639 0 0
sp_supplier_order 5475 5475 0 0.00 5 97692 1512 0 0
srv_aggregate_perf_data 117 117 0 0.00 2576 175701 14686 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 2769 137117 43927 0 0
srv_find_qd_qs_mism 1020392 1020392 0 0.00 0 22124 5 0 0
srv_make_invnt_saldo 456 456 0 0.00 27 100785 3861 0 0
srv_make_money_saldo 721 721 0 0.00 1 26523 611 0 0
srv_recalc_idx_stat 4 4 0 0.00 71724 486400 298143 0 0
t$perf-abend:sp_add_invoice_to_stock 165 165 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 17 17 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5013 5013 0 0.00 0 8707 155 0 0
t$perf-norm:sp_cancel_adding_invoice 403 403 0 0.00 0 2857 84 0 0
t$perf-norm:sp_cancel_client_order 1799 1799 0 0.00 0 2264 11 0 0
t$perf-norm:sp_cancel_customer_reserve 636 636 0 0.00 0 789 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 694 694 0 0.00 0 818 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 414 414 0 0.00 0 237 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 407 407 0 0.00 0 2143 32 0 0
t$perf-norm:sp_cancel_supplier_order 401 401 0 0.00 0 23071 1528 0 0
t$perf-norm:sp_cancel_write_off 992 992 0 0.00 0 3098 13 0 0
t$perf-norm:sp_client_order 20396 20396 0 0.00 0 13313 20 0 0
t$perf-norm:sp_customer_reserve 987 987 0 0.00 0 8039 16 0 0
t$perf-norm:sp_pay_from_customer 7047 7047 0 0.00 0 4411 9 0 0
t$perf-norm:sp_pay_to_supplier 5822 5822 0 0.00 0 8236 11 0 0
t$perf-norm:sp_reserve_write_off 10014 10014 0 0.00 0 4405 11 0 0
t$perf-norm:sp_supplier_invoice 5403 5403 0 0.00 0 4959 16 0 0
t$perf-norm:sp_supplier_order 5475 5475 0 0.00 0 3063 15 0 0
t$perf-norm:srv_aggregate_perf_data 117 117 0 0.00 0 891 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 68 1 0 0
t$perf-norm:srv_make_invnt_saldo 456 456 0 0.00 0 2860 23 0 0
t$perf-norm:srv_make_money_saldo 721 721 0 0.00 0 1606 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 4 0 0
v_add_invoice_to_stock 10354 10024 330 3.18 0 8148 20 0 330
v_all_customers 37304 37304 0 0.00 0 13 1 0 0
v_all_suppliers 30312 30312 0 0.00 0 20 1 0 0
v_all_wares 40790 40790 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 806 806 0 0.00 0 320 6 0 0
v_cancel_client_order 3598 3598 0 0.00 0 2105 5 0 0
v_cancel_customer_prepayment 1388 1388 0 0.00 0 3100 12 0 0
v_cancel_customer_reserve 1272 1272 0 0.00 0 3095 7 0 0
v_cancel_payment_to_supplier 828 828 0 0.00 0 1509 10 0 0
v_cancel_supplier_invoice 848 814 34 4.00 0 3614 23 0 34
v_cancel_supplier_order 802 802 0 0.00 0 2792 11 0 0
v_cancel_write_off 1984 1984 0 0.00 0 1072 12 0 0
v_max_id_clo_ord 5474 5474 0 0.00 0 6046 3 0 0
v_max_id_clo_res 987 987 0 0.00 0 3132 15 0 0
v_max_id_ord_sup 5396 5396 0 0.00 0 4730 6 0 0
v_max_non_paid_invoice 1535 1535 0 0.00 0 220 2 0 0
v_max_non_paid_realizn 3727 3727 0 0.00 0 4511 4 0 0
v_min_id_clo_ord 5474 5474 0 0.00 0 1396 5 0 0
v_min_id_clo_res 987 987 0 0.00 0 706 2 0 0
v_min_id_ord_sup 5396 5396 0 0.00 0 14663 15 0 0
v_min_non_paid_invoice 5821 5821 0 0.00 0 5879 15 0 0
v_min_non_paid_realizn 7047 7047 0 0.00 0 6307 22 0 0
v_our_firm 10126 10126 0 0.00 0 16 1 0 0
v_reserve_write_off 20028 20028 0 0.00 0 3662 6 0 0
x_lock_depdocs_on_canc_invoice 10777 10777 0 0.00 0 14806 111 0 0
x_lock_depdocs_on_canc_sup_ord 401 401 0 0.00 0 13632 374 0 0
x_make_qstorno_1000_1200 5475 5475 0 0.00 2 97674 1429 0 0
x_make_qstorno_1200_2000 5403 5403 0 0.00 3 101729 3454 0 0
x_make_qstorno_2100_3300 461402 461402 0 0.00 0 81558 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 01-04-2021 07:40:46 to 01-04-2021 07:40:50.

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
05:09:44 1 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
05:10:45 30521 8192000000 9716184784 10014871552 796032544 8.856 100 87 174 1 825732464 7916240 21783680
05:12:22 25608 8192000000 9767676704 10074038272 837146736 9.271 100 88 176 1 872066128 13742592 21176800
05:13:50 22785 8192000000 9820049472 10140258304 868964752 9.590 100 81 162 1 920611248 32207488 19439008
05:15:32 12870 8192000000 9886431760 10219671552 926726416 10.162 100 84 168 1 996008224 50583936 18697872
05:16:47 7146 8192000000 9933659232 10276020224 962392304 10.512 100 90 181 0 1042027792 60920560 18714928
05:17:56 4810 8192000000 9976068592 10329645056 997940288 10.859 100 81 160 1 1091702000 77167040 16594672
05:19:03 2860 8192000000 9992446048 10353213440 1025259360 11.123 100 85 163 3 1104498656 63113328 16125968
05:20:08 2668 8192000000 10028388784 10392907776 1048157696 11.343 100 88 174 1 1138442144 72120448 18164000
05:21:12 2244 8192000000 10046082912 10414579712 1064367072 11.498 100 95 191 1 1160708704 77077888 19263744
05:22:15 2068 8192000000 10009953184 10387578880 1066980912 11.523 100 82 164 2 1117888480 32667232 18240336
05:23:18 5393 8192000000 10008611040 10405494784 1077448592 11.623 100 43 86 2 1124627264 38580048 8598624
05:24:26 5045 8192000000 10004437376 10401775616 1083811520 11.684 100 37 72 2 1120627568 28528704 8287344
05:25:32 5572 8192000000 10012741840 10407538688 1088020048 11.724 100 33 65 2 1117224096 22640368 6563680
05:26:41 32138 8192000000 10019649296 10431672320 1092454528 11.766 100 48 95 0 1117331200 14957088 9919584
05:28:14 6556 8192000000 10051055328 10456092672 1094494496 11.785 100 42 82 1 1163164688 60835120 7835072
05:29:21 4247 8192000000 10012770800 10404499456 1091858512 11.760 100 52 103 0 1114873056 11573072 11441472
05:30:26 5993 8192000000 10008349472 10413604864 1087274976 11.717 99 35 70 1 1126674400 31505248 7894176
05:31:41 45038 8192000000 9971746592 10381017088 992135552 10.802 91 30 60 1 1020073568 21736384 6201632
05:33:29 8336 8192000000 9933386416 10308739072 998978496 10.869 96 34 65 2 1021311168 14042192 8290480
05:34:38 5276 8192000000 9955332592 10340065280 1031599120 11.184 100 46 93 2 1061993920 18909776 11485024
05:35:45 6247 8192000000 9943634800 10329710592 1017750672 11.050 98 37 75 1 1038320400 11450992 9118736
05:36:54 15015 8192000000 9991938272 10389524480 1025760256 11.128 99 46 92 1 1090899008 56620128 8518624
05:38:11 7379 8192000000 9968314064 10361032704 1049724496 11.358 100 40 81 2 1076723120 16243424 10755200
05:39:18 8007 8192000000 10013504944 10417664000 1076962688 11.619 100 46 91 1 1114934944 28036016 9936240
05:40:37 24112 8192000000 10034188096 10432110592 1094331488 11.784 100 59 117 2 1147569840 41620736 11617616
05:42:06 40567 8192000000 10048999872 10463088640 1103528496 11.871 100 37 72 0 1163976512 52904272 7543744
05:43:52 38856 8192000000 10056405456 10473328640 1117655680 12.005 100 33 66 0 1163252832 38901648 6695504
05:45:34 7460 8192000000 10086021824 10495586304 1126578288 12.089 100 43 86 1 1196644288 60731472 9334528
05:46:47 12114 8192000000 10110856192 10525655040 1133218528 12.152 100 49 98 3 1237078960 93022576 10837856
05:48:17 31748 8192000000 10142649664 10560749568 1148519504 12.296 100 60 118 1 1243686304 82910640 12256160
05:49:57 7695 8192000000 10187327296 10601689088 1156039152 12.366 100 65 128 0 1287304928 117291696 13974080
05:51:11 4066 8192000000 10195802400 10607362048 1162469232 12.426 100 60 118 1 1297376992 121791168 13116592
05:52:20 6071 8192000000 10219519600 10631929856 1171979280 12.515 100 76 152 0 1316635392 127279840 17376272
05:53:31 2767 8192000000 10190816272 10607980544 1171623712 12.512 100 64 128 1 1295724560 109736752 14364096
05:54:37 4219 8192000000 10239846048 10648059904 1169576624 12.493 100 80 158 0 1346841792 159902752 17362416
05:55:47 5276 8192000000 10244502896 10656264192 1168657744 12.484 100 65 128 2 1345124560 161758592 14708224
05:57:09 2775 8192000000 10277188800 10704924672 1167421984 12.473 100 83 163 3 1390139632 204556672 18160976
05:58:16 4013 8192000000 10305028224 10712854528 1163812624 12.439 100 75 148 1 1406218912 224751696 17654592
05:59:24 2834 8192000000 10367664832 10770223104 1163850000 12.439 100 89 178 0 1463654912 279592144 20212768
06:00:34 4851 8192000000 10338756784 10756415488 1143028736 12.244 99 81 158 0 1440598432 278346288 19223408
06:01:46 3927 8192000000 10385992864 10788876288 1138460528 12.201 98 91 183 0 1468540144 308482464 21597152
06:02:55 3578 8192000000 10346810016 10760318976 1140428672 12.220 100 48 96 0 1440785200 288170560 12185968
06:04:14 5981 8192000000 10382645344 10814783488 1136716736 12.185 100 40 80 0 1476345424 329030448 10598240
06:05:26 9235 8192000000 10382436592 10823188480 1140755664 12.223 99 34 68 1 1471051984 321535392 8760928
06:06:41 19736 8192000000 10383139408 10835255296 1142460672 12.239 100 36 73 1 1492140336 339091088 10588576
06:08:11 24256 8192000000 10314448480 10777927680 1123029456 12.056 100 23 46 0 1436261984 306548432 6684096
06:09:40 13530 8192000000 10270928960 10755252224 1144300352 12.256 100 31 63 2 1388539328 236840976 7398000
06:10:56 48170 8192000000 10176721840 10673098752 1149810368 12.308 100 17 34 0 1297413760 143599888 4003504
06:12:56 133219 8192000000 10091024960 10589933568 1087757408 11.721 95 9 17 0 1171208480 82025920 1425152
06:16:09 79135 8192000000 10057156720 10476216320 1088776688 11.731 96 22 44 0 1113066048 19721872 4567488
06:18:29 59757 8192000000 10072323600 10496684032 1087942304 11.723 96 24 49 1 1133393344 40168992 5282048
06:20:35 89432 8192000000 10056942256 10481577984 1089374816 11.737 97 16 32 1 1126855856 34226736 3254304
06:23:06 137115 8192000000 10088564624 10537984000 1124946368 12.074 99 27 54 1 1168768144 38211216 5610560
06:26:24 46667 8192000000 10100542144 10595016704 1132937808 12.149 100 25 50 0 1196308400 58005280 5365312
06:28:12 18835 8192000000 10083690352 10525777920 1131197264 12.133 99 36 71 0 1183231968 44350624 7684080
06:29:34 41153 8192000000 10089242416 10543005696 1143443136 12.248 100 16 32 0 1192349312 45198288 3707888
06:31:20 57387 8192000000 10111734352 10577473536 1154627904 12.353 100 13 26 0 1199445056 42178224 2638928
06:33:18 76675 8192000000 10109894464 10583523328 1137113776 12.188 99 13 26 0 1201749472 61543104 3092592
06:35:37 78065 8192000000 10124082176 10621169664 1148880240 12.299 100 8 16 0 1239589568 88850528 1858800
06:37:55 77497 8192000000 10073569552 10558406656 1131930992 12.140 98 6 12 0 1216208192 82703168 1574032
06:40:24 44090 8192000000 10108955344 10570395648 1144458864 12.257 99 25 50 1 1206269456 57005936 4804656
06:42:08 45423 8192000000 10152587760 10602229760 1145117456 12.264 99 15 30 1 1254683776 106079440 3486880
06:44:07 58866 8192000000 10066258688 10522886144 1091969152 11.761 95 19 38 0 1118490688 22955344 3566192
06:46:11 20940 8192000000 10110243312 10544414720 1140497248 12.220 100 47 94 1 1174641360 24187248 9956864
06:47:32 30359 8192000000 10057626496 10487394304 1103156272 11.868 97 40 79 1 1133997248 22632480 8208496
06:49:10 33027 8192000000 10080164800 10519130112 1124869632 12.073 99 18 36 0 1162226096 33663264 3693200
06:50:45 100065 8192000000 10039087248 10502762496 1101243792 11.849 98 13 26 0 1173631664 69856832 2531040
06:54:11 67221 8192000000 10047343920 10499981312 1099276688 11.831 97 11 23 0 1120000496 18461696 2262112
06:56:23 54553 8192000000 10081550384 10540126208 1108626608 11.919 98 14 25 0 1167749024 56337008 2785408
06:58:21 34924 8192000000 10046059936 10500382720 1125915200 12.083 100 10 20 0 1153424288 25267888 2241200
07:00:01 26413 8192000000 10056168432 10487185408 1097491808 11.814 98 20 40 0 1131670176 30126576 4051792
07:01:30 46347 8192000000 10070724400 10506842112 1100424480 11.842 98 21 40 2 1138981216 34367424 4189312
07:03:28 60224 8192000000 10117534352 10594058240 1108098336 11.914 99 30 60 1 1202044992 87196992 6749664
07:05:41 51339 8192000000 10098600432 10528800768 1096076560 11.800 98 30 59 1 1152752992 50482208 6194224
07:07:33 58126 8192000000 10095066048 10529943552 1102350144 11.860 99 29 58 0 1173294320 64976960 5967216
07:09:32 46184 8192000000 10067834672 10531999744 1092871872 11.770 97 32 62 2 1165267728 66209712 6186144
07:11:26 93531 8192000000 10059633184 10545975296 1131188816 12.133 100 17 34 0 1166002528 31657264 3156448
07:14:02 61868 8192000000 10061987088 10540990464 1125309104 12.077 99 7 14 0 1180938448 54202992 1426352
07:16:04 109150 8192000000 10105701504 10552610816 1099859552 11.836 97 15 30 0 1132935520 30221232 2854736
07:19:13 55064 8192000000 10092210592 10531237888 1134510656 12.164 100 26 50 2 1171301120 31904688 4885776
07:21:09 31634 8192000000 10082988400 10523406336 1119599104 12.023 98 22 42 1 1157671328 33981936 4090288
07:22:44 64892 8192000000 10088504976 10585903104 1130041488 12.122 98 23 46 2 1206692032 71791072 4859472
07:25:11 48020 8192000000 10117494208 10590625792 1086052080 11.705 96 35 69 2 1151734048 58897456 6784512
07:27:06 77779 8192000000 10074607520 10526507008 1095514240 11.795 97 8 16 1 1126272560 28814432 1943888
07:29:28 122315 8192000000 10067563920 10534309888 1116997440 11.999 99 23 43 1 1145047648 23368048 4682160
07:32:36 44266 8192000000 10106590416 10558218240 1132833072 12.148 100 11 22 0 1196707072 60980896 2893104
07:34:34 68058 8192000000 10074868144 10544578560 1108090112 11.914 97 14 28 0 1151401712 40544288 2767312
07:36:43 36965 8192000000 10131504496 10574200832 1139813776 12.214 100 23 45 2 1217131120 72544896 4772448
07:38:20 40746 8192000000 10160038528 10609192960 1152084688 12.329 100 31 63 1 1252939184 93661792 7192704

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 01-04-2021 07:40:52 to 01-04-2021 07:41:07.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 165 v_add_invoice_to_stock 335544517
except 17 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 01-04-2021 07:41:08 to 01-04-2021 07:41:10.

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          231301
MON$OLDEST_ACTIVE               232243
MON$OLDEST_SNAPSHOT             232243
MON$NEXT_TRANSACTION            232243
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                       2106496
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             791
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2396 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2396 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2396 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.2396 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Thu Apr  1 07:39:51 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
--- end of diff output ---

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

01.04.21 07:41:11. 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-04-01 07:41:14 No severe PSQL-related problems occured
01.04.21 07:41:15. End of report.