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

Hardware and OS info

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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-03-17 10:26:55
DTS_END                         2021-03-17 10:26:55.9680
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/58820
TRN_ID                          238712
ATT_ID                          672
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: 8923.10 23025 1070772
customer order: creation 175.72 427 21086
customer order: refuse 15.53 321 1864
order to supplier: creation 44.93 1305 5391
order to supplier: removal 3.29 117547 395
invoice (draft): creation 47.38 3519 5685
invoice (draft): removal 51.14 1034 6137
invoice accept: apply 42.30 15529 5076
invoice accept: cancel 45.37 887 5444
customer reserve: creation 3805.42 154 456650
customer reserve: removal 4405.90 69 528708
realization accept: apply 84.42 160 10130
realization accept: cancel 72.14 134 8657
payment from customer: creation 60.24 112 7229
payment from customer: removal 5.78 92 693
payment to supplier: creation 48.62 108 5834
payment to supplier: removal 3.68 108 441
service: total inventory turnovers 3.96 3029 475
service: total monetary turnovers 6.32 415 758
service: aggregate perf. data 0.95 12334 114
service: refresh index statistics 0.04 303210 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 17-03-2021 10:29:30 to 17-03-2021 10:29:33.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3368
TEST_TIME 2 2216
TEST_TIME 3 2560
TEST_TIME 4 3542
TEST_TIME 5 3700
TEST_TIME 6 3840
TEST_TIME 7 4059
TEST_TIME 8 4613
TEST_TIME 9 4843
TEST_TIME 10 5222
TEST_TIME 11 5562
TEST_TIME 12 5879
TEST_TIME 13 5881
TEST_TIME 14 6088
TEST_TIME 15 6673
TEST_TIME 16 7213
TEST_TIME 17 7195
TEST_TIME 18 7585
TEST_TIME 19 7559
TEST_TIME 20 7402
TEST_TIME 21 7569
TEST_TIME 22 7853
TEST_TIME 23 8037
TEST_TIME 24 8562
TEST_TIME 25 9521
TEST_TIME 26 9880
TEST_TIME 27 10669
TEST_TIME 28 10438
TEST_TIME 29 10088
TEST_TIME 30 9919
TEST_TIME 31 9723
TEST_TIME 32 9755
TEST_TIME 33 9720
TEST_TIME 34 9553
TEST_TIME 35 9534
TEST_TIME 36 9454
TEST_TIME 37 9341
TEST_TIME 38 9328
TEST_TIME 39 9166
TEST_TIME 40 9203
TEST_TIME 41 9302
TEST_TIME 42 9360
TEST_TIME 43 9369
TEST_TIME 44 9284
TEST_TIME 45 9378
TEST_TIME 46 9507
TEST_TIME 47 9479
TEST_TIME 48 9453
TEST_TIME 49 9398
TEST_TIME 50 9504
TEST_TIME 51 9586
TEST_TIME 52 9501
TEST_TIME 53 9494
TEST_TIME 54 9338
TEST_TIME 55 9264
TEST_TIME 56 9177
TEST_TIME 57 9084
TEST_TIME 58 9065
TEST_TIME 59 9060
TEST_TIME 60 9054
TEST_TIME 61 9053
TEST_TIME 62 9007
TEST_TIME 63 9026
TEST_TIME 64 9227
TEST_TIME 65 9214
TEST_TIME 66 9240
TEST_TIME 67 9209
TEST_TIME 68 9210
TEST_TIME 69 9289
TEST_TIME 70 9175
TEST_TIME 71 9111
TEST_TIME 72 9040
TEST_TIME 73 9022
TEST_TIME 74 8993
TEST_TIME 75 8946
TEST_TIME 76 8920
TEST_TIME 77 8909
TEST_TIME 78 8930
TEST_TIME 79 8969
TEST_TIME 80 8928
TEST_TIME 81 8944
TEST_TIME 82 8930
TEST_TIME 83 9037
TEST_TIME 84 9038
TEST_TIME 85 9062
TEST_TIME 86 9070
TEST_TIME 87 9000
TEST_TIME 88 8925
TEST_TIME 89 8867
TEST_TIME 90 8876
TEST_TIME 91 8845
TEST_TIME 92 8841
TEST_TIME 93 8819
TEST_TIME 94 8836
TEST_TIME 95 8800
TEST_TIME 96 8791
TEST_TIME 97 8928
TEST_TIME 98 8956
TEST_TIME 99 8948
TEST_TIME 100 8925
TEST_TIME 101 8984
TEST_TIME 102 9032
TEST_TIME 103 9019
TEST_TIME 104 8990
TEST_TIME 105 8944
TEST_TIME 106 8901
TEST_TIME 107 8871
TEST_TIME 108 8830
TEST_TIME 109 8834
TEST_TIME 110 8824
TEST_TIME 111 8822
TEST_TIME 112 8834
TEST_TIME 113 8816
TEST_TIME 114 8785
TEST_TIME 115 8841
TEST_TIME 116 8844
TEST_TIME 117 8829
TEST_TIME 118 8891
TEST_TIME 119 8929
TEST_TIME 120 8927

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-03-2021 10:29:34 to 17-03-2021 10:29:37.

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 1494453 1494453 0 0.00 0 13621 6 0 0
doc_list_biud 1494450 1494450 0 0.00 0 80074 32 0 0
sp_add_invoice_to_stock 5188 5076 112 2.15 22 166301 15530 0 112
sp_cancel_adding_invoice 5444 5444 0 0.00 6 60913 887 0 0
sp_cancel_client_order 1864 1864 0 0.00 4 12500 321 0 0
sp_cancel_customer_reserve 528708 528708 0 0.00 1 75695 70 0 0
sp_cancel_pay_from_customer 693 693 0 0.00 3 8902 92 0 0
sp_cancel_pay_to_supplier 441 441 0 0.00 3 7387 109 0 0
sp_cancel_supplier_invoice 6148 6137 11 0.17 2 80152 1035 0 11
sp_cancel_supplier_order 395 395 0 0.00 7 1048727 117547 0 0
sp_cancel_write_off 8657 8657 0 0.00 1 48421 134 0 0
sp_client_order 21086 21086 0 0.00 4 56736 428 0 0
sp_customer_reserve 456650 456650 0 0.00 0 61299 154 0 0
sp_fill_shopping_cart 32158 32158 0 0.00 1 20879 45 0 0
sp_fill_shopping_cart_clo_res 456644 456644 0 0.00 0 41003 20 0 0
sp_get_clo_for_invoice 5069 5069 0 0.00 0 26396 423 0 0
sp_kill_cost_storno 15235 15235 0 0.00 0 6191 59 0 0
sp_kill_qstorno_ret_qs2qd 1072605 1072605 0 0.00 0 80073 39 0 0
sp_kill_qty_storno 566404 566404 0 0.00 0 80074 82 0 0
sp_lock_selected_doc 21149 21149 0 0.00 0 10098 7 0 0
sp_make_cost_storno 28262 28262 0 0.00 0 11925 50 0 0
sp_multiply_rows_for_pdistr 28262 28262 0 0.00 0 9199 28 0 0
sp_multiply_rows_for_qdistr 21086 21086 0 0.00 0 56725 264 0 0
sp_pay_from_customer 7229 7229 0 0.00 4 12456 113 0 0
sp_pay_to_supplier 5834 5834 0 0.00 5 11588 108 0 0
sp_payment_common 13063 13063 0 0.00 1 8323 22 0 0
sp_qd_handle_on_cancel_clo 1864 1864 0 0.00 0 12488 289 0 0
sp_qd_handle_on_invoice_upd_sts 10513 10513 0 0.00 0 33645 368 0 0
sp_qd_handle_on_reserve_upd_sts 10130 10130 0 0.00 0 10603 45 0 0
sp_reserve_write_off 10130 10130 0 0.00 7 11019 161 0 0
sp_supplier_invoice 5685 5685 0 0.00 7 93996 3519 0 0
sp_supplier_order 5391 5391 0 0.00 6 23126 1305 0 0
srv_aggregate_perf_data 114 114 0 0.00 2698 164943 12334 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1975 138245 45661 0 0
srv_find_qd_qs_mism 1023333 1023333 0 0.00 0 13619 5 0 0
srv_make_invnt_saldo 475 475 0 0.00 61 114707 3029 0 0
srv_make_money_saldo 758 758 0 0.00 1 9667 416 0 0
srv_recalc_idx_stat 5 5 0 0.00 147194 375482 303211 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 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5076 5076 0 0.00 0 16178 150 0 0
t$perf-norm:sp_cancel_adding_invoice 418 418 0 0.00 0 6266 85 0 0
t$perf-norm:sp_cancel_client_order 1864 1864 0 0.00 0 3632 12 0 0
t$perf-norm:sp_cancel_customer_reserve 716 716 0 0.00 0 1901 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 693 693 0 0.00 0 2301 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 441 441 0 0.00 0 6976 21 0 0
t$perf-norm:sp_cancel_supplier_invoice 445 445 0 0.00 0 2558 31 0 0
t$perf-norm:sp_cancel_supplier_order 395 395 0 0.00 0 16786 1394 0 0
t$perf-norm:sp_cancel_write_off 978 978 0 0.00 0 2340 7 0 0
t$perf-norm:sp_client_order 21086 21086 0 0.00 0 8291 16 0 0
t$perf-norm:sp_customer_reserve 994 994 0 0.00 0 7632 20 0 0
t$perf-norm:sp_pay_from_customer 7229 7229 0 0.00 0 13327 11 0 0
t$perf-norm:sp_pay_to_supplier 5834 5834 0 0.00 0 12743 14 0 0
t$perf-norm:sp_reserve_write_off 10130 10130 0 0.00 0 12971 11 0 0
t$perf-norm:sp_supplier_invoice 5685 5685 0 0.00 0 11678 22 0 0
t$perf-norm:sp_supplier_order 5391 5391 0 0.00 0 7477 18 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 202 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 3 0 0 0
t$perf-norm:srv_make_invnt_saldo 475 475 0 0.00 0 233 6 0 0
t$perf-norm:srv_make_money_saldo 758 758 0 0.00 0 1725 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10362 10138 224 2.16 0 6792 19 0 224
v_all_customers 38292 38292 0 0.00 0 33 1 0 0
v_all_suppliers 30126 30126 0 0.00 0 51 1 0 0
v_all_wares 42172 42172 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 836 836 0 0.00 0 3045 9 0 0
v_cancel_client_order 3728 3728 0 0.00 0 6823 7 0 0
v_cancel_customer_prepayment 1386 1386 0 0.00 0 3415 9 0 0
v_cancel_customer_reserve 1432 1432 0 0.00 0 794 4 0 0
v_cancel_payment_to_supplier 882 882 0 0.00 0 3415 9 0 0
v_cancel_supplier_invoice 912 890 22 2.41 0 2485 19 0 22
v_cancel_supplier_order 790 790 0 0.00 0 3602 7 0 0
v_cancel_write_off 1956 1956 0 0.00 0 2578 14 0 0
v_max_id_clo_ord 5390 5390 0 0.00 0 178 1 0 0
v_max_id_clo_res 994 994 0 0.00 0 6650 19 0 0
v_max_id_ord_sup 5682 5682 0 0.00 0 4450 6 0 0
v_max_non_paid_invoice 1843 1843 0 0.00 0 249 2 0 0
v_max_non_paid_realizn 3867 3867 0 0.00 0 4850 4 0 0
v_min_id_clo_ord 5390 5390 0 0.00 0 4197 6 0 0
v_min_id_clo_res 994 994 0 0.00 0 9872 17 0 0
v_min_id_ord_sup 5682 5682 0 0.00 0 5229 7 0 0
v_min_non_paid_invoice 5834 5834 0 0.00 0 6769 14 0 0
v_min_non_paid_realizn 7229 7229 0 0.00 0 8178 18 0 0
v_our_firm 10604 10604 0 0.00 0 127 1 0 0
v_reserve_write_off 20260 20260 0 0.00 0 5747 6 0 0
x_lock_depdocs_on_canc_invoice 11136 11136 0 0.00 0 16003 109 0 0
x_lock_depdocs_on_canc_sup_ord 395 395 0 0.00 0 32708 483 0 0
x_make_qstorno_1000_1200 5391 5391 0 0.00 2 23122 1239 0 0
x_make_qstorno_1200_2000 5685 5685 0 0.00 4 93973 3333 0 0
x_make_qstorno_2100_3300 456650 456650 0 0.00 0 61261 132 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 17-03-2021 10:29:38 to 17-03-2021 10:29:43.

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:59 1 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
07:58:00 30159 8192000000 9728749632 10027220992 800702240 8.903 100 84 168 0 828104416 7375056 20027120
07:59:40 20505 8192000000 9768921232 10075222016 839416176 9.294 100 90 180 0 875043952 14001312 21626464
08:01:05 20609 8192000000 9818922400 10139275264 872902528 9.629 100 87 174 1 926461536 33534272 20024736
08:02:40 12370 8192000000 9885241456 10219696128 931785248 10.212 100 77 154 1 991837392 43705296 16346848
08:03:55 8619 8192000000 9940260528 10287476736 971945088 10.606 100 91 183 0 1055412832 64699264 18768480
08:05:09 4543 8192000000 9981084240 10337542144 1007331568 10.950 100 78 156 0 1096573920 73548064 15694288
08:06:15 7544 8192000000 9996651104 10359250944 1032535984 11.193 100 77 154 0 1108921520 61313536 15072000
08:07:25 1755 8192000000 10011429440 10380431360 1055806848 11.416 100 88 176 1 1127145984 53310224 18028912
08:08:29 4771 8192000000 10013476000 10395021312 1068006768 11.533 100 70 134 3 1126376080 43611936 14757376
08:09:35 5340 8192000000 10004634688 10404618240 1076714304 11.616 100 50 100 1 1132480096 45162992 10602800
08:10:44 5709 8192000000 9983894512 10374819840 1080818256 11.655 100 24 48 1 1106984256 21294768 4871232
08:11:51 4822 8192000000 9999275792 10401300480 1090136992 11.744 100 27 56 0 1127213520 31315776 5760752
08:12:57 44316 8192000000 10020624704 10416132096 1100983424 11.847 100 40 79 0 1126899056 16469824 9445808
08:14:44 5465 8192000000 10022287104 10428747776 1105556112 11.890 100 30 59 1 1145637776 34582064 5499600
08:15:50 6747 8192000000 10028425808 10423025664 1104985840 11.885 100 36 74 2 1138764224 25353360 8425024
08:16:57 6516 8192000000 9994365776 10390626304 1057425744 11.432 97 27 55 3 1078748304 15330416 5992144
08:18:04 31668 8192000000 9959090864 10338459648 1022660800 11.098 96 67 134 2 1057068976 20039568 14368608
08:19:36 6905 8192000000 9951342080 10345787392 1013924976 11.013 97 38 76 3 1041981904 19999456 8057472
08:20:44 9424 8192000000 9928110176 10309611520 968255632 10.570 94 27 53 1 993535952 19481360 5798960
08:21:56 18086 8192000000 9919037056 10309251072 976033760 10.646 94 29 58 0 1005619616 22774880 6810976
08:23:15 26058 8192000000 9955656848 10358591488 1045847056 11.321 100 22 44 1 1081207904 30760048 4600800
08:24:42 7164 8192000000 9972532800 10379153408 1062604624 11.481 100 43 85 3 1093611680 20325056 10682000
08:25:50 6254 8192000000 9989496976 10380857344 1069534160 11.548 100 43 88 1 1105885168 27236656 9114352
08:26:59 5138 8192000000 10012063552 10398801920 1096779632 11.807 100 52 104 1 1117559664 9809440 10970592
08:28:23 76465 8192000000 10059143024 10466643968 1111270832 11.944 100 41 81 2 1160969424 41442304 8256288
08:30:40 58260 8192000000 10103170832 10516500480 1125004816 12.074 100 36 70 1 1198340800 65505808 7830176
08:33:21 11612 8192000000 10167844880 10580844544 1148472304 12.295 100 56 112 1 1268533744 108020080 12041360
08:34:40 12887 8192000000 10180704512 10598412288 1159590912 12.399 100 40 79 0 1289544800 121145872 8808016
08:35:59 4346 8192000000 10216576192 10639818752 1173194496 12.527 100 48 93 1 1323594624 139530000 10870128
08:37:07 4065 8192000000 10273898192 10690301952 1184144400 12.629 100 68 136 1 1376069872 175831968 16093504
08:38:14 9250 8192000000 10310858848 10733502464 1186674448 12.652 100 66 132 1 1407019456 205411872 14933136
08:39:33 1975 8192000000 10342782960 10762289152 1188029552 12.665 100 66 132 0 1444823824 241649040 15145232
08:40:40 2997 8192000000 10359265824 10777817088 1167844048 12.477 99 66 127 0 1458795664 276106336 14845280
08:41:48 2520 8192000000 10357828752 10767888384 1172588080 12.521 100 89 177 1 1456230992 263047120 20595792
08:42:54 5783 8192000000 10301724672 10720514048 1146781792 12.279 99 55 110 1 1393329344 234637760 11909792
08:44:06 2847 8192000000 10360237104 10764967936 1156784752 12.373 100 92 182 1 1456227952 278657072 20786128
08:45:14 1976 8192000000 10332966496 10744832000 1136802272 12.185 99 53 102 2 1429037344 280684352 11550720
08:46:25 6588 8192000000 10401489136 10811953152 1135354320 12.172 100 63 126 0 1494656320 343862160 15439840
08:47:35 10585 8192000000 10440004272 10855387136 1119990080 12.027 98 53 106 0 1523722192 389483072 14249040
08:48:52 14474 8192000000 10462002832 10886561792 1114598896 11.976 98 48 96 0 1543953504 416165808 13188800
08:50:25 13230 8192000000 10399789312 10900271104 1131795552 12.138 100 31 62 0 1513607904 373669664 8142688
08:51:53 38638 8192000000 10208579968 10662158336 1099001152 11.828 99 15 30 0 1350737664 247591584 4144928
08:53:44 60812 8192000000 10043926976 10486636544 1080721168 11.654 97 19 38 1 1125577520 40233856 4622496
08:55:45 30574 8192000000 10071169056 10501488640 1101528736 11.852 99 36 72 1 1150410000 40939840 7941424
08:57:20 52519 8192000000 10085801408 10525380608 1106632592 11.901 100 23 46 0 1170716960 58843296 5241072
08:59:22 36584 8192000000 10055549936 10525040640 1103770816 11.873 99 8 16 1 1154715088 49683040 1261232
09:00:59 85345 8192000000 10082019104 10524897280 1087521120 11.719 96 16 32 0 1146139360 55322784 3295456
09:03:26 74108 8192000000 10130927232 10567417856 1121590480 12.042 100 28 55 2 1206267072 78207328 6469264
09:05:40 69046 8192000000 10131190064 10595168256 1123355184 12.059 100 17 34 0 1228937536 101816880 3765472
09:07:50 75449 8192000000 10069871632 10592776192 1118760688 12.015 99 13 26 1 1207769168 86064208 2944272
09:10:10 88915 8192000000 10114288896 10610888704 1139217968 12.208 99 19 38 0 1229695888 86665824 3812096
09:12:39 48026 8192000000 10085149152 10532716544 1142060304 12.235 100 35 69 1 1193156480 42873136 8223040
09:14:32 60743 8192000000 10121411872 10567852032 1141198912 12.227 99 32 63 2 1201201584 52965072 7037600
09:16:37 60932 8192000000 10118927728 10583556096 1138421616 12.201 99 9 18 1 1222660528 82198736 2040176
09:18:41 37738 8192000000 10121262528 10554015744 1123776720 12.063 98 48 91 1 1161129360 26922496 10430144
09:20:20 55828 8192000000 10059555648 10527899648 1126505856 12.088 99 17 34 1 1161463952 31675488 3282608
09:22:26 46752 8192000000 10064366320 10520313856 1113901360 11.969 98 8 16 0 1152207008 36545136 1760512
09:24:13 44502 8192000000 10063115168 10530947072 1121455200 12.041 99 15 31 1 1175318624 50789824 3073600
09:26:01 38120 8192000000 10076847232 10533138432 1113248976 11.963 98 21 41 2 1162731200 45062160 4420064
09:27:40 78675 8192000000 10121309776 10557693952 1113820080 11.969 96 24 48 1 1184147648 65024800 5302768
09:30:06 56289 8192000000 10093727968 10650329088 1110775648 11.940 98 20 41 3 1247118384 131772304 4570432
09:33:11 50277 8192000000 10083808016 10509520896 1081007312 11.657 95 12 24 2 1122413952 38997328 2409312
09:35:06 138245 8192000000 10090618048 10528296960 1093134496 11.772 96 27 53 0 1142571984 43776144 5661344
09:38:36 65375 8192000000 10074705104 10523496448 1094025872 11.781 97 13 26 1 1149004896 52309792 2669232
09:40:50 63393 8192000000 10037374160 10489921536 1091264240 11.755 97 9 18 1 1132326640 38799664 2262736
09:43:27 71012 8192000000 10128716720 10584682496 1125900176 12.083 99 20 40 1 1199433872 69154544 4379152
09:45:51 50972 8192000000 10058111392 10493235200 1090983280 11.752 97 19 38 1 1129062032 33921104 4157648
09:47:43 46659 8192000000 10054302048 10487271424 1091185424 11.754 97 21 42 0 1139124432 43557664 4381344
09:49:31 104316 8192000000 10064206208 10526171136 1090148752 11.744 97 11 22 1 1118056464 25862336 2045376
09:52:20 44515 8192000000 10082735584 10526470144 1104203760 11.878 98 27 54 2 1140132080 30106752 5821568
09:54:06 98033 8192000000 10073555840 10527436800 1117550256 12.004 99 15 30 1 1152760464 31956080 3254128
09:56:52 37597 8192000000 10061494752 10564186112 1131473648 12.135 100 2 4 0 1178062704 46211984 377072
09:58:30 37554 8192000000 10101203632 10563006464 1140911264 12.224 100 21 42 0 1185994448 41057024 4026160
10:00:16 43430 8192000000 10141915360 10606063616 1156400768 12.370 100 27 54 1 1245923632 83549280 5973584
10:02:09 50865 8192000000 10202836192 10689867776 1154908272 12.356 100 22 44 3 1282501216 122534448 5058496
10:04:01 67325 8192000000 10104914624 10562277376 1132907968 12.149 98 15 30 1 1190815984 54752352 3155664
10:06:10 35633 8192000000 10157328928 10589081600 1141720992 12.232 98 44 88 0 1234480896 83469760 9290144
10:07:45 32985 8192000000 10113340176 10564980736 1143975488 12.253 99 40 80 1 1218660272 66001216 8683568
10:09:19 33180 8192000000 10081029472 10518446080 1112261776 11.954 96 26 52 0 1139741840 22162128 5317936
10:10:56 111513 8192000000 10073421104 10543104000 1118762848 12.015 98 25 50 0 1160329552 35865648 5701056
10:13:59 41566 8192000000 10080336464 10564390912 1094852064 11.789 96 4 8 0 1148272384 52718592 701728
10:15:43 45237 8192000000 10106123696 10561662976 1120646720 12.033 99 17 34 1 1167464912 43052128 3766064
10:17:55 47824 8192000000 10091470608 10527797248 1061544032 11.471 94 22 44 0 1103914768 37872816 4497920
10:20:01 31621 8192000000 10099427472 10529726464 1115934064 11.989 99 42 84 2 1179025632 54599280 8492288
10:21:37 65691 8192000000 10085427568 10575265792 1078925680 11.637 96 12 24 0 1175910944 94321840 2663424
10:23:48 48693 8192000000 10146453296 10634330112 1143311008 12.247 100 16 32 0 1245383920 98491712 3581200
10:25:37 74175 8192000000 10079358624 10559885312 1126278928 12.086 99 17 32 1 1189662624 58782608 4601088

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 17-03-2021 10:29:44 to 17-03-2021 10:29:59.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-03-2021 10:30:00 to 17-03-2021 10:30:02.

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          238711
MON$OLDEST_ACTIVE               239244
MON$OLDEST_SNAPSHOT             239244
MON$NEXT_TRANSACTION            239244
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                       2086112
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             801
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2389 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2389 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2389 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.2389 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 ---
:15: 
:16: mscs-test-161.budzdorov.ru    Wed Mar 17 10:27:48 2021
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:18: 
:19: 
:20: mscs-test-161.budzdorov.ru    Wed Mar 17 10:27:50 2021
:21:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:22: 
:23: 
:24: mscs-test-161.budzdorov.ru    Wed Mar 17 10:27:51 2021
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:26: 
--- end of diff output ---

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

17.03.21 10:30:03. 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-03-17 10:30:06 No severe PSQL-related problems occured
17.03.21 10:30:07. End of report.