Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.09.2021 19:39
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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 16121172 kB
MemAvailable 30776140 kB
Buffers 0 kB
Cached 15587688 kB
SwapCached 3072 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 3.0.8
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-09-27 19:37:49
DTS_END                         2021-09-27 19:37:49.5560
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(57:17)
IP                              ::1/59462
TRN_ID                          238440
ATT_ID                          645
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: 9186.23 22108 1102347
customer order: creation 168.98 367 20277
customer order: refuse 13.74 385 1649
order to supplier: creation 43.65 1269 5238
order to supplier: removal 3.19 105221 383
invoice (draft): creation 44.50 3367 5340
invoice (draft): removal 53.51 732 6421
invoice accept: apply 40.53 19572 4863
invoice accept: cancel 48.95 830 5874
customer reserve: creation 3745.88 195 449506
customer reserve: removal 4734.82 55 568178
realization accept: apply 81.77 192 9812
realization accept: cancel 81.67 116 9800
payment from customer: creation 57.18 124 6861
payment from customer: removal 5.50 82 660
payment to supplier: creation 47.18 138 5661
payment to supplier: removal 3.53 67 424
service: total inventory turnovers 4.64 1257 557
service: total monetary turnovers 6.10 35 732
service: aggregate perf. data 0.89 18729 107
service: refresh index statistics 0.03 289420 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-09-2021 19:39:22 to 27-09-2021 19:39:24.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2701
TEST_TIME 2 3117
TEST_TIME 3 3634
TEST_TIME 4 4411
TEST_TIME 5 4082
TEST_TIME 6 4386
TEST_TIME 7 4581
TEST_TIME 8 4576
TEST_TIME 9 4611
TEST_TIME 10 4722
TEST_TIME 11 4867
TEST_TIME 12 4885
TEST_TIME 13 5258
TEST_TIME 14 5269
TEST_TIME 15 5970
TEST_TIME 16 6233
TEST_TIME 17 6557
TEST_TIME 18 7128
TEST_TIME 19 7445
TEST_TIME 20 7530
TEST_TIME 21 8116
TEST_TIME 22 8886
TEST_TIME 23 9241
TEST_TIME 24 9595
TEST_TIME 25 9755
TEST_TIME 26 9575
TEST_TIME 27 9755
TEST_TIME 28 9717
TEST_TIME 29 9653
TEST_TIME 30 9943
TEST_TIME 31 9687
TEST_TIME 32 9548
TEST_TIME 33 9563
TEST_TIME 34 9513
TEST_TIME 35 9429
TEST_TIME 36 9388
TEST_TIME 37 9299
TEST_TIME 38 9175
TEST_TIME 39 9229
TEST_TIME 40 9128
TEST_TIME 41 9252
TEST_TIME 42 9113
TEST_TIME 43 9148
TEST_TIME 44 8979
TEST_TIME 45 8984
TEST_TIME 46 9354
TEST_TIME 47 9383
TEST_TIME 48 9412
TEST_TIME 49 9416
TEST_TIME 50 9403
TEST_TIME 51 9508
TEST_TIME 52 9558
TEST_TIME 53 9592
TEST_TIME 54 9493
TEST_TIME 55 9585
TEST_TIME 56 9641
TEST_TIME 57 9540
TEST_TIME 58 9418
TEST_TIME 59 9424
TEST_TIME 60 9329
TEST_TIME 61 9284
TEST_TIME 62 9313
TEST_TIME 63 9228
TEST_TIME 64 9118
TEST_TIME 65 9152
TEST_TIME 66 9124
TEST_TIME 67 9140
TEST_TIME 68 9123
TEST_TIME 69 9107
TEST_TIME 70 9137
TEST_TIME 71 9119
TEST_TIME 72 9122
TEST_TIME 73 9317
TEST_TIME 74 9350
TEST_TIME 75 9395
TEST_TIME 76 9337
TEST_TIME 77 9359
TEST_TIME 78 9344
TEST_TIME 79 9276
TEST_TIME 80 9210
TEST_TIME 81 9281
TEST_TIME 82 9239
TEST_TIME 83 9202
TEST_TIME 84 9179
TEST_TIME 85 9169
TEST_TIME 86 9203
TEST_TIME 87 9298
TEST_TIME 88 9293
TEST_TIME 89 9261
TEST_TIME 90 9265
TEST_TIME 91 9331
TEST_TIME 92 9315
TEST_TIME 93 9317
TEST_TIME 94 9419
TEST_TIME 95 9412
TEST_TIME 96 9429
TEST_TIME 97 9358
TEST_TIME 98 9305
TEST_TIME 99 9279
TEST_TIME 100 9235
TEST_TIME 101 9199
TEST_TIME 102 9216
TEST_TIME 103 9180
TEST_TIME 104 9168
TEST_TIME 105 9137
TEST_TIME 106 9149
TEST_TIME 107 9165
TEST_TIME 108 9182
TEST_TIME 109 9207
TEST_TIME 110 9197
TEST_TIME 111 9222
TEST_TIME 112 9192
TEST_TIME 113 9234
TEST_TIME 114 9314
TEST_TIME 115 9328
TEST_TIME 116 9311
TEST_TIME 117 9275
TEST_TIME 118 9230
TEST_TIME 119 9216
TEST_TIME 120 9188

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 27-09-2021 19:39:25 to 27-09-2021 19:39:29.

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 1519683 1519683 0 0.00 0 52204 7 0 0
doc_list_biud 1519682 1519682 0 0.00 0 36750 27 0 0
sp_add_invoice_to_stock 5026 4863 163 3.24 18 188173 19573 0 163
sp_cancel_adding_invoice 5874 5874 0 0.00 5 79803 831 0 0
sp_cancel_client_order 1649 1649 0 0.00 4 22966 386 0 0
sp_cancel_customer_reserve 568178 568178 0 0.00 1 36751 56 0 0
sp_cancel_pay_from_customer 660 660 0 0.00 2 7574 82 0 0
sp_cancel_pay_to_supplier 424 424 0 0.00 2 2745 67 0 0
sp_cancel_supplier_invoice 6435 6421 14 0.21 2 31023 732 0 14
sp_cancel_supplier_order 383 383 0 0.00 24 1102640 105222 0 0
sp_cancel_write_off 9800 9800 0 0.00 1 21827 116 0 0
sp_client_order 20277 20277 0 0.00 4 35988 368 0 0
sp_customer_reserve 449506 449506 0 0.00 0 47280 196 0 0
sp_fill_shopping_cart 30848 30848 0 0.00 1 10116 35 0 0
sp_fill_shopping_cart_clo_res 449499 449499 0 0.00 0 14609 16 0 0
sp_get_clo_for_invoice 4856 4856 0 0.00 0 19242 315 0 0
sp_kill_cost_storno 16758 16758 0 0.00 0 30923 51 0 0
sp_kill_qstorno_ret_qs2qd 1152960 1152960 0 0.00 0 36748 30 0 0
sp_kill_qty_storno 606972 606972 0 0.00 0 36750 66 0 0
sp_lock_selected_doc 20302 20302 0 0.00 0 11263 6 0 0
sp_make_cost_storno 27189 27189 0 0.00 0 52177 61 0 0
sp_multiply_rows_for_pdistr 27189 27189 0 0.00 0 12291 27 0 0
sp_multiply_rows_for_qdistr 20277 20277 0 0.00 0 34274 169 0 0
sp_pay_from_customer 6861 6861 0 0.00 4 51621 125 0 0
sp_pay_to_supplier 5661 5661 0 0.00 5 52214 138 0 0
sp_payment_common 12522 12522 0 0.00 2 51492 39 0 0
sp_qd_handle_on_cancel_clo 1649 1649 0 0.00 0 22961 359 0 0
sp_qd_handle_on_invoice_upd_sts 10729 10729 0 0.00 0 31572 336 0 0
sp_qd_handle_on_reserve_upd_sts 9812 9812 0 0.00 0 21587 68 0 0
sp_reserve_write_off 9812 9812 0 0.00 6 21602 193 0 0
sp_supplier_invoice 5340 5340 0 0.00 6 94178 3367 0 0
sp_supplier_order 5238 5238 0 0.00 4 50828 1270 0 0
srv_aggregate_perf_data 107 107 0 0.00 2865 165846 18730 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1872 151074 50390 0 0
srv_find_qd_qs_mism 1056529 1056529 0 0.00 0 13533 6 0 0
srv_make_invnt_saldo 557 557 0 0.00 51 131365 1258 0 0
srv_make_money_saldo 732 732 0 0.00 1 7889 35 0 0
srv_recalc_idx_stat 4 4 0 0.00 131268 366048 289421 0 0
t$perf-abend:sp_add_invoice_to_stock 163 163 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4864 4864 0 0.00 0 12727 152 0 0
t$perf-norm:sp_cancel_adding_invoice 442 442 0 0.00 1 4068 93 0 0
t$perf-norm:sp_cancel_client_order 1649 1649 0 0.00 0 13124 27 0 0
t$perf-norm:sp_cancel_customer_reserve 682 682 0 0.00 0 671 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 660 660 0 0.00 0 4357 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 424 424 0 0.00 0 629 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 411 411 0 0.00 0 4208 17 0 0
t$perf-norm:sp_cancel_supplier_order 383 383 0 0.00 0 17571 1643 0 0
t$perf-norm:sp_cancel_write_off 984 984 0 0.00 0 3817 15 0 0
t$perf-norm:sp_client_order 20277 20277 0 0.00 0 11744 16 0 0
t$perf-norm:sp_customer_reserve 949 949 0 0.00 0 1846 5 0 0
t$perf-norm:sp_pay_from_customer 6861 6861 0 0.00 0 7424 11 0 0
t$perf-norm:sp_pay_to_supplier 5661 5661 0 0.00 0 11837 13 0 0
t$perf-norm:sp_reserve_write_off 9812 9812 0 0.00 0 14274 12 0 0
t$perf-norm:sp_supplier_invoice 5340 5340 0 0.00 0 9474 14 0 0
t$perf-norm:sp_supplier_order 5238 5238 0 0.00 0 2054 11 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 925 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 495 16 0 0
t$perf-norm:srv_make_invnt_saldo 557 557 0 0.00 0 2753 27 0 0
t$perf-norm:srv_make_money_saldo 732 732 0 0.00 0 542 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 2 0 0
v_add_invoice_to_stock 10036 9710 326 3.24 0 7539 18 0 326
v_all_customers 37032 37032 0 0.00 0 134 1 0 0
v_all_suppliers 29248 29248 0 0.00 0 133 1 0 0
v_all_wares 40554 40554 0 0.00 0 121 1 0 0
v_cancel_adding_invoice 884 884 0 0.00 0 787 7 0 0
v_cancel_client_order 3298 3298 0 0.00 0 3213 5 0 0
v_cancel_customer_prepayment 1320 1320 0 0.00 0 1417 4 0 0
v_cancel_customer_reserve 1364 1364 0 0.00 0 3259 5 0 0
v_cancel_payment_to_supplier 848 848 0 0.00 0 2642 10 0 0
v_cancel_supplier_invoice 850 822 28 3.29 0 1980 19 0 28
v_cancel_supplier_order 766 766 0 0.00 0 2724 8 0 0
v_cancel_write_off 1968 1968 0 0.00 0 1984 15 0 0
v_max_id_clo_ord 5234 5234 0 0.00 0 217 1 0 0
v_max_id_clo_res 949 949 0 0.00 0 2008 13 0 0
v_max_id_ord_sup 5337 5337 0 0.00 0 2457 2 0 0
v_max_non_paid_invoice 1608 1608 0 0.00 0 3533 4 0 0
v_max_non_paid_realizn 3622 3622 0 0.00 0 1336 4 0 0
v_min_id_clo_ord 5234 5234 0 0.00 0 3952 6 0 0
v_min_id_clo_res 949 949 0 0.00 0 2271 6 0 0
v_min_id_ord_sup 5337 5337 0 0.00 0 4386 4 0 0
v_min_non_paid_invoice 5661 5661 0 0.00 0 16005 25 0 0
v_min_non_paid_realizn 6861 6861 0 0.00 0 51487 29 0 0
v_our_firm 10000 10000 0 0.00 0 25 1 0 0
v_reserve_write_off 19624 19624 0 0.00 0 5932 6 0 0
x_lock_depdocs_on_canc_invoice 11884 11884 0 0.00 0 23448 118 0 0
x_lock_depdocs_on_canc_sup_ord 383 383 0 0.00 0 23526 403 0 0
x_make_qstorno_1000_1200 5238 5238 0 0.00 1 50811 1201 0 0
x_make_qstorno_1200_2000 5340 5340 0 0.00 3 94166 3243 0 0
x_make_qstorno_2100_3300 449506 449506 0 0.00 0 45937 178 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-09-2021 19:39:30 to 27-09-2021 19:39:34.

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
17:07:53 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
17:08:54 21545 8192000000 10379253712 10698350592 1420001984 14.773 100 84 168 0 1452782208 5704816 27075408
17:10:29 9642 8192000000 10452330736 10787106816 1511969232 15.580 100 90 180 0 1549301600 8574048 28758320
17:11:47 16429 8192000000 10500852048 10842963968 1556034112 15.962 100 86 172 0 1592579776 9777248 26768416
17:13:08 11508 8192000000 10564280064 10919137280 1620059536 16.510 100 83 166 1 1655155136 9962272 25133328
17:14:24 11901 8192000000 10625968480 10997940224 1689784672 17.099 100 66 131 0 1719738240 10831520 19122048
17:15:39 5576 8192000000 10700389216 11078189056 1769143808 17.760 100 87 171 0 1806678896 12736176 24798912
17:16:46 3943 8192000000 10759299200 11141455872 1814081520 18.129 100 81 162 0 1850912352 14233472 22597360
17:17:52 2709 8192000000 10795368912 11178827776 1851611200 18.435 100 86 172 3 1889889520 14358240 23920080
17:18:57 2139 8192000000 10812504256 11202719744 1869060832 18.577 100 88 174 2 1907513728 13924336 24528560
17:20:00 12365 8192000000 10820705824 11217240064 1880844960 18.672 100 74 142 0 1912694112 12450592 19398560
17:21:14 7257 8192000000 10781463488 11212189696 1880415376 18.668 100 31 62 2 1897568496 7792992 9360128
17:22:25 13695 8192000000 10789484128 11229921280 1893278416 18.772 100 19 38 1 1904119408 5594288 5246704
17:23:42 5456 8192000000 10814943360 11244601344 1901121936 18.835 100 22 42 0 1913878864 7029536 5727392
17:24:48 60518 8192000000 10812540592 11253915648 1906955840 18.882 100 33 66 1 1922282640 5249520 10077280
17:26:49 7582 8192000000 10839784848 11271286784 1914664480 18.944 100 35 71 1 1936227200 12204224 9358496
17:27:57 7003 8192000000 10800558048 11232620544 1862690640 18.525 98 30 60 0 1878282384 6891552 8700192
17:29:05 3907 8192000000 10740067824 11158851584 1795931984 17.981 98 24 50 1 1808934064 6193888 6808192
17:30:09 28315 8192000000 10709610832 11129909248 1668362976 16.919 91 27 53 0 1681668896 5676960 7628960
17:31:39 7109 8192000000 10724241872 11140431872 1809064736 18.088 100 34 67 0 1825104480 5516832 10522912
17:32:46 5953 8192000000 10741417456 11163475968 1826746848 18.233 100 47 94 0 1852144176 11176160 14221168
17:33:56 77113 8192000000 10758079824 11188809728 1835509824 18.304 99 30 60 0 1849424576 5178064 8736688
17:36:14 4127 8192000000 10786239440 11217338368 1874971264 18.624 100 28 56 2 1889156240 5842000 8342976
17:37:19 9392 8192000000 10827255440 11250941952 1906848032 18.881 100 27 50 4 1919674832 4821200 8005600
17:38:31 14220 8192000000 10858078336 11281649664 1932930384 19.090 100 50 96 3 1954094768 7542336 13622048
17:39:48 18437 8192000000 10889247184 11313729536 1951487824 19.238 100 42 83 1 1974817584 11164672 12165088
17:41:22 53312 8192000000 10880847312 11322302464 1953084592 19.251 100 28 56 0 1974963376 13568368 8310416
17:43:23 11499 8192000000 10908262000 11348746240 1975243520 19.427 100 39 76 2 2006197840 19003104 11951216
17:44:37 3175 8192000000 10936355824 11358965760 1982483632 19.484 100 70 138 3 2024261632 22488352 19289648
17:46:04 7854 8192000000 10954324176 11390959616 2006698096 19.676 100 55 107 1 2047812240 25563456 15550688
17:47:14 4538 8192000000 10997901840 11419234304 2026813392 19.834 100 83 163 1 2086236144 35302800 24119952
17:48:27 3681 8192000000 10996882512 11417276416 2026815392 19.834 100 75 150 2 2083529504 34353680 22360432
17:49:42 12895 8192000000 11005635504 11435589632 2032460464 19.878 100 60 120 3 2089320736 38577664 18282608
17:51:02 4822 8192000000 10992603104 11414818816 2017381552 19.760 100 70 136 0 2079331584 40652080 21297952
17:52:12 2960 8192000000 11009342608 11433951232 2020434960 19.784 100 66 128 4 2087635456 46766448 20434048
17:53:19 3786 8192000000 11010038688 11424100352 2009866288 19.700 100 86 169 1 2083395568 47670384 25858896
17:54:27 1869 8192000000 10983228736 11393376256 1983134688 19.490 100 90 181 1 2064091744 51540032 29417024
17:55:36 8931 8192000000 10945700368 11375513600 1968885696 19.377 100 50 99 1 2030576400 45006432 16684272
17:56:51 11864 8192000000 10921317600 11368210432 1960445840 19.310 100 36 72 1 2014099040 41264720 12388480
17:58:09 11802 8192000000 10936678816 11389640704 1947910528 19.210 99 34 68 1 2009265968 50133664 11221776
17:59:28 23831 8192000000 10884014336 11333349376 1892501264 18.766 98 23 47 0 1933047216 32443216 8102736
18:00:53 57291 8192000000 10834736304 11302031360 1854152656 18.456 97 16 33 1 1881361984 22403392 4805936
18:02:51 33540 8192000000 10838968160 11292909568 1878379392 18.652 98 9 18 1 1898233856 17586640 2267824
18:04:25 45834 8192000000 10859192240 11308060672 1893459840 18.774 98 30 60 0 1919151216 16156480 9534896
18:06:28 70139 8192000000 10858291760 11315134464 1883214624 18.691 97 30 60 1 1907855296 16677040 7963632
18:08:47 72175 8192000000 10872271008 11324837888 1909387488 18.902 98 28 55 1 1929772400 12672496 7712416
18:11:00 9101 8192000000 10904316048 11351232512 1967322384 19.364 100 42 82 1 1994426128 15912784 11190960
18:12:11 28154 8192000000 10900959984 11353370624 1948507872 19.215 99 26 52 1 1972085712 16056768 7521072
18:13:41 39611 8192000000 10917250320 11377643520 1987777280 19.526 100 33 66 0 2013119072 15856912 9484880
18:15:21 49849 8192000000 10913635296 11376619520 1960495968 19.310 99 22 44 1 1989796160 22289152 7011040
18:17:15 77207 8192000000 10905512608 11379130368 1964994608 19.346 99 20 40 1 1993823696 22556192 6272896
18:19:33 50125 8192000000 10905856560 11370041344 1967253088 19.364 99 13 26 0 1995730032 24237104 4239840
18:21:23 87224 8192000000 10934835088 11407986688 1994149168 19.577 100 29 57 2 2031597088 28677488 8770432
18:23:52 55118 8192000000 10921271760 11402878976 1994301184 19.578 100 23 47 0 2016960736 16218080 6441472
18:26:30 38731 8192000000 10937946480 11401920512 2000376960 19.626 100 23 46 1 2020233536 13344896 6511680
18:28:14 70455 8192000000 10940072576 11418570752 1985454480 19.508 99 18 36 2 2008327792 18048304 4825008
18:30:30 76972 8192000000 10961276432 11411718144 2004498848 19.658 100 21 42 1 2026586816 16512016 5575952
18:32:49 125449 8192000000 10891970128 11361112064 1880745968 18.671 94 22 44 0 1903648080 16174624 6727488
18:37:05 43791 8192000000 10885157360 11346747392 1957226704 19.284 99 23 46 2 1970633184 7407488 5998992
18:38:50 17513 8192000000 10872328992 11336908800 1947268864 19.205 100 37 72 2 1967831360 10730624 9831872
18:40:18 49006 8192000000 10825552336 11293982720 1839992160 18.341 95 8 16 0 1847986016 6182832 1811024
18:42:09 65509 8192000000 10832300528 11311185920 1860976272 18.511 96 6 12 1 1869913248 7546400 1390576
18:44:15 67205 8192000000 10865278704 11323879424 1902577936 18.847 98 15 30 0 1914561456 7751600 4231920
18:46:24 47907 8192000000 10887299648 11337064448 1870998064 18.592 96 32 64 0 1894076768 14312304 8766400
18:48:22 58958 8192000000 10866368640 11329982464 1895915312 18.793 98 19 38 2 1920705872 19325664 5464896
18:50:24 70419 8192000000 10843499264 11306184704 1858059408 18.488 97 10 20 0 1883987888 22912560 3015920
18:52:45 56365 8192000000 10872506560 11317637120 1871705296 18.598 97 12 24 1 1890953104 15964000 3283808
18:54:48 31566 8192000000 10892418512 11349385216 1923152240 19.012 99 32 64 0 1944640208 12768640 8719328
18:56:24 90303 8192000000 10863178224 11332132864 1932325360 19.085 100 19 38 0 1952557792 14766112 5466320
18:58:59 63697 8192000000 10848413616 11336458240 1902405776 18.846 98 10 20 0 1920261376 14618304 3237296
19:01:28 76662 8192000000 10892764256 11353604096 1921505488 18.999 98 16 32 0 1939685472 13738240 4441744
19:04:08 121748 8192000000 10904612992 11355582464 1890879968 18.753 96 7 14 1 1906780240 14137072 1763200
19:07:10 27036 8192000000 10904220496 11364388864 1945173632 19.188 98 28 56 2 1965021712 12509088 7338992
19:08:47 61171 8192000000 10935960464 11388051456 1964033056 19.338 99 17 34 0 1982036704 13620944 4382704
19:10:52 105161 8192000000 10925177312 11407847424 1968774864 19.376 99 23 46 0 1993343904 18777312 5791728
19:13:37 123127 8192000000 10912686352 11379867648 1954168272 19.260 98 17 34 0 1972894608 14213696 4512640
19:16:44 68036 8192000000 10916925488 11403644928 1992891440 19.567 100 7 14 0 2012038048 17266800 1879808
19:18:54 80181 8192000000 10926081568 11417821184 2005439632 19.666 100 14 28 0 2021680128 11890928 4349568
19:21:16 45619 8192000000 10919613424 11407212544 2005473472 19.666 100 11 23 1 2016544416 7083808 3987136
19:23:02 90503 8192000000 10915374688 11401244672 1969135264 19.379 98 25 50 0 1986029744 9800480 7094000
19:25:35 38386 8192000000 10919058992 11394449408 1989126256 19.537 100 16 32 0 2003882400 9352224 5403920
19:27:22 44314 8192000000 10916306608 11386429440 1924011504 19.019 96 22 44 0 1938685504 8470752 6203248
19:29:19 68573 8192000000 10914864336 11384643584 1934521600 19.103 97 23 46 1 1954971840 13393296 7056944
19:31:28 151074 8192000000 10887796096 11364118528 1816934400 18.153 92 9 18 0 1830179200 10939136 2305664
19:35:04 123443 8192000000 10884410560 11361435648 1929957056 19.067 98 34 68 0 1951666640 11985760 9723824

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 27-09-2021 19:39:35 to 27-09-2021 19:39:50.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 163 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 27-09-2021 19:39:51 to 27-09-2021 19:39:53.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          237711
MON$OLDEST_ACTIVE               238650
MON$OLDEST_SNAPSHOT             238650
MON$NEXT_TRANSACTION            238650
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-03 16:41:55.3470
MON$PAGES                       2024064
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33501 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33501 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33501 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33501 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:946: 
:947: mscs-test-161.budzdorov.ru    Mon Sep 27 19:38:06 2021
:948:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:949: 
:950: 
:951: mscs-test-161.budzdorov.ru    Mon Sep 27 19:38:06 2021
:952:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:953: 
:954: 
:955: mscs-test-161.budzdorov.ru    Mon Sep 27 19:38:06 2021
:956:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:957: 
:958: 
:959: mscs-test-161.budzdorov.ru    Mon Sep 27 19:38:06 2021
:960:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:961: 
--- end of diff output ---

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

27.09.21 19:39:54. There are 3 logs in /var/tmp/logs-oltp30 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-09-27 19:39:56 No severe PSQL-related problems occured
27.09.21 19:39:58. End of report.