Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.01.2021 10:27
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID e0e7b46250ba4f9795ceb55e0face73f
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2020-09-25 13:42
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.00
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16701156 kB
MemAvailable 31226892 kB
Buffers 0 kB
Cached 14854440 kB
SwapCached 4864 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.0
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-01-13 10:26:54
DTS_END                         2021-01-13 10:26:54.4120
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/39958
TRN_ID                          228760
ATT_ID                          674
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: 8771.05 30020 1052526
customer order: creation 163.99 420 19679
customer order: refuse 13.90 364 1668
order to supplier: creation 44.47 1459 5336
order to supplier: removal 3.18 134004 382
invoice (draft): creation 43.83 4278 5259
invoice (draft): removal 49.49 1085 5939
invoice accept: apply 40.38 20214 4845
invoice accept: cancel 44.80 973 5376
customer reserve: creation 3680.86 200 441703
customer reserve: removal 4395.19 75 527423
realization accept: apply 79.12 189 9494
realization accept: cancel 87.73 136 10528
payment from customer: creation 57.58 115 6909
payment from customer: removal 5.81 101 697
payment to supplier: creation 47.03 127 5643
payment to supplier: removal 3.14 97 377
service: total inventory turnovers 3.58 3965 430
service: total monetary turnovers 6.13 575 736
service: aggregate perf. data 0.82 18946 98
service: refresh index statistics 0.03 413068 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-01-2021 10:28:07 to 13-01-2021 10:28:09.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7415
TEST_TIME 2 5640
TEST_TIME 3 4987
TEST_TIME 4 4841
TEST_TIME 5 5450
TEST_TIME 6 5654
TEST_TIME 7 5978
TEST_TIME 8 5786
TEST_TIME 9 5681
TEST_TIME 10 5839
TEST_TIME 11 5935
TEST_TIME 12 5851
TEST_TIME 13 6071
TEST_TIME 14 6083
TEST_TIME 15 5968
TEST_TIME 16 6052
TEST_TIME 17 6191
TEST_TIME 18 6179
TEST_TIME 19 6253
TEST_TIME 20 6307
TEST_TIME 21 6503
TEST_TIME 22 6599
TEST_TIME 23 7056
TEST_TIME 24 7320
TEST_TIME 25 7560
TEST_TIME 26 8108
TEST_TIME 27 8475
TEST_TIME 28 9153
TEST_TIME 29 9479
TEST_TIME 30 10182
TEST_TIME 31 10099
TEST_TIME 32 10037
TEST_TIME 33 9813
TEST_TIME 34 9629
TEST_TIME 35 9592
TEST_TIME 36 9487
TEST_TIME 37 9552
TEST_TIME 38 9525
TEST_TIME 39 9564
TEST_TIME 40 9469
TEST_TIME 41 9489
TEST_TIME 42 9368
TEST_TIME 43 9297
TEST_TIME 44 9225
TEST_TIME 45 9283
TEST_TIME 46 9402
TEST_TIME 47 9473
TEST_TIME 48 9425
TEST_TIME 49 9602
TEST_TIME 50 9462
TEST_TIME 51 9569
TEST_TIME 52 9582
TEST_TIME 53 9545
TEST_TIME 54 9634
TEST_TIME 55 9617
TEST_TIME 56 9665
TEST_TIME 57 9611
TEST_TIME 58 9563
TEST_TIME 59 9500
TEST_TIME 60 9367
TEST_TIME 61 9324
TEST_TIME 62 9281
TEST_TIME 63 9211
TEST_TIME 64 9117
TEST_TIME 65 9068
TEST_TIME 66 9059
TEST_TIME 67 9096
TEST_TIME 68 9063
TEST_TIME 69 9043
TEST_TIME 70 9032
TEST_TIME 71 9006
TEST_TIME 72 8993
TEST_TIME 73 9063
TEST_TIME 74 9343
TEST_TIME 75 9376
TEST_TIME 76 9341
TEST_TIME 77 9275
TEST_TIME 78 9210
TEST_TIME 79 9207
TEST_TIME 80 9145
TEST_TIME 81 9175
TEST_TIME 82 9140
TEST_TIME 83 9108
TEST_TIME 84 9036
TEST_TIME 85 9043
TEST_TIME 86 9084
TEST_TIME 87 9092
TEST_TIME 88 9170
TEST_TIME 89 9162
TEST_TIME 90 9188
TEST_TIME 91 9215
TEST_TIME 92 9201
TEST_TIME 93 9208
TEST_TIME 94 9115
TEST_TIME 95 9047
TEST_TIME 96 9000
TEST_TIME 97 8937
TEST_TIME 98 8971
TEST_TIME 99 8916
TEST_TIME 100 8901
TEST_TIME 101 8938
TEST_TIME 102 8900
TEST_TIME 103 8870
TEST_TIME 104 8967
TEST_TIME 105 8968
TEST_TIME 106 8985
TEST_TIME 107 9034
TEST_TIME 108 9042
TEST_TIME 109 9035
TEST_TIME 110 9089
TEST_TIME 111 9077
TEST_TIME 112 9024
TEST_TIME 113 8967
TEST_TIME 114 8937
TEST_TIME 115 8898
TEST_TIME 116 8843
TEST_TIME 117 8812
TEST_TIME 118 8781
TEST_TIME 119 8757
TEST_TIME 120 8773

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 13-01-2021 10:28:11 to 13-01-2021 10:28:14.

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 1460691 1460691 0 0.00 0 27246 7 0 0
doc_list_biud 1460690 1460690 0 0.00 0 92575 35 0 0
sp_add_invoice_to_stock 4987 4845 142 2.84 38 212931 20215 0 142
sp_cancel_adding_invoice 5376 5376 0 0.00 5 92392 973 0 0
sp_cancel_client_order 1668 1668 0 0.00 4 7237 364 0 0
sp_cancel_customer_reserve 527423 527423 0 0.00 1 26863 76 0 0
sp_cancel_pay_from_customer 697 697 0 0.00 3 5458 101 0 0
sp_cancel_pay_to_supplier 377 377 0 0.00 3 2956 97 0 0
sp_cancel_supplier_invoice 5951 5939 12 0.20 2 33941 1086 0 12
sp_cancel_supplier_order 382 382 0 0.00 7 1305729 134004 0 0
sp_cancel_write_off 10528 10528 0 0.00 1 92580 136 0 0
sp_client_order 19679 19679 0 0.00 4 67104 421 0 0
sp_customer_reserve 441703 441703 0 0.00 0 104944 200 0 0
sp_fill_shopping_cart 30261 30261 0 0.00 1 53575 47 0 0
sp_fill_shopping_cart_clo_res 441701 441701 0 0.00 0 21032 16 0 0
sp_get_clo_for_invoice 4841 4841 0 0.00 0 61652 407 0 0
sp_kill_cost_storno 16978 16978 0 0.00 0 19550 64 0 0
sp_kill_qstorno_ret_qs2qd 1071695 1071695 0 0.00 0 92574 41 0 0
sp_kill_qty_storno 565651 565651 0 0.00 0 92575 88 0 0
sp_lock_selected_doc 19858 19858 0 0.00 0 10391 6 0 0
sp_make_cost_storno 26887 26887 0 0.00 0 14937 56 0 0
sp_multiply_rows_for_pdistr 26887 26887 0 0.00 0 6375 36 0 0
sp_multiply_rows_for_qdistr 19679 19679 0 0.00 0 67086 234 0 0
sp_pay_from_customer 6909 6909 0 0.00 4 12514 115 0 0
sp_pay_to_supplier 5643 5643 0 0.00 5 11431 127 0 0
sp_payment_common 12552 12552 0 0.00 1 6113 23 0 0
sp_qd_handle_on_cancel_clo 1668 1668 0 0.00 0 7230 339 0 0
sp_qd_handle_on_invoice_upd_sts 10217 10217 0 0.00 0 17020 409 0 0
sp_qd_handle_on_reserve_upd_sts 9494 9494 0 0.00 0 17943 60 0 0
sp_reserve_write_off 9494 9494 0 0.00 6 18868 190 0 0
sp_supplier_invoice 5259 5259 0 0.00 7 157241 4278 0 0
sp_supplier_order 5336 5336 0 0.00 5 141343 1460 0 0
srv_aggregate_perf_data 98 98 0 0.00 2669 240983 18947 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1410 141525 44406 0 0
srv_find_qd_qs_mism 1006365 1006365 0 0.00 0 27246 6 0 0
srv_make_invnt_saldo 430 430 0 0.00 33 100406 3966 0 0
srv_make_money_saldo 736 736 0 0.00 1 14335 576 0 0
srv_recalc_idx_stat 4 4 0 0.00 261205 585059 413069 0 0
t$perf-abend:sp_add_invoice_to_stock 142 142 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4845 4845 0 0.00 0 42312 213 0 0
t$perf-norm:sp_cancel_adding_invoice 394 394 0 0.00 0 6885 127 0 0
t$perf-norm:sp_cancel_client_order 1668 1668 0 0.00 0 1832 10 0 0
t$perf-norm:sp_cancel_customer_reserve 667 667 0 0.00 0 1608 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 697 697 0 0.00 0 5278 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 377 377 0 0.00 0 1693 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 372 372 0 0.00 0 2685 30 0 0
t$perf-norm:sp_cancel_supplier_order 382 382 0 0.00 0 24433 1539 0 0
t$perf-norm:sp_cancel_write_off 967 967 0 0.00 0 2650 11 0 0
t$perf-norm:sp_client_order 19679 19679 0 0.00 0 32601 21 0 0
t$perf-norm:sp_customer_reserve 919 919 0 0.00 0 1205 8 0 0
t$perf-norm:sp_pay_from_customer 6909 6909 0 0.00 0 5667 11 0 0
t$perf-norm:sp_pay_to_supplier 5643 5643 0 0.00 0 4794 10 0 0
t$perf-norm:sp_reserve_write_off 9494 9494 0 0.00 0 5128 13 0 0
t$perf-norm:sp_supplier_invoice 5259 5259 0 0.00 0 6686 23 0 0
t$perf-norm:sp_supplier_order 5336 5336 0 0.00 0 4129 21 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 558 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 90 2 0 0
t$perf-norm:srv_make_invnt_saldo 430 430 0 0.00 0 1895 12 0 0
t$perf-norm:srv_make_money_saldo 736 736 0 0.00 0 1935 21 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 10 5 0 0
v_add_invoice_to_stock 9964 9680 284 2.85 0 6841 18 0 284
v_all_customers 35938 35938 0 0.00 0 77 1 0 0
v_all_suppliers 29254 29254 0 0.00 0 27 1 0 0
v_all_wares 39356 39356 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 788 788 0 0.00 0 1078 9 0 0
v_cancel_client_order 3336 3336 0 0.00 0 2924 5 0 0
v_cancel_customer_prepayment 1394 1394 0 0.00 0 2969 8 0 0
v_cancel_customer_reserve 1334 1334 0 0.00 0 888 4 0 0
v_cancel_payment_to_supplier 754 754 0 0.00 0 551 6 0 0
v_cancel_supplier_invoice 768 744 24 3.12 0 3723 29 0 24
v_cancel_supplier_order 764 764 0 0.00 0 1625 9 0 0
v_cancel_write_off 1934 1934 0 0.00 0 2248 14 0 0
v_max_id_clo_ord 5334 5334 0 0.00 0 4036 3 0 0
v_max_id_clo_res 919 919 0 0.00 0 4912 25 0 0
v_max_id_ord_sup 5249 5249 0 0.00 0 6330 5 0 0
v_max_non_paid_invoice 1599 1599 0 0.00 0 243 2 0 0
v_max_non_paid_realizn 3736 3736 0 0.00 0 1723 3 0 0
v_min_id_clo_ord 5334 5334 0 0.00 0 9035 12 0 0
v_min_id_clo_res 919 919 0 0.00 0 2308 7 0 0
v_min_id_ord_sup 5249 5249 0 0.00 0 12112 14 0 0
v_min_non_paid_invoice 5643 5643 0 0.00 0 6106 15 0 0
v_min_non_paid_realizn 6909 6909 0 0.00 0 6086 17 0 0
v_our_firm 9764 9764 0 0.00 0 20 1 0 0
v_reserve_write_off 18988 18988 0 0.00 0 3955 6 0 0
x_lock_depdocs_on_canc_invoice 10943 10943 0 0.00 0 37035 128 0 0
x_lock_depdocs_on_canc_sup_ord 382 382 0 0.00 0 4547 374 0 0
x_make_qstorno_1000_1200 5336 5336 0 0.00 2 140252 1360 0 0
x_make_qstorno_1200_2000 5259 5259 0 0.00 4 156786 4104 0 0
x_make_qstorno_2100_3300 441703 441703 0 0.00 0 104942 181 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-01-2021 10:28:15 to 13-01-2021 10:28:19.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
07:56:58 10 8192000000 8798403472 8809598976 4445904 0.054 2 1 2 0 4545296 63616 35776
07:57:59 25366 8192000000 9727278864 10021376000 799039136 8.887 100 84 168 0 827367296 7407568 20920592
07:59:37 28828 8192000000 9768985520 10075512832 840355712 9.303 100 93 186 1 876703824 14029216 22318896
08:01:18 15848 8192000000 9811258240 10128687104 873248592 9.632 100 82 165 0 916339840 24143312 18947936
08:02:42 12251 8192000000 9856684848 10186838016 909518256 9.993 100 76 152 1 963802656 37948944 16335456
08:03:57 12381 8192000000 9929798720 10272952320 954941424 10.440 100 78 156 0 1039000896 68107616 15951856
08:05:12 4599 8192000000 9967464896 10321588224 994372304 10.824 100 83 166 0 1082926256 71217056 17336896
08:06:20 3289 8192000000 9989908864 10352926720 1026790800 11.138 100 86 172 0 1105332208 61619632 16921776
08:07:26 2826 8192000000 10022355008 10389000192 1042038256 11.284 100 87 174 0 1132356320 72737360 17580704
08:08:30 1607 8192000000 10046151776 10415218688 1063077152 11.486 100 94 189 0 1157997200 76092384 18827664
08:09:34 33880 8192000000 10031677664 10416648192 1072859904 11.579 100 58 116 0 1160529776 76289008 11380864
08:11:09 5835 8192000000 9995247376 10397106176 1079686304 11.644 100 40 83 0 1122381392 33978256 8716832
08:12:19 10848 8192000000 10003128304 10409852928 1082423088 11.671 100 44 85 3 1112632800 19923760 10285952
08:13:32 15458 8192000000 10024691776 10409381888 1085992016 11.705 100 75 150 2 1118552480 15648848 16911616
08:14:49 7412 8192000000 10008046544 10413895680 1090149440 11.744 100 41 82 0 1113775456 13815888 9810128
08:15:57 9120 8192000000 10004507888 10417377280 1096838064 11.808 100 40 79 0 1126604032 20000256 9765712
08:17:08 6280 8192000000 10007827376 10408091648 1084393296 11.689 99 35 70 2 1111028816 18770080 7865440
08:18:15 62549 8192000000 9976913888 10381029376 1027357280 11.143 95 23 46 0 1051403792 18875248 5171264
08:20:22 8418 8192000000 9971961632 10364448768 1017653536 11.049 97 42 84 1 1055531424 28273824 9604064
08:21:31 4095 8192000000 9936319520 10307256320 1000564288 10.884 98 44 88 2 1033744608 22848000 10332320
08:22:36 8260 8192000000 9923395328 10303537152 985992960 10.743 97 35 70 1 1016140336 22109504 8037872
08:23:48 19900 8192000000 9918027616 10295619584 991138544 10.793 97 39 78 1 1023030208 22888240 9003424
08:25:10 6421 8192000000 9946545600 10339012608 1044953280 11.312 100 44 87 1 1068771856 14144640 9673936
08:26:20 7303 8192000000 9968544288 10373132288 1060104560 11.457 100 35 70 1 1087008400 19700784 7203056
08:27:34 7295 8192000000 10007240320 10395926528 1074958800 11.599 100 43 86 1 1110742048 26584240 9199008
08:28:45 30176 8192000000 10023665328 10419851264 1085093328 11.696 100 52 101 2 1135124384 39976032 10055024
08:30:18 4647 8192000000 10054728304 10453000192 1095351776 11.794 100 51 102 0 1162027744 55292608 11383360
08:31:27 33502 8192000000 10060489184 10474594304 1107154864 11.905 100 51 102 1 1182798848 64483232 11160752
08:33:06 27322 8192000000 10129074240 10530279424 1120545344 12.032 100 64 128 0 1228282080 94463568 13273168
08:34:37 11699 8192000000 10115250704 10522214400 1135209504 12.170 100 52 104 3 1221273440 74568384 11495552
08:35:54 3195 8192000000 10174049936 10582188032 1135090576 12.169 100 53 104 1 1277244448 130913072 11240800
08:37:02 13343 8192000000 10187315728 10597138432 1150594096 12.315 100 55 108 1 1289171248 126572032 12005120
08:38:31 4965 8192000000 10232602432 10640207872 1162441504 12.426 100 65 128 0 1332940000 157451184 13047312
08:39:46 14450 8192000000 10236671760 10663079936 1166180480 12.461 100 55 109 0 1336025744 158283520 11561744
08:41:09 9543 8192000000 10265074192 10667655168 1164682224 12.447 100 66 132 0 1372023344 193453248 13887872
08:42:24 1892 8192000000 10308293664 10710888448 1172489520 12.520 100 72 142 0 1407656384 219752384 15414480
08:43:34 1824 8192000000 10355773488 10749190144 1170840864 12.505 100 91 183 1 1455914560 264458032 20615664
08:44:43 4339 8192000000 10384005472 10788036608 1166377664 12.463 100 78 154 1 1484241680 299868512 17995504
08:45:53 5304 8192000000 10427789696 10830372864 1166252352 12.462 100 72 143 0 1534154960 350894896 17007712
08:47:05 1406 8192000000 10474677808 10890465280 1166716768 12.466 100 77 153 0 1568060400 383096080 18247552
08:48:12 2289 8192000000 10524756880 10922123264 1164486048 12.445 100 89 176 2 1620066256 434639872 20940336
08:49:18 6324 8192000000 10507702848 10928242688 1150666736 12.316 99 65 127 1 1595613632 429118304 15828592
08:50:31 2114 8192000000 10516951120 10922876928 1156137040 12.367 100 84 165 1 1614438992 438855888 19446064
08:51:38 9464 8192000000 10487417024 10907262976 1158461088 12.389 100 53 105 1 1584438592 412468880 13508624
08:52:52 14766 8192000000 10373952976 10842169344 1125533392 12.079 99 36 71 1 1484826752 350066960 9226400
08:54:14 22224 8192000000 10282739264 10731474944 1107969696 11.913 98 21 42 0 1369428384 255669200 5789488
08:55:51 78716 8192000000 10047917936 10516979712 1051874128 11.379 94 9 18 0 1171193568 117078976 2240464
08:58:13 30624 8192000000 10088149664 10512351232 1099913168 11.837 98 35 71 1 1158525936 50972784 7639984
09:00:29 141525 8192000000 10077356912 10530562048 1089891936 11.742 97 35 71 1 1176493216 78238256 8363024
09:03:52 47548 8192000000 10124382848 10560475136 1122219696 12.048 100 36 72 1 1229364736 99261488 7883552
09:05:40 25650 8192000000 10072918736 10528526336 1109774368 11.930 99 28 56 1 1198507744 82095088 6638288
09:07:08 80966 8192000000 10133535888 10572599296 1148170784 12.292 100 20 40 0 1221578256 69234032 4173440
09:09:30 87952 8192000000 10165549440 10629578752 1126063136 12.084 98 14 28 0 1262217760 132752256 3402368
09:12:09 75011 8192000000 10081911040 10560208896 1096635040 11.806 96 14 28 0 1184308688 84754400 2919248
09:14:32 24832 8192000000 10134323920 10562662400 1138078128 12.197 100 39 78 2 1219839168 72864912 8896128
09:16:06 39557 8192000000 10141000368 10569076736 1151809872 12.326 100 18 36 2 1209766832 53985264 3971696
09:17:49 38834 8192000000 10088523872 10546020352 1132216176 12.142 99 22 44 2 1206094784 69736640 4141968
09:19:32 40224 8192000000 10139873168 10579558400 1135209296 12.170 98 30 60 1 1214735744 73586624 5939824
09:21:14 124835 8192000000 10072096080 10536292352 1103364560 11.870 96 24 48 0 1150171296 42001680 4805056
09:24:22 56795 8192000000 10088872784 10535165952 1091373568 11.756 96 47 92 2 1146350464 45885984 9090912
09:26:20 64060 8192000000 10038084800 10489634816 1106346864 11.898 99 18 36 1 1132788944 22877184 3564896
09:28:31 47828 8192000000 10039658128 10499407872 1119206384 12.019 99 6 12 0 1143994720 23601792 1186544
09:30:25 45130 8192000000 10049195152 10500771840 1108142048 11.915 99 12 24 1 1157598512 47536928 1919536
09:32:11 52825 8192000000 10052431568 10486493184 1072112992 11.572 96 7 14 1 1129783504 56134224 1536288
09:34:10 10945 8192000000 10048445712 10471198720 1110632032 11.938 99 50 97 3 1139320272 18343680 10344560
09:35:30 28278 8192000000 10118340800 10551451648 1112415312 11.955 98 32 64 0 1188434368 68923696 7095360
09:37:02 49382 8192000000 10169329840 10619002880 1103491616 11.871 97 18 36 1 1254443552 146846048 4105888
09:39:02 34176 8192000000 10226570496 10678779904 1119219360 12.020 99 34 68 0 1310701424 183866864 7615200
09:40:36 68320 8192000000 10056500880 10554064896 1093736848 11.778 98 9 18 0 1161464592 65775744 1952000
09:42:45 18056 8192000000 10077839328 10492719104 1110377920 11.936 99 56 112 1 1156521440 35253408 10890112
09:44:06 79204 8192000000 10105625104 10591346688 1120657664 12.033 100 30 60 0 1227399344 100840096 5901584
09:46:34 45845 8192000000 10055510512 10552913920 1090823504 11.750 98 9 18 0 1136300736 43790688 1686544
09:48:29 79816 8192000000 10089201920 10522193920 1113006800 11.961 99 20 40 2 1171286176 54201136 4078240
09:51:12 110224 8192000000 10154864736 10655064064 1129897136 12.120 99 24 48 2 1263405856 128040400 5468320
09:54:42 51851 8192000000 10082436576 10534256640 1125793264 12.082 100 45 90 2 1158290960 23207776 9289920
09:56:43 90268 8192000000 10079417712 10534256640 1101704672 11.854 97 26 51 1 1144841136 37672192 5464272
09:59:17 138813 8192000000 10050836256 10501517312 1094241392 11.783 96 14 28 0 1117499312 19878880 3379040
10:02:42 63197 8192000000 10109469392 10561630208 1138446064 12.201 100 12 25 2 1208338176 67345504 2546608
10:04:46 25634 8192000000 10063273360 10568548352 1133402832 12.153 100 8 16 1 1172355024 37242768 1709424
10:06:12 33420 8192000000 10099390704 10541776896 1118011968 12.008 99 19 34 0 1166489536 45300848 3176720
10:09:04 77131 8192000000 10174079680 10650431488 1158971616 12.394 100 22 44 0 1259224896 95364720 4888560
10:11:22 61383 8192000000 10136553056 10614046720 1146596368 12.278 99 11 22 0 1225491776 76887296 2008112
10:13:24 49671 8192000000 10102963184 10538000384 1127350608 12.096 98 23 46 1 1149083024 16811344 4921072
10:15:18 54461 8192000000 10151336912 10614259712 1116361936 11.993 97 21 40 2 1169656320 49433456 3860928
10:17:13 122575 8192000000 10089838352 10559266816 1123228240 12.057 98 18 35 1 1176134848 49458496 3448112
10:20:45 89735 8192000000 10059247088 10520915968 1108777792 11.921 97 7 14 0 1139274736 28691088 1805856
10:23:15 59816 8192000000 10095546096 10570350592 1141782336 12.232 100 6 12 1 1186921760 43741744 1397680
10:25:18 64671 8192000000 10066463744 10548518912 1093006448 11.771 96 19 38 1 1175934832 78423024 4505360

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-01-2021 10:28:21 to 13-01-2021 10:28:36.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 13-01-2021 10:28:37 to 13-01-2021 10:28:39.

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          228108
MON$OLDEST_ACTIVE               229258
MON$OLDEST_SNAPSHOT             229258
MON$NEXT_TRANSACTION            229258
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                       2086816
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             785
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2325 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2325 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2325 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.2325 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 ---
:23: 
:24: mscs-test-161.budzdorov.ru    Wed Jan 13 10:26:59 2021
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:26: 
:27: 
:28: mscs-test-161.budzdorov.ru    Wed Jan 13 10:27:00 2021
:29:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:30: 
:31: 
:32: mscs-test-161.budzdorov.ru    Wed Jan 13 10:27:04 2021
:33:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:34: 
--- end of diff output ---

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

13.01.21 10:28:40. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-01-13 10:28:43 No severe PSQL-related problems occured
13.01.21 10:28:44. End of report.