Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.11.2021 16:38
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 15816544 kB
MemAvailable 30765868 kB
Buffers 0 kB
Cached 15854644 kB
SwapCached 2452 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-11-08 16:37:59
DTS_END                         2021-11-08 16:37:59.6420
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/34290
TRN_ID                          246450
ATT_ID                          654
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: 9494.58 23689 1139350
customer order: creation 176.68 337 21201
customer order: refuse 15.58 304 1870
order to supplier: creation 47.18 1325 5662
order to supplier: removal 3.65 94708 438
invoice (draft): creation 47.12 3160 5654
invoice (draft): removal 55.36 784 6643
invoice accept: apply 42.68 17980 5121
invoice accept: cancel 49.80 762 5976
customer reserve: creation 3912.15 179 469458
customer reserve: removal 4844.38 55 581325
realization accept: apply 85.24 174 10229
realization accept: cancel 81.18 103 9742
payment from customer: creation 61.47 98 7376
payment from customer: removal 5.92 76 710
payment to supplier: creation 50.58 120 6070
payment to supplier: removal 3.33 93 400
service: total inventory turnovers 4.95 1154 594
service: total monetary turnovers 6.53 52 784
service: aggregate perf. data 0.78 23787 93
service: refresh index statistics 0.03 328538 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-11-2021 16:38:47 to 08-11-2021 16:38:49.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1684
TEST_TIME 2 3521
TEST_TIME 3 4354
TEST_TIME 4 4468
TEST_TIME 5 4406
TEST_TIME 6 4529
TEST_TIME 7 4856
TEST_TIME 8 5073
TEST_TIME 9 5420
TEST_TIME 10 5659
TEST_TIME 11 6037
TEST_TIME 12 6127
TEST_TIME 13 6406
TEST_TIME 14 6776
TEST_TIME 15 6937
TEST_TIME 16 7359
TEST_TIME 17 7715
TEST_TIME 18 8058
TEST_TIME 19 8122
TEST_TIME 20 8504
TEST_TIME 21 8770
TEST_TIME 22 8793
TEST_TIME 23 9068
TEST_TIME 24 9406
TEST_TIME 25 9784
TEST_TIME 26 10168
TEST_TIME 27 10121
TEST_TIME 28 10369
TEST_TIME 29 10421
TEST_TIME 30 10310
TEST_TIME 31 10110
TEST_TIME 32 10133
TEST_TIME 33 10064
TEST_TIME 34 10041
TEST_TIME 35 9952
TEST_TIME 36 9914
TEST_TIME 37 10110
TEST_TIME 38 10061
TEST_TIME 39 10008
TEST_TIME 40 10093
TEST_TIME 41 9985
TEST_TIME 42 10000
TEST_TIME 43 10046
TEST_TIME 44 9900
TEST_TIME 45 9712
TEST_TIME 46 9709
TEST_TIME 47 9643
TEST_TIME 48 9643
TEST_TIME 49 9639
TEST_TIME 50 9657
TEST_TIME 51 9533
TEST_TIME 52 9646
TEST_TIME 53 9737
TEST_TIME 54 9612
TEST_TIME 55 9669
TEST_TIME 56 9727
TEST_TIME 57 9641
TEST_TIME 58 9617
TEST_TIME 59 9627
TEST_TIME 60 9507
TEST_TIME 61 9411
TEST_TIME 62 9314
TEST_TIME 63 9371
TEST_TIME 64 9369
TEST_TIME 65 9402
TEST_TIME 66 9420
TEST_TIME 67 9507
TEST_TIME 68 9553
TEST_TIME 69 9521
TEST_TIME 70 9541
TEST_TIME 71 9467
TEST_TIME 72 9605
TEST_TIME 73 9612
TEST_TIME 74 9631
TEST_TIME 75 9597
TEST_TIME 76 9626
TEST_TIME 77 9644
TEST_TIME 78 9551
TEST_TIME 79 9508
TEST_TIME 80 9431
TEST_TIME 81 9449
TEST_TIME 82 9396
TEST_TIME 83 9338
TEST_TIME 84 9281
TEST_TIME 85 9313
TEST_TIME 86 9341
TEST_TIME 87 9350
TEST_TIME 88 9338
TEST_TIME 89 9334
TEST_TIME 90 9360
TEST_TIME 91 9356
TEST_TIME 92 9358
TEST_TIME 93 9441
TEST_TIME 94 9417
TEST_TIME 95 9495
TEST_TIME 96 9452
TEST_TIME 97 9563
TEST_TIME 98 9589
TEST_TIME 99 9602
TEST_TIME 100 9537
TEST_TIME 101 9468
TEST_TIME 102 9511
TEST_TIME 103 9458
TEST_TIME 104 9454
TEST_TIME 105 9463
TEST_TIME 106 9393
TEST_TIME 107 9416
TEST_TIME 108 9387
TEST_TIME 109 9382
TEST_TIME 110 9438
TEST_TIME 111 9412
TEST_TIME 112 9396
TEST_TIME 113 9392
TEST_TIME 114 9404
TEST_TIME 115 9386
TEST_TIME 116 9391
TEST_TIME 117 9355
TEST_TIME 118 9406
TEST_TIME 119 9433
TEST_TIME 120 9501

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 08-11-2021 16:38:50 to 08-11-2021 16:38:54.

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 1574709 1574709 0 0.00 0 26575 6 0 0
doc_list_biud 1574709 1574709 0 0.00 0 39074 26 0 0
sp_add_invoice_to_stock 5250 5121 129 2.45 22 208279 17980 0 129
sp_cancel_adding_invoice 5976 5976 0 0.00 6 51763 763 0 0
sp_cancel_client_order 1870 1870 0 0.00 4 9356 304 0 0
sp_cancel_customer_reserve 581325 581325 0 0.00 1 39076 55 0 0
sp_cancel_pay_from_customer 710 710 0 0.00 2 4233 77 0 0
sp_cancel_pay_to_supplier 400 400 0 0.00 3 4639 94 0 0
sp_cancel_supplier_invoice 6658 6643 15 0.22 2 19520 784 0 15
sp_cancel_supplier_order 438 438 0 0.00 5 986125 94709 0 0
sp_cancel_write_off 9742 9742 0 0.00 1 8881 104 0 0
sp_client_order 21201 21201 0 0.00 4 51442 338 0 0
sp_customer_reserve 469458 469458 0 0.00 0 117255 180 0 0
sp_fill_shopping_cart 32512 32512 0 0.00 1 13996 36 0 0
sp_fill_shopping_cart_clo_res 469441 469441 0 0.00 0 14369 12 0 0
sp_get_clo_for_invoice 5104 5104 0 0.00 0 36323 274 0 0
sp_kill_cost_storno 16828 16828 0 0.00 0 9030 53 0 0
sp_kill_qstorno_ret_qs2qd 1179473 1179473 0 0.00 0 39070 30 0 0
sp_kill_qty_storno 621327 621327 0 0.00 0 39074 66 0 0
sp_lock_selected_doc 21298 21298 0 0.00 0 10137 6 0 0
sp_make_cost_storno 28779 28779 0 0.00 0 19479 48 0 0
sp_multiply_rows_for_pdistr 28779 28779 0 0.00 0 8508 30 0 0
sp_multiply_rows_for_qdistr 21201 21201 0 0.00 0 15031 144 0 0
sp_pay_from_customer 7376 7376 0 0.00 4 19442 99 0 0
sp_pay_to_supplier 6070 6070 0 0.00 4 11830 121 0 0
sp_payment_common 13446 13446 0 0.00 2 13517 25 0 0
sp_qd_handle_on_cancel_clo 1870 1870 0 0.00 0 9347 285 0 0
sp_qd_handle_on_invoice_upd_sts 11080 11080 0 0.00 0 18962 337 0 0
sp_qd_handle_on_reserve_upd_sts 10229 10229 0 0.00 0 13122 59 0 0
sp_reserve_write_off 10229 10229 0 0.00 6 26696 174 0 0
sp_supplier_invoice 5654 5654 0 0.00 6 75538 3161 0 0
sp_supplier_order 5662 5662 0 0.00 6 26282 1325 0 0
srv_aggregate_perf_data 93 93 0 0.00 2956 358507 23787 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 4243 169028 48660 0 0
srv_find_qd_qs_mism 1090744 1090744 0 0.00 0 22498 5 0 0
srv_make_invnt_saldo 594 594 0 0.00 43 125095 1154 0 0
srv_make_money_saldo 784 784 0 0.00 0 22027 53 0 0
srv_recalc_idx_stat 4 4 0 0.00 136990 519354 328538 0 0
t$perf-abend:sp_add_invoice_to_stock 129 129 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5121 5121 0 0.00 0 11874 172 0 0
t$perf-norm:sp_cancel_adding_invoice 419 419 0 0.00 0 6022 96 0 0
t$perf-norm:sp_cancel_client_order 1870 1870 0 0.00 0 8019 16 0 0
t$perf-norm:sp_cancel_customer_reserve 652 652 0 0.00 0 2561 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 710 710 0 0.00 0 3225 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 400 400 0 0.00 0 2955 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 438 438 0 0.00 0 3326 16 0 0
t$perf-norm:sp_cancel_supplier_order 438 438 0 0.00 0 23770 1521 0 0
t$perf-norm:sp_cancel_write_off 1048 1048 0 0.00 0 3334 16 0 0
t$perf-norm:sp_client_order 21201 21201 0 0.00 0 6814 17 0 0
t$perf-norm:sp_customer_reserve 1013 1013 0 0.00 0 2457 7 0 0
t$perf-norm:sp_pay_from_customer 7376 7376 0 0.00 0 11014 11 0 0
t$perf-norm:sp_pay_to_supplier 6070 6070 0 0.00 0 6549 9 0 0
t$perf-norm:sp_reserve_write_off 10229 10229 0 0.00 0 8149 13 0 0
t$perf-norm:sp_supplier_invoice 5654 5654 0 0.00 0 12256 22 0 0
t$perf-norm:sp_supplier_order 5662 5662 0 0.00 0 7390 16 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 195 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 5 0 0 0
t$perf-norm:srv_make_invnt_saldo 594 594 0 0.00 0 931 19 0 0
t$perf-norm:srv_make_money_saldo 784 784 0 0.00 0 3152 21 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 5 91 28 0 0
v_add_invoice_to_stock 10446 10188 258 2.46 0 7635 19 0 258
v_all_customers 38308 38308 0 0.00 0 69 1 0 0
v_all_suppliers 31544 31544 0 0.00 0 24 1 0 0
v_all_wares 42402 42402 0 0.00 0 141 1 0 0
v_cancel_adding_invoice 838 838 0 0.00 0 1180 8 0 0
v_cancel_client_order 3740 3740 0 0.00 0 1835 3 0 0
v_cancel_customer_prepayment 1420 1420 0 0.00 0 4214 12 0 0
v_cancel_customer_reserve 1304 1304 0 0.00 0 2046 7 0 0
v_cancel_payment_to_supplier 800 800 0 0.00 0 3413 11 0 0
v_cancel_supplier_invoice 906 876 30 3.31 0 1151 14 0 30
v_cancel_supplier_order 876 876 0 0.00 0 736 6 0 0
v_cancel_write_off 2096 2096 0 0.00 0 293 12 0 0
v_max_id_clo_ord 5659 5659 0 0.00 0 2063 2 0 0
v_max_id_clo_res 1013 1013 0 0.00 0 2321 18 0 0
v_max_id_ord_sup 5652 5652 0 0.00 0 3041 4 0 0
v_max_non_paid_invoice 1609 1609 0 0.00 0 2948 5 0 0
v_max_non_paid_realizn 4052 4052 0 0.00 0 2512 4 0 0
v_min_id_clo_ord 5659 5659 0 0.00 0 13990 11 0 0
v_min_id_clo_res 1013 1013 0 0.00 0 3276 8 0 0
v_min_id_ord_sup 5652 5652 0 0.00 0 5502 3 0 0
v_min_non_paid_invoice 6070 6070 0 0.00 0 11818 21 0 0
v_min_non_paid_realizn 7376 7376 0 0.00 1 6740 14 0 0
v_our_firm 10742 10742 0 0.00 0 30 1 0 0
v_reserve_write_off 20458 20458 0 0.00 0 3026 5 0 0
x_lock_depdocs_on_canc_invoice 12181 12181 0 0.00 0 14791 115 0 0
x_lock_depdocs_on_canc_sup_ord 438 438 0 0.00 0 4001 292 0 0
x_make_qstorno_1000_1200 5662 5662 0 0.00 1 26259 1243 0 0
x_make_qstorno_1200_2000 5654 5654 0 0.00 3 68673 3038 0 0
x_make_qstorno_2100_3300 469458 469458 0 0.00 0 117254 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-11-2021 16:38:55 to 08-11-2021 16:38:59.

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
14:08:03 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:04 27419 8192000000 9903363616 10214277120 982206528 10.706 100 86 172 0 1010569456 5692816 22670112
14:10:37 5409 8192000000 9947301344 10269605888 1031691504 11.185 100 89 178 0 1062646752 8118832 22836416
14:11:47 20314 8192000000 9991526272 10323480576 1069350880 11.546 100 88 177 1 1100982000 9731408 21899712
14:13:09 12898 8192000000 10039890384 10386423808 1122630656 12.052 100 81 162 0 1153159728 11355632 19173440
14:14:25 9680 8192000000 10086322752 10446106624 1172912288 12.524 100 82 164 0 1205325232 13658128 18754816
14:15:37 4761 8192000000 10119860208 10492735488 1209281728 12.862 100 68 135 0 1236559504 11889312 15388464
14:16:43 3358 8192000000 10163138800 10535886848 1250837744 13.246 100 87 174 0 1283960800 14107296 19015760
14:17:48 1586 8192000000 10198009440 10577973248 1279004224 13.504 100 91 181 2 1312368064 12277168 21086672
14:18:51 10401 8192000000 10205952944 10593234944 1293331760 13.635 100 72 147 2 1319217824 9379424 16506640
14:20:02 6434 8192000000 10189561840 10603556864 1299961168 13.695 100 28 54 1 1317711248 11225024 6525056
14:21:12 12850 8192000000 10195471472 10611548160 1310528720 13.791 100 22 44 0 1321616176 6288192 4799264
14:22:26 8901 8192000000 10206275536 10625843200 1323242624 13.906 100 37 75 0 1342431952 9877024 9312304
14:23:37 30514 8192000000 10221174720 10641985536 1327612256 13.946 100 35 70 0 1342670736 6636528 8421952
14:25:10 7252 8192000000 10233242368 10649333760 1337416032 14.034 100 33 66 0 1352218448 7086640 7715776
14:26:21 8992 8192000000 10225549568 10643460096 1322606400 13.900 99 20 40 0 1332682928 5511520 4565008
14:27:32 57541 8192000000 10186174192 10600402944 1253585200 13.271 95 44 88 1 1269043136 5238368 10219568
14:29:35 4318 8192000000 10177482944 10582065152 1279441072 13.508 100 28 56 3 1292770640 7068432 6261136
14:30:40 8122 8192000000 10123733632 10514919424 1183532320 12.623 95 20 40 0 1193469632 5440688 4496624
14:31:48 62803 8192000000 10158093136 10567561216 1240466160 13.151 97 25 50 1 1251336512 4741568 6128784
14:33:52 7333 8192000000 10166794512 10578227200 1281480656 13.527 100 24 48 0 1292390416 5194512 5715248
14:35:00 7968 8192000000 10192228608 10602696704 1299255312 13.688 100 21 42 0 1309438048 5426112 4756624
14:36:08 40096 8192000000 10201627344 10613690368 1304520784 13.736 100 24 46 2 1315917968 5097776 6299408
14:37:48 9684 8192000000 10208509280 10623885312 1311344976 13.798 100 20 40 0 1325495488 10126416 4024096
14:39:07 6392 8192000000 10264101728 10667651072 1341840112 14.074 100 64 127 0 1370944976 15098560 14006304
14:40:24 8651 8192000000 10280201952 10691366912 1362357920 14.259 100 52 103 0 1389430704 15527648 11545136
14:41:36 15664 8192000000 10279806320 10698719232 1366354480 14.294 100 45 89 1 1392123296 15180880 10587936
14:43:00 59219 8192000000 10291441824 10715975680 1379223744 14.410 100 32 62 3 1407796592 21214944 7357904
14:45:03 25428 8192000000 10308031472 10732724224 1391698736 14.521 100 49 98 0 1425195744 20664512 12832496
14:46:35 11918 8192000000 10314597856 10734292992 1388807776 14.495 100 66 131 0 1425473168 21723856 14941536
14:47:54 4933 8192000000 10315569216 10738405376 1379316496 14.410 99 53 106 1 1420809600 28378624 13114480
14:49:07 5550 8192000000 10331944704 10751864832 1396978288 14.568 100 61 122 2 1442121872 29992064 15151520
14:50:17 4431 8192000000 10356264416 10764460032 1397947392 14.577 100 86 172 0 1458227600 39833424 20446784
14:51:24 4241 8192000000 10330611968 10748944384 1379458192 14.412 99 62 121 3 1439038160 44198416 15381552
14:52:35 4830 8192000000 10320850080 10732683264 1369978464 14.327 99 69 138 2 1428521936 40882304 17661168
14:53:44 4405 8192000000 10326272320 10737799168 1372442224 14.349 100 74 148 0 1432163408 40928896 18792288
14:54:52 12417 8192000000 10310385456 10734014464 1358054064 14.220 99 44 88 0 1407843968 38063712 11726192
14:56:11 10629 8192000000 10303474096 10726023168 1366386592 14.295 100 35 69 1 1409859744 34413824 9059328
14:57:28 9890 8192000000 10313133520 10739032064 1370983200 14.336 100 43 86 1 1424020192 41173856 11863136
14:58:41 7563 8192000000 10310750048 10732503040 1366310656 14.294 100 49 94 3 1417350480 39004832 12034992
14:59:53 17410 8192000000 10305926240 10736492544 1355313840 14.195 99 37 74 0 1410506448 45451792 9740816
15:01:30 6788 8192000000 10317502640 10746159104 1365483264 14.287 100 34 66 0 1423038672 48699632 8855776
15:02:41 10909 8192000000 10303521376 10739720192 1354418576 14.187 99 26 52 1 1403323952 41696704 7208672
15:03:53 50925 8192000000 10299379360 10739351552 1375411248 14.376 100 30 59 1 1406832912 24061488 7360176
15:05:45 71959 8192000000 10277984336 10718654464 1358629744 14.225 99 14 28 2 1371997440 10653184 2714512
15:07:58 63875 8192000000 10302384032 10734211072 1355253520 14.195 98 31 61 1 1368996816 7053648 6689648
15:10:10 169028 8192000000 10314057712 10766393344 1346812240 14.119 96 10 20 0 1370068800 21206000 2050560
15:14:07 132480 8192000000 10296543280 10742996992 1352651680 14.171 97 5 10 0 1369077008 15881600 543728
15:17:21 68877 8192000000 10311393520 10755977216 1390571376 14.511 99 19 38 0 1404217600 9166976 4479248
15:19:34 46918 8192000000 10324154992 10781208576 1407065328 14.658 100 6 12 0 1418338544 10142864 1130352
15:21:27 101443 8192000000 10310597856 10752856064 1378994672 14.408 98 37 74 2 1395151408 8342672 7814064
15:24:11 87965 8192000000 10307560512 10767814656 1376073936 14.381 98 30 60 0 1397865376 14409728 7381712
15:26:53 56734 8192000000 10313690768 10760904704 1364700656 14.280 97 26 50 1 1384422416 12921776 6799984
15:28:50 25733 8192000000 10341094032 10785468416 1412972992 14.710 100 53 106 1 1445193904 19024208 13196704
15:30:24 136140 8192000000 10280981136 10744877056 1311912768 13.803 93 14 28 1 1336831408 21129888 3788752
15:34:28 51698 8192000000 10259082400 10717483008 1314784144 13.829 96 21 41 0 1339607184 20357216 4465824
15:36:25 52334 8192000000 10255203248 10715922432 1346759200 14.118 99 17 33 2 1359078816 8252912 4066704
15:38:42 63585 8192000000 10254186704 10707329024 1322718032 13.901 97 29 56 3 1340832976 12253168 5861776
15:40:47 46203 8192000000 10260409808 10711425024 1342961360 14.084 99 9 16 1 1360581568 15870832 1749376
15:42:34 62053 8192000000 10265309616 10718253056 1297596064 13.673 95 12 25 0 1311421040 11439856 2385120
15:44:37 46568 8192000000 10267163840 10722312192 1325500688 13.926 97 11 22 0 1341513312 13838080 2174544
15:47:06 32193 8192000000 10259875664 10700378112 1336578064 14.027 99 34 68 0 1354782048 10702336 7501648
15:48:39 25363 8192000000 10288955616 10720571392 1350732416 14.154 99 57 114 0 1380395840 16635280 13028144
15:50:11 79032 8192000000 10266055120 10718400512 1312414064 13.808 96 14 26 0 1329799632 14608144 2777424
15:52:37 13696 8192000000 10282331264 10704908288 1343039824 14.085 99 46 90 0 1360827760 8176496 9611440
15:53:51 63580 8192000000 10261929728 10698231808 1317271120 13.852 97 34 68 0 1336656896 12090928 7294848
15:55:56 83783 8192000000 10257574928 10706763776 1345709584 14.109 99 34 68 0 1361898592 8359072 7829936
15:58:21 39583 8192000000 10250660304 10714021888 1339641296 14.054 99 15 30 0 1359946576 16836784 3468496
16:00:05 29276 8192000000 10253739424 10709458944 1358264400 14.222 100 12 24 0 1373404496 11895872 3244224
16:01:35 76402 8192000000 10265852832 10734866432 1353859376 14.182 99 23 46 1 1376665536 17367600 5438560
16:05:06 51386 8192000000 10270039312 10717396992 1338578400 14.045 97 31 62 1 1355090144 8654080 7857664
16:07:02 59891 8192000000 10286847184 10745507840 1367646864 14.306 99 16 32 0 1381920208 10396848 3876496
16:09:10 74559 8192000000 10295963376 10760728576 1365723872 14.289 98 15 30 0 1381213904 12340672 3149360
16:11:25 47543 8192000000 10309044528 10767900672 1384302896 14.455 99 27 54 1 1404321856 13969520 6049440
16:13:51 59657 8192000000 10304079664 10773725184 1356301680 14.204 97 15 30 0 1384573440 24475696 3796064
16:15:51 44837 8192000000 10295244112 10737704960 1339341008 14.051 96 25 50 0 1355146000 10287648 5517344
16:17:40 89401 8192000000 10279340576 10722185216 1358038960 14.220 98 42 84 2 1381038192 13591776 9407456
16:20:26 14903 8192000000 10280672160 10727837696 1366165712 14.293 100 27 54 0 1384170688 11667840 6337136
16:21:59 91609 8192000000 10298432272 10759229440 1391038160 14.515 100 17 34 0 1416644752 21620416 3986176
16:24:32 43870 8192000000 10282027712 10745769984 1375897952 14.380 99 24 48 2 1406598832 24874256 5826624
16:26:16 67241 8192000000 10270318448 10737795072 1360795632 14.244 99 9 18 1 1377907696 14886656 2225408
16:28:41 93602 8192000000 10255358736 10712547328 1265281792 13.378 92 6 12 0 1273994256 7574816 1137648
16:31:14 60540 8192000000 10250756272 10700800000 1294221264 13.643 95 15 30 0 1306874656 9100256 3553136
16:33:16 67401 8192000000 10265128256 10701176832 1282939536 13.540 94 27 54 0 1300242688 11233888 6069264
16:36:11 93067 8192000000 10265232096 10742484992 1319030208 13.868 97 10 20 0 1348148704 26642128 2476368

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 08-11-2021 16:39:01 to 08-11-2021 16:39:15.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 129 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-11-2021 16:39:16 to 08-11-2021 16:39:18.

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          246215
MON$OLDEST_ACTIVE               246697
MON$OLDEST_SNAPSHOT             246697
MON$NEXT_TRANSACTION            246697
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                       2052512
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.33528 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33528 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33528 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33528 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 ---
:241: 
:242: mscs-test-161.budzdorov.ru    Mon Nov  8 16:38:03 2021
:243:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:244: 
:245: 
:246: mscs-test-161.budzdorov.ru    Mon Nov  8 16:38:04 2021
:247:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:248: 
:249: 
:250: mscs-test-161.budzdorov.ru    Mon Nov  8 16:38:04 2021
:251:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:252: 
:253: 
:254: mscs-test-161.budzdorov.ru    Mon Nov  8 16:38:08 2021
:255:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:256: 
:257: 
:258: mscs-test-161.budzdorov.ru    Mon Nov  8 16:38:08 2021
:259:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:260: 
:261: 
:262: mscs-test-161.budzdorov.ru    Mon Nov  8 16:38:08 2021
:263:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:264: 
--- end of diff output ---

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

08.11.21 16:39:19. 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-11-08 16:39:22 No severe PSQL-related problems occured
08.11.21 16:39:23. End of report.