Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.12.2020 07:28
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 16689284 kB
MemAvailable 31433020 kB
Buffers 0 kB
Cached 14917924 kB
SwapCached 3696 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2020-12-21 07:26:51
DTS_END                         2020-12-21 07:26:51.4470
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/43882
TRN_ID                          233966
ATT_ID                          666
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: 9005.07 21600 1080608
customer order: creation 170.42 383 20450
customer order: refuse 15.03 352 1804
order to supplier: creation 45.28 1378 5433
order to supplier: removal 3.29 117455 395
invoice (draft): creation 45.79 3476 5495
invoice (draft): removal 51.03 989 6124
invoice accept: apply 41.38 17609 4965
invoice accept: cancel 46.10 906 5532
customer reserve: creation 3818.49 173 458219
customer reserve: removal 4473.18 67 536782
realization accept: apply 82.68 170 9921
realization accept: cancel 85.93 112 10311
payment from customer: creation 58.14 103 6977
payment from customer: removal 5.58 67 670
payment to supplier: creation 48.84 119 5861
payment to supplier: removal 3.33 99 399
service: total inventory turnovers 3.92 3035 470
service: total monetary turnovers 5.89 583 707
service: aggregate perf. data 0.74 20363 89
service: refresh index statistics 0.03 264568 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 21-12-2020 07:28:23 to 21-12-2020 07:28:26.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3705
TEST_TIME 2 5152
TEST_TIME 3 5392
TEST_TIME 4 5287
TEST_TIME 5 5739
TEST_TIME 6 5941
TEST_TIME 7 6149
TEST_TIME 8 6227
TEST_TIME 9 6549
TEST_TIME 10 6981
TEST_TIME 11 7401
TEST_TIME 12 7341
TEST_TIME 13 7777
TEST_TIME 14 7846
TEST_TIME 15 7815
TEST_TIME 16 8134
TEST_TIME 17 8253
TEST_TIME 18 8718
TEST_TIME 19 8770
TEST_TIME 20 9087
TEST_TIME 21 9269
TEST_TIME 22 9165
TEST_TIME 23 9340
TEST_TIME 24 9263
TEST_TIME 25 9408
TEST_TIME 26 9603
TEST_TIME 27 10378
TEST_TIME 28 11227
TEST_TIME 29 11675
TEST_TIME 30 11677
TEST_TIME 31 11626
TEST_TIME 32 11502
TEST_TIME 33 11607
TEST_TIME 34 11666
TEST_TIME 35 11623
TEST_TIME 36 11487
TEST_TIME 37 11226
TEST_TIME 38 10948
TEST_TIME 39 10840
TEST_TIME 40 10692
TEST_TIME 41 10670
TEST_TIME 42 10667
TEST_TIME 43 10564
TEST_TIME 44 10532
TEST_TIME 45 10409
TEST_TIME 46 10270
TEST_TIME 47 10200
TEST_TIME 48 10073
TEST_TIME 49 10043
TEST_TIME 50 10090
TEST_TIME 51 10112
TEST_TIME 52 10007
TEST_TIME 53 10185
TEST_TIME 54 10116
TEST_TIME 55 10082
TEST_TIME 56 10025
TEST_TIME 57 10086
TEST_TIME 58 10059
TEST_TIME 59 10018
TEST_TIME 60 10200
TEST_TIME 61 10157
TEST_TIME 62 9999
TEST_TIME 63 9996
TEST_TIME 64 9927
TEST_TIME 65 9916
TEST_TIME 66 9859
TEST_TIME 67 9786
TEST_TIME 68 9744
TEST_TIME 69 9747
TEST_TIME 70 9769
TEST_TIME 71 9738
TEST_TIME 72 9740
TEST_TIME 73 9682
TEST_TIME 74 9821
TEST_TIME 75 9801
TEST_TIME 76 9809
TEST_TIME 77 9793
TEST_TIME 78 9696
TEST_TIME 79 9660
TEST_TIME 80 9614
TEST_TIME 81 9554
TEST_TIME 82 9530
TEST_TIME 83 9483
TEST_TIME 84 9422
TEST_TIME 85 9418
TEST_TIME 86 9369
TEST_TIME 87 9352
TEST_TIME 88 9358
TEST_TIME 89 9405
TEST_TIME 90 9435
TEST_TIME 91 9464
TEST_TIME 92 9461
TEST_TIME 93 9413
TEST_TIME 94 9436
TEST_TIME 95 9424
TEST_TIME 96 9410
TEST_TIME 97 9355
TEST_TIME 98 9318
TEST_TIME 99 9245
TEST_TIME 100 9228
TEST_TIME 101 9212
TEST_TIME 102 9169
TEST_TIME 103 9149
TEST_TIME 104 9206
TEST_TIME 105 9166
TEST_TIME 106 9221
TEST_TIME 107 9193
TEST_TIME 108 9170
TEST_TIME 109 9233
TEST_TIME 110 9224
TEST_TIME 111 9255
TEST_TIME 112 9295
TEST_TIME 113 9230
TEST_TIME 114 9170
TEST_TIME 115 9124
TEST_TIME 116 9102
TEST_TIME 117 9071
TEST_TIME 118 9040
TEST_TIME 119 9039
TEST_TIME 120 9005

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 21-12-2020 07:28:27 to 21-12-2020 07:28:30.

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 1503689 1503689 0 0.00 0 18051 6 0 0
doc_list_biud 1503688 1503688 0 0.00 0 94633 31 0 0
sp_add_invoice_to_stock 5064 4965 99 1.95 24 170782 17610 0 99
sp_cancel_adding_invoice 5532 5532 0 0.00 5 66963 906 0 0
sp_cancel_client_order 1804 1804 0 0.00 4 12728 353 0 0
sp_cancel_customer_reserve 536782 536782 0 0.00 1 25342 68 0 0
sp_cancel_pay_from_customer 670 670 0 0.00 2 3888 68 0 0
sp_cancel_pay_to_supplier 399 399 0 0.00 3 2650 100 0 0
sp_cancel_supplier_invoice 6133 6124 9 0.14 2 94642 990 0 9
sp_cancel_supplier_order 395 395 0 0.00 10 1035912 117455 0 0
sp_cancel_write_off 10311 10311 0 0.00 1 10545 113 0 0
sp_client_order 20450 20450 0 0.00 4 20560 383 0 0
sp_customer_reserve 458219 458219 0 0.00 0 43898 173 0 0
sp_fill_shopping_cart 31354 31354 0 0.00 1 23279 47 0 0
sp_fill_shopping_cart_clo_res 458219 458219 0 0.00 0 22587 16 0 0
sp_get_clo_for_invoice 4965 4965 0 0.00 0 29963 361 0 0
sp_kill_cost_storno 16912 16912 0 0.00 0 10539 62 0 0
sp_kill_qstorno_ret_qs2qd 1090394 1090394 0 0.00 0 94632 37 0 0
sp_kill_qty_storno 575834 575834 0 0.00 0 94633 80 0 0
sp_lock_selected_doc 20595 20595 0 0.00 0 8610 5 0 0
sp_make_cost_storno 27724 27724 0 0.00 0 9168 47 0 0
sp_multiply_rows_for_pdistr 27724 27724 0 0.00 0 11338 34 0 0
sp_multiply_rows_for_qdistr 20450 20450 0 0.00 0 20552 229 0 0
sp_pay_from_customer 6977 6977 0 0.00 4 10539 104 0 0
sp_pay_to_supplier 5861 5861 0 0.00 5 16079 119 0 0
sp_payment_common 12838 12838 0 0.00 1 8029 19 0 0
sp_qd_handle_on_cancel_clo 1804 1804 0 0.00 0 12721 320 0 0
sp_qd_handle_on_invoice_upd_sts 10497 10497 0 0.00 0 18918 399 0 0
sp_qd_handle_on_reserve_upd_sts 9921 9921 0 0.00 0 13071 47 0 0
sp_reserve_write_off 9921 9921 0 0.00 7 15839 171 0 0
sp_supplier_invoice 5495 5495 0 0.00 5 119802 3477 0 0
sp_supplier_order 5433 5433 0 0.00 5 92389 1379 0 0
srv_aggregate_perf_data 89 89 0 0.00 2794 478201 20363 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1934 142772 47577 0 0
srv_find_qd_qs_mism 1032819 1032819 0 0.00 0 7872 5 0 0
srv_make_invnt_saldo 470 470 0 0.00 56 91555 3035 0 0
srv_make_money_saldo 707 707 0 0.00 1 18544 583 0 0
srv_recalc_idx_stat 4 4 0 0.00 121317 371111 264568 0 0
t$perf-abend:sp_add_invoice_to_stock 99 99 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4965 4965 0 0.00 0 14405 146 0 0
t$perf-norm:sp_cancel_adding_invoice 417 417 0 0.00 0 3403 73 0 0
t$perf-norm:sp_cancel_client_order 1804 1804 0 0.00 0 6600 21 0 0
t$perf-norm:sp_cancel_customer_reserve 673 673 0 0.00 0 3606 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 670 670 0 0.00 0 810 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 399 399 0 0.00 0 99 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 413 413 0 0.00 0 407 13 0 0
t$perf-norm:sp_cancel_supplier_order 395 395 0 0.00 0 19319 1451 0 0
t$perf-norm:sp_cancel_write_off 941 941 0 0.00 0 9178 14 0 0
t$perf-norm:sp_client_order 20450 20450 0 0.00 0 12547 20 0 0
t$perf-norm:sp_customer_reserve 939 939 0 0.00 0 874 5 0 0
t$perf-norm:sp_pay_from_customer 6977 6977 0 0.00 0 6938 12 0 0
t$perf-norm:sp_pay_to_supplier 5861 5861 0 0.00 0 3878 10 0 0
t$perf-norm:sp_reserve_write_off 9921 9921 0 0.00 0 12589 12 0 0
t$perf-norm:sp_supplier_invoice 5495 5495 0 0.00 0 5531 17 0 0
t$perf-norm:sp_supplier_order 5433 5433 0 0.00 0 2831 14 0 0
t$perf-norm:srv_aggregate_perf_data 89 89 0 0.00 0 1399 33 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 7995 130 0 0
t$perf-norm:srv_make_invnt_saldo 470 470 0 0.00 0 1613 10 0 0
t$perf-norm:srv_make_money_saldo 707 707 0 0.00 0 10220 23 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 17 7 0 0
v_add_invoice_to_stock 10122 9924 198 1.95 0 5184 16 0 198
v_all_customers 37302 37302 0 0.00 0 20 1 0 0
v_all_suppliers 30288 30288 0 0.00 0 31 1 0 0
v_all_wares 40898 40898 0 0.00 0 39 1 0 0
v_cancel_adding_invoice 834 834 0 0.00 0 1541 10 0 0
v_cancel_client_order 3608 3608 0 0.00 0 5577 7 0 0
v_cancel_customer_prepayment 1340 1340 0 0.00 0 3883 8 0 0
v_cancel_customer_reserve 1346 1346 0 0.00 0 999 4 0 0
v_cancel_payment_to_supplier 798 798 0 0.00 0 1469 11 0 0
v_cancel_supplier_invoice 844 826 18 2.13 0 1053 16 0 18
v_cancel_supplier_order 790 790 0 0.00 0 2906 9 0 0
v_cancel_write_off 1882 1882 0 0.00 0 3894 16 0 0
v_max_id_clo_ord 5423 5423 0 0.00 0 4616 4 0 0
v_max_id_clo_res 939 939 0 0.00 0 2061 13 0 0
v_max_id_ord_sup 5481 5481 0 0.00 0 6729 6 0 0
v_max_non_paid_invoice 1621 1621 0 0.00 0 864 3 0 0
v_max_non_paid_realizn 3686 3686 0 0.00 0 1232 3 0 0
v_min_id_clo_ord 5423 5423 0 0.00 0 5580 9 0 0
v_min_id_clo_res 939 939 0 0.00 0 7386 16 0 0
v_min_id_ord_sup 5481 5481 0 0.00 0 6353 12 0 0
v_min_non_paid_invoice 5861 5861 0 0.00 0 4805 13 0 0
v_min_non_paid_realizn 6977 6977 0 0.00 0 6518 12 0 0
v_our_firm 10178 10178 0 0.00 0 14 1 0 0
v_reserve_write_off 19842 19842 0 0.00 0 3883 5 0 0
x_lock_depdocs_on_canc_invoice 11243 11243 0 0.00 0 12991 104 0 0
x_lock_depdocs_on_canc_sup_ord 395 395 0 0.00 0 4228 322 0 0
x_make_qstorno_1000_1200 5433 5433 0 0.00 2 92378 1288 0 0
x_make_qstorno_1200_2000 5495 5495 0 0.00 2 113703 3299 0 0
x_make_qstorno_2100_3300 458219 458219 0 0.00 0 43896 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-12-2020 07:28:32 to 21-12-2020 07:28:36.

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
04:56:55 1 8192000000 8796637808 8807108608 3167696 0.038 1 1 2 0 3265392 61840 35856
04:57:55 21387 8192000000 9715097824 10007396352 786778400 8.762 100 87 174 0 815965520 7725888 21461232
04:59:23 14583 8192000000 9746786704 10049835008 820674544 9.105 100 88 176 0 854608144 12884528 21049072
05:00:43 10548 8192000000 9781646896 10094616576 854328208 9.443 100 81 163 0 894816096 21393856 19094032
05:01:59 15459 8192000000 9855397808 10185932800 900209824 9.900 100 72 140 2 958206448 42215168 15781456
05:03:20 10338 8192000000 9920723632 10264858624 961074992 10.500 100 81 162 1 1029355072 50423664 17856416
05:04:33 6162 8192000000 9963652432 10317492224 1001963744 10.898 100 83 163 2 1073329632 55080064 16285824
05:05:40 3740 8192000000 10002912400 10361638912 1021781216 11.089 100 83 163 4 1108991872 71300304 15910352
05:06:46 2027 8192000000 10025005648 10392346624 1049001792 11.351 100 84 166 0 1140636272 75156928 16477552
05:07:51 2006 8192000000 10017840416 10388086784 1055128256 11.410 100 87 174 1 1132244224 59616480 17499488
05:08:56 2589 8192000000 10026271440 10402840576 1066739024 11.521 100 94 189 0 1146442544 59377440 20326080
05:10:00 7034 8192000000 9990297408 10383892480 1071807728 11.569 100 49 99 2 1108224912 26047536 10369648
05:11:10 13652 8192000000 9993499312 10403270656 1078662608 11.635 100 27 52 3 1117794960 33934448 5197904
05:12:25 7993 8192000000 9994573968 10403831808 1087813728 11.722 100 30 62 4 1114133744 19973472 6346544
05:13:33 65798 8192000000 10004126960 10400714752 1091446784 11.756 100 41 81 0 1117868928 16546976 9875168
05:15:39 4407 8192000000 9998210864 10392670208 1062276992 11.478 97 51 96 1 1095530752 22162720 11091040
05:16:47 7234 8192000000 10013724896 10419830784 1094475472 11.785 100 32 65 2 1131428304 29509104 7443728
05:17:57 4328 8192000000 9999372768 10379005952 1059798624 11.455 98 42 80 3 1097019136 28823696 8396816
05:19:02 43709 8192000000 9947244976 10347409408 1006245216 10.939 95 28 56 2 1033939712 21312928 6381568
05:20:47 6694 8192000000 9941149504 10321936384 1009972160 10.975 99 50 101 1 1034691616 14498128 10221328
05:21:59 10413 8192000000 9930170336 10321330176 996441616 10.844 98 31 60 3 1021418496 18046224 6930656
05:23:12 8585 8192000000 9970308944 10369941504 1052703696 11.387 100 39 80 3 1080224000 18042096 9478208
05:24:23 31090 8192000000 9953349168 10357686272 1055794512 11.416 100 10 20 0 1081888336 23648752 2445072
05:25:59 4458 8192000000 9991679168 10376011776 1070301312 11.555 100 46 90 1 1098794960 18435696 10057952
05:27:06 27691 8192000000 10021039984 10416377856 1097986272 11.819 100 54 108 1 1129099840 19078272 12035296
05:28:39 8418 8192000000 10076332176 10473050112 1111067664 11.943 100 54 108 0 1184669600 62937872 10664064
05:29:49 17172 8192000000 10065381840 10474287104 1118513760 12.013 100 43 84 2 1184688256 57088256 9086240
05:31:15 10694 8192000000 10115400464 10524569600 1130563856 12.127 100 50 98 2 1211522496 70978240 9980400
05:32:27 7086 8192000000 10147256880 10552299520 1145744064 12.270 100 66 128 0 1268688848 109450864 13493920
05:33:42 6738 8192000000 10164778560 10574561280 1158991952 12.394 100 55 108 0 1276274720 104919840 12362928
05:34:56 10282 8192000000 10200846496 10621743104 1168109664 12.479 100 47 94 1 1304951008 125853728 10987616
05:36:10 5881 8192000000 10218220320 10631925760 1170526544 12.502 100 48 96 1 1323224208 142392528 10305136
05:37:20 5914 8192000000 10240956656 10656657408 1169720880 12.494 100 53 106 0 1353507744 171970144 11816720
05:38:30 9234 8192000000 10280154656 10689904640 1173338608 12.528 100 59 116 4 1365178080 179547472 12292000
05:39:42 4948 8192000000 10275032800 10684919808 1169521616 12.492 100 65 130 2 1385320160 201350688 14447856
05:40:53 2478 8192000000 10319383936 10721062912 1157732544 12.382 99 84 166 0 1409279616 233395120 18151952
05:42:00 7289 8192000000 10354301296 10761564160 1155135248 12.358 100 78 153 2 1461962864 289788736 17038880
05:43:17 8449 8192000000 10352248800 10767314944 1146196080 12.274 100 72 142 4 1462119504 298466352 17457072
05:44:32 3562 8192000000 10403874896 10804838400 1148687952 12.297 100 86 171 3 1496238816 327183696 20367168
05:45:40 1932 8192000000 10372925584 10797047808 1132150496 12.142 99 85 170 1 1462149536 308949280 21049760
05:46:43 8094 8192000000 10384293328 10839306240 1142776496 12.242 100 45 89 1 1490989168 336954720 11257952
05:48:16 12964 8192000000 10400050880 10819182592 1133882688 12.158 100 40 80 0 1515860672 371521808 10456176
05:49:44 15089 8192000000 10427639040 10841899008 1127083920 12.094 99 41 82 0 1528616352 390744016 10788416
05:51:13 8439 8192000000 10490330096 10919837696 1119063616 12.018 99 46 91 3 1590822784 459587216 12171952
05:52:23 25899 8192000000 10438821600 10918211584 1109768048 11.930 99 27 54 0 1541700720 423964496 7968176
05:54:09 21041 8192000000 10336291680 10773872640 1110044688 11.933 100 35 69 3 1425186272 306380864 8760720
05:55:35 69409 8192000000 10078936272 10579107840 1103876096 11.874 99 11 22 0 1165636976 59591136 2169744
05:57:45 68966 8192000000 10078675520 10501222400 1109866144 11.931 99 26 51 0 1173059280 57485520 5707616
06:00:11 142772 8192000000 10051496896 10509139968 1094990720 11.790 97 14 26 0 1125304880 28104128 2210032
06:03:40 78448 8192000000 10063819760 10489368576 1127083968 12.094 100 49 100 0 1150199968 12281136 10834864
06:06:01 57074 8192000000 10127725952 10579689472 1129756032 12.119 99 27 53 1 1212852112 77115056 5981024
06:09:01 83272 8192000000 10071159376 10530594816 1115486960 11.984 98 12 24 0 1163779248 45691648 2600640
06:11:26 74200 8192000000 10105929840 10545606656 1145486768 12.267 100 13 26 0 1202689424 54322336 2880320
06:13:41 66101 8192000000 10093242784 10566664192 1146589376 12.277 100 11 22 2 1198607744 49930160 2088208
06:15:49 38900 8192000000 10113153296 10581577728 1134667760 12.165 99 19 38 0 1204616656 65505968 4442928
06:17:34 99754 8192000000 10126496416 10598379520 1123085936 12.056 97 13 26 1 1215356208 89252032 3018240
06:20:51 37209 8192000000 10101102624 10539802624 1148841776 12.299 100 31 63 0 1187114240 31768944 6503520
06:22:30 31733 8192000000 10148502144 10588340224 1160235376 12.405 100 31 60 1 1228557200 62418800 5903024
06:24:18 67602 8192000000 10136244864 10587811840 1094587200 11.786 95 9 18 1 1183135184 86682800 1865184
06:26:29 102973 8192000000 10130592160 10609844224 1129424544 12.116 98 13 26 0 1190910864 59172736 2313584
06:30:01 59753 8192000000 10082685984 10581843968 1135852960 12.177 99 4 8 0 1179398800 42923968 621872
06:32:03 94870 8192000000 10085760880 10520563712 1110792384 11.940 97 20 40 1 1143047152 28101584 4153184
06:34:44 79805 8192000000 10051287408 10581045248 1069617568 11.548 94 14 28 0 1169663072 96744016 3301488
06:37:24 60894 8192000000 10074804720 10546376704 1083328976 11.679 96 14 28 2 1155412080 68942816 3140288
06:39:30 31934 8192000000 10130826992 10557624320 1097905664 11.818 98 41 82 0 1177734608 71555392 8273552
06:41:17 35017 8192000000 10067267104 10501455872 1075763520 11.607 97 34 68 2 1152602528 70302816 6536192
06:43:01 46125 8192000000 10078236416 10504859648 1098561920 11.824 98 29 58 0 1156465088 52094288 5808880
06:45:00 92885 8192000000 10053962432 10538135552 1109468832 11.927 99 21 42 0 1148539040 34586064 4484144
06:47:33 45177 8192000000 10074311856 10536939520 1123247648 12.058 100 10 20 0 1157916064 32494752 2173664
06:49:19 71308 8192000000 10070133232 10533826560 1109804288 11.931 99 6 12 0 1157832432 46818496 1209648
06:51:33 68460 8192000000 10092606704 10531753984 1090713216 11.749 96 22 45 0 1157858768 62476624 4668928
06:53:46 49117 8192000000 10140480416 10604650496 1108994976 11.923 98 26 52 2 1209637984 94782240 5860768
06:55:45 76540 8192000000 10082668000 10539024384 1109463840 11.927 98 16 32 1 1192490880 79172256 3854784
06:58:08 64481 8192000000 10055937184 10493005824 1120721216 12.034 99 16 32 1 1149394928 25389600 3284112
07:00:13 14515 8192000000 10114754944 10538860544 1144747520 12.260 100 52 103 0 1204739632 50036816 9955296
07:01:35 68884 8192000000 10105139248 10534801408 1128012112 12.103 99 22 45 2 1202961872 70288448 4661312
07:03:44 127483 8192000000 10088662240 10542252032 1132050528 12.141 99 14 28 0 1174679856 39595760 3033568
07:06:54 89264 8192000000 10116213248 10608500736 1141755536 12.232 100 5 10 0 1202833312 59981200 1096576
07:10:00 86247 8192000000 10152220128 10602381312 1154743728 12.354 99 23 46 2 1290387936 130080112 5564096
07:12:28 71534 8192000000 10112788848 10562789376 1134814448 12.167 99 15 30 0 1204882688 66981040 3087200
07:14:49 56869 8192000000 10118602512 10563190784 1136354928 12.181 99 25 48 0 1189066912 47905792 4806192
07:16:47 39541 8192000000 10101235344 10582376448 1137492704 12.192 99 31 62 2 1197752944 52905472 7354768
07:18:27 74847 8192000000 10093754464 10598973440 1129087360 12.113 99 23 46 1 1223564496 89761712 4715424
07:20:42 47764 8192000000 10040037504 10508476416 1112846544 11.959 98 13 26 1 1140021408 24261936 2912928
07:22:32 64755 8192000000 10067082400 10516684800 1108953488 11.923 98 5 10 0 1140620768 30855968 811312
07:24:38 83908 8192000000 10104495664 10579742720 1118203776 12.010 97 18 36 0 1185963232 63572240 4187216

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 21-12-2020 07:28:37 to 21-12-2020 07:28:52.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 99 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-12-2020 07:28:53 to 21-12-2020 07:28:55.

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          231771
MON$OLDEST_ACTIVE               234323
MON$OLDEST_SNAPSHOT             234323
MON$NEXT_TRANSACTION            234323
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                       2106368
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             796
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2300 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2300 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2300 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.2300 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 ---
:114: 
:115: mscs-test-161.budzdorov.ru    Mon Dec 21 07:26:55 2020
:116:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:117: 
:118: 
:119: mscs-test-161.budzdorov.ru    Mon Dec 21 07:26:58 2020
:120:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:121: 
:122: 
:123: mscs-test-161.budzdorov.ru    Mon Dec 21 07:27:02 2020
:124:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:125: 
--- end of diff output ---

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

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