Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.01.2021 07: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 16680080 kB
MemAvailable 31277408 kB
Buffers 0 kB
Cached 14867000 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-04 07:26:54
DTS_END                         2021-01-04 07:26:54.5250
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> X_MAKE_QSTORNO_1000_1200(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/38426
TRN_ID                          230218
ATT_ID                          606
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: 8650.78 24472 1038093
customer order: creation 166.84 404 20021
customer order: refuse 14.28 401 1714
order to supplier: creation 44.25 1511 5310
order to supplier: removal 3.05 141211 366
invoice (draft): creation 45.75 3941 5490
invoice (draft): removal 48.73 1143 5848
invoice accept: apply 39.78 19254 4773
invoice accept: cancel 44.22 1008 5306
customer reserve: creation 3644.64 188 437357
customer reserve: removal 4319.85 78 518382
realization accept: apply 81.33 225 9760
realization accept: cancel 73.60 131 8832
payment from customer: creation 58.28 133 6993
payment from customer: removal 5.47 94 656
payment to supplier: creation 47.09 149 5651
payment to supplier: removal 3.16 80 379
service: total inventory turnovers 3.66 3973 439
service: total monetary turnovers 5.97 689 716
service: aggregate perf. data 0.80 18148 96
service: refresh index statistics 0.03 296678 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 04-01-2021 07:27:48 to 04-01-2021 07:27:51.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2056
TEST_TIME 2 2179
TEST_TIME 3 3014
TEST_TIME 4 4058
TEST_TIME 5 4610
TEST_TIME 6 5091
TEST_TIME 7 5111
TEST_TIME 8 5168
TEST_TIME 9 5160
TEST_TIME 10 5314
TEST_TIME 11 5803
TEST_TIME 12 6133
TEST_TIME 13 6276
TEST_TIME 14 6928
TEST_TIME 15 7179
TEST_TIME 16 7568
TEST_TIME 17 7718
TEST_TIME 18 7860
TEST_TIME 19 7718
TEST_TIME 20 7924
TEST_TIME 21 8085
TEST_TIME 22 7956
TEST_TIME 23 8076
TEST_TIME 24 8542
TEST_TIME 25 8519
TEST_TIME 26 8476
TEST_TIME 27 8591
TEST_TIME 28 8848
TEST_TIME 29 8722
TEST_TIME 30 8708
TEST_TIME 31 9732
TEST_TIME 32 11002
TEST_TIME 33 10744
TEST_TIME 34 10579
TEST_TIME 35 10443
TEST_TIME 36 10269
TEST_TIME 37 10084
TEST_TIME 38 9992
TEST_TIME 39 9899
TEST_TIME 40 9781
TEST_TIME 41 9723
TEST_TIME 42 9670
TEST_TIME 43 9552
TEST_TIME 44 9684
TEST_TIME 45 9533
TEST_TIME 46 9353
TEST_TIME 47 9323
TEST_TIME 48 9195
TEST_TIME 49 9171
TEST_TIME 50 9160
TEST_TIME 51 9072
TEST_TIME 52 8969
TEST_TIME 53 8896
TEST_TIME 54 8809
TEST_TIME 55 8771
TEST_TIME 56 8841
TEST_TIME 57 8882
TEST_TIME 58 8925
TEST_TIME 59 8939
TEST_TIME 60 8911
TEST_TIME 61 8901
TEST_TIME 62 8946
TEST_TIME 63 9021
TEST_TIME 64 9051
TEST_TIME 65 9027
TEST_TIME 66 8970
TEST_TIME 67 9005
TEST_TIME 68 9074
TEST_TIME 69 9025
TEST_TIME 70 8961
TEST_TIME 71 8864
TEST_TIME 72 8806
TEST_TIME 73 8731
TEST_TIME 74 8660
TEST_TIME 75 8609
TEST_TIME 76 8580
TEST_TIME 77 8551
TEST_TIME 78 8536
TEST_TIME 79 8536
TEST_TIME 80 8601
TEST_TIME 81 8609
TEST_TIME 82 8692
TEST_TIME 83 8707
TEST_TIME 84 8692
TEST_TIME 85 8720
TEST_TIME 86 8772
TEST_TIME 87 8820
TEST_TIME 88 8731
TEST_TIME 89 8681
TEST_TIME 90 8672
TEST_TIME 91 8606
TEST_TIME 92 8601
TEST_TIME 93 8650
TEST_TIME 94 8639
TEST_TIME 95 8599
TEST_TIME 96 8645
TEST_TIME 97 8676
TEST_TIME 98 8680
TEST_TIME 99 8710
TEST_TIME 100 8719
TEST_TIME 101 8768
TEST_TIME 102 8736
TEST_TIME 103 8781
TEST_TIME 104 8762
TEST_TIME 105 8874
TEST_TIME 106 8822
TEST_TIME 107 8744
TEST_TIME 108 8702
TEST_TIME 109 8679
TEST_TIME 110 8638
TEST_TIME 111 8635
TEST_TIME 112 8595
TEST_TIME 113 8540
TEST_TIME 114 8503
TEST_TIME 115 8531
TEST_TIME 116 8509
TEST_TIME 117 8488
TEST_TIME 118 8496
TEST_TIME 119 8512
TEST_TIME 120 8651

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 04-01-2021 07:27:52 to 04-01-2021 07:27:55.

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 1440044 1440044 0 0.00 0 31800 7 0 0
doc_list_biud 1440013 1440013 0 0.00 0 33108 36 0 0
sp_add_invoice_to_stock 4885 4773 112 2.29 40 185501 19255 0 112
sp_cancel_adding_invoice 5306 5306 0 0.00 5 79429 1008 0 0
sp_cancel_client_order 1714 1714 0 0.00 5 21393 401 0 0
sp_cancel_customer_reserve 518382 518382 0 0.00 1 33112 78 0 0
sp_cancel_pay_from_customer 656 656 0 0.00 3 2085 94 0 0
sp_cancel_pay_to_supplier 379 379 0 0.00 3 2579 81 0 0
sp_cancel_supplier_invoice 5856 5848 8 0.13 2 31620 1143 0 8
sp_cancel_supplier_order 366 366 0 0.00 11 1231990 141211 0 0
sp_cancel_write_off 8832 8832 0 0.00 1 7998 131 0 0
sp_client_order 20021 20021 0 0.00 4 31423 405 0 0
sp_customer_reserve 437357 437357 0 0.00 0 65064 188 0 0
sp_fill_shopping_cart 30816 30816 0 0.00 1 22712 54 0 0
sp_fill_shopping_cart_clo_res 437357 437357 0 0.00 0 34898 18 0 0
sp_get_clo_for_invoice 4773 4773 0 0.00 0 52267 396 0 0
sp_kill_cost_storno 15173 15173 0 0.00 0 7995 69 0 0
sp_kill_qstorno_ret_qs2qd 1051810 1051810 0 0.00 0 33103 43 0 0
sp_kill_qty_storno 554963 554963 0 0.00 0 33108 92 0 0
sp_lock_selected_doc 20072 20072 0 0.00 0 9435 7 0 0
sp_make_cost_storno 27175 27175 0 0.00 0 31794 78 0 0
sp_multiply_rows_for_pdistr 27149 27149 0 0.00 0 9385 33 0 0
sp_multiply_rows_for_qdistr 20021 20021 0 0.00 0 31401 218 0 0
sp_pay_from_customer 6993 6993 0 0.00 4 18208 134 0 0
sp_pay_to_supplier 5651 5651 0 0.00 4 25451 149 0 0
sp_payment_common 12629 12629 0 0.00 1 25437 23 0 0
sp_qd_handle_on_cancel_clo 1714 1714 0 0.00 0 21346 373 0 0
sp_qd_handle_on_invoice_upd_sts 10069 10069 0 0.00 0 28329 446 0 0
sp_qd_handle_on_reserve_upd_sts 9752 9752 0 0.00 0 17485 57 0 0
sp_reserve_write_off 9760 9760 0 0.00 6 31918 225 0 0
sp_supplier_invoice 5490 5490 0 0.00 7 105894 3942 0 0
sp_supplier_order 5310 5310 0 0.00 5 106986 1511 0 0
srv_aggregate_perf_data 96 96 0 0.00 2677 336547 18148 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1223 134052 42764 0 0
srv_find_qd_qs_mism 990657 990657 0 0.00 0 28285 5 0 0
srv_make_invnt_saldo 439 439 0 0.00 36 103170 3973 0 0
srv_make_money_saldo 716 716 0 0.00 1 12123 690 0 0
srv_recalc_idx_stat 4 4 0 0.00 205061 408225 296678 0 0
t$perf-abend:sp_add_invoice_to_stock 112 112 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 4773 4773 0 0.00 0 13878 184 0 0
t$perf-norm:sp_cancel_adding_invoice 420 420 0 0.00 0 5293 119 0 0
t$perf-norm:sp_cancel_client_order 1714 1714 0 0.00 0 1850 13 0 0
t$perf-norm:sp_cancel_customer_reserve 719 719 0 0.00 0 1008 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 656 656 0 0.00 0 5047 35 0 0
t$perf-norm:sp_cancel_pay_to_supplier 379 379 0 0.00 0 1324 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 371 371 0 0.00 0 693 18 0 0
t$perf-norm:sp_cancel_supplier_order 366 366 0 0.00 0 20237 2035 0 0
t$perf-norm:sp_cancel_write_off 933 933 0 0.00 0 2826 8 0 0
t$perf-norm:sp_client_order 20021 20021 0 0.00 0 9240 23 0 0
t$perf-norm:sp_customer_reserve 988 988 0 0.00 0 5719 23 0 0
t$perf-norm:sp_pay_from_customer 6993 6993 0 0.00 0 8529 9 0 0
t$perf-norm:sp_pay_to_supplier 5651 5651 0 0.00 0 9285 13 0 0
t$perf-norm:sp_reserve_write_off 9760 9760 0 0.00 0 21391 14 0 0
t$perf-norm:sp_supplier_invoice 5490 5490 0 0.00 0 4689 21 0 0
t$perf-norm:sp_supplier_order 5310 5310 0 0.00 0 5557 20 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 320 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 57 2 0 0
t$perf-norm:srv_make_invnt_saldo 439 439 0 0.00 0 2268 18 0 0
t$perf-norm:srv_make_money_saldo 716 716 0 0.00 0 819 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 10 4 0 0
v_add_invoice_to_stock 9750 9526 224 2.29 0 10321 21 0 224
v_all_customers 36592 36592 0 0.00 0 96 1 0 0
v_all_suppliers 29532 29532 0 0.00 0 25 1 0 0
v_all_wares 40040 40040 0 0.00 0 88 1 0 0
v_cancel_adding_invoice 840 840 0 0.00 0 5913 19 0 0
v_cancel_client_order 3428 3428 0 0.00 0 2240 5 0 0
v_cancel_customer_prepayment 1312 1312 0 0.00 0 1949 9 0 0
v_cancel_customer_reserve 1438 1438 0 0.00 0 2478 5 0 0
v_cancel_payment_to_supplier 758 758 0 0.00 0 1333 6 0 0
v_cancel_supplier_invoice 758 742 16 2.11 0 159 11 0 16
v_cancel_supplier_order 732 732 0 0.00 0 3344 13 0 0
v_cancel_write_off 1866 1866 0 0.00 0 5716 23 0 0
v_max_id_clo_ord 5306 5306 0 0.00 0 3434 3 0 0
v_max_id_clo_res 988 988 0 0.00 0 2403 14 0 0
v_max_id_ord_sup 5490 5490 0 0.00 0 6933 7 0 0
v_max_non_paid_invoice 1671 1671 0 0.00 0 1096 3 0 0
v_max_non_paid_realizn 3806 3806 0 0.00 0 7781 6 0 0
v_min_id_clo_ord 5306 5306 0 0.00 0 2063 6 0 0
v_min_id_clo_res 988 988 0 0.00 0 16021 25 0 0
v_min_id_ord_sup 5490 5490 0 0.00 0 7884 17 0 0
v_min_non_paid_invoice 5644 5644 0 0.00 0 25430 14 0 0
v_min_non_paid_realizn 6983 6983 0 0.00 0 6594 15 0 0
v_our_firm 9802 9802 0 0.00 0 17 1 0 0
v_reserve_write_off 19502 19502 0 0.00 0 5698 6 0 0
x_lock_depdocs_on_canc_invoice 10783 10783 0 0.00 0 24952 139 0 0
x_lock_depdocs_on_canc_sup_ord 366 366 0 0.00 0 14874 467 0 0
x_make_qstorno_1000_1200 5310 5310 0 0.00 2 106898 1416 0 0
x_make_qstorno_1200_2000 5490 5490 0 0.00 3 100109 3736 0 0
x_make_qstorno_2100_3300 437357 437357 0 0.00 0 65062 167 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 04-01-2021 07:27:56 to 04-01-2021 07:28:01.

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:58 12 8192000000 8796637808 8807108608 3167696 0.038 1 1 2 0 3265392 61840 35856
04:57:58 21245 8192000000 9705270304 10003468288 792626288 8.822 100 82 165 0 819981776 7405168 19950320
04:59:26 28907 8192000000 9768161584 10074030080 840836144 9.308 100 84 168 0 872916256 12286912 19793200
05:01:00 20253 8192000000 9805401472 10123792384 871625216 9.616 100 85 171 1 911339920 20257296 19457408
05:02:26 13217 8192000000 9858558704 10185363456 911134784 10.009 100 85 170 1 967959408 38154048 18670576
05:03:41 11631 8192000000 9910465360 10248179712 951850272 10.409 100 83 166 1 1032437456 63247952 17339232
05:05:00 4109 8192000000 9941880432 10294464512 984948704 10.732 100 80 160 1 1057355264 55710144 16696416
05:06:07 3586 8192000000 9993459856 10350931968 1008589840 10.962 100 83 165 0 1106710752 81961792 16159120
05:07:12 2782 8192000000 9984112272 10347859968 1034638464 11.213 100 82 164 1 1108639456 57470192 16530800
05:08:16 1591 8192000000 10022796272 10392047616 1050162960 11.362 100 88 176 0 1142067824 73652192 18252672
05:09:18 4869 8192000000 10009179856 10401447936 1061207200 11.468 100 57 113 1 1130188112 57728800 11252112
05:10:24 7470 8192000000 9985448720 10375581696 1073819056 11.589 100 26 49 0 1105287968 26278608 5190304
05:11:37 10527 8192000000 9988939472 10385240064 1081490944 11.662 100 31 61 2 1106857584 18909568 6457072
05:12:50 36682 8192000000 10033609184 10445258752 1086325904 11.708 100 48 96 0 1119148880 23156912 9666064
05:14:33 6011 8192000000 10007107008 10412539904 1092058688 11.762 100 37 74 0 1120270592 20435824 7776080
05:15:42 4734 8192000000 10012622736 10403708928 1093764576 11.778 100 49 96 2 1120571408 16130032 10676800
05:16:47 6347 8192000000 9998459760 10404171776 1069570880 11.548 98 40 79 2 1093444352 14997392 8876080
05:17:58 12002 8192000000 10005827440 10393018368 1068254704 11.535 98 63 124 4 1096920480 14512768 14153008
05:19:10 5690 8192000000 9987553040 10385649664 1065542592 11.509 99 46 89 4 1092189008 16559872 10086544
05:20:19 7205 8192000000 9960082880 10348212224 1025400048 11.124 99 40 76 1 1048872464 15224432 8247984
05:21:31 8589 8192000000 9933463504 10321502208 1007585936 10.952 99 40 80 2 1030121888 13355168 9180784
05:22:39 31810 8192000000 9937173760 10333106176 984335312 10.726 95 26 52 3 1012173344 22787216 5050816
05:24:11 8306 8192000000 9966922608 10363695104 1040062336 11.265 100 53 106 3 1086653568 34297136 12294096
05:25:23 12328 8192000000 9976569824 10380689408 1053452000 11.394 100 39 78 0 1096246080 34158928 8635152
05:26:38 11090 8192000000 9999353008 10408153088 1076972704 11.619 100 20 38 4 1102628624 21575792 4080128
05:27:50 50377 8192000000 10020908592 10418216960 1095089104 11.791 100 34 67 2 1124275296 22676944 6509248
05:29:44 4413 8192000000 10065990752 10465050624 1112102496 11.952 100 64 128 0 1180811232 54561552 14147184
05:30:54 4733 8192000000 10114638496 10523910144 1126034416 12.084 100 55 108 1 1229979488 92174608 11770464
05:32:02 13682 8192000000 10115245360 10529746944 1136628656 12.184 100 51 102 2 1228049680 79760080 11660944
05:33:19 17067 8192000000 10142909968 10557272064 1144528784 12.258 100 45 90 0 1248611824 93838272 10244768
05:34:41 10884 8192000000 10171933056 10581409792 1147369984 12.285 100 51 101 1 1280585984 121575056 11640944
05:35:58 6399 8192000000 10196320848 10614665216 1154700528 12.354 100 64 125 0 1307888000 138957024 14230448
05:37:12 5663 8192000000 10240253488 10649804800 1146222736 12.274 99 63 126 0 1336500176 175964736 14312704
05:38:26 5388 8192000000 10241150400 10651492352 1157442192 12.379 100 58 116 2 1352962768 182516832 13003744
05:39:39 5835 8192000000 10236902400 10657964032 1147405760 12.285 99 69 135 2 1340829056 177690832 15732464
05:40:48 3120 8192000000 10264178368 10674450432 1141996304 12.234 99 87 171 1 1356982912 195396608 19590000
05:41:57 1900 8192000000 10220705504 10635898880 1149470720 12.305 99 88 171 2 1314148416 145928208 18749488
05:43:04 1832 8192000000 10198949552 10590904320 1123190896 12.057 98 75 149 2 1299241392 159960064 16090432
05:44:10 16461 8192000000 10224264208 10648068096 1119883840 12.026 98 45 88 1 1310965904 180849120 10232944
05:45:31 2866 8192000000 10292677488 10693193728 1154237680 12.349 100 92 182 1 1386443648 211913664 20292304
05:46:38 2713 8192000000 10329643616 10729652224 1164894384 12.449 100 78 156 0 1425683200 242540096 18248720
05:47:47 3163 8192000000 10321704304 10719584256 1148873408 12.299 99 75 149 2 1409537680 242808064 17856208
05:48:58 2743 8192000000 10430871744 10834649088 1167586832 12.474 100 78 154 1 1519296112 333452256 18257024
05:50:07 1220 8192000000 10469193792 10882756608 1174683152 12.541 100 92 184 3 1563270256 366864320 21722784
05:51:13 8531 8192000000 10447256880 10884272128 1180738736 12.597 100 48 96 0 1542247808 349357584 12151488
05:52:37 11025 8192000000 10505092544 10928857088 1178735312 12.578 100 52 104 3 1592510848 400606176 13169360
05:53:56 22375 8192000000 10503248832 10975076352 1175951184 12.552 100 38 76 0 1616683952 430850112 9882656
05:55:36 11662 8192000000 10512684528 10948411392 1149849248 12.308 100 35 70 4 1605295472 445586064 9860160
05:57:25 22168 8192000000 10380839104 10883399680 1141771680 12.232 100 30 61 0 1537015920 387210816 8033424
05:58:50 85019 8192000000 10036836624 10465128448 1109602832 11.929 97 21 42 3 1149855248 34470912 5781504
06:01:16 20192 8192000000 10073134992 10504155136 1117299664 12.001 99 43 82 0 1161343376 35680208 8363504
06:02:46 122071 8192000000 10066828688 10525282304 1127220688 12.095 98 19 38 0 1167629408 36567008 3841712
06:05:57 100364 8192000000 10082640352 10545725440 1111245936 11.944 97 18 36 2 1156730160 41480400 4003824
06:09:00 134052 8192000000 10102981680 10641420288 1149195472 12.302 100 21 42 1 1269776944 115208128 5373344
06:12:30 32192 8192000000 10076367280 10529509376 1138689456 12.203 99 41 81 0 1164953056 17802912 8460688
06:14:06 45882 8192000000 10081649840 10517426176 1113042800 11.961 97 39 77 4 1153269120 31865152 8361168
06:16:47 38698 8192000000 10061062672 10510843904 1135279184 12.171 100 16 32 0 1167174496 27568320 4326992
06:18:30 65912 8192000000 10063784944 10528079872 1125239264 12.076 99 12 24 1 1154792944 27123552 2430128
06:20:55 60605 8192000000 10127809136 10596782080 1140006208 12.216 100 12 21 2 1222461152 80010160 2444784
06:23:08 46149 8192000000 10109382032 10562310144 1128013248 12.103 99 11 22 1 1205143136 74640656 2489232
06:24:54 28960 8192000000 10088468560 10519126016 1133643712 12.156 99 35 70 1 1170756832 29588352 7524768
06:26:28 44557 8192000000 10117876160 10560671744 1126831952 12.091 99 21 43 3 1196839600 65235536 4772112
06:28:28 45809 8192000000 10099238720 10533814272 1098956880 11.828 96 39 75 2 1155110720 48635808 7518032
06:30:14 49470 8192000000 10062297840 10516361216 1094261104 11.783 97 19 38 1 1136385184 38361008 3763072
06:32:11 16134 8192000000 10097263712 10507833344 1119144496 12.019 99 65 130 0 1167289408 34508288 13636624
06:33:30 47734 8192000000 10084028768 10543087616 1094124688 11.782 96 17 34 0 1162374736 64241520 4008528
06:35:22 128303 8192000000 10043708784 10490212352 1066450768 11.518 94 23 46 0 1106820384 35933792 4435824
06:38:47 17196 8192000000 10036674352 10502348800 1112523888 11.956 100 4 8 0 1142468992 29326672 618432
06:40:10 49355 8192000000 10037745552 10481643520 1096791696 11.807 98 9 18 1 1126955824 28266944 1897184
06:42:00 62549 8192000000 10097587312 10537988096 1085360512 11.699 97 24 48 0 1162476832 71966496 5149824
06:44:10 47025 8192000000 10150459936 10593136640 1104816272 11.883 98 26 52 1 1218089216 107098480 6174464
06:46:03 65422 8192000000 10143835248 10628530176 1118071488 12.009 99 23 46 0 1246714304 123560128 5082688
06:48:18 75175 8192000000 10070175024 10527977472 1093942016 11.780 97 13 26 0 1136932320 40016016 2974288
06:50:34 57863 8192000000 10130408320 10555600896 1105693264 11.892 98 26 52 0 1191417776 80515232 5209280
06:52:35 68615 8192000000 10078472800 10560049152 1123777008 12.063 100 12 24 1 1207417936 81038128 2602800
06:54:46 95336 8192000000 10082889552 10566148096 1133218512 12.152 100 19 38 1 1166167088 29306400 3642176
06:57:45 100052 8192000000 10075583600 10602176512 1125263312 12.077 99 16 32 0 1237437008 108724096 3449600
07:00:25 86358 8192000000 10118905904 10556325888 1089874496 11.741 96 19 38 3 1135792400 41828928 4088976
07:02:57 50443 8192000000 10109720784 10574159872 1121096176 12.037 99 25 50 1 1192803296 66323504 5383616
07:04:53 103751 8192000000 10127640592 10552659968 1106041152 11.895 97 29 57 1 1203187920 90864560 6282208
07:07:37 43480 8192000000 10090285600 10522750976 1111147568 11.943 98 28 56 1 1159205792 42624768 5433456
07:09:25 48767 8192000000 10091711824 10539245568 1111523648 11.947 98 31 62 3 1177488336 59851040 6113648
07:11:15 25263 8192000000 10144927600 10625691648 1138321904 12.200 100 35 70 0 1233091120 87731840 7037376
07:12:40 130103 8192000000 10051373648 10503319552 1106788544 11.902 98 17 34 1 1145890464 35491248 3610672
07:15:52 70122 8192000000 10090159648 10546675712 1122905920 12.054 99 17 33 2 1181622800 54494912 4221968
07:18:03 49477 8192000000 10081359776 10551881728 1139941584 12.215 100 12 24 0 1194289328 51825600 2522144
07:19:55 68388 8192000000 10113092816 10565091328 1106575184 11.900 97 14 28 0 1188986560 79193184 3218192
07:22:05 30628 8192000000 10122272016 10559422464 1108276464 11.916 97 23 45 1 1174296304 61282592 4737248
07:23:44 73540 8192000000 10199729648 10669936640 1121820784 12.044 99 20 40 2 1271202560 144633232 4748544
07:26:50 12293 8192000000 8907946352 9099264000 71507184 0.865 7 2 2 1 76647248 4823520 316544

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 04-01-2021 07:28:02 to 04-01-2021 07:28:18.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 112 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 04-01-2021 07:28:18 to 04-01-2021 07:28:20.

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          230686
MON$OLDEST_ACTIVE               230687
MON$OLDEST_SNAPSHOT             230687
MON$NEXT_TRANSACTION            230687
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                       2096128
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             791
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2315 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2315 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2315 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.2315 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 ---
:154: 
:155: mscs-test-161.budzdorov.ru    Mon Jan  4 07:27:05 2021
:156:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:157: 
--- end of diff output ---

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

04.01.21 07:28:22. 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-04 07:28:24 No severe PSQL-related problems occured
04.01.21 07:28:25. End of report.