Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.12.2021 22:40
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 16745492 kB
MemAvailable 31503512 kB
Buffers 0 kB
Cached 14958680 kB
SwapCached 0 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.9
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-12-09 22:37:58
DTS_END                         2021-12-09 22:37:58.5880
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/59524
TRN_ID                          238960
ATT_ID                          720
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: 8987.72 23962 1078526
customer order: creation 169.44 327 20333
customer order: refuse 14.08 373 1690
order to supplier: creation 45.35 1196 5442
order to supplier: removal 3.11 100764 373
invoice (draft): creation 45.80 3470 5496
invoice (draft): removal 50.30 746 6036
invoice accept: apply 40.97 17955 4916
invoice accept: cancel 45.86 755 5503
customer reserve: creation 3761.02 178 451322
customer reserve: removal 4514.89 54 541787
realization accept: apply 83.16 201 9979
realization accept: cancel 84.71 116 10165
payment from customer: creation 59.45 129 7134
payment from customer: removal 5.77 85 692
payment to supplier: creation 48.84 139 5861
payment to supplier: removal 3.35 136 402
service: total inventory turnovers 4.62 1173 554
service: total monetary turnovers 6.30 27 756
service: aggregate perf. data 0.68 26286 81
service: refresh index statistics 0.03 325139 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-12-2021 22:40:36 to 09-12-2021 22:40:38.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3734
TEST_TIME 2 3974
TEST_TIME 3 4117
TEST_TIME 4 4680
TEST_TIME 5 4613
TEST_TIME 6 5182
TEST_TIME 7 5573
TEST_TIME 8 5601
TEST_TIME 9 5986
TEST_TIME 10 6604
TEST_TIME 11 6637
TEST_TIME 12 6584
TEST_TIME 13 6794
TEST_TIME 14 6963
TEST_TIME 15 7063
TEST_TIME 16 6909
TEST_TIME 17 6920
TEST_TIME 18 7307
TEST_TIME 19 7517
TEST_TIME 20 7956
TEST_TIME 21 8153
TEST_TIME 22 8204
TEST_TIME 23 8411
TEST_TIME 24 8526
TEST_TIME 25 9012
TEST_TIME 26 9156
TEST_TIME 27 9577
TEST_TIME 28 10108
TEST_TIME 29 10113
TEST_TIME 30 10322
TEST_TIME 31 9991
TEST_TIME 32 9735
TEST_TIME 33 9543
TEST_TIME 34 9844
TEST_TIME 35 9773
TEST_TIME 36 9659
TEST_TIME 37 9576
TEST_TIME 38 9587
TEST_TIME 39 9419
TEST_TIME 40 9437
TEST_TIME 41 9267
TEST_TIME 42 9356
TEST_TIME 43 9278
TEST_TIME 44 9232
TEST_TIME 45 9234
TEST_TIME 46 9092
TEST_TIME 47 9040
TEST_TIME 48 9028
TEST_TIME 49 9020
TEST_TIME 50 8921
TEST_TIME 51 8901
TEST_TIME 52 8863
TEST_TIME 53 8906
TEST_TIME 54 8943
TEST_TIME 55 8915
TEST_TIME 56 9069
TEST_TIME 57 9112
TEST_TIME 58 9085
TEST_TIME 59 9170
TEST_TIME 60 9144
TEST_TIME 61 9220
TEST_TIME 62 9225
TEST_TIME 63 9322
TEST_TIME 64 9413
TEST_TIME 65 9338
TEST_TIME 66 9401
TEST_TIME 67 9477
TEST_TIME 68 9411
TEST_TIME 69 9297
TEST_TIME 70 9331
TEST_TIME 71 9287
TEST_TIME 72 9225
TEST_TIME 73 9237
TEST_TIME 74 9243
TEST_TIME 75 9175
TEST_TIME 76 9163
TEST_TIME 77 9093
TEST_TIME 78 9036
TEST_TIME 79 9101
TEST_TIME 80 9102
TEST_TIME 81 9079
TEST_TIME 82 9087
TEST_TIME 83 9056
TEST_TIME 84 9115
TEST_TIME 85 9144
TEST_TIME 86 9123
TEST_TIME 87 9194
TEST_TIME 88 9165
TEST_TIME 89 9170
TEST_TIME 90 9264
TEST_TIME 91 9232
TEST_TIME 92 9272
TEST_TIME 93 9213
TEST_TIME 94 9149
TEST_TIME 95 9119
TEST_TIME 96 9101
TEST_TIME 97 9067
TEST_TIME 98 9042
TEST_TIME 99 9054
TEST_TIME 100 9016
TEST_TIME 101 9044
TEST_TIME 102 9020
TEST_TIME 103 9033
TEST_TIME 104 9097
TEST_TIME 105 9077
TEST_TIME 106 9038
TEST_TIME 107 9040
TEST_TIME 108 9152
TEST_TIME 109 9137
TEST_TIME 110 9182
TEST_TIME 111 9171
TEST_TIME 112 9193
TEST_TIME 113 9166
TEST_TIME 114 9097
TEST_TIME 115 9074
TEST_TIME 116 9073
TEST_TIME 117 9071
TEST_TIME 118 9051
TEST_TIME 119 9033
TEST_TIME 120 8993

