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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-05-09 08:45
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4793.92
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15907236 kB
MemAvailable 30658472 kB
Buffers 0 kB
Cached 15830188 kB
SwapCached 3704 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 4.0.1
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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-10-23 13:37:10
DTS_END                         2021-10-23 13:37:10.5510
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(127:13) ==> X_MAKE_QSTORNO_2100_3300(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/43032
TRN_ID                          232732
ATT_ID                          634
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: 8750.93 24628 1050112
customer order: creation 166.18 385 19941
customer order: refuse 13.99 377 1679
order to supplier: creation 44.17 1476 5300
order to supplier: removal 3.34 100436 401
invoice (draft): creation 44.62 3956 5354
invoice (draft): removal 49.21 895 5905
invoice accept: apply 41.48 18209 4977
invoice accept: cancel 44.00 852 5280
customer reserve: creation 3782.30 180 453876
customer reserve: removal 4281.29 60 513755
realization accept: apply 81.16 197 9739
realization accept: cancel 73.91 121 8869
payment from customer: creation 57.77 122 6932
payment from customer: removal 5.41 116 649
payment to supplier: creation 47.59 143 5711
payment to supplier: removal 3.78 99 453
service: total inventory turnovers 3.73 3603 447
service: total monetary turnovers 6.10 539 732
service: aggregate perf. data 0.90 15756 108
service: refresh index statistics 0.03 345046 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 23-10-2021 13:39:40 to 23-10-2021 13:39:42.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4091
TEST_TIME 2 3664
TEST_TIME 3 4264
TEST_TIME 4 4111
TEST_TIME 5 4010
TEST_TIME 6 5024
TEST_TIME 7 4858
TEST_TIME 8 5559
TEST_TIME 9 5650
TEST_TIME 10 6434
TEST_TIME 11 6697
TEST_TIME 12 7269
TEST_TIME 13 7647
TEST_TIME 14 7748
TEST_TIME 15 7922
TEST_TIME 16 8090
TEST_TIME 17 8642
TEST_TIME 18 8923
TEST_TIME 19 9291
TEST_TIME 20 9971
TEST_TIME 21 9852
TEST_TIME 22 9810
TEST_TIME 23 9876
TEST_TIME 24 10211
TEST_TIME 25 10711
TEST_TIME 26 10820
TEST_TIME 27 10666
TEST_TIME 28 10637
TEST_TIME 29 10911
TEST_TIME 30 10834
TEST_TIME 31 10577
TEST_TIME 32 10553
TEST_TIME 33 10285
TEST_TIME 34 10081
TEST_TIME 35 10017
TEST_TIME 36 10060
TEST_TIME 37 9924
TEST_TIME 38 9811
TEST_TIME 39 9685
TEST_TIME 40 9711
TEST_TIME 41 9779
TEST_TIME 42 9655
TEST_TIME 43 9499
TEST_TIME 44 9451
TEST_TIME 45 9383
TEST_TIME 46 9434
TEST_TIME 47 9360
TEST_TIME 48 9243
TEST_TIME 49 9091
TEST_TIME 50 9033
TEST_TIME 51 8930
TEST_TIME 52 8969
TEST_TIME 53 9016
TEST_TIME 54 8978
TEST_TIME 55 8934
TEST_TIME 56 8967
TEST_TIME 57 8996
TEST_TIME 58 8963
TEST_TIME 59 9035
TEST_TIME 60 9103
TEST_TIME 61 9095
TEST_TIME 62 9100
TEST_TIME 63 9215
TEST_TIME 64 9222
TEST_TIME 65 9231
TEST_TIME 66 9128
TEST_TIME 67 9036
TEST_TIME 68 9042
TEST_TIME 69 9045
TEST_TIME 70 8994
TEST_TIME 71 8951
TEST_TIME 72 8859
TEST_TIME 73 8820
TEST_TIME 74 8844
TEST_TIME 75 8810
TEST_TIME 76 8893
TEST_TIME 77 8909
TEST_TIME 78 8919
TEST_TIME 79 8891
TEST_TIME 80 9019
TEST_TIME 81 8969
TEST_TIME 82 8974
TEST_TIME 83 8952
TEST_TIME 84 9033
TEST_TIME 85 9001
TEST_TIME 86 9111
TEST_TIME 87 9066
TEST_TIME 88 9032
TEST_TIME 89 9005
TEST_TIME 90 8957
TEST_TIME 91 8909
TEST_TIME 92 8878
TEST_TIME 93 8807
TEST_TIME 94 8818
TEST_TIME 95 8788
TEST_TIME 96 8808
TEST_TIME 97 8781
TEST_TIME 98 8805
TEST_TIME 99 8838
TEST_TIME 100 8847
TEST_TIME 101 8967
TEST_TIME 102 8974
TEST_TIME 103 8961
TEST_TIME 104 9033
TEST_TIME 105 9006
TEST_TIME 106 8981
TEST_TIME 107 9013
TEST_TIME 108 9083
TEST_TIME 109 9077
TEST_TIME 110 8998
TEST_TIME 111 8964
TEST_TIME 112 8956
TEST_TIME 113 8916
TEST_TIME 114 8886
TEST_TIME 115 8901
TEST_TIME 116 8880
TEST_TIME 117 8834
TEST_TIME 118 8807
TEST_TIME 119 8821
TEST_TIME 120 8752

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 23-10-2021 13:39:43 to 23-10-2021 13:39:47.

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 1467725 1467725 0 0.00 0 23679 7 0 0
doc_list_biud 1467724 1467724 0 0.00 0 28349 28 0 0
sp_add_invoice_to_stock 5109 4977 132 2.58 18 207107 18209 0 132
sp_cancel_adding_invoice 5280 5280 0 0.00 5 57155 853 0 0
sp_cancel_client_order 1679 1679 0 0.00 4 17675 378 0 0
sp_cancel_customer_reserve 513755 513755 0 0.00 1 22136 61 0 0
sp_cancel_pay_from_customer 649 649 0 0.00 3 5988 116 0 0
sp_cancel_pay_to_supplier 453 453 0 0.00 3 7374 100 0 0
sp_cancel_supplier_invoice 5912 5905 7 0.11 3 19534 895 0 7
sp_cancel_supplier_order 401 401 0 0.00 8 1002104 100437 0 0
sp_cancel_write_off 8869 8869 0 0.00 1 18216 121 0 0
sp_client_order 19941 19941 0 0.00 4 19051 385 0 0
sp_customer_reserve 453876 453876 0 0.00 0 39578 180 0 0
sp_fill_shopping_cart 30591 30591 0 0.00 1 25362 57 0 0
sp_fill_shopping_cart_clo_res 453875 453875 0 0.00 0 15614 16 0 0
sp_get_clo_for_invoice 4976 4976 0 0.00 0 21831 436 0 0
sp_kill_cost_storno 15251 15251 0 0.00 0 5682 56 0 0
sp_kill_qstorno_ret_qs2qd 1042685 1042685 0 0.00 0 22048 33 0 0
sp_kill_qty_storno 550604 550604 0 0.00 0 28349 73 0 0
sp_lock_selected_doc 20347 20347 0 0.00 0 6775 5 0 0
sp_make_cost_storno 27358 27358 0 0.00 0 22719 58 0 0
sp_multiply_rows_for_pdistr 27358 27358 0 0.00 0 10527 36 0 0
sp_multiply_rows_for_qdistr 19941 19941 0 0.00 0 13469 206 0 0
sp_pay_from_customer 6932 6932 0 0.00 4 15145 123 0 0
sp_pay_to_supplier 5711 5711 0 0.00 5 16215 144 0 0
sp_payment_common 12643 12643 0 0.00 1 12167 31 0 0
sp_qd_handle_on_cancel_clo 1679 1679 0 0.00 0 17666 337 0 0
sp_qd_handle_on_invoice_upd_sts 10256 10256 0 0.00 0 28348 380 0 0
sp_qd_handle_on_reserve_upd_sts 9739 9739 0 0.00 0 17466 62 0 0
sp_reserve_write_off 9739 9739 0 0.00 7 23688 197 0 0
sp_supplier_invoice 5354 5354 0 0.00 7 96835 3956 0 0
sp_supplier_order 5300 5300 0 0.00 6 96344 1477 0 0
srv_aggregate_perf_data 108 108 0 0.00 2645 353300 15757 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1069 152912 44085 0 0
srv_find_qd_qs_mism 1002293 1002293 0 0.00 0 22936 6 0 0
srv_make_invnt_saldo 447 447 0 0.00 73 92053 3603 0 0
srv_make_money_saldo 732 732 0 0.00 1 13002 540 0 0
srv_recalc_idx_stat 4 4 0 0.00 218419 489269 345046 0 0
t$perf-abend:sp_add_invoice_to_stock 132 132 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4977 4977 0 0.00 0 20992 189 0 0
t$perf-norm:sp_cancel_adding_invoice 354 354 0 0.00 0 3909 110 0 0
t$perf-norm:sp_cancel_client_order 1679 1679 0 0.00 0 3115 17 0 0
t$perf-norm:sp_cancel_customer_reserve 704 704 0 0.00 0 1357 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 649 649 0 0.00 0 3745 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 453 453 0 0.00 0 583 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 402 402 0 0.00 0 618 15 0 0
t$perf-norm:sp_cancel_supplier_order 401 401 0 0.00 0 34921 1494 0 0
t$perf-norm:sp_cancel_write_off 990 990 0 0.00 0 8261 14 0 0
t$perf-norm:sp_client_order 19941 19941 0 0.00 0 20943 26 0 0
t$perf-norm:sp_customer_reserve 954 954 0 0.00 0 7578 20 0 0
t$perf-norm:sp_pay_from_customer 6932 6932 0 0.00 0 21409 13 0 0
t$perf-norm:sp_pay_to_supplier 5711 5711 0 0.00 0 7515 10 0 0
t$perf-norm:sp_reserve_write_off 9739 9739 0 0.00 0 7819 12 0 0
t$perf-norm:sp_supplier_invoice 5354 5354 0 0.00 0 7639 25 0 0
t$perf-norm:sp_supplier_order 5300 5300 0 0.00 0 6849 22 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 453 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 36 2 0 0
t$perf-norm:srv_make_invnt_saldo 447 447 0 0.00 0 796 6 0 0
t$perf-norm:srv_make_money_saldo 732 732 0 0.00 0 1686 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 9 4 0 0
v_add_invoice_to_stock 10216 9952 264 2.58 0 5520 22 0 264
v_all_customers 36030 36030 0 0.00 0 29 1 0 0
v_all_suppliers 28942 28942 0 0.00 0 25 1 0 0
v_all_wares 39882 39882 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 708 708 0 0.00 0 4450 21 0 0
v_cancel_client_order 3358 3358 0 0.00 0 2637 6 0 0
v_cancel_customer_prepayment 1298 1298 0 0.00 0 5321 23 0 0
v_cancel_customer_reserve 1408 1408 0 0.00 0 3403 8 0 0
v_cancel_payment_to_supplier 906 906 0 0.00 0 6706 20 0 0
v_cancel_supplier_invoice 818 804 14 1.71 0 1139 15 0 14
v_cancel_supplier_order 802 802 0 0.00 0 3505 12 0 0
v_cancel_write_off 1980 1980 0 0.00 0 2761 17 0 0
v_max_id_clo_ord 5299 5299 0 0.00 0 3632 2 0 0
v_max_id_clo_res 954 954 0 0.00 0 1646 12 0 0
v_max_id_ord_sup 5351 5351 0 0.00 0 9266 10 0 0
v_max_non_paid_invoice 1890 1890 0 0.00 0 404 2 0 0
v_max_non_paid_realizn 3889 3889 0 0.00 0 4639 5 0 0
v_min_id_clo_ord 5299 5299 0 0.00 0 6117 12 0 0
v_min_id_clo_res 954 954 0 0.00 0 5530 15 0 0
v_min_id_ord_sup 5351 5351 0 0.00 0 5191 10 0 0
v_min_non_paid_invoice 5711 5711 0 0.00 0 8533 23 0 0
v_min_non_paid_realizn 6932 6932 0 0.00 0 9782 21 0 0
v_our_firm 9938 9938 0 0.00 0 20 1 0 0
v_reserve_write_off 19478 19478 0 0.00 0 3204 7 0 0
x_lock_depdocs_on_canc_invoice 10783 10783 0 0.00 0 14670 121 0 0
x_lock_depdocs_on_canc_sup_ord 401 401 0 0.00 0 7841 313 0 0
x_make_qstorno_1000_1200 5300 5300 0 0.00 2 86630 1383 0 0
x_make_qstorno_1200_2000 5354 5354 0 0.00 3 90006 3728 0 0
x_make_qstorno_2100_3300 453876 453876 0 0.00 0 39572 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-10-2021 13:39:48 to 23-10-2021 13:39:52.

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
11:07:14 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:15 23191 8192000000 9715257920 10014416896 796484960 8.861 100 86 172 2 825546928 7814112 21247856
11:09:43 4981 8192000000 9776562240 10090627072 855503344 9.455 100 87 174 0 887089904 11223168 20363392
11:10:50 15189 8192000000 9801990416 10124279808 877971488 9.679 100 84 169 0 914630304 17164080 19494736
11:12:08 9514 8192000000 9856122912 10188570624 909421168 9.992 100 78 156 0 964704176 38231072 17051936
11:13:20 14002 8192000000 9924987264 10267824128 954363504 10.434 100 83 166 0 1026615168 54750000 17501664
11:14:38 6605 8192000000 9960950384 10315575296 993731632 10.818 100 79 156 2 1067603888 57639120 16233136
11:15:51 3041 8192000000 10006290112 10367418368 1030554704 11.174 100 87 174 0 1119728464 71315616 17858144
11:16:57 2177 8192000000 10028699648 10398892032 1053236400 11.392 100 92 183 0 1143390688 71348128 18806160
11:18:02 1494 8192000000 10053208224 10424807424 1068201696 11.535 100 93 185 0 1156942176 69728960 19011520
11:19:04 2280 8192000000 9997614224 10374488064 1074736400 11.597 100 56 110 0 1105981552 19082608 12162544
11:20:08 5723 8192000000 10007148176 10402590720 1077775616 11.626 100 31 62 1 1133269040 49211328 6282096
11:21:15 6345 8192000000 10002949552 10403049472 1089278928 11.736 100 49 98 1 1124866560 25277984 10309648
11:22:23 7211 8192000000 10017468576 10420178944 1099651696 11.834 100 49 98 1 1132171680 21515600 11004384
11:23:32 44091 8192000000 10008428528 10411171840 1091039392 11.753 99 40 78 2 1114484288 15332176 8112720
11:25:20 6864 8192000000 10027838800 10438090752 1107160704 11.906 100 46 91 0 1139269504 21988640 10120160
11:26:27 10569 8192000000 10021373120 10429845504 1104488176 11.880 100 33 66 0 1135616304 23174528 7953600
11:27:38 6841 8192000000 9994670864 10390487040 1073020800 11.581 99 48 95 1 1104114768 20276880 10817088
11:28:47 22460 8192000000 9993345760 10371256320 1062180064 11.477 99 69 138 0 1089633184 11135696 16317424
11:30:09 9785 8192000000 9936846544 10325143552 995967104 10.839 96 30 60 0 1031418112 28447664 7003344
11:31:23 7714 8192000000 9930467776 10311741440 982177584 10.705 95 31 60 2 1012854000 24469328 6207088
11:32:34 9066 8192000000 9961308960 10358439936 1035800976 11.224 99 41 80 0 1063245328 18640368 8803984
11:33:44 57621 8192000000 9951788896 10357592064 1023790768 11.109 98 39 78 0 1053754080 21212352 8750960
11:35:42 6735 8192000000 9981597952 10367500288 1061246400 11.468 100 45 90 1 1082790352 11633968 9909984
11:36:52 8895 8192000000 9988082336 10383597568 1071390816 11.565 100 34 66 2 1092798224 14114240 7293168
11:38:06 9794 8192000000 10037337616 10430988288 1100683056 11.844 100 51 102 1 1143356448 31845648 10827744
11:39:19 112985 8192000000 10099385696 10523144192 1122901392 12.054 100 49 97 3 1215581264 81711120 10968752
11:42:30 24199 8192000000 10158435360 10566344704 1140352256 12.219 100 47 94 3 1266922416 115724784 10845376
11:43:59 4919 8192000000 10174807280 10574229504 1147222656 12.283 100 82 162 2 1275355264 111646560 16486048
11:45:10 4027 8192000000 10185872672 10594201600 1153824720 12.345 100 60 118 0 1290190176 124130496 12234960
11:46:19 20558 8192000000 10227050208 10648207360 1145747552 12.270 99 63 126 0 1327394080 168052032 13594496
11:47:58 1293 8192000000 10253405424 10651987968 1171331136 12.509 100 91 182 2 1349629392 157216928 21081328
11:49:05 3232 8192000000 10283322576 10690154496 1169845872 12.495 100 77 151 0 1389179632 203370768 15962992
11:50:14 1815 8192000000 10290116320 10691846144 1158253440 12.387 99 83 165 1 1381347920 204970304 18124176
11:51:19 2313 8192000000 10292112000 10701500416 1165319328 12.453 100 77 154 1 1395221392 212704272 17197792
11:52:30 2543 8192000000 10295916128 10696011776 1157221904 12.377 99 89 178 1 1391270592 213677616 20371072
11:53:37 4229 8192000000 10308332400 10720559104 1135602272 12.174 99 56 112 0 1404381008 254982576 13796160
11:54:48 3789 8192000000 10317028080 10751623168 1137876112 12.196 100 65 130 1 1410171936 256874768 15421056
11:56:03 1068 8192000000 10302222176 10700537856 1128988736 12.112 99 91 181 2 1392751552 242815184 20947632
11:57:08 11825 8192000000 10253074544 10683215872 1117595056 12.004 99 33 66 0 1349275824 223821280 7859488
11:58:25 5962 8192000000 10225038864 10640158720 1129955056 12.121 100 48 96 0 1321292464 181006672 10330736
11:59:32 31991 8192000000 10250115904 10674753536 1111780336 11.949 99 22 44 0 1320757120 203460032 5516752
12:01:22 4278 8192000000 10252360128 10703200256 1117775744 12.006 100 44 88 1 1352997936 224382432 10839760
12:02:33 19545 8192000000 10171449392 10652037120 1106784672 11.902 99 27 54 0 1297433456 184103664 6545120
12:04:04 75756 8192000000 10149591440 10588553216 1107190528 11.906 99 36 72 2 1240639600 125518192 7930880
12:06:20 22236 8192000000 10038979472 10490327040 1086850240 11.713 98 20 38 1 1165502688 73825728 4826720
12:07:47 50280 8192000000 10050719600 10484273152 1093971376 11.780 98 15 30 0 1119596288 22966416 2658496
12:09:50 66881 8192000000 10011250784 10456645632 1085317328 11.698 97 13 25 0 1108439328 20774976 2347024
12:11:59 152912 8192000000 10085178064 10545688576 1120379232 12.031 99 17 31 1 1200765024 77066976 3318816
12:15:34 30629 8192000000 10113844496 10553257984 1129890768 12.120 99 32 64 1 1218639408 81762720 6985920
12:17:32 36764 8192000000 10105737776 10573828096 1129338496 12.115 100 11 22 0 1207245264 75051936 2854832
12:19:10 42274 8192000000 10101145520 10564718592 1140128016 12.217 100 14 28 0 1203136832 59848656 3160160
12:20:52 42767 8192000000 10103964464 10569994240 1124675536 12.071 99 17 34 0 1204779552 75906512 4197504
12:22:36 76076 8192000000 10035073552 10494648320 1120448128 12.031 99 18 36 1 1163482112 39060960 3973024
12:24:53 76204 8192000000 10038331744 10496880640 1135956096 12.177 100 14 28 0 1166975376 27983984 3035296
12:27:24 66260 8192000000 10074992640 10534756352 1134828224 12.167 99 15 30 1 1186504560 48431184 3245152
12:29:31 78052 8192000000 10079524848 10531512320 1109429056 11.927 97 19 38 0 1163631824 49585104 4617664
12:31:49 25002 8192000000 10051172624 10484244480 1131939312 12.140 100 28 56 0 1159072160 21744336 5388512
12:33:14 46241 8192000000 10080181616 10540593152 1141678512 12.231 100 36 72 1 1205971472 56944256 7348704
12:35:04 51562 8192000000 10113934896 10541576192 1111421888 11.946 97 29 58 2 1184374240 66838160 6114192
12:36:56 59142 8192000000 10053509344 10508894208 1085071216 11.696 95 17 34 0 1136804768 48638992 3094560
12:39:01 88127 8192000000 10091887216 10546446336 1064511456 11.500 94 11 22 0 1126606768 59596112 2499200
12:41:31 36829 8192000000 10054330416 10477568000 1058370000 11.441 95 41 82 1 1086039232 18648592 9020640
12:43:16 109568 8192000000 10015214048 10480865280 1075872432 11.608 98 25 50 0 1146683808 64665344 6146032
12:46:24 34515 8192000000 10043329408 10485977088 1082340432 11.670 98 24 48 0 1136696176 49670208 4685536
12:47:59 42416 8192000000 10026382480 10481872896 1094534720 11.786 99 11 22 1 1147579712 50341360 2703632
12:49:43 51640 8192000000 10065368224 10514964480 1105608544 11.891 100 9 18 0 1162296576 55039280 1648752
12:52:14 56083 8192000000 10047394992 10517176320 1100110576 11.839 99 10 20 0 1136647408 34504304 2032528
12:54:14 62331 8192000000 10055474496 10479931392 1082934624 11.675 97 16 32 0 1136328496 49672752 3721120
12:56:20 45694 8192000000 10095617152 10557124608 1122869248 12.054 99 23 46 0 1194383088 66628240 4885600
12:58:08 50611 8192000000 10052755456 10483593216 1115685568 11.986 99 18 36 0 1176114112 56351360 4077184
13:00:21 50833 8192000000 10092876544 10565910528 1109811376 11.931 98 20 40 0 1185193200 71416864 3964960
13:02:13 20577 8192000000 10129424336 10577580032 1140836176 12.223 100 48 96 0 1225793280 73919712 11037392
13:03:34 51126 8192000000 10100657424 10644082688 1120420640 12.031 99 12 24 1 1228309888 105222832 2666416
13:05:49 22974 8192000000 10058589840 10490232832 1132886208 12.149 100 32 63 0 1155260000 16178624 6195168
13:07:13 41726 8192000000 10071743584 10526420992 1136596656 12.184 100 12 24 1 1178034848 39203664 2234528
13:08:55 41823 8192000000 10065590720 10527059968 1117382128 12.002 98 5 10 1 1161795280 43358048 1055104
13:12:05 69223 8192000000 10129009168 10568433664 1159182688 12.396 100 22 42 1 1224289200 60797072 4309440
13:14:26 33033 8192000000 10153124784 10589184000 1159311856 12.397 99 32 64 1 1256712368 90442320 6958192
13:16:01 81749 8192000000 10127464000 10583437312 1133167872 12.151 98 22 42 1 1226104640 88204080 4732688
13:19:09 70814 8192000000 10092954704 10541178880 1120307024 12.030 97 17 34 1 1149695328 25866112 3522192
13:21:27 93063 8192000000 10086839776 10533974016 1110362224 11.936 96 16 32 0 1187381424 74094064 2925136
13:24:01 47541 8192000000 10155514816 10626822144 1115946848 11.989 97 38 76 1 1220371744 96628608 7796288
13:26:08 65880 8192000000 10080168640 10615877632 1126980048 12.093 99 6 12 0 1200801488 72794848 1026592
13:28:26 83672 8192000000 10089390528 10551177216 1106407168 11.898 97 21 42 0 1165945920 55412416 4126336
13:31:12 52294 8192000000 10066600672 10510544896 1076818432 11.617 95 10 20 1 1143962272 64964992 2178848
13:34:07 59292 8192000000 10076725776 10510913536 1084524336 11.691 97 15 31 1 1143105040 55262016 3318688
13:36:23 44408 8192000000 10046630272 10498318336 1090031760 11.743 98 13 26 0 1155423984 62912336 2479888

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 23-10-2021 13:39:53 to 23-10-2021 13:40:08.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 132 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-10-2021 13:40:09 to 23-10-2021 13:40:11.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          233041
MON$OLDEST_ACTIVE               233042
MON$OLDEST_SNAPSHOT             233042
MON$NEXT_TRANSACTION            233042
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-30 07:30:08.6030 GMT
MON$PAGES                       2037344
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             800
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2639 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2639 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2639 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2639 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
--- end of diff output ---

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

23.10.21 13:40:12. There are 3 logs in /var/tmp/logs-oltp40 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-10-23 13:40:15 No severe PSQL-related problems occured
23.10.21 13:40:16. End of report.