Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.06.2022 19:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 16078040 kB
MemAvailable 31289768 kB
Buffers 0 kB
Cached 15607436 kB
SwapCached 2448 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.10
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-06-07 19:38:22
DTS_END                         2022-06-07 19:38:22.5200
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/59616
TRN_ID                          244127
ATT_ID                          709
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: 9438.94 20645 1132673
customer order: creation 174.20 350 20904
customer order: refuse 14.73 425 1767
order to supplier: creation 46.87 1408 5624
order to supplier: removal 3.63 97942 435
invoice (draft): creation 47.02 3374 5642
invoice (draft): removal 53.59 881 6431
invoice accept: apply 42.92 16470 5150
invoice accept: cancel 48.69 756 5843
customer reserve: creation 3883.36 165 466003
customer reserve: removal 4823.12 56 578774
realization accept: apply 85.27 166 10232
realization accept: cancel 83.26 100 9991
payment from customer: creation 59.77 106 7172
payment from customer: removal 5.39 118 647
payment to supplier: creation 51.43 113 6172
payment to supplier: removal 3.63 65 436
service: total inventory turnovers 4.94 1144 593
service: total monetary turnovers 6.30 42 756
service: aggregate perf. data 0.81 23446 97
service: refresh index statistics 0.03 265776 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-06-2022 19:39:11 to 07-06-2022 19:39:13.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4434
TEST_TIME 2 3775
TEST_TIME 3 4147
TEST_TIME 4 5168
TEST_TIME 5 5212
TEST_TIME 6 5236
TEST_TIME 7 5620
TEST_TIME 8 5646
TEST_TIME 9 5617
TEST_TIME 10 5774
TEST_TIME 11 6434
TEST_TIME 12 6522
TEST_TIME 13 6572
TEST_TIME 14 7068
TEST_TIME 15 7201
TEST_TIME 16 7428
TEST_TIME 17 8184
TEST_TIME 18 8653
TEST_TIME 19 8860
TEST_TIME 20 8658
TEST_TIME 21 8449
TEST_TIME 22 8483
TEST_TIME 23 8514
TEST_TIME 24 8844
TEST_TIME 25 8881
TEST_TIME 26 8994
TEST_TIME 27 9012
TEST_TIME 28 9294
TEST_TIME 29 9461
TEST_TIME 30 10499
TEST_TIME 31 10655
TEST_TIME 32 10358
TEST_TIME 33 10514
TEST_TIME 34 10573
TEST_TIME 35 10405
TEST_TIME 36 10263
TEST_TIME 37 10224
TEST_TIME 38 10094
TEST_TIME 39 10084
TEST_TIME 40 9909
TEST_TIME 41 9847
TEST_TIME 42 10065
TEST_TIME 43 10002
TEST_TIME 44 9978
TEST_TIME 45 9866
TEST_TIME 46 9885
TEST_TIME 47 9913
TEST_TIME 48 9884
TEST_TIME 49 9761
TEST_TIME 50 9657
TEST_TIME 51 9594
TEST_TIME 52 9587
TEST_TIME 53 9495
TEST_TIME 54 9480
TEST_TIME 55 9463
TEST_TIME 56 9401
TEST_TIME 57 9491
TEST_TIME 58 9544
TEST_TIME 59 9571
TEST_TIME 60 9552
TEST_TIME 61 9532
TEST_TIME 62 9431
TEST_TIME 63 9417
TEST_TIME 64 9456
TEST_TIME 65 9405
TEST_TIME 66 9458
TEST_TIME 67 9348
TEST_TIME 68 9456
TEST_TIME 69 9442
TEST_TIME 70 9577
TEST_TIME 71 9572
TEST_TIME 72 9556
TEST_TIME 73 9558
TEST_TIME 74 9670
TEST_TIME 75 9686
TEST_TIME 76 9783
TEST_TIME 77 9770
TEST_TIME 78 9732
TEST_TIME 79 9641
TEST_TIME 80 9637
TEST_TIME 81 9596
TEST_TIME 82 9518
TEST_TIME 83 9517
TEST_TIME 84 9477
TEST_TIME 85 9419
TEST_TIME 86 9426
TEST_TIME 87 9361
TEST_TIME 88 9419
TEST_TIME 89 9438
TEST_TIME 90 9462
TEST_TIME 91 9443
TEST_TIME 92 9461
TEST_TIME 93 9474
TEST_TIME 94 9488
TEST_TIME 95 9463
TEST_TIME 96 9538
TEST_TIME 97 9534
TEST_TIME 98 9536
TEST_TIME 99 9602
TEST_TIME 100 9606
TEST_TIME 101 9631
TEST_TIME 102 9708
TEST_TIME 103 9620
TEST_TIME 104 9569
TEST_TIME 105 9515
TEST_TIME 106 9493
TEST_TIME 107 9493
TEST_TIME 108 9490
TEST_TIME 109 9453
TEST_TIME 110 9444
TEST_TIME 111 9416
TEST_TIME 112 9439
TEST_TIME 113 9436
TEST_TIME 114 9409
TEST_TIME 115 9395
TEST_TIME 116 9478
TEST_TIME 117 9486
TEST_TIME 118 9502
TEST_TIME 119 9496
TEST_TIME 120 9443

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 07-06-2022 19:39:14 to 07-06-2022 19:39:18.

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 1567392 1567392 0 0.00 0 25955 5 0 0
doc_list_biud 1567391 1567391 0 0.00 0 51400 27 0 0
sp_add_invoice_to_stock 5248 5150 98 1.86 41 199104 16470 0 98
sp_cancel_adding_invoice 5843 5843 0 0.00 5 50860 757 0 0
sp_cancel_client_order 1767 1767 0 0.00 4 17124 426 0 0
sp_cancel_customer_reserve 578774 578774 0 0.00 1 26903 56 0 0
sp_cancel_pay_from_customer 647 647 0 0.00 3 16192 118 0 0
sp_cancel_pay_to_supplier 436 436 0 0.00 3 2673 66 0 0
sp_cancel_supplier_invoice 6434 6431 3 0.04 2 51403 881 0 3
sp_cancel_supplier_order 435 435 0 0.00 9 1072152 97943 0 0
sp_cancel_write_off 9991 9991 0 0.00 1 8965 101 0 0
sp_client_order 20904 20904 0 0.00 3 22240 351 0 0
sp_customer_reserve 466003 466003 0 0.00 0 162524 165 0 0
sp_fill_shopping_cart 32146 32146 0 0.00 1 19849 37 0 0
sp_fill_shopping_cart_clo_res 465997 465997 0 0.00 0 16355 12 0 0
sp_get_clo_for_invoice 5144 5144 0 0.00 0 31493 276 0 0
sp_kill_cost_storno 16917 16917 0 0.00 0 10680 54 0 0
sp_kill_qstorno_ret_qs2qd 1174405 1174405 0 0.00 0 51397 31 0 0
sp_kill_qty_storno 618617 618617 0 0.00 0 51399 68 0 0
sp_lock_selected_doc 21125 21125 0 0.00 0 8590 5 0 0
sp_make_cost_storno 28720 28720 0 0.00 0 11129 46 0 0
sp_multiply_rows_for_pdistr 28720 28720 0 0.00 0 9695 25 0 0
sp_multiply_rows_for_qdistr 20904 20904 0 0.00 0 21686 153 0 0
sp_pay_from_customer 7172 7172 0 0.00 4 13647 106 0 0
sp_pay_to_supplier 6172 6172 0 0.00 5 14948 113 0 0
sp_payment_common 13344 13344 0 0.00 1 11857 25 0 0
sp_qd_handle_on_cancel_clo 1767 1767 0 0.00 0 17117 400 0 0
sp_qd_handle_on_invoice_upd_sts 10987 10987 0 0.00 0 24751 364 0 0
sp_qd_handle_on_reserve_upd_sts 10232 10232 0 0.00 0 15347 60 0 0
sp_reserve_write_off 10232 10232 0 0.00 6 15363 167 0 0
sp_supplier_invoice 5642 5642 0 0.00 6 93916 3375 0 0
sp_supplier_order 5624 5624 0 0.00 5 66582 1408 0 0
srv_aggregate_perf_data 97 97 0 0.00 2895 476761 23446 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1855 218870 51824 0 0
srv_find_qd_qs_mism 1085804 1085804 0 0.00 0 25955 4 0 0
srv_make_invnt_saldo 593 593 0 0.00 43 132949 1144 0 0
srv_make_money_saldo 756 756 0 0.00 1 7439 43 0 0
srv_recalc_idx_stat 4 4 0 0.00 183636 323161 265777 0 0
t$perf-abend:sp_add_invoice_to_stock 98 98 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5151 5151 0 0.00 0 17474 162 0 0
t$perf-norm:sp_cancel_adding_invoice 393 393 0 0.00 0 2955 95 0 0
t$perf-norm:sp_cancel_client_order 1767 1767 0 0.00 0 3113 12 0 0
t$perf-norm:sp_cancel_customer_reserve 693 693 0 0.00 0 3371 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 647 647 0 0.00 0 3025 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 436 436 0 0.00 0 2381 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 385 385 0 0.00 0 974 13 0 0
t$perf-norm:sp_cancel_supplier_order 435 435 0 0.00 0 20137 1522 0 0
t$perf-norm:sp_cancel_write_off 995 995 0 0.00 0 3167 7 0 0
t$perf-norm:sp_client_order 20904 20904 0 0.00 0 10821 16 0 0
t$perf-norm:sp_customer_reserve 1068 1068 0 0.00 0 8427 11 0 0
t$perf-norm:sp_pay_from_customer 7172 7172 0 0.00 0 8011 9 0 0
t$perf-norm:sp_pay_to_supplier 6172 6172 0 0.00 0 8418 13 0 0
t$perf-norm:sp_reserve_write_off 10232 10232 0 0.00 0 3299 10 0 0
t$perf-norm:sp_supplier_invoice 5642 5642 0 0.00 0 6136 21 0 0
t$perf-norm:sp_supplier_order 5624 5624 0 0.00 0 5126 19 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 1798 30 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 144 3 0 0
t$perf-norm:srv_make_invnt_saldo 593 593 0 0.00 0 1998 28 0 0
t$perf-norm:srv_make_money_saldo 756 756 0 0.00 0 1540 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 2 1 0 0
v_add_invoice_to_stock 10480 10284 196 1.87 0 9615 16 0 196
v_all_customers 37968 37968 0 0.00 0 40 1 0 0
v_all_suppliers 31410 31410 0 0.00 0 147 1 0 0
v_all_wares 41808 41808 0 0.00 0 52 1 0 0
v_cancel_adding_invoice 786 786 0 0.00 0 3291 12 0 0
v_cancel_client_order 3534 3534 0 0.00 0 2948 6 0 0
v_cancel_customer_prepayment 1294 1294 0 0.00 0 3373 7 0 0
v_cancel_customer_reserve 1386 1386 0 0.00 0 2147 7 0 0
v_cancel_payment_to_supplier 872 872 0 0.00 0 363 4 0 0
v_cancel_supplier_invoice 776 770 6 0.77 0 3310 18 0 6
v_cancel_supplier_order 870 870 0 0.00 0 2199 8 0 0
v_cancel_write_off 1990 1990 0 0.00 0 4061 18 0 0
v_max_id_clo_ord 5622 5622 0 0.00 0 857 1 0 0
v_max_id_clo_res 1068 1068 0 0.00 0 4711 13 0 0
v_max_id_ord_sup 5619 5619 0 0.00 0 2929 3 0 0
v_max_non_paid_invoice 1708 1708 0 0.00 0 4977 5 0 0
v_max_non_paid_realizn 3869 3869 0 0.00 0 1353 3 0 0
v_min_id_clo_ord 5622 5622 0 0.00 0 2561 4 0 0
v_min_id_clo_res 1068 1068 0 0.00 0 2977 8 0 0
v_min_id_ord_sup 5619 5619 0 0.00 0 5652 5 0 0
v_min_non_paid_invoice 6172 6172 0 0.00 0 11853 17 0 0
v_min_non_paid_realizn 7172 7172 0 0.00 0 8935 17 0 0
v_our_firm 10446 10446 0 0.00 0 15 1 0 0
v_reserve_write_off 20464 20464 0 0.00 0 5459 7 0 0
x_lock_depdocs_on_canc_invoice 11889 11889 0 0.00 0 21087 137 0 0
x_lock_depdocs_on_canc_sup_ord 435 435 0 0.00 0 12670 366 0 0
x_make_qstorno_1000_1200 5624 5624 0 0.00 1 66573 1331 0 0
x_make_qstorno_1200_2000 5642 5642 0 0.00 2 93898 3246 0 0
x_make_qstorno_2100_3300 466003 466003 0 0.00 0 162523 151 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-06-2022 19:39:19 to 07-06-2022 19:39:23.

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:08:26 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:27 42941 8192000000 9917845328 10232745984 1000554816 10.884 100 88 176 1 1030308640 6627072 23126752
17:11:13 13889 8192000000 9953622400 10276880384 1037064160 11.236 100 81 162 0 1065934560 8187296 20683104
17:12:34 13273 8192000000 9992059840 10324848640 1074460576 11.595 100 84 168 0 1105625712 10173264 20991872
17:14:04 11907 8192000000 10038755632 10388574208 1127248736 12.095 100 73 146 0 1156164416 11634848 17280832
17:15:20 7213 8192000000 10095064512 10456264704 1177112400 12.563 100 74 146 0 1205203952 12166608 15924944
17:16:30 4043 8192000000 10135302656 10508689408 1224800640 13.006 100 80 161 1 1255385920 12947968 17637312
17:17:36 2758 8192000000 10173877520 10550403072 1250169968 13.240 100 85 170 1 1283791072 15070112 18550992
17:18:41 1797 8192000000 10200024736 10581446656 1286621936 13.573 100 90 180 1 1321545264 15116656 19806672
17:19:45 17569 8192000000 10220764032 10606706688 1302565168 13.719 100 71 141 2 1327068064 9450896 15052000
17:21:05 7601 8192000000 10208100144 10613391360 1312288032 13.807 100 43 86 1 1331392672 9873184 9231456
17:22:13 60910 8192000000 10224378208 10630619136 1317700512 13.856 100 36 72 1 1330655952 5084176 7871264
17:24:14 4630 8192000000 10230474448 10639810560 1327176336 13.942 100 33 65 1 1345645248 10958736 7510176
17:25:19 8907 8192000000 10222696224 10639118336 1330239552 13.969 100 27 54 1 1343314960 7269072 5806336
17:26:32 55081 8192000000 10221276896 10645573632 1334423552 14.007 100 27 54 0 1346638016 6175088 6039376
17:28:28 6238 8192000000 10211289600 10634502144 1317271584 13.852 99 33 65 0 1332859616 8453392 7134640
17:29:35 7923 8192000000 10193431600 10590830592 1270162816 13.423 98 39 77 3 1284619008 5741600 8714592
17:30:44 68032 8192000000 10149681520 10560004096 1200956752 12.785 94 24 48 1 1211239392 4923840 5358800
17:32:53 4121 8192000000 10148677312 10551099392 1256606112 13.299 100 41 81 1 1272035792 6261120 9168560
17:33:57 9718 8192000000 10164258288 10564239360 1261400768 13.343 99 26 52 0 1273692592 5439408 6852416
17:35:08 54141 8192000000 10177479008 10592067584 1290498624 13.609 100 28 56 0 1302905808 5836512 6570672
17:37:03 11715 8192000000 10207281648 10611146752 1299907952 13.694 100 27 53 0 1311847840 5368208 6571680
17:38:17 10836 8192000000 10214623952 10628145152 1320907312 13.885 100 32 63 1 1333837856 4723168 8207376
17:39:35 11475 8192000000 10250006864 10658406400 1340293120 14.060 100 48 96 0 1363811536 12535168 10983248
17:40:55 92287 8192000000 10252635056 10681880576 1337840688 14.038 99 29 58 0 1355122288 10524720 6756880
17:43:40 9228 8192000000 10295682720 10708836352 1374443760 14.367 100 65 130 0 1405981264 16741936 14795568
17:44:52 7304 8192000000 10301500800 10713292800 1377616720 14.395 100 60 120 2 1408000368 15941792 14441856
17:46:04 7472 8192000000 10293934992 10721304576 1385080160 14.462 100 42 82 1 1407439808 13244064 9115584
17:47:25 5065 8192000000 10317509808 10740359168 1397186736 14.570 100 55 109 0 1426601728 16546352 12868640
17:48:34 4276 8192000000 10333238896 10753982464 1406764976 14.655 100 70 139 0 1444598528 21309584 16523968
17:49:45 4103 8192000000 10316523424 10731016192 1374559616 14.368 99 72 144 0 1413678992 21668960 17450416
17:50:51 3292 8192000000 10336674656 10750701568 1401129824 14.605 100 74 148 2 1444500272 25389568 17980880
17:52:00 1855 8192000000 10321936352 10736414720 1375694240 14.378 99 77 154 0 1427535600 33209712 18631648
17:53:06 3886 8192000000 10294434384 10695020544 1313797424 13.821 95 80 159 0 1364325200 31600784 18926992
17:54:14 5431 8192000000 10301322912 10712473600 1322234688 13.897 97 68 134 0 1377342160 38645344 16462128
17:55:24 5690 8192000000 10320751824 10738528256 1369561600 14.323 100 55 105 3 1425119296 41862464 13695232
17:56:33 3800 8192000000 10323692864 10732326912 1371711600 14.342 100 78 156 0 1423917552 33825072 18380880
17:57:44 2772 8192000000 10344706960 10761396224 1390504496 14.510 100 76 151 3 1446323136 36527648 19290992
17:58:53 9687 8192000000 10313472784 10743115776 1366955008 14.300 99 32 64 1 1411448688 36157024 8336656
18:00:15 4403 8192000000 10333817104 10750418944 1383283776 14.446 100 54 108 0 1436003888 39091664 13628448
18:01:29 7883 8192000000 10316513920 10750586880 1369254944 14.320 99 43 86 1 1420902080 39867824 11779312
18:02:42 10709 8192000000 10312168512 10748723200 1369654592 14.324 100 38 76 4 1421619120 41642096 10322432
18:03:56 13985 8192000000 10315855968 10745094144 1363059392 14.265 99 32 64 1 1413235536 41158784 9017360
18:05:22 24585 8192000000 10322525200 10755112960 1369634192 14.324 100 27 55 0 1426441840 48763520 8044128
18:06:53 20267 8192000000 10322949088 10764365824 1373945776 14.362 100 22 43 1 1429339904 48670928 6723200
18:08:19 40603 8192000000 10293956464 10742550528 1354421920 14.187 99 14 28 0 1398342080 40221632 3698528
18:10:02 62243 8192000000 10283088992 10721869824 1369859536 14.326 100 20 40 0 1388233744 14206320 4167888
18:12:08 25993 8192000000 10278973408 10726129664 1363224864 14.266 99 15 33 0 1375232688 8814448 3193376
18:13:37 58991 8192000000 10295137824 10747035648 1386925616 14.478 100 22 44 1 1404117360 12688736 4503008
18:15:40 215829 8192000000 10335698656 10788528128 1384537584 14.457 99 17 34 0 1401814448 13972720 3304144
18:20:38 85810 8192000000 10308465760 10768510976 1375331776 14.375 98 4 8 0 1389308880 13315136 661968
18:23:09 218870 8192000000 10313606704 10774790144 1359925728 14.237 96 15 30 0 1379624144 16067616 3630800
18:27:49 125748 8192000000 10302678992 10750464000 1359177168 14.230 97 31 62 3 1373188432 7346736 6664528
18:30:57 159695 8192000000 10317565584 10769313792 1375672400 14.378 98 16 32 1 1396126144 16459632 3994112
18:34:47 55186 8192000000 10291802032 10755788800 1362751840 14.262 97 39 78 1 1392749024 20103744 9893440
18:36:47 51829 8192000000 10267247984 10735075328 1335732720 14.019 97 10 20 0 1349374608 11359232 2282656
18:38:38 20923 8192000000 10256496800 10710728704 1351295472 14.159 98 12 23 2 1359373968 6148080 1930416
18:40:01 72973 8192000000 10272771648 10738647040 1374173952 14.364 100 6 12 0 1384828384 9319280 1335152
18:42:14 101632 8192000000 10277324416 10731053056 1303192944 13.724 94 13 26 1 1315291632 9091280 3007408
18:45:04 46826 8192000000 10306181760 10749632512 1370866768 14.335 99 49 98 2 1396804704 14461760 11476176
18:46:52 115318 8192000000 10277012880 10737819648 1310537120 13.791 95 20 40 0 1327804048 12774032 4492896
18:49:47 89814 8192000000 10273234272 10723803136 1321944672 13.894 96 24 48 0 1341264720 13550432 5769616
18:52:36 37231 8192000000 10271984624 10714464256 1328431808 13.953 97 28 56 0 1347901232 13196880 6272544
18:54:18 70018 8192000000 10247779280 10688659456 1280262624 13.515 95 13 26 0 1295653360 12676896 2713840
18:56:32 115907 8192000000 10236688176 10679644160 1298053552 13.678 97 24 48 1 1310208224 6894640 5260032
18:59:33 21700 8192000000 10244117808 10708066304 1347864816 14.128 100 10 20 0 1362519184 12563296 2091072
19:00:56 31418 8192000000 10246155152 10693701632 1340584944 14.063 99 36 72 0 1358364032 9428864 8350224
19:02:32 69471 8192000000 10256448352 10708684800 1323913152 13.912 97 10 20 0 1335505264 9186848 2405264
19:04:42 77493 8192000000 10258960720 10711801856 1356105296 14.202 100 21 42 1 1372322128 10573072 5643760
19:07:06 69627 8192000000 10269377536 10735091712 1361337200 14.249 99 20 40 2 1379806752 13397424 5072128
19:09:16 59954 8192000000 10293762448 10758656000 1387553264 14.484 100 22 44 0 1403085808 10610000 4922544
19:11:17 71763 8192000000 10299899504 10756657152 1378486032 14.403 99 15 30 0 1389026480 7476832 3063616
19:13:29 16014 8192000000 10311906752 10758897664 1399425568 14.590 100 45 90 2 1423119728 13426704 10267456
19:14:45 56957 8192000000 10309860832 10768670720 1389371776 14.500 100 24 48 1 1415194240 20378112 5444352
19:16:47 67360 8192000000 10305922624 10776907776 1385009584 14.461 99 8 16 0 1402236544 15366096 1860864
19:19:00 109616 8192000000 10289894400 10759974912 1310458832 13.790 94 7 14 0 1326751648 14946768 1346048
19:21:50 77924 8192000000 10287279296 10738016256 1355994320 14.201 98 27 54 2 1371134304 9412512 5727472
19:24:09 31369 8192000000 10287323072 10739896320 1381437424 14.429 100 29 58 2 1399753360 11602128 6713808
19:25:40 47368 8192000000 10269463776 10741239808 1359847280 14.236 99 4 8 0 1380114800 19131632 1135888
19:27:32 72506 8192000000 10270096304 10721783808 1321241344 13.888 95 18 36 0 1337465072 11260192 4963536
19:29:54 62335 8192000000 10259282864 10719412224 1348896320 14.138 99 13 26 0 1363470336 11078272 3495744
19:32:01 42260 8192000000 10267764928 10708443136 1332488880 13.990 98 23 46 0 1346113408 8156960 5467568
19:33:53 60783 8192000000 10262760528 10732601344 1358098896 14.220 100 18 36 0 1375682880 13282624 4301360
19:36:02 125338 8192000000 10278494400 10724368384 1297758944 13.675 94 16 32 1 1313235216 12041808 3434464

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 07-06-2022 19:39:25 to 07-06-2022 19:39:39.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 98 v_add_invoice_to_stock 335544517
except 3 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 07-06-2022 19:39:40 to 07-06-2022 19:39:42.

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          243485
MON$OLDEST_ACTIVE               244659
MON$OLDEST_SNAPSHOT             244659
MON$NEXT_TRANSACTION            244659
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                       2059616
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33602 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33602 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33602 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33602 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 ---
:143: 
:144: mscs-test-161.budzdorov.ru    Tue Jun  7 19:38:26 2022
:145:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:146: 
:147: 
:148: mscs-test-161.budzdorov.ru    Tue Jun  7 19:38:27 2022
:149:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:150: 
:151: 
:152: mscs-test-161.budzdorov.ru    Tue Jun  7 19:38:28 2022
:153:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:154: 
--- end of diff output ---

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

07.06.22 19:39:43. 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-06-07 19:39:46 No severe PSQL-related problems occured
07.06.22 19:39:47. End of report.