Performance per minute, chart


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

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 1498236 1498236 0 0.00 0 15642 6 0 0
doc_list_biud 1498223 1498223 0 0.00 0 48872 25 0 0
sp_add_invoice_to_stock 5086 4916 170 3.34 21 225742 17955 0 170
sp_cancel_adding_invoice 5503 5503 0 0.00 4 66487 756 0 0
sp_cancel_client_order 1690 1690 0 0.00 4 12737 374 0 0
sp_cancel_customer_reserve 541787 541787 0 0.00 1 48876 55 0 0
sp_cancel_pay_from_customer 692 692 0 0.00 2 7163 86 0 0
sp_cancel_pay_to_supplier 402 402 0 0.00 2 14995 137 0 0
sp_cancel_supplier_invoice 6051 6036 15 0.24 1 27702 747 0 15
sp_cancel_supplier_order 373 373 0 0.00 5 987289 100765 0 0
sp_cancel_write_off 10165 10165 0 0.00 1 21236 117 0 0
sp_client_order 20333 20333 0 0.00 4 39421 328 0 0
sp_customer_reserve 451322 451322 0 0.00 0 109242 179 0 0
sp_fill_shopping_cart 31252 31252 0 0.00 1 16284 40 0 0
sp_fill_shopping_cart_clo_res 451305 451305 0 0.00 0 13682 13 0 0
sp_get_clo_for_invoice 4899 4899 0 0.00 0 28431 316 0 0
sp_kill_cost_storno 16762 16762 0 0.00 0 15636 61 0 0
sp_kill_qstorno_ret_qs2qd 1100148 1100148 0 0.00 0 48869 30 0 0
sp_kill_qty_storno 580432 580432 0 0.00 0 48872 65 0 0
sp_lock_selected_doc 20457 20457 0 0.00 0 8460 5 0 0
sp_make_cost_storno 27873 27873 0 0.00 0 9012 47 0 0
sp_multiply_rows_for_pdistr 27873 27873 0 0.00 0 15229 41 0 0
sp_multiply_rows_for_qdistr 20333 20333 0 0.00 0 27188 141 0 0
sp_pay_from_customer 7134 7134 0 0.00 4 18605 129 0 0
sp_pay_to_supplier 5861 5861 0 0.00 4 19598 140 0 0
sp_payment_common 12995 12995 0 0.00 2 19314 41 0 0
sp_qd_handle_on_cancel_clo 1690 1690 0 0.00 0 12728 340 0 0
sp_qd_handle_on_invoice_upd_sts 10402 10402 0 0.00 0 39169 334 0 0
sp_qd_handle_on_reserve_upd_sts 9979 9979 0 0.00 0 24673 70 0 0
sp_reserve_write_off 9979 9979 0 0.00 6 24688 202 0 0
sp_supplier_invoice 5496 5496 0 0.00 7 116780 3471 0 0
sp_supplier_order 5442 5442 0 0.00 6 40851 1197 0 0
srv_aggregate_perf_data 81 81 0 0.00 3540 630560 26287 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1842 120736 46744 0 0
srv_find_qd_qs_mism 1032300 1032300 0 0.00 0 10897 5 0 0
srv_make_invnt_saldo 554 554 0 0.00 31 131245 1173 0 0
srv_make_money_saldo 756 756 0 0.00 1 3516 27 0 0
srv_recalc_idx_stat 4 4 0 0.00 166926 424363 325139 0 0
t$perf-abend:sp_add_invoice_to_stock 170 170 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 4916 4916 0 0.00 0 15291 207 0 0
t$perf-norm:sp_cancel_adding_invoice 385 385 0 0.00 1 4661 107 0 0
t$perf-norm:sp_cancel_client_order 1690 1690 0 0.00 0 4390 19 0 0
t$perf-norm:sp_cancel_customer_reserve 666 666 0 0.00 0 6445 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 692 692 0 0.00 0 304 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 402 402 0 0.00 0 1243 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 399 399 0 0.00 0 834 10 0 0
t$perf-norm:sp_cancel_supplier_order 373 373 0 0.00 0 20764 1817 0 0
t$perf-norm:sp_cancel_write_off 972 972 0 0.00 0 2614 12 0 0
t$perf-norm:sp_client_order 20333 20333 0 0.00 0 14678 23 0 0
t$perf-norm:sp_customer_reserve 1035 1035 0 0.00 0 3962 11 0 0
t$perf-norm:sp_pay_from_customer 7134 7134 0 0.00 0 5790 12 0 0
t$perf-norm:sp_pay_to_supplier 5861 5861 0 0.00 0 8808 12 0 0
t$perf-norm:sp_reserve_write_off 9979 9979 0 0.00 0 8815 12 0 0
t$perf-norm:sp_supplier_invoice 5496 5496 0 0.00 0 10586 23 0 0
t$perf-norm:sp_supplier_order 5442 5442 0 0.00 0 8809 19 0 0
t$perf-norm:srv_aggregate_perf_data 81 81 0 0.00 0 568 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 698 17 0 0
t$perf-norm:srv_make_invnt_saldo 554 554 0 0.00 0 1026 23 0 0
t$perf-norm:srv_make_money_saldo 756 756 0 0.00 0 1050 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 3 0 0
v_add_invoice_to_stock 10138 9798 340 3.35 0 7055 21 0 340
v_all_customers 37444 37444 0 0.00 0 22 1 0 0
v_all_suppliers 30360 30360 0 0.00 0 18 1 0 0
v_all_wares 40662 40662 0 0.00 0 182 1 0 0
v_cancel_adding_invoice 770 770 0 0.00 0 126 5 0 0
v_cancel_client_order 3380 3380 0 0.00 0 3821 9 0 0
v_cancel_customer_prepayment 1384 1384 0 0.00 0 1646 6 0 0
v_cancel_customer_reserve 1332 1332 0 0.00 0 3357 14 0 0
v_cancel_payment_to_supplier 804 804 0 0.00 0 796 4 0 0
v_cancel_supplier_invoice 828 798 30 3.62 0 2522 20 0 30
v_cancel_supplier_order 746 746 0 0.00 0 4660 11 0 0
v_cancel_write_off 1944 1944 0 0.00 0 3591 17 0 0
v_max_id_clo_ord 5434 5434 0 0.00 0 1008 1 0 0
v_max_id_clo_res 1035 1035 0 0.00 0 3423 14 0 0
v_max_id_ord_sup 5487 5487 0 0.00 0 2299 2 0 0
v_max_non_paid_invoice 1602 1602 0 0.00 0 2281 3 0 0
v_max_non_paid_realizn 3645 3645 0 0.00 0 2776 3 0 0
v_min_id_clo_ord 5434 5434 0 0.00 0 2081 6 0 0
v_min_id_clo_res 1035 1035 0 0.00 0 2651 11 0 0
v_min_id_ord_sup 5487 5487 0 0.00 0 5303 6 0 0
v_min_non_paid_invoice 5861 5861 0 0.00 0 19306 35 0 0
v_min_non_paid_realizn 7134 7134 0 0.00 0 15057 34 0 0
v_our_firm 10196 10196 0 0.00 0 17 1 0 0
v_reserve_write_off 19958 19958 0 0.00 0 6577 7 0 0
x_lock_depdocs_on_canc_invoice 11140 11140 0 0.00 0 8659 100 0 0
x_lock_depdocs_on_canc_sup_ord 373 373 0 0.00 0 9406 355 0 0
x_make_qstorno_1000_1200 5442 5442 0 0.00 1 40821 1117 0 0
x_make_qstorno_1200_2000 5496 5496 0 0.00 3 116754 3327 0 0
x_make_qstorno_2100_3300 451322 451322 0 0.00 0 109235 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-12-2021 22:40:44 to 09-12-2021 22:40:48.

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:02 4 8192000000 8798901424 8810233856 5314912 0.064 2 1 2 0 5414512 56816 42784
20:09:03 21192 8192000000 9878080992 10183663616 961472400 10.503 100 91 181 1 991572576 5722896 24377280
20:10:27 18763 8192000000 9922938848 10240851968 1003325088 10.911 100 80 160 0 1031560368 7247712 20987568
20:12:06 13608 8192000000 9977612544 10308206592 1063242320 11.488 100 81 162 0 1092683648 8802816 20638512
20:13:23 14613 8192000000 10020222848 10370179072 1112987504 11.961 100 82 164 0 1142717104 10043904 19685696
20:14:39 11249 8192000000 10091968384 10448748544 1175916944 12.552 100 82 164 0 1207279040 12825760 18536336
20:15:56 5832 8192000000 10122962096 10491715584 1218575776 12.949 100 84 169 0 1250744896 12666624 19502496
20:17:04 3424 8192000000 10155170256 10531078144 1250905280 13.247 100 82 164 2 1281881856 12954912 18021664
20:18:09 2862 8192000000 10193810016 10574143488 1273608976 13.455 100 88 177 0 1307490320 14535696 19345648
20:19:14 1757 8192000000 10222240512 10606288896 1298517440 13.682 100 95 190 0 1333453600 13692048 21244112
20:20:16 31952 8192000000 10203459728 10611130368 1306797184 13.757 100 36 72 0 1321898640 7128160 7973296
20:21:49 5479 8192000000 10212741968 10626912256 1318657168 13.865 100 26 52 0 1330929904 6718704 5554032
20:22:55 15602 8192000000 10219515792 10634014720 1327321632 13.943 100 17 34 0 1336774192 5149440 4303120
20:24:20 4384 8192000000 10220408656 10640519168 1338309440 14.042 100 45 94 1 1355323968 5411456 11603072
20:25:25 19303 8192000000 10223686992 10650451968 1336819984 14.029 100 25 49 0 1347676336 5948464 4907888
20:26:46 6267 8192000000 10233423888 10656833536 1342240368 14.078 100 31 61 1 1355618048 6213568 7164112
20:27:56 6725 8192000000 10214674832 10630897664 1302532704 13.718 98 26 52 1 1315207088 6889696 5784688
20:29:06 7018 8192000000 10149352752 10552958976 1192518048 12.707 93 26 54 1 1203916848 5238160 6160640
20:30:17 22127 8192000000 10130076816 10536873984 1189786832 12.681 94 35 70 2 1203053040 4974912 8291296
20:31:40 4920 8192000000 10146146768 10544848896 1242680160 13.171 99 27 53 1 1255362992 6951600 5731232
20:32:47 3662 8192000000 10165503888 10566057984 1266984512 13.394 100 34 68 2 1281566560 6686016 7896032
20:33:52 18135 8192000000 10181589536 10585112576 1271628160 13.437 99 35 68 1 1286091536 5895376 8568000
20:35:10 13553 8192000000 10187369360 10594041856 1285278304 13.561 100 22 44 3 1295899632 6082128 4539200
20:36:29 6043 8192000000 10181242320 10599170048 1293977408 13.640 100 27 54 0 1305826976 5675184 6174384
20:37:38 7877 8192000000 10198559920 10611822592 1305126320 13.742 100 25 49 2 1315827568 5041552 5659696
20:38:56 11224 8192000000 10245885056 10651750400 1336791056 14.028 100 58 111 1 1357631856 8680080 12160720
20:40:16 74320 8192000000 10253075712 10680954880 1349927872 14.147 100 33 66 1 1367589792 10016736 7645184
20:42:44 3803 8192000000 10292442336 10705797120 1376673856 14.387 100 69 136 3 1403725776 11478784 15573136
20:43:54 10904 8192000000 10306470384 10716946432 1382668528 14.440 100 62 122 0 1411386608 14939216 13778864
20:45:13 14247 8192000000 10319358752 10741256192 1390772048 14.513 100 52 103 1 1425296800 21990240 12534512
20:46:36 3610 8192000000 10335758640 10744426496 1397530896 14.573 100 83 166 0 1436796656 19664832 19600928
20:47:42 11251 8192000000 10310340080 10730385408 1389112784 14.498 100 62 121 1 1420280528 16906992 14260752
20:48:57 4669 8192000000 10301409744 10717245440 1367872160 14.308 99 74 143 2 1403558416 18483808 17202448
20:50:09 10672 8192000000 10313929904 10730168320 1374328848 14.366 99 63 126 2 1413272832 23637088 15306896
20:51:26 1842 8192000000 10323313632 10735230976 1386217120 14.472 100 77 155 1 1433981824 29268896 18495808
20:52:34 4280 8192000000 10325298176 10738540544 1378375936 14.402 100 68 135 2 1428281536 33029456 16876144
20:53:44 5172 8192000000 10331229168 10741796864 1369155872 14.319 99 69 138 1 1431738080 45333936 17248272
20:54:59 9314 8192000000 10338857376 10748960768 1364993424 14.282 99 54 108 0 1431887824 52801024 14093376
20:56:14 2545 8192000000 10350268544 10756694016 1378994784 14.408 100 86 170 1 1452503376 52155168 21353424
20:57:22 8442 8192000000 10321785552 10747027456 1360596032 14.243 99 45 89 0 1428441968 55994848 11851088
20:58:36 5967 8192000000 10320816352 10745475072 1369001440 14.318 99 40 80 1 1420344384 40841984 10500960
20:59:46 19515 8192000000 10330603776 10754084864 1378278656 14.401 100 34 68 0 1430292960 42395584 9618720
21:01:13 21462 8192000000 10314977712 10744487936 1364154736 14.275 100 25 51 3 1417887936 45823168 7910032
21:02:38 23544 8192000000 10313551856 10749296640 1374065424 14.363 100 22 44 2 1414621216 34420880 6134912
21:04:03 10589 8192000000 10324509504 10758774784 1371355904 14.339 100 33 68 0 1420783664 39138816 10288944
21:05:16 13964 8192000000 10301011008 10740670464 1371753120 14.343 100 21 42 0 1412273328 34896128 5624080
21:06:31 23008 8192000000 10282778432 10724098048 1367936096 14.309 100 31 62 0 1392291728 16644304 7711328
21:08:15 97870 8192000000 10312290288 10754646016 1393091920 14.533 100 29 57 3 1413523280 13889952 6541408
21:11:04 36582 8192000000 10292004624 10742329344 1393298416 14.535 100 35 70 1 1420443280 19207376 7937488
21:12:44 51345 8192000000 10283218384 10735964160 1386352048 14.473 100 8 16 1 1396633456 8025232 2256176
21:14:36 49299 8192000000 10295838832 10746863616 1388919664 14.496 100 11 22 0 1404513696 12720640 2873392
21:16:29 30646 8192000000 10290252256 10736136192 1385227280 14.463 100 21 43 0 1400825920 10527360 5071280
21:18:04 99115 8192000000 10300566576 10760478720 1396257296 14.562 100 16 32 0 1414809552 14399216 4153040
21:21:07 85290 8192000000 10292872272 10753769472 1382904032 14.443 99 27 54 0 1401698496 12493168 6301296
21:23:35 18044 8192000000 10304696832 10758500352 1387952384 14.488 99 24 48 0 1407639120 14101040 5585696
21:24:58 76553 8192000000 10313619568 10782986240 1409709520 14.681 100 25 50 1 1431269408 15932160 5627728
21:27:15 37862 8192000000 10315177776 10780127232 1405333504 14.642 99 16 32 0 1426321056 17171904 3815648
21:28:55 72927 8192000000 10311565344 10773614592 1378535664 14.403 98 22 44 0 1407670768 23454832 5680272
21:31:09 58443 8192000000 10309792608 10770231296 1392841152 14.531 99 14 28 0 1407933728 11662784 3429792
21:33:08 47730 8192000000 10298544928 10760445952 1364980416 14.282 98 12 24 1 1381462272 13445552 3036304
21:35:16 73926 8192000000 10281905232 10741800960 1329194928 13.960 95 31 62 1 1349308080 12719872 7393280
21:37:33 62124 8192000000 10300689728 10756837376 1378446080 14.403 100 19 38 1 1402201216 19681552 4073584
21:39:36 120735 8192000000 10262577088 10724528128 1279548704 13.509 93 14 28 1 1299327392 17172832 2605856
21:42:38 22575 8192000000 10286389040 10716246016 1335389152 14.016 98 66 130 1 1363716272 13574064 14753056
21:44:02 93819 8192000000 10269567664 10730340352 1335222144 14.014 98 30 60 1 1363217312 21346688 6648480
21:46:36 95951 8192000000 10252617120 10705035264 1319909648 13.876 97 11 20 2 1334904208 12679488 2315072
21:49:13 52099 8192000000 10250508288 10697736192 1334570432 14.008 99 19 38 0 1351602016 12275280 4756304
21:51:16 47077 8192000000 10235397776 10697805824 1323976912 13.913 98 5 10 0 1335658000 10681200 999888
21:53:03 65833 8192000000 10255580032 10712670208 1347700832 14.127 99 12 24 1 1363299264 13060528 2537904
21:55:09 55199 8192000000 10243293760 10694529024 1338512608 14.044 99 13 26 0 1354105568 12574384 3018576
21:57:07 21821 8192000000 10236483440 10680033280 1319355104 13.871 98 18 36 1 1332986432 9754960 3876368
21:58:30 79361 8192000000 10252217440 10703261696 1322403744 13.898 98 12 24 0 1340582416 15547984 2630688
22:00:50 60965 8192000000 10254580816 10702319616 1342238672 14.078 99 25 50 0 1358300176 10625728 5435776
22:02:53 44351 8192000000 10266914544 10714877952 1322084352 13.896 97 19 38 0 1335045184 8959696 4001136
22:04:39 84021 8192000000 10283638192 10723360768 1342615536 14.081 98 24 48 0 1359949536 12066720 5267280
22:07:05 76042 8192000000 10282757744 10748551168 1377450928 14.394 100 21 42 0 1398526000 16518224 4556848
22:09:21 94054 8192000000 10308217520 10752323584 1387757856 14.486 100 11 22 0 1403493264 13372608 2362800
22:12:01 66543 8192000000 10300015520 10757894144 1394476656 14.546 100 16 30 2 1406817664 9168368 3172640
22:14:07 78505 8192000000 10292116272 10760437760 1385628176 14.467 100 10 20 0 1401027216 13181232 2217808
22:16:26 52835 8192000000 10296364384 10744143872 1375531232 14.377 99 25 50 0 1389712688 8932368 5249088
22:18:24 50478 8192000000 10298167408 10753449984 1380664736 14.422 99 13 26 1 1392960432 9363184 2932512
22:20:15 93849 8192000000 10299349040 10758103040 1362896576 14.263 98 20 40 0 1385151104 17954544 4299984
22:22:59 77494 8192000000 10308648528 10751598592 1339775296 14.055 96 29 58 0 1361969584 16068720 6125568
22:25:21 76727 8192000000 10296220832 10748420096 1357850224 14.218 98 14 28 0 1379003296 17892608 3260464
22:27:38 60871 8192000000 10297044832 10729783296 1340950208 14.066 97 21 42 2 1359585312 13747408 4887696
22:29:51 81028 8192000000 10265954656 10718511104 1321460928 13.890 96 18 36 1 1337820976 12309904 4050144
22:32:59 35992 8192000000 10249505520 10697916416 1327387872 13.944 98 20 40 0 1348771344 16814080 4569392
22:34:35 40219 8192000000 10225140400 10670587904 1318270640 13.861 98 11 22 0 1326622752 5790720 2561392
22:36:16 70953 8192000000 10251838320 10702884864 1321963824 13.894 97 8 16 0 1335120768 11368944 1788000

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 09-12-2021 22:40:50 to 09-12-2021 22:41:05.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 170 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:01, from 09-12-2021 22:41:06 to 09-12-2021 22:41:07.

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          236845
MON$OLDEST_ACTIVE               240635
MON$OLDEST_SNAPSHOT             240635
MON$NEXT_TRANSACTION            240635
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                       2030112
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33542 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33542 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33542 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33542 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 ---
:168: 
:169: mscs-test-161.budzdorov.ru    Thu Dec  9 22:37:59 2021
:170:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:171: 
:172: 
:173: mscs-test-161.budzdorov.ru    Thu Dec  9 22:38:28 2021
:174:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:175: 
:176: 
:177: mscs-test-161.budzdorov.ru    Thu Dec  9 22:38:29 2021
:178:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:179: 
--- end of diff output ---

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

09.12.21 22:41:08. 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-12-09 22:41:11 No severe PSQL-related problems occured
09.12.21 22:41:12. End of report.