Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.10.2022 22: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 2a7b53d1c5ed44b9bb2712fffac5d8c6
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-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
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 15472992 kB
MemAvailable 30539216 kB
Buffers 0 kB
Cached 16221464 kB
SwapCached 2116 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.11
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 2022-10-04 22:38:45
DTS_END                         2022-10-04 22:38:26.3500
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/58700
TRN_ID                          246964
ATT_ID                          274
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: 9503.75 23356 1140450
customer order: creation 176.58 370 21190
customer order: refuse 15.00 378 1800
order to supplier: creation 46.51 1443 5581
order to supplier: removal 3.49 84371 419
invoice (draft): creation 46.70 3465 5604
invoice (draft): removal 55.49 654 6659
invoice accept: apply 41.98 16549 5038
invoice accept: cancel 49.86 754 5983
customer reserve: creation 3834.88 164 460186
customer reserve: removal 4929.40 46 591528
realization accept: apply 86.03 193 10323
realization accept: cancel 86.28 106 10354
payment from customer: creation 60.53 103 7264
payment from customer: removal 6.26 82 751
payment to supplier: creation 49.10 133 5892
payment to supplier: removal 3.53 60 423
service: total inventory turnovers 4.91 1069 589
service: total monetary turnovers 6.36 33 763
service: aggregate perf. data 0.83 23102 99
service: refresh index statistics 0.03 334050 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 04-10-2022 22:40:04 to 04-10-2022 22:40:06.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3395
TEST_TIME 2 4376
TEST_TIME 3 4888
TEST_TIME 4 5497
TEST_TIME 5 5479
TEST_TIME 6 5423
TEST_TIME 7 6553
TEST_TIME 8 6451
TEST_TIME 9 6168
TEST_TIME 10 5991
TEST_TIME 11 6043
TEST_TIME 12 6248
TEST_TIME 13 6435
TEST_TIME 14 6717
TEST_TIME 15 6929
TEST_TIME 16 7202
TEST_TIME 17 7502
TEST_TIME 18 7634
TEST_TIME 19 7722
TEST_TIME 20 8467
TEST_TIME 21 8910
TEST_TIME 22 9101
TEST_TIME 23 9218
TEST_TIME 24 9292
TEST_TIME 25 9701
TEST_TIME 26 9968
TEST_TIME 27 10351
TEST_TIME 28 10362
TEST_TIME 29 10298
TEST_TIME 30 10012
TEST_TIME 31 9786
TEST_TIME 32 10136
TEST_TIME 33 10075
TEST_TIME 34 9915
TEST_TIME 35 9872
TEST_TIME 36 9883
TEST_TIME 37 9914
TEST_TIME 38 9772
TEST_TIME 39 9698
TEST_TIME 40 9673
TEST_TIME 41 9732
TEST_TIME 42 9621
TEST_TIME 43 9602
TEST_TIME 44 9595
TEST_TIME 45 9488
TEST_TIME 46 9451
TEST_TIME 47 9329
TEST_TIME 48 9487
TEST_TIME 49 9557
TEST_TIME 50 9570
TEST_TIME 51 9495
TEST_TIME 52 9675
TEST_TIME 53 9652
TEST_TIME 54 9627
TEST_TIME 55 9665
TEST_TIME 56 9728
TEST_TIME 57 9658
TEST_TIME 58 9698
TEST_TIME 59 9821
TEST_TIME 60 9880
TEST_TIME 61 9725
TEST_TIME 62 9644
TEST_TIME 63 9548
TEST_TIME 64 9524
TEST_TIME 65 9569
TEST_TIME 66 9563
TEST_TIME 67 9498
TEST_TIME 68 9550
TEST_TIME 69 9567
TEST_TIME 70 9552
TEST_TIME 71 9528
TEST_TIME 72 9513
TEST_TIME 73 9727
TEST_TIME 74 9673
TEST_TIME 75 9791
TEST_TIME 76 9776
TEST_TIME 77 9769
TEST_TIME 78 9792
TEST_TIME 79 9866
TEST_TIME 80 9845
TEST_TIME 81 9742
TEST_TIME 82 9762
TEST_TIME 83 9732
TEST_TIME 84 9706
TEST_TIME 85 9696
TEST_TIME 86 9629
TEST_TIME 87 9620
TEST_TIME 88 9576
TEST_TIME 89 9593
TEST_TIME 90 9535
TEST_TIME 91 9492
TEST_TIME 92 9504
TEST_TIME 93 9523
TEST_TIME 94 9571
TEST_TIME 95 9567
TEST_TIME 96 9577
TEST_TIME 97 9616
TEST_TIME 98 9570
TEST_TIME 99 9626
TEST_TIME 100 9673
TEST_TIME 101 9694
TEST_TIME 102 9703
TEST_TIME 103 9725
TEST_TIME 104 9787
TEST_TIME 105 9847
TEST_TIME 106 9830
TEST_TIME 107 9784
TEST_TIME 108 9710
TEST_TIME 109 9732
TEST_TIME 110 9697
TEST_TIME 111 9648
TEST_TIME 112 9666
TEST_TIME 113 9620
TEST_TIME 114 9572
TEST_TIME 115 9549
TEST_TIME 116 9588
TEST_TIME 117 9558
TEST_TIME 118 9588
TEST_TIME 119 9577
TEST_TIME 120 9507

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 04-10-2022 22:40:08 to 04-10-2022 22:40:11.

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 1566691 1566691 0 0.00 0 19493 6 0 0
doc_list_biud 1566689 1566689 0 0.00 0 33785 23 0 0
sp_add_invoice_to_stock 5162 5038 124 2.40 24 147798 16550 0 124
sp_cancel_adding_invoice 5983 5983 0 0.00 5 50689 754 0 0
sp_cancel_client_order 1800 1800 0 0.00 4 11838 378 0 0
sp_cancel_customer_reserve 591528 591528 0 0.00 0 26461 46 0 0
sp_cancel_pay_from_customer 751 751 0 0.00 2 7047 82 0 0
sp_cancel_pay_to_supplier 423 423 0 0.00 3 2911 60 0 0
sp_cancel_supplier_invoice 6673 6659 14 0.20 1 23430 654 0 14
sp_cancel_supplier_order 419 419 0 0.00 7 847748 84372 0 0
sp_cancel_write_off 10354 10354 0 0.00 1 9225 106 0 0
sp_client_order 21190 21190 0 0.00 3 18106 371 0 0
sp_customer_reserve 460186 460186 0 0.00 0 80120 165 0 0
sp_fill_shopping_cart 32370 32370 0 0.00 1 11320 36 0 0
sp_fill_shopping_cart_clo_res 460183 460183 0 0.00 0 12288 14 0 0
sp_get_clo_for_invoice 5034 5034 0 0.00 0 29871 306 0 0
sp_kill_cost_storno 17511 17511 0 0.00 0 8443 51 0 0
sp_kill_qstorno_ret_qs2qd 1200488 1200488 0 0.00 0 26457 25 0 0
sp_kill_qty_storno 632099 632099 0 0.00 0 33784 56 0 0
sp_lock_selected_doc 21309 21309 0 0.00 0 12227 7 0 0
sp_make_cost_storno 28512 28512 0 0.00 0 15783 53 0 0
sp_multiply_rows_for_pdistr 28511 28511 0 0.00 0 10889 27 0 0
sp_multiply_rows_for_qdistr 21190 21190 0 0.00 0 13228 158 0 0
sp_pay_from_customer 7264 7264 0 0.00 4 15293 103 0 0
sp_pay_to_supplier 5892 5892 0 0.00 4 17172 134 0 0
sp_payment_common 13155 13155 0 0.00 2 11497 26 0 0
sp_qd_handle_on_cancel_clo 1800 1800 0 0.00 0 11833 348 0 0
sp_qd_handle_on_invoice_upd_sts 11016 11016 0 0.00 0 33782 322 0 0
sp_qd_handle_on_reserve_upd_sts 10323 10323 0 0.00 0 14392 74 0 0
sp_reserve_write_off 10323 10323 0 0.00 6 15132 194 0 0
sp_supplier_invoice 5604 5604 0 0.00 5 99445 3465 0 0
sp_supplier_order 5581 5581 0 0.00 4 45863 1444 0 0
srv_aggregate_perf_data 100 99 1 1.00 2788 623830 23102 0 1
srv_fill_mon_cache_memory 63 63 0 0.00 1774 146047 47597 0 0
srv_find_qd_qs_mism 1092233 1092233 0 0.00 0 19486 5 0 0
srv_make_invnt_saldo 589 589 0 0.00 53 25488 1069 0 0
srv_make_money_saldo 763 763 0 0.00 1 4296 33 0 0
srv_recalc_idx_stat 4 4 0 0.00 150287 553593 334051 0 0
t$perf-abend:sp_add_invoice_to_stock 124 124 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-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5039 5039 0 0.00 0 13809 177 0 0
t$perf-norm:sp_cancel_adding_invoice 378 378 0 0.00 0 4740 133 0 0
t$perf-norm:sp_cancel_client_order 1800 1800 0 0.00 0 5562 18 0 0
t$perf-norm:sp_cancel_customer_reserve 673 673 0 0.00 0 2907 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 751 751 0 0.00 0 333 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 423 423 0 0.00 0 374 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 454 454 0 0.00 0 3093 24 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 25549 1575 0 0
t$perf-norm:sp_cancel_write_off 1056 1056 0 0.00 0 11165 22 0 0
t$perf-norm:sp_client_order 21190 21190 0 0.00 0 7361 17 0 0
t$perf-norm:sp_customer_reserve 1055 1055 0 0.00 0 734 4 0 0
t$perf-norm:sp_pay_from_customer 7264 7264 0 0.00 0 4241 10 0 0
t$perf-norm:sp_pay_to_supplier 5892 5892 0 0.00 0 5109 14 0 0
t$perf-norm:sp_reserve_write_off 10323 10323 0 0.00 0 15560 15 0 0
t$perf-norm:sp_supplier_invoice 5604 5604 0 0.00 0 10372 19 0 0
t$perf-norm:sp_supplier_order 5581 5581 0 0.00 0 5864 16 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 299 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 518 20 0 0
t$perf-norm:srv_make_invnt_saldo 589 589 0 0.00 0 2188 23 0 0
t$perf-norm:srv_make_money_saldo 763 763 0 0.00 0 710 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 19 9 0 0
v_add_invoice_to_stock 10314 10066 248 2.40 0 8864 22 0 248
v_all_customers 38060 38060 0 0.00 0 84 1 0 0
v_all_suppliers 30412 30412 0 0.00 0 60 1 0 0
v_all_wares 42378 42378 0 0.00 0 74 1 0 0
v_cancel_adding_invoice 756 756 0 0.00 0 692 8 0 0
v_cancel_client_order 3600 3600 0 0.00 0 5074 7 0 0
v_cancel_customer_prepayment 1502 1502 0 0.00 0 1778 7 0 0
v_cancel_customer_reserve 1346 1346 0 0.00 0 2403 9 0 0
v_cancel_payment_to_supplier 846 846 0 0.00 0 1055 7 0 0
v_cancel_supplier_invoice 936 908 28 2.99 0 3636 27 0 28
v_cancel_supplier_order 838 838 0 0.00 0 5187 12 0 0
v_cancel_write_off 2112 2112 0 0.00 0 1181 14 0 0
v_max_id_clo_ord 5578 5578 0 0.00 0 650 1 0 0
v_max_id_clo_res 1055 1055 0 0.00 0 1959 15 0 0
v_max_id_ord_sup 5601 5601 0 0.00 0 3276 2 0 0
v_max_non_paid_invoice 1864 1864 0 0.00 0 782 3 0 0
v_max_non_paid_realizn 4076 4076 0 0.00 0 408 3 0 0
v_min_id_clo_ord 5578 5578 0 0.00 0 8536 12 0 0
v_min_id_clo_res 1055 1055 0 0.00 0 5628 14 0 0
v_min_id_ord_sup 5601 5601 0 0.00 0 5669 5 0 0
v_min_non_paid_invoice 5891 5891 0 0.00 0 8117 19 0 0
v_min_non_paid_realizn 7264 7264 0 0.00 0 7810 18 0 0
v_our_firm 10694 10694 0 0.00 0 35 1 0 0
v_reserve_write_off 20644 20644 0 0.00 0 5412 7 0 0
x_lock_depdocs_on_canc_invoice 12188 12188 0 0.00 0 14420 109 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 16327 382 0 0
x_make_qstorno_1000_1200 5581 5581 0 0.00 1 45853 1364 0 0
x_make_qstorno_1200_2000 5604 5604 0 0.00 2 99436 3341 0 0
x_make_qstorno_2100_3300 460186 460186 0 0.00 0 80118 148 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 04-10-2022 22:40:12 to 04-10-2022 22:40:17.

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
20:08:30 3 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:31 16717 8192000000 9897662864 10204905472 979415008 10.678 100 90 180 0 1008583616 5495552 23673056
20:10:52 20710 8192000000 9942487088 10259329024 1028202928 11.151 100 91 182 0 1059581184 8121600 23256656
20:12:18 18505 8192000000 9961137280 10288992256 1046948048 11.331 100 89 178 0 1079080880 9372832 22760000
20:13:40 6417 8192000000 10008785648 10352226304 1099325328 11.831 100 72 144 0 1127146000 9615424 18205248
20:14:49 4765 8192000000 10072279984 10427105280 1159287120 12.397 100 80 160 0 1189947040 12127232 18532688
20:15:58 2804 8192000000 10120764240 10485829632 1208352800 12.854 100 82 164 1 1239650176 12777408 18519968
20:17:03 2006 8192000000 10157533232 10529275904 1247025072 13.211 100 88 176 0 1281866848 15214880 19626896
20:18:07 1123 8192000000 10173649040 10548428800 1261059280 13.340 100 90 178 0 1299083088 18523104 19500704
20:19:09 1410 8192000000 10200785344 10585120768 1291074352 13.614 100 86 172 0 1323264944 13254608 18935984
20:20:11 11246 8192000000 10193539552 10591928320 1302220064 13.715 100 36 72 1 1317963424 7833680 7909680
20:21:23 12731 8192000000 10189394784 10604900352 1307655264 13.765 100 19 38 0 1318086288 6181040 4249984
20:22:41 19858 8192000000 10206902416 10622234624 1312823360 13.812 100 38 76 1 1331400784 9906512 8670912
20:24:01 3799 8192000000 10212444736 10626449408 1320670192 13.883 100 32 64 0 1334406608 5997216 7739200
20:25:11 7582 8192000000 10222201632 10644492288 1331063136 13.977 100 17 34 0 1344776832 10339792 3373904
20:26:21 62181 8192000000 10190998176 10609553408 1281858112 13.530 96 24 48 0 1292238288 4786128 5594048
20:28:26 5225 8192000000 10209532960 10626981888 1299792800 13.693 98 29 58 3 1312828752 6300400 6735552
20:29:32 9693 8192000000 10189861136 10602323968 1297123936 13.669 100 17 34 1 1306545392 5380112 4041344
20:30:45 13104 8192000000 10159323328 10552487936 1245320240 13.195 98 39 75 1 1259830736 6118832 8391664
20:31:59 17713 8192000000 10129353840 10523242496 1190169328 12.685 95 19 38 0 1199795616 5801104 3825184
20:33:20 6130 8192000000 10184928896 10585513984 1269370256 13.416 99 32 63 0 1282875968 5897920 7607792
20:34:28 11947 8192000000 10194785024 10605035520 1298393344 13.681 100 17 34 0 1307977376 5663776 3920256
20:35:44 70859 8192000000 10200931504 10616602624 1309961280 13.786 100 37 69 2 1323957088 5903168 8092640
20:37:56 5513 8192000000 10209983360 10625613824 1317612960 13.855 100 31 62 0 1330558704 6543696 6402048
20:39:09 59538 8192000000 10260182336 10677649408 1354616208 14.189 100 33 66 0 1370365408 7489696 8259504
20:41:14 62129 8192000000 10263366592 10686144512 1359063424 14.229 99 43 86 0 1380062368 10726480 10272464
20:43:44 19558 8192000000 10292168944 10715467776 1378458944 14.403 100 49 96 1 1403897840 13503392 11935504
20:45:11 8786 8192000000 10293299344 10715561984 1380552880 14.421 100 46 92 2 1408810480 16980976 11276624
20:46:24 12174 8192000000 10313750608 10742689792 1400920432 14.603 100 39 77 0 1424318112 14158448 9239232
20:47:42 24193 8192000000 10323113792 10752790528 1409833824 14.682 100 38 76 1 1436549744 17805024 8910896
20:49:24 8761 8192000000 10327296032 10751655936 1404685872 14.637 100 50 100 0 1437877312 21106528 12084912
20:50:37 3739 8192000000 10311652176 10729189376 1382946736 14.443 99 61 118 1 1420166336 23164144 14055456
20:51:45 7022 8192000000 10326503680 10749009920 1397251168 14.571 100 58 115 0 1440216816 27603552 15362096
20:53:05 6208 8192000000 10331380640 10751504384 1385187472 14.463 99 63 126 0 1429927920 29635344 15105104
20:54:24 3895 8192000000 10322287296 10734608384 1380337984 14.420 99 70 139 2 1425188608 28011552 16839072
20:55:34 1772 8192000000 10327896688 10729598976 1373758192 14.361 100 85 166 3 1430022416 35905888 20358336
20:56:40 13949 8192000000 10275119984 10686558208 1322330384 13.898 98 48 96 2 1373187104 38097328 12759392
20:58:00 2322 8192000000 10310091808 10718543872 1351645152 14.162 100 69 130 1 1417071600 48100912 17325536
20:59:04 19014 8192000000 10262319328 10689253376 1335791984 14.019 100 29 58 0 1382738192 38719968 8226240
21:00:32 18347 8192000000 10265995040 10700894208 1345732896 14.109 100 28 55 0 1383108848 30913056 6462896
21:01:51 24287 8192000000 10284130080 10717290496 1332643568 13.991 98 19 38 1 1370230384 32631968 4954848
21:03:26 22482 8192000000 10295051680 10734432256 1370137632 14.328 100 20 40 1 1402674144 27455632 5080880
21:04:51 52494 8192000000 10278761296 10727407616 1367390320 14.304 100 16 32 2 1389610000 18201136 4018544
21:07:00 14892 8192000000 10298219280 10721005568 1368272640 14.312 100 65 129 2 1399139760 15145424 15721696
21:08:16 95722 8192000000 10289915488 10719514624 1360474000 14.242 100 26 52 1 1386068128 19377712 6216416
21:10:53 6829 8192000000 10297555936 10732920832 1382208640 14.436 100 68 135 1 1408003920 10784144 15011136
21:12:01 55984 8192000000 10307053168 10737164288 1376900448 14.389 99 39 79 0 1396107232 10132752 9074032
21:14:00 33049 8192000000 10295482304 10744442880 1383173776 14.445 99 51 100 0 1406239040 12340576 10724688
21:15:36 96147 8192000000 10308194096 10762080256 1369876480 14.326 98 18 36 0 1389983584 15643504 4463600
21:18:18 35389 8192000000 10310094944 10767949824 1403460416 14.626 100 10 20 0 1419863632 13723568 2679648
21:19:54 60851 8192000000 10318668016 10776104960 1406363712 14.652 99 27 54 0 1424209696 10885920 6960064
21:22:02 57292 8192000000 10313915600 10786099200 1413403584 14.714 100 17 33 0 1430394688 13919472 3071632
21:24:00 84064 8192000000 10319511376 10793111552 1412387616 14.705 100 18 36 0 1434253264 17440096 4425552
21:26:25 61442 8192000000 10323670800 10810896384 1419819712 14.771 100 12 24 0 1441036752 18162976 3054064
21:28:34 52731 8192000000 10318600976 10777776128 1389162192 14.498 98 15 30 0 1406797824 14311056 3324576
21:30:40 59786 8192000000 10288818288 10740682752 1331265504 13.979 95 13 26 0 1342607904 8756928 2585472
21:32:41 40116 8192000000 10291507792 10738896896 1356481024 14.206 98 40 80 1 1377900128 12327568 9091536
21:34:24 81325 8192000000 10255613344 10696060928 1243008576 13.174 90 15 30 0 1256892480 10046704 3837200
21:36:48 102313 8192000000 10256851616 10707156992 1212090768 12.888 89 13 25 0 1229804800 15273392 2440640
21:39:47 71331 8192000000 10261282736 10700750848 1288113424 13.587 95 31 58 0 1304355376 9378240 6863712
21:42:03 14706 8192000000 10260092032 10704433152 1350684768 14.154 100 34 66 2 1374197472 16401232 7111472
21:43:32 52921 8192000000 10252358992 10708590592 1350167856 14.149 99 15 30 0 1367416256 13592816 3655584
21:45:31 87949 8192000000 10260041680 10715836416 1330946816 13.976 98 13 26 0 1345791392 12006352 2838224
21:48:01 48670 8192000000 10282448496 10721157120 1352637584 14.171 99 26 53 0 1370080368 11135440 6307344
21:49:55 32495 8192000000 10304934064 10747858944 1375526320 14.377 100 45 89 1 1401793904 15958656 10308928
21:51:42 49106 8192000000 10287485712 10729361408 1347296752 14.123 98 27 53 2 1362348080 8800352 6250976
21:53:38 124842 8192000000 10279922688 10730680320 1310983968 13.795 95 19 38 0 1334667184 19399984 4283232
21:56:44 41626 8192000000 10302605648 10744647680 1377021360 14.390 100 34 66 0 1391422736 7463264 6938112
21:58:30 146047 8192000000 10306644992 10770120704 1399952752 14.595 100 26 52 1 1417074448 10383456 6738240
22:01:58 40486 8192000000 10292580272 10746748928 1362687376 14.261 98 30 60 1 1382917968 13512832 6717760
22:03:38 45227 8192000000 10279414768 10742616064 1377234640 14.392 99 13 26 0 1389299760 9083280 2981840
22:05:48 45293 8192000000 10287256256 10750332928 1383653152 14.449 99 20 39 1 1400213392 12223184 4337056
22:07:47 51556 8192000000 10292927392 10764742656 1396485904 14.564 100 16 32 0 1410149168 9560832 4102432
22:09:39 46287 8192000000 10305131680 10763931648 1387638960 14.485 99 19 37 0 1401345712 9907264 3799488
22:11:26 57143 8192000000 10310565248 10781888512 1393940496 14.541 99 11 22 0 1410792400 14670912 2180992
22:13:26 49249 8192000000 10311207072 10774679552 1385848608 14.469 98 20 40 0 1398966192 8899552 4218032
22:15:21 78690 8192000000 10307012416 10757398528 1327320224 13.943 94 17 35 0 1344194592 12730880 4143488
22:17:40 58333 8192000000 10288295056 10740768768 1331665792 13.982 95 13 25 0 1350779536 16493600 2620144
22:19:43 34091 8192000000 10303855232 10748616704 1366417248 14.295 98 30 59 0 1383442704 10215792 6809664
22:21:21 38883 8192000000 10310183968 10766049280 1359207984 14.230 97 30 60 0 1386324688 20220880 6895824
22:23:01 81694 8192000000 10290826304 10737483776 1309863392 13.785 94 8 14 0 1331396320 19909232 1623696
22:25:35 107308 8192000000 10252721104 10703175680 1322352496 13.898 97 24 46 0 1336729072 9231040 5145536
22:28:23 43201 8192000000 10262413088 10724253696 1358519984 14.224 100 11 22 0 1371408496 10651520 2236992
22:30:07 69247 8192000000 10272806160 10737496064 1345524144 14.107 98 19 38 0 1364486048 14218192 4743712
22:32:18 42788 8192000000 10291997696 10739277824 1355328512 14.195 98 19 39 0 1372296848 12121648 4846688
22:34:29 59543 8192000000 10276430688 10733088768 1372676096 14.351 100 12 24 0 1390329168 14586688 3066384
22:36:34 71265 8192000000 10261985024 10726678528 1335386000 14.016 97 8 16 0 1353188192 16011040 1791152

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 04-10-2022 22:40:18 to 04-10-2022 22:40:34.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 124 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 04-10-2022 22:40:35 to 04-10-2022 22:40:37.

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          245166
MON$OLDEST_ACTIVE               254121
MON$OLDEST_SNAPSHOT             254121
MON$NEXT_TRANSACTION            254121
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                       2032800
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33632 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33632 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33632 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33632 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 ---
:208: 
:209: mscs-test-161.budzdorov.ru    Tue Oct  4 22:38:47 2022
:210:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:211: 
--- end of diff output ---

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

04.10.22 22:40:38. 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
2022-10-04 22:40:40 No severe PSQL-related problems occured
04.10.22 22:40:42. End of report.