Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.10.2020 13:27
Common Performance Final Results
  1. Hardware and OS info
  2. DB and 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, full
  3. Ratio "Versions / Records" for tables
  4. Database Validation Results
  5. New in firebird.log while test was run
  6. 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 17019260 kB
MemAvailable 31534296 kB
Buffers 0 kB
Cached 14628912 kB
SwapCached 5152 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-10-20 13:26:48
DTS_END                         2020-10-20 13:26:48.2010
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(67:17)
IP                              ::1/32856
TRN_ID                          236605
ATT_ID                          668
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: 8911.44 23997 1069373
customer order: creation 168.61 404 20233
customer order: refuse 14.58 342 1749
order to supplier: creation 45.18 1361 5422
order to supplier: removal 3.18 114592 381
invoice (draft): creation 45.27 3538 5432
invoice (draft): removal 49.41 972 5929
invoice accept: apply 41.23 18250 4948
invoice accept: cancel 45.09 835 5411
customer reserve: creation 3834.18 177 460101
customer reserve: removal 4384.63 65 526155
realization accept: apply 81.13 175 9735
realization accept: cancel 73.02 120 8762
payment from customer: creation 57.69 98 6923
payment from customer: removal 5.53 112 664
payment to supplier: creation 48.64 103 5837
payment to supplier: removal 3.53 62 423
service: total inventory turnovers 3.77 3587 452
service: total monetary turnovers 5.83 550 699
service: aggregate perf. data 0.94 15596 113
service: refresh index statistics 0.03 319003 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-10-2020 13:27:33 to 20-10-2020 13:27:35.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3306
TEST_TIME 2 4197
TEST_TIME 3 4265
TEST_TIME 4 5050
TEST_TIME 5 6047
TEST_TIME 6 6264
TEST_TIME 7 7000
TEST_TIME 8 6887
TEST_TIME 9 6789
TEST_TIME 10 6709
TEST_TIME 11 6970
TEST_TIME 12 7052
TEST_TIME 13 6906
TEST_TIME 14 7099
TEST_TIME 15 7113
TEST_TIME 16 7365
TEST_TIME 17 7869
TEST_TIME 18 7993
TEST_TIME 19 8042
TEST_TIME 20 8480
TEST_TIME 21 8695
TEST_TIME 22 8878
TEST_TIME 23 9336
TEST_TIME 24 9287
TEST_TIME 25 9692
TEST_TIME 26 10196
TEST_TIME 27 11148
TEST_TIME 28 11160
TEST_TIME 29 11057
TEST_TIME 30 10785
TEST_TIME 31 10757
TEST_TIME 32 10509
TEST_TIME 33 10313
TEST_TIME 34 10149
TEST_TIME 35 10036
TEST_TIME 36 9842
TEST_TIME 37 9862
TEST_TIME 38 9806
TEST_TIME 39 9698
TEST_TIME 40 9808
TEST_TIME 41 9722
TEST_TIME 42 9626
TEST_TIME 43 9665
TEST_TIME 44 9812
TEST_TIME 45 9897
TEST_TIME 46 9930
TEST_TIME 47 9857
TEST_TIME 48 9786
TEST_TIME 49 9880
TEST_TIME 50 9852
TEST_TIME 51 9877
TEST_TIME 52 9956
TEST_TIME 53 9881
TEST_TIME 54 9855
TEST_TIME 55 9682
TEST_TIME 56 9636
TEST_TIME 57 9538
TEST_TIME 58 9480
TEST_TIME 59 9424
TEST_TIME 60 9358
TEST_TIME 61 9271
TEST_TIME 62 9230
TEST_TIME 63 9311
TEST_TIME 64 9266
TEST_TIME 65 9249
TEST_TIME 66 9294
TEST_TIME 67 9414
TEST_TIME 68 9375
TEST_TIME 69 9444
TEST_TIME 70 9424
TEST_TIME 71 9485
TEST_TIME 72 9526
TEST_TIME 73 9509
TEST_TIME 74 9469
TEST_TIME 75 9392
TEST_TIME 76 9298
TEST_TIME 77 9334
TEST_TIME 78 9262
TEST_TIME 79 9177
TEST_TIME 80 9122
TEST_TIME 81 9077
TEST_TIME 82 9022
TEST_TIME 83 9143
TEST_TIME 84 9114
TEST_TIME 85 9098
TEST_TIME 86 9073
TEST_TIME 87 9051
TEST_TIME 88 9016
TEST_TIME 89 9063
TEST_TIME 90 9190
TEST_TIME 91 9179
TEST_TIME 92 9171
TEST_TIME 93 9135
TEST_TIME 94 9092
TEST_TIME 95 9069
TEST_TIME 96 9049
TEST_TIME 97 9011
TEST_TIME 98 9033
TEST_TIME 99 9017
TEST_TIME 100 9035
TEST_TIME 101 9003
TEST_TIME 102 9045
TEST_TIME 103 9062
TEST_TIME 104 9068
TEST_TIME 105 9177
TEST_TIME 106 9125
TEST_TIME 107 9149
TEST_TIME 108 9138
TEST_TIME 109 9076
TEST_TIME 110 9012
TEST_TIME 111 8961
TEST_TIME 112 8907
TEST_TIME 113 8899
TEST_TIME 114 8921
TEST_TIME 115 8939
TEST_TIME 116 8900
TEST_TIME 117 8930
TEST_TIME 118 8917
TEST_TIME 119 8914
TEST_TIME 120 8912

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 20-10-2020 13:27:36 to 20-10-2020 13:27:40.

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 1492989 1492989 0 0.00 0 27649 6 0 0
doc_list_biud 1492988 1492988 0 0.00 0 47707 30 0 0
sp_add_invoice_to_stock 5096 4948 148 2.90 13 184317 18251 0 148
sp_cancel_adding_invoice 5411 5411 0 0.00 5 83469 835 0 0
sp_cancel_client_order 1749 1749 0 0.00 4 9049 342 0 0
sp_cancel_customer_reserve 526155 526155 0 0.00 1 47715 66 0 0
sp_cancel_pay_from_customer 664 664 0 0.00 3 18489 112 0 0
sp_cancel_pay_to_supplier 423 423 0 0.00 3 1687 63 0 0
sp_cancel_supplier_invoice 5937 5929 8 0.13 2 26008 973 0 8
sp_cancel_supplier_order 381 381 0 0.00 5 980054 114592 0 0
sp_cancel_write_off 8762 8762 0 0.00 1 13553 121 0 0
sp_client_order 20233 20233 0 0.00 4 27498 404 0 0
sp_customer_reserve 460101 460101 0 0.00 0 71203 178 0 0
sp_fill_shopping_cart 31070 31070 0 0.00 1 16716 46 0 0
sp_fill_shopping_cart_clo_res 460099 460099 0 0.00 0 22883 21 0 0
sp_get_clo_for_invoice 4945 4945 0 0.00 0 40734 453 0 0
sp_kill_cost_storno 15260 15260 0 0.00 0 18484 55 0 0
sp_kill_qstorno_ret_qs2qd 1067382 1067382 0 0.00 0 47677 36 0 0
sp_kill_qty_storno 563067 563067 0 0.00 0 47706 78 0 0
sp_lock_selected_doc 20370 20370 0 0.00 0 5896 3 0 0
sp_make_cost_storno 27440 27440 0 0.00 0 27645 48 0 0
sp_multiply_rows_for_pdistr 27440 27440 0 0.00 0 6168 29 0 0
sp_multiply_rows_for_qdistr 20233 20233 0 0.00 0 26146 224 0 0
sp_pay_from_customer 6923 6923 0 0.00 4 8383 99 0 0
sp_pay_to_supplier 5837 5837 0 0.00 5 27442 103 0 0
sp_payment_common 12760 12760 0 0.00 1 27405 20 0 0
sp_qd_handle_on_cancel_clo 1749 1749 0 0.00 0 9039 319 0 0
sp_qd_handle_on_invoice_upd_sts 10356 10356 0 0.00 0 27299 364 0 0
sp_qd_handle_on_reserve_upd_sts 9735 9735 0 0.00 0 11536 54 0 0
sp_reserve_write_off 9735 9735 0 0.00 7 27676 176 0 0
sp_supplier_invoice 5432 5432 0 0.00 7 73054 3539 0 0
sp_supplier_order 5422 5422 0 0.00 5 44580 1361 0 0
srv_aggregate_perf_data 113 113 0 0.00 2772 218559 15597 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 982 140276 45704 0 0
srv_find_qd_qs_mism 1021111 1021111 0 0.00 0 24928 6 0 0
srv_make_invnt_saldo 452 452 0 0.00 54 92213 3587 0 0
srv_make_money_saldo 699 699 0 0.00 1 19833 551 0 0
srv_recalc_idx_stat 4 4 0 0.00 62694 444175 319003 0 0
t$perf-abend:sp_add_invoice_to_stock 148 148 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4948 4948 0 0.00 0 12848 156 0 0
t$perf-norm:sp_cancel_adding_invoice 422 422 0 0.00 0 2745 69 0 0
t$perf-norm:sp_cancel_client_order 1749 1749 0 0.00 0 7663 19 0 0
t$perf-norm:sp_cancel_customer_reserve 634 634 0 0.00 0 12438 33 0 0
t$perf-norm:sp_cancel_pay_from_customer 664 664 0 0.00 0 165 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 423 423 0 0.00 0 7344 23 0 0
t$perf-norm:sp_cancel_supplier_invoice 382 382 0 0.00 0 1520 27 0 0
t$perf-norm:sp_cancel_supplier_order 381 381 0 0.00 0 30257 1563 0 0
t$perf-norm:sp_cancel_write_off 1036 1036 0 0.00 0 265 2 0 0
t$perf-norm:sp_client_order 20233 20233 0 0.00 0 15216 17 0 0
t$perf-norm:sp_customer_reserve 1042 1042 0 0.00 0 848 4 0 0
t$perf-norm:sp_pay_from_customer 6923 6923 0 0.00 0 15184 11 0 0
t$perf-norm:sp_pay_to_supplier 5837 5837 0 0.00 0 15198 11 0 0
t$perf-norm:sp_reserve_write_off 9735 9735 0 0.00 0 5658 8 0 0
t$perf-norm:sp_supplier_invoice 5432 5432 0 0.00 0 8052 21 0 0
t$perf-norm:sp_supplier_order 5422 5422 0 0.00 0 4722 15 0 0
t$perf-norm:srv_aggregate_perf_data 113 113 0 0.00 0 211 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 50 2 0 0
t$perf-norm:srv_make_invnt_saldo 452 452 0 0.00 0 312 5 0 0
t$perf-norm:srv_make_money_saldo 699 699 0 0.00 0 691 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 4 0 0
v_add_invoice_to_stock 10184 9888 296 2.90 0 10463 18 0 296
v_all_customers 36856 36856 0 0.00 0 27 1 0 0
v_all_suppliers 29914 29914 0 0.00 0 132 1 0 0
v_all_wares 40466 40466 0 0.00 0 28 1 0 0
v_cancel_adding_invoice 844 844 0 0.00 0 326 7 0 0
v_cancel_client_order 3498 3498 0 0.00 0 2110 4 0 0
v_cancel_customer_prepayment 1328 1328 0 0.00 0 1641 7 0 0
v_cancel_customer_reserve 1268 1268 0 0.00 0 1809 5 0 0
v_cancel_payment_to_supplier 846 846 0 0.00 0 1620 7 0 0
v_cancel_supplier_invoice 780 764 16 2.05 0 828 14 0 16
v_cancel_supplier_order 762 762 0 0.00 0 1649 5 0 0
v_cancel_write_off 2072 2072 0 0.00 0 3813 14 0 0
v_max_id_clo_ord 5417 5417 0 0.00 0 1608 2 0 0
v_max_id_clo_res 1042 1042 0 0.00 0 4066 20 0 0
v_max_id_ord_sup 5420 5420 0 0.00 0 7240 5 0 0
v_max_non_paid_invoice 1717 1717 0 0.00 0 155 2 0 0
v_max_non_paid_realizn 3739 3739 0 0.00 0 2959 3 0 0
v_min_id_clo_ord 5417 5417 0 0.00 0 13677 10 0 0
v_min_id_clo_res 1042 1042 0 0.00 0 6267 27 0 0
v_min_id_ord_sup 5420 5420 0 0.00 0 8188 13 0 0
v_min_non_paid_invoice 5837 5837 0 0.00 0 4229 8 0 0
v_min_non_paid_realizn 6923 6923 0 0.00 0 4472 14 0 0
v_our_firm 9978 9978 0 0.00 0 38 1 0 0
v_reserve_write_off 19470 19470 0 0.00 0 6050 5 0 0
x_lock_depdocs_on_canc_invoice 10958 10958 0 0.00 0 18106 108 0 0
x_lock_depdocs_on_canc_sup_ord 381 381 0 0.00 0 5281 382 0 0
x_make_qstorno_1000_1200 5422 5422 0 0.00 2 44565 1279 0 0
x_make_qstorno_1200_2000 5432 5432 0 0.00 4 73034 3357 0 0
x_make_qstorno_2100_3300 460101 460101 0 0.00 0 71201 154 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-10-2020 13:27:41 to 20-10-2020 13:27:45.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
10:56:51 10 8192000000 8796624000 8807108608 3156752 0.038 1 1 2 0 3254112 61504 35856
10:57:53 35491 8192000000 9721899520 10016583680 790469952 8.800 100 85 170 1 818856560 7364352 21022256
10:59:33 18051 8192000000 9749668800 10054389760 826739440 9.166 100 88 176 1 860492816 12409808 21343568
11:00:54 13353 8192000000 9809105456 10128400384 876301104 9.663 100 83 166 1 917610848 22396832 18912912
11:02:10 12816 8192000000 9861958064 10196324352 922083824 10.117 100 80 160 1 971368944 31698256 17586864
11:03:26 9601 8192000000 9923895888 10266988544 965791024 10.546 100 80 160 0 1032149232 49673024 16685184
11:04:37 3960 8192000000 9957805280 10317197312 1002052624 10.898 100 72 142 1 1072710272 56104752 14552896
11:05:43 5375 8192000000 9999881040 10366763008 1034691088 11.214 100 78 154 0 1116305072 66609616 15004368
11:06:49 2054 8192000000 10023690368 10391699456 1058313648 11.440 100 85 167 3 1133146944 57506320 17326976
11:07:52 7706 8192000000 10028262448 10401456128 1064538416 11.500 100 62 124 0 1128575696 50924640 13112640
11:09:00 5615 8192000000 9989653360 10378211328 1069681648 11.549 100 36 71 2 1103473328 25815584 7976096
11:10:08 14366 8192000000 9996125632 10400833536 1078727024 11.635 100 25 49 3 1110597808 26685648 5185136
11:11:23 5155 8192000000 9997397040 10396725248 1084313376 11.689 100 41 82 1 1120956384 27128064 9514944
11:12:32 55576 8192000000 10014919984 10425331712 1089297056 11.736 100 48 97 2 1113334304 13471168 10566080
11:14:29 8126 8192000000 10005494688 10411843584 1080910816 11.656 99 46 93 6 1112069216 20589072 10569328
11:15:40 6021 8192000000 10001296688 10402811904 1087135264 11.715 100 29 57 1 1106773248 14158704 5479280
11:16:53 4036 8192000000 9979511632 10380308480 1069409696 11.546 99 28 56 1 1095162192 19423600 6328896
11:17:57 52129 8192000000 9952218480 10351894528 1020307104 11.075 96 28 56 2 1042562912 15667248 6588560
11:19:50 6590 8192000000 9931864432 10310389760 966502112 10.553 94 35 69 3 987551712 13738432 7311168
11:20:58 9148 8192000000 9939521328 10320756736 1004551520 10.923 98 40 80 3 1047190688 34106432 8532736
11:22:07 9419 8192000000 9956110048 10340929536 1026538480 11.135 99 37 74 6 1062783520 28806608 7438432
11:23:18 50073 8192000000 9958333264 10367676416 1044084208 11.304 100 30 60 1 1091786800 41087056 6615536
11:25:14 5873 8192000000 9964297168 10349756416 1055893424 11.417 100 33 62 4 1072147920 8170880 8083616
11:26:25 7282 8192000000 10004947120 10408042496 1078506208 11.633 100 39 77 4 1105756432 18448704 8801520
11:27:37 28396 8192000000 10028857792 10421911552 1094614336 11.787 100 43 82 3 1136810688 33734592 8461760
11:29:09 40904 8192000000 10064801344 10468417536 1108617904 11.919 100 52 104 1 1167561008 47755840 11187264
11:30:55 3679 8192000000 10100300304 10496380928 1124132976 12.066 100 60 120 1 1212112672 75692400 12287296
11:32:02 11531 8192000000 10134113488 10536448000 1121507216 12.041 99 57 114 2 1240298496 107081504 11709776
11:33:27 8105 8192000000 10144211120 10547261440 1143586864 12.249 100 60 119 1 1246556992 90957024 12013104
11:34:40 5646 8192000000 10185816032 10586984448 1154355856 12.350 100 71 142 0 1293842656 124908384 14578416
11:35:49 4856 8192000000 10196221216 10602545152 1156912544 12.374 100 61 120 2 1309092768 139096928 13083296
11:36:59 3659 8192000000 10241378096 10649309184 1169663824 12.494 100 64 126 1 1348960160 165183088 14113248
11:38:07 16861 8192000000 10272134704 10685423616 1162124176 12.423 99 58 116 3 1371475168 195627920 13723072
11:39:28 982 8192000000 10288236912 10687954944 1158318992 12.388 99 63 122 2 1381571344 207868080 15384272
11:40:34 3283 8192000000 10323724864 10726559744 1146950064 12.281 98 93 189 2 1415186720 246858768 21377888
11:41:42 4888 8192000000 10370637056 10772791296 1162080944 12.423 99 77 154 3 1472835808 292501184 18253680
11:42:50 10520 8192000000 10366171440 10785550336 1149053920 12.301 100 70 136 7 1479457872 313218656 17185296
11:44:05 1404 8192000000 10352557984 10747604992 1151401312 12.323 100 85 167 2 1450886960 279399840 20085808
11:45:16 6463 8192000000 10375806512 10783711232 1129841968 12.120 100 59 116 0 1481760784 337582720 14336096
11:46:30 3239 8192000000 10373848576 10807713792 1147200176 12.283 100 60 120 2 1503872176 342344080 14327920
11:47:42 5858 8192000000 10386295888 10810417152 1155228656 12.359 100 57 113 1 1499129776 330337152 13563968
11:48:57 8055 8192000000 10381128224 10809212928 1128274928 12.105 99 41 82 0 1468741856 329528624 10938304
11:50:11 16154 8192000000 10381008224 10804637696 1137637408 12.193 100 30 60 1 1466552144 320884128 8030608
11:51:40 21516 8192000000 10280594096 10767618048 1106203440 11.896 99 26 52 0 1418727760 305664224 6860096
11:53:06 78781 8192000000 10101808096 10528108544 1114012400 11.970 99 12 24 1 1268146112 151525392 2608320
11:55:30 16551 8192000000 10063766544 10505785344 1118838640 12.016 99 10 20 1 1155306816 34602992 1865184
11:57:30 134911 8192000000 10042813248 10480848896 1096568480 11.805 97 21 40 0 1128006208 27848768 3588960
12:00:45 45582 8192000000 10072844624 10514640896 1122475888 12.050 99 15 30 1 1152011744 25617904 3917952
12:02:47 11830 8192000000 10120202304 10548826112 1134200320 12.161 99 21 42 2 1214568064 75988992 4378752
12:04:02 26200 8192000000 10127469312 10561376256 1135135680 12.170 100 25 50 1 1221826624 81321776 5369168
12:05:29 34962 8192000000 10186278304 10616467456 1158269024 12.387 100 45 90 2 1283479600 115038048 10172528
12:07:34 53337 8192000000 10144132112 10592268288 1141064208 12.226 99 11 22 2 1235914656 92424608 2425840
12:09:28 16958 8192000000 10164096096 10602422272 1155276768 12.359 100 57 111 3 1266161872 97639392 13245712
12:10:50 60598 8192000000 10108388320 10577960960 1127552480 12.098 98 29 57 1 1178733168 44547584 6633104
12:12:51 54942 8192000000 10079708672 10536345600 1125185360 12.076 98 14 27 1 1163183024 35666448 2331216
12:14:52 84080 8192000000 10094928944 10547224576 1119281040 12.020 97 32 64 0 1195719888 69342208 7096640
12:17:17 70686 8192000000 10124588864 10587897856 1120316000 12.030 98 18 34 1 1180938160 57157136 3465024
12:19:29 139733 8192000000 10100669088 10569551872 1135133376 12.170 99 13 26 0 1190362560 52678624 2550560
12:23:03 40909 8192000000 10071717040 10513874944 1129330928 12.115 99 4 8 0 1175746928 45597984 818016
12:25:48 37605 8192000000 10066091696 10520813568 1123897712 12.064 100 8 16 0 1177261984 51619376 1744896
12:27:32 70761 8192000000 10113934368 10569646080 1116273248 11.992 98 16 32 0 1177708320 57980288 3454784
12:29:44 63443 8192000000 10088182800 10514608128 1068971952 11.542 94 17 34 0 1125855984 53943136 2940896
12:31:52 49334 8192000000 10133988672 10586570752 1081576256 11.662 95 27 54 1 1184120864 96092032 6452576
12:33:48 101353 8192000000 10131701792 10598600704 1117826240 12.006 99 14 28 1 1215923728 94997456 3100032
12:36:32 36714 8192000000 10080007120 10509586432 1103384272 11.870 98 37 74 1 1148027728 37320560 7322896
12:38:09 72885 8192000000 10093455216 10584649728 1129220768 12.114 100 32 64 1 1216444096 81046272 6177056
12:40:33 18830 8192000000 10070519040 10488664064 1102257616 11.859 98 42 84 1 1133548240 21604832 9685792
12:41:52 134682 8192000000 10055561248 10540531712 1121691856 12.043 100 19 38 1 1172622016 47449984 3480176
12:45:11 11545 8192000000 10054868688 10545934336 1119640544 12.024 100 3 6 0 1162918880 42795872 482464
12:46:23 86471 8192000000 10115937264 10560266240 1100304336 11.841 97 16 32 1 1156823664 53350560 3168768
12:48:50 73761 8192000000 10060896928 10566250496 1116884256 11.998 99 25 50 0 1212696608 89947648 5864704
12:51:04 27583 8192000000 10075330944 10506641408 1121400208 12.040 99 38 77 2 1154503440 25879696 7223536
12:52:34 46594 8192000000 10096785296 10527870976 1121352752 12.040 99 28 56 1 1162786560 35662992 5770816
12:54:22 117195 8192000000 10145555872 10658050048 1130669152 12.128 100 18 36 0 1224712656 89310800 4732704
12:58:31 140276 8192000000 10085698704 10566627328 1143011616 12.244 100 22 44 3 1189958240 42600384 4346240
13:01:53 60937 8192000000 10067147632 10510712832 1111288736 11.945 97 6 12 0 1148002992 35705296 1008960
13:03:54 90936 8192000000 10118132192 10598711296 1144179616 12.255 99 22 44 3 1234480144 85499536 4800992
13:06:51 45235 8192000000 10143369360 10564153344 1138315472 12.200 99 40 80 1 1195283728 48754384 8213872
13:08:39 89112 8192000000 10092220672 10517712896 1117609616 12.004 97 14 28 1 1175483744 55069696 2804432
13:11:09 40538 8192000000 10144625088 10675785728 1146224032 12.274 100 15 30 1 1252690688 103219264 3247392
13:12:50 22314 8192000000 10107742208 10534318080 1133632336 12.156 99 47 94 4 1166938864 23144704 10161824
13:14:13 104889 8192000000 10098932640 10570690560 1133436912 12.154 99 19 38 2 1183967920 46847168 3683840
13:17:11 75250 8192000000 10086225648 10546765824 1133509824 12.154 99 8 16 0 1177432384 41842864 2079696
13:20:02 64050 8192000000 10071021232 10504527872 1080398896 11.651 95 19 38 1 1113195264 28991376 3804992
13:22:50 48807 8192000000 10075805840 10522796032 1109705712 11.930 98 29 58 2 1179794080 63478784 6609584
13:24:40 40858 8192000000 10087107424 10512347136 1094186368 11.782 97 20 40 3 1133061040 34647104 4227568
13:26:21 31192 8192000000 9222921520 9496485888 346194960 4.054 31 11 19 2 357950608 9814576 1941072

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 20-10-2020 13:27:46 to 20-10-2020 13:28:01.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 148 v_add_invoice_to_stock 335544517
user_exc 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 20-10-2020 13:28:02 to 20-10-2020 13:28:04.

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          236880
MON$OLDEST_ACTIVE               236881
MON$OLDEST_SNAPSHOT             236881
MON$NEXT_TRANSACTION            236881
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                       2088736
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             787
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2228 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2228 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2228 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.2228 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 ---
:752: 
:753: mscs-test-161.budzdorov.ru    Tue Oct 20 13:26:54 2020
:754:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:755: 
:756: 
:757: mscs-test-161.budzdorov.ru    Tue Oct 20 13:26:54 2020
:758:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:759: 
--- end of diff output ---

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

20.10.20 13:28:05. 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-10-20 13:28:07 No severe PSQL-related problems occured
20.10.20 13:28:08. End of report.