Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.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 16175176 kB
MemAvailable 30859516 kB
Buffers 0 kB
Cached 15536988 kB
SwapCached 416 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-14 19:37:50
DTS_END                         2021-09-14 19:37:50.4720
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/55242
TRN_ID                          246839
ATT_ID                          650
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: 9125.98 18124 1095118
customer order: creation 183.67 365 22040
customer order: refuse 15.48 344 1857
order to supplier: creation 47.55 1321 5706
order to supplier: removal 3.53 96199 424
invoice (draft): creation 47.12 3245 5654
invoice (draft): removal 50.95 773 6114
invoice accept: apply 43.88 18512 5265
invoice accept: cancel 45.66 857 5479
customer reserve: creation 3949.46 187 473935
customer reserve: removal 4434.96 61 532195
realization accept: apply 87.38 168 10485
realization accept: cancel 78.77 118 9452
payment from customer: creation 62.88 129 7546
payment from customer: removal 6.08 78 729
payment to supplier: creation 51.58 140 6190
payment to supplier: removal 3.99 86 479
service: total inventory turnovers 5.03 1388 603
service: total monetary turnovers 7.17 34 860
service: aggregate perf. data 0.84 24721 101
service: refresh index statistics 0.03 213745 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-09-2021 19:40:15 to 14-09-2021 19:40:17.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3878
TEST_TIME 2 3430
TEST_TIME 3 4284
TEST_TIME 4 4714
TEST_TIME 5 4535
TEST_TIME 6 4592
TEST_TIME 7 4860
TEST_TIME 8 4825
TEST_TIME 9 5026
TEST_TIME 10 5543
TEST_TIME 11 6069
TEST_TIME 12 6532
TEST_TIME 13 6780
TEST_TIME 14 6814
TEST_TIME 15 6923
TEST_TIME 16 7166
TEST_TIME 17 7486
TEST_TIME 18 7551
TEST_TIME 19 8326
TEST_TIME 20 8487
TEST_TIME 21 8352
TEST_TIME 22 8702
TEST_TIME 23 9182
TEST_TIME 24 9168
TEST_TIME 25 9309
TEST_TIME 26 9443
TEST_TIME 27 9596
TEST_TIME 28 9693
TEST_TIME 29 10260
TEST_TIME 30 10858
TEST_TIME 31 10697
TEST_TIME 32 10726
TEST_TIME 33 10486
TEST_TIME 34 10319
TEST_TIME 35 10270
TEST_TIME 36 10367
TEST_TIME 37 10364
TEST_TIME 38 10236
TEST_TIME 39 10099
TEST_TIME 40 9976
TEST_TIME 41 9830
TEST_TIME 42 9895
TEST_TIME 43 9775
TEST_TIME 44 9736
TEST_TIME 45 9667
TEST_TIME 46 9681
TEST_TIME 47 9575
TEST_TIME 48 9506
TEST_TIME 49 9477
TEST_TIME 50 9378
TEST_TIME 51 9357
TEST_TIME 52 9393
TEST_TIME 53 9370
TEST_TIME 54 9542
TEST_TIME 55 9548
TEST_TIME 56 9556
TEST_TIME 57 9569
TEST_TIME 58 9590
TEST_TIME 59 9679
TEST_TIME 60 9706
TEST_TIME 61 9703
TEST_TIME 62 9677
TEST_TIME 63 9766
TEST_TIME 64 9709
TEST_TIME 65 9679
TEST_TIME 66 9590
TEST_TIME 67 9616
TEST_TIME 68 9578
TEST_TIME 69 9497
TEST_TIME 70 9507
TEST_TIME 71 9487
TEST_TIME 72 9438
TEST_TIME 73 9448
TEST_TIME 74 9410
TEST_TIME 75 9363
TEST_TIME 76 9320
TEST_TIME 77 9336
TEST_TIME 78 9336
TEST_TIME 79 9313
TEST_TIME 80 9379
TEST_TIME 81 9340
TEST_TIME 82 9402
TEST_TIME 83 9456
TEST_TIME 84 9445
TEST_TIME 85 9391
TEST_TIME 86 9466
TEST_TIME 87 9455
TEST_TIME 88 9529
TEST_TIME 89 9532
TEST_TIME 90 9489
TEST_TIME 91 9417
TEST_TIME 92 9368
TEST_TIME 93 9340
TEST_TIME 94 9351
TEST_TIME 95 9313
TEST_TIME 96 9294
TEST_TIME 97 9255
TEST_TIME 98 9199
TEST_TIME 99 9149
TEST_TIME 100 9196
TEST_TIME 101 9218
TEST_TIME 102 9212
TEST_TIME 103 9212
TEST_TIME 104 9216
TEST_TIME 105 9276
TEST_TIME 106 9241
TEST_TIME 107 9289
TEST_TIME 108 9253
TEST_TIME 109 9260
TEST_TIME 110 9269
TEST_TIME 111 9375
TEST_TIME 112 9422
TEST_TIME 113 9411
TEST_TIME 114 9340
TEST_TIME 115 9283
TEST_TIME 116 9274
TEST_TIME 117 9233
TEST_TIME 118 9197
TEST_TIME 119 9172
TEST_TIME 120 9136

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-09-2021 19:40:19 to 14-09-2021 19:40:22.

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 1534868 1534868 0 0.00 0 34040 7 0 0
doc_list_biud 1534863 1534863 0 0.00 0 65321 27 0 0
sp_add_invoice_to_stock 5408 5265 143 2.64 38 198765 18513 0 143
sp_cancel_adding_invoice 5479 5479 0 0.00 5 50780 857 0 0
sp_cancel_client_order 1857 1857 0 0.00 4 9954 344 0 0
sp_cancel_customer_reserve 532195 532195 0 0.00 1 65324 62 0 0
sp_cancel_pay_from_customer 729 729 0 0.00 2 5656 79 0 0
sp_cancel_pay_to_supplier 479 479 0 0.00 3 4248 87 0 0
sp_cancel_supplier_invoice 6128 6114 14 0.22 2 42772 774 0 14
sp_cancel_supplier_order 424 424 0 0.00 10 1083424 96199 0 0
sp_cancel_write_off 9452 9452 0 0.00 1 19062 118 0 0
sp_client_order 22040 22040 0 0.00 4 66974 365 0 0
sp_customer_reserve 473935 473935 0 0.00 0 44004 188 0 0
sp_fill_shopping_cart 33392 33392 0 0.00 1 12043 40 0 0
sp_fill_shopping_cart_clo_res 473921 473921 0 0.00 0 18180 14 0 0
sp_get_clo_for_invoice 5247 5247 0 0.00 0 29233 332 0 0
sp_kill_cost_storno 16139 16139 0 0.00 0 9381 56 0 0
sp_kill_qstorno_ret_qs2qd 1080380 1080380 0 0.00 0 65311 33 0 0
sp_kill_qty_storno 571253 571253 0 0.00 0 65321 72 0 0
sp_lock_selected_doc 21821 21821 0 0.00 0 12211 6 0 0
sp_make_cost_storno 29468 29468 0 0.00 0 34025 51 0 0
sp_multiply_rows_for_pdistr 29468 29468 0 0.00 0 7563 29 0 0
sp_multiply_rows_for_qdistr 22040 22040 0 0.00 0 46369 156 0 0
sp_pay_from_customer 7546 7546 0 0.00 4 24777 129 0 0
sp_pay_to_supplier 6190 6190 0 0.00 5 15711 141 0 0
sp_payment_common 13736 13736 0 0.00 1 12776 30 0 0
sp_qd_handle_on_cancel_clo 1857 1857 0 0.00 0 9931 318 0 0
sp_qd_handle_on_invoice_upd_sts 10726 10726 0 0.00 0 17894 320 0 0
sp_qd_handle_on_reserve_upd_sts 10485 10485 0 0.00 0 11912 54 0 0
sp_reserve_write_off 10485 10485 0 0.00 6 13116 169 0 0
sp_supplier_invoice 5654 5654 0 0.00 5 87683 3245 0 0
sp_supplier_order 5706 5706 0 0.00 5 39487 1322 0 0
srv_aggregate_perf_data 101 101 0 0.00 2653 392442 24721 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1819 144419 41664 0 0
srv_find_qd_qs_mism 1045597 1045597 0 0.00 0 23428 6 0 0
srv_make_invnt_saldo 603 603 0 0.00 38 96931 1389 0 0
srv_make_money_saldo 860 860 0 0.00 1 3365 34 0 0
srv_recalc_idx_stat 4 4 0 0.00 97156 318138 213745 0 0
t$perf-abend:sp_add_invoice_to_stock 143 143 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 5266 5266 0 0.00 0 14994 168 0 0
t$perf-norm:sp_cancel_adding_invoice 430 430 0 0.00 0 3691 95 0 0
t$perf-norm:sp_cancel_client_order 1857 1857 0 0.00 0 4358 12 0 0
t$perf-norm:sp_cancel_customer_reserve 715 715 0 0.00 0 1637 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 729 729 0 0.00 0 459 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 479 479 0 0.00 0 3312 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 447 447 0 0.00 0 3335 24 0 0
t$perf-norm:sp_cancel_supplier_order 424 424 0 0.00 0 22728 1428 0 0
t$perf-norm:sp_cancel_write_off 1008 1008 0 0.00 0 1360 6 0 0
t$perf-norm:sp_client_order 22040 22040 0 0.00 0 10533 17 0 0
t$perf-norm:sp_customer_reserve 1068 1068 0 0.00 0 2247 6 0 0
t$perf-norm:sp_pay_from_customer 7546 7546 0 0.00 0 5274 11 0 0
t$perf-norm:sp_pay_to_supplier 6190 6190 0 0.00 0 5655 11 0 0
t$perf-norm:sp_reserve_write_off 10485 10485 0 0.00 0 6655 11 0 0
t$perf-norm:sp_supplier_invoice 5654 5654 0 0.00 0 14933 24 0 0
t$perf-norm:sp_supplier_order 5706 5706 0 0.00 0 2668 15 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 1031 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 297 5 0 0
t$perf-norm:srv_make_invnt_saldo 603 603 0 0.00 0 1245 23 0 0
t$perf-norm:srv_make_money_saldo 860 860 0 0.00 0 1323 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 2 2 0 0
v_add_invoice_to_stock 10780 10494 286 2.65 0 6039 20 0 286
v_all_customers 39870 39870 0 0.00 0 16 1 0 0
v_all_suppliers 31228 31228 0 0.00 0 21 1 0 0
v_all_wares 44080 44080 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 860 860 0 0.00 0 2435 10 0 0
v_cancel_client_order 3714 3714 0 0.00 0 872 3 0 0
v_cancel_customer_prepayment 1458 1458 0 0.00 0 5629 9 0 0
v_cancel_customer_reserve 1430 1430 0 0.00 0 718 4 0 0
v_cancel_payment_to_supplier 958 958 0 0.00 0 4174 11 0 0
v_cancel_supplier_invoice 922 894 28 3.03 0 5115 20 0 28
v_cancel_supplier_order 848 848 0 0.00 0 2214 8 0 0
v_cancel_write_off 2016 2016 0 0.00 0 2316 14 0 0
v_max_id_clo_ord 5706 5706 0 0.00 0 3549 2 0 0
v_max_id_clo_res 1068 1068 0 0.00 0 1894 9 0 0
v_max_id_ord_sup 5646 5646 0 0.00 0 3211 3 0 0
v_max_non_paid_invoice 1928 1928 0 0.00 0 304 2 0 0
v_max_non_paid_realizn 4095 4095 0 0.00 0 3165 5 0 0
v_min_id_clo_ord 5706 5706 0 0.00 0 2817 4 0 0
v_min_id_clo_res 1068 1068 0 0.00 0 9210 24 0 0
v_min_id_ord_sup 5646 5646 0 0.00 0 5286 8 0 0
v_min_non_paid_invoice 6190 6190 0 0.00 0 12773 17 0 0
v_min_non_paid_realizn 7546 7546 0 0.00 0 11359 21 0 0
v_our_firm 11112 11112 0 0.00 0 17 1 0 0
v_reserve_write_off 20970 20970 0 0.00 0 4528 6 0 0
x_lock_depdocs_on_canc_invoice 11146 11146 0 0.00 0 17450 124 0 0
x_lock_depdocs_on_canc_sup_ord 424 424 0 0.00 0 4967 328 0 0
x_make_qstorno_1000_1200 5706 5706 0 0.00 2 39470 1237 0 0
x_make_qstorno_1200_2000 5654 5654 0 0.00 2 78638 3097 0 0
x_make_qstorno_2100_3300 473935 473935 0 0.00 0 44001 171 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-09-2021 19:40:23 to 14-09-2021 19:40:27.

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:54 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
17:08:55 23097 8192000000 10362572944 10680238080 1419819328 14.771 100 91 183 0 1456674064 6548368 30306368
17:10:24 24028 8192000000 10411368976 10740027392 1462864784 15.151 100 90 180 0 1500881984 8522816 29494384
17:11:50 15132 8192000000 10481224048 10818805760 1535869696 15.788 100 93 187 0 1575091888 9455120 29767072
17:13:10 15155 8192000000 10546526704 10903797760 1596327680 16.308 100 73 143 2 1628112512 9943968 21840864
17:14:31 7309 8192000000 10647102080 11018391552 1706063296 17.236 100 75 150 1 1740113808 12373072 21677440
17:15:41 5570 8192000000 10710831456 11088220160 1772319552 17.786 100 82 164 0 1809285376 13744656 23221168
17:16:48 2888 8192000000 10761197248 11140448256 1816972176 18.153 100 84 163 1 1852142320 12688896 22481248
17:17:52 2773 8192000000 10794342640 11179839488 1850679152 18.428 100 80 159 0 1885569824 12302640 22588032
17:18:58 1579 8192000000 10813365200 11209711616 1874647824 18.622 100 78 156 2 1909785856 13156960 21981072
17:20:03 5362 8192000000 10803201184 11214696448 1879216512 18.659 100 49 97 4 1902171456 9614576 13340368
17:21:11 7247 8192000000 10798348032 11230666752 1893518736 18.774 100 20 40 0 1905783152 6621536 5642880
17:22:20 32921 8192000000 10812020656 11244982272 1897369968 18.805 100 26 52 1 1909800112 5333920 7096224
17:23:53 6352 8192000000 10813915536 11247104000 1903609344 18.855 100 29 58 0 1918806192 6512096 8684752
17:25:02 6580 8192000000 10828828672 11256782848 1908794848 18.897 100 19 38 1 1919682336 5687904 5199584
17:26:10 64466 8192000000 10835114800 11255504896 1883879120 18.696 98 37 74 0 1900264944 5419920 10965904
17:28:15 5170 8192000000 10792228176 11220635648 1849244064 18.416 98 23 46 0 1862216656 6186144 6786448
17:29:21 8469 8192000000 10746314064 11172352000 1773557536 17.796 95 30 58 4 1791457856 10088416 7811904
17:30:30 57698 8192000000 10709830512 11110400000 1672146400 16.951 92 24 47 0 1683374816 4418320 6810096
17:32:32 3855 8192000000 10712690848 11120054272 1781374368 17.861 99 32 61 1 1796416064 6217952 8823744
17:33:38 9399 8192000000 10736184272 11156590592 1808870368 18.087 99 18 36 3 1819655664 5836624 4948672
17:34:48 12832 8192000000 10774209952 11204136960 1857799488 18.485 100 12 24 3 1866824464 4977408 4047568
17:36:03 39446 8192000000 10781463664 11214434304 1867729184 18.566 100 21 42 1 1879623920 5631344 6263392
17:37:43 5552 8192000000 10830595872 11254001664 1910346448 18.909 100 30 60 1 1924196096 5158720 8690928
17:38:57 30843 8192000000 10867107008 11289440256 1936098160 19.116 100 44 87 2 1957396032 8751424 12546448
17:40:31 11764 8192000000 10888171088 11314864128 1953801472 19.257 100 55 110 1 1982151280 13138720 15211088
17:41:47 11435 8192000000 10906390736 11336343552 1969112704 19.378 100 61 120 1 2004300704 18116592 17071408
17:43:01 15566 8192000000 10910749024 11344343040 1974304704 19.420 100 39 78 1 2000604656 15662480 10637472
17:44:20 2683 8192000000 10938514352 11362557952 1991633488 19.557 100 62 124 0 2027830480 18392304 17804688
17:45:28 9809 8192000000 10946268640 11365748736 1993841856 19.574 100 59 116 2 2029938656 19558992 16537808
17:46:46 8051 8192000000 10949723984 11368730624 1978985728 19.457 99 66 132 4 2021512304 23357152 19169424
17:47:58 1817 8192000000 10972848704 11393589248 2008556048 19.690 100 72 141 2 2057692000 27806512 21329440
17:49:04 2733 8192000000 10972317264 11398332416 2006762320 19.676 100 65 129 0 2054368976 28164048 19442608
17:50:15 10663 8192000000 10939144208 11379695616 1968164224 19.371 98 55 108 0 2010977648 26381856 16431568
17:51:47 9081 8192000000 10949338176 11382706176 1975557600 19.430 99 49 98 0 2028952064 38258288 15136176
17:53:01 11662 8192000000 10951640064 11379388416 1962112656 19.323 98 59 113 1 2013712304 33144032 18455616
17:54:18 3556 8192000000 10946624816 11357782016 1966078944 19.354 100 84 167 2 2029701680 37571584 26051152
17:55:28 3888 8192000000 10929492560 11339255808 1933625504 19.096 99 85 168 1 2001754544 41232928 26896112
17:56:40 8239 8192000000 10928547200 11363733504 1957637584 19.287 100 49 98 0 2014448352 41247968 15562800
17:57:53 11885 8192000000 10901990080 11345121280 1951235472 19.236 100 45 90 3 2000093584 34053744 14804368
17:59:17 6776 8192000000 10953662144 11396771840 1985867792 19.511 100 46 92 1 2043093632 42195504 15030336
18:00:26 11444 8192000000 10949430304 11394297856 1970931296 19.393 99 26 52 0 2015388112 35818672 8638144
18:01:47 11183 8192000000 10958500016 11402240000 1986867472 19.519 100 34 68 1 2038884464 40978976 11038016
18:03:01 16253 8192000000 10945014032 11398860800 1967169792 19.363 99 26 52 1 2018503776 43256048 8077936
18:04:24 9044 8192000000 10921007424 11369070592 1929060896 19.059 98 29 58 1 1977292096 37957968 10273232
18:05:35 29414 8192000000 10943009280 11404378112 1968149888 19.371 100 20 40 1 2032229280 57067696 7011696
18:07:07 57461 8192000000 10912416064 11371683840 1955102256 19.267 99 10 20 0 1988793968 30727600 2964112
18:09:17 31205 8192000000 10913110224 11362525184 1957431696 19.286 99 18 36 0 1971315200 9099152 4784352
18:10:49 24107 8192000000 10923153872 11366875136 1978399216 19.452 99 50 102 1 1999225152 7288976 13536960
18:12:17 39647 8192000000 10915430688 11382001664 1985804000 19.511 100 28 56 1 2005277824 11382256 8091568
18:14:01 25831 8192000000 10889625472 11356975104 1922917056 19.010 97 22 44 4 1940756864 11821728 6018080
18:15:34 45170 8192000000 10881044576 11357761536 1931061808 19.075 98 6 13 0 1942861552 10111360 1688384
18:17:20 55696 8192000000 10911532672 11370356736 1979114096 19.458 100 30 59 2 1995020640 8286448 7620096
18:19:16 84310 8192000000 10889875840 11354787840 1936609376 19.120 98 18 36 1 1955117520 13164144 5344000
18:21:40 81830 8192000000 10883458880 11356209152 1953423104 19.254 99 15 30 0 1966021008 8730272 3867632
18:24:07 34339 8192000000 10901167504 11372584960 1978316848 19.451 100 14 28 0 1993527856 11447296 3763712
18:25:48 37635 8192000000 10930768192 11408375808 2009592176 19.698 100 37 74 2 2037791024 17030592 11168256
18:27:33 86851 8192000000 10914261808 11385810944 1943242944 19.173 97 23 46 0 1972232336 21689232 7300160
18:30:27 24475 8192000000 10872304768 11342573568 1915343888 18.950 97 20 40 1 1941206352 19572272 6290192
18:31:53 110693 8192000000 10896165472 11350208512 1853843840 18.453 94 19 38 2 1878210624 19066320 5300464
18:34:44 48277 8192000000 10877313408 11339075584 1930446688 19.070 99 23 45 1 1954274512 16254816 7573008
18:36:40 60685 8192000000 10885528752 11361165312 1917353888 18.966 98 16 32 1 1940954528 19056496 4544144
18:38:45 124721 8192000000 10869370144 11346997248 1835808320 18.307 93 16 32 2 1853614032 13261632 4544080
18:41:52 44011 8192000000 10884127936 11329114112 1896894512 18.801 97 33 66 1 1917195328 11288128 9012688
18:43:38 70485 8192000000 10863245920 11330297856 1890249984 18.748 97 27 54 0 1904398496 6246976 7901536
18:45:49 43079 8192000000 10856147120 11314425856 1910852832 18.913 98 28 56 0 1928048640 8747536 8448272
18:47:34 48484 8192000000 10876949168 11348066304 1955932224 19.274 100 15 30 0 1968705808 8557072 4216512
18:50:06 28782 8192000000 10879662832 11354525696 1950119440 19.227 99 15 30 1 1962162912 7896432 4147040
18:52:09 45967 8192000000 10859980016 11344650240 1930656240 19.072 98 10 20 0 1941751536 8074432 3020864
18:54:21 51516 8192000000 10874390032 11353825280 1935515408 19.111 99 17 32 1 1953869424 13738096 4615920
18:56:17 52881 8192000000 10871368832 11347099648 1924201248 19.020 98 29 58 1 1945809392 12408992 9199152
18:58:12 25437 8192000000 10906277136 11366318080 1966961536 19.361 100 30 60 1 1984909104 9374544 8573024
18:59:37 67864 8192000000 10884164928 11370205184 1927092144 19.044 98 12 24 1 1946186992 16465568 2629280
19:01:47 48051 8192000000 10898761872 11357712384 1951940960 19.242 99 23 46 2 1970479184 12038704 6499520
19:03:40 50938 8192000000 10901518144 11367706624 1952454304 19.246 99 14 27 1 1975588880 18922672 4211904
19:05:34 53250 8192000000 10903616128 11383922688 1946868064 19.202 99 24 46 1 1967528192 14081792 6578336
19:07:33 118957 8192000000 10895709360 11360194560 1945606576 19.191 98 31 62 1 1962315904 7908896 8800432
19:10:32 55384 8192000000 10907200848 11383824384 1984441344 19.500 100 15 30 0 1998036528 9169856 4425328
19:12:34 33009 8192000000 10900065952 11384459264 1987793040 19.526 100 4 8 0 1997421728 8606512 1022176
19:14:07 58647 8192000000 10914882768 11388203008 1958951808 19.298 98 18 36 2 1974673824 10469008 5253008
19:16:18 95506 8192000000 10890048784 11374944256 1914133744 18.940 97 9 18 0 1931680976 14902960 2644272
19:18:58 22653 8192000000 10862658848 11325988864 1882183648 18.683 96 25 48 1 1895552624 6575488 6793488
19:20:28 34407 8192000000 10885552192 11352100864 1945074128 19.187 99 24 48 0 1961677472 9718672 6884672
19:22:04 48999 8192000000 10886478288 11363450880 1930026592 19.067 98 17 34 3 1947905312 12956768 4921952
19:23:53 49058 8192000000 10872209536 11340902400 1929838480 19.066 99 41 82 0 1952761712 11496032 11427200
19:25:52 59727 8192000000 10869102864 11330883584 1839764400 18.339 94 36 72 0 1866126256 16341408 10020448
19:27:53 98753 8192000000 10850386384 11325444096 1859048176 18.496 96 12 24 1 1880587984 17721600 3818208
19:30:34 144418 8192000000 10867250112 11337531392 1945683776 19.192 100 18 36 0 1958153216 7764672 4704768
19:33:59 42318 8192000000 10854562992 11333292032 1928383024 19.054 99 8 16 0 1938573792 7722432 2468336
19:37:10 46360 8192000000 10295336048 10707189760 1369115808 14.319 69 16 32 1 1379027264 5230272 4681184

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 14-09-2021 19:40:29 to 14-09-2021 19:40:44.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 143 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 14-09-2021 19:40:45 to 14-09-2021 19:40:47.

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          247423
MON$OLDEST_ACTIVE               247424
MON$OLDEST_SNAPSHOT             247424
MON$NEXT_TRANSACTION            247424
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                       2027424
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.33495 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33495 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33495 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33495 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 ---
--- end of diff output ---

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

14.09.21 19:40:48. 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-14 19:40:50 No severe PSQL-related problems occured
14.09.21 19:40:51. End of report.