Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.11.2021 10: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 16676260 kB
MemAvailable 31611600 kB
Buffers 0 kB
Cached 14999400 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 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-11-15 10:37:08
DTS_END                         2021-11-15 10:37:08.0770
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/57424
TRN_ID                          232736
ATT_ID                          687
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: 8849.54 23867 1061945
customer order: creation 167.99 421 20159
customer order: refuse 14.29 410 1715
order to supplier: creation 43.55 1492 5226
order to supplier: removal 3.39 112251 407
invoice (draft): creation 44.64 3975 5357
invoice (draft): removal 51.19 1033 6143
invoice accept: apply 40.51 19748 4861
invoice accept: cancel 45.75 921 5490
customer reserve: creation 3717.96 196 446155
customer reserve: removal 4427.77 66 531332
realization accept: apply 80.56 209 9667
realization accept: cancel 86.12 122 10334
payment from customer: creation 57.80 135 6936
payment from customer: removal 5.58 99 669
payment to supplier: creation 48.24 154 5789
payment to supplier: removal 3.56 85 427
service: total inventory turnovers 3.42 4141 410
service: total monetary turnovers 6.17 506 740
service: aggregate perf. data 1.03 14859 123
service: refresh index statistics 0.04 316523 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 15-11-2021 10:38:24 to 15-11-2021 10:38:27.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4448
TEST_TIME 2 3393
TEST_TIME 3 4215
TEST_TIME 4 4828
TEST_TIME 5 4657
TEST_TIME 6 4919
TEST_TIME 7 5022
TEST_TIME 8 4905
TEST_TIME 9 5171
TEST_TIME 10 4987
TEST_TIME 11 5485
TEST_TIME 12 5864
TEST_TIME 13 6276
TEST_TIME 14 6216
TEST_TIME 15 6257
TEST_TIME 16 6510
TEST_TIME 17 6609
TEST_TIME 18 6966
TEST_TIME 19 6827
TEST_TIME 20 7539
TEST_TIME 21 7347
TEST_TIME 22 7814
TEST_TIME 23 8158
TEST_TIME 24 8673
TEST_TIME 25 9004
TEST_TIME 26 9206
TEST_TIME 27 9273
TEST_TIME 28 9968
TEST_TIME 29 10395
TEST_TIME 30 10198
TEST_TIME 31 10171
TEST_TIME 32 10082
TEST_TIME 33 10158
TEST_TIME 34 10156
TEST_TIME 35 10191
TEST_TIME 36 10298
TEST_TIME 37 10220
TEST_TIME 38 9957
TEST_TIME 39 9735
TEST_TIME 40 9669
TEST_TIME 41 9548
TEST_TIME 42 9741
TEST_TIME 43 9677
TEST_TIME 44 9635
TEST_TIME 45 9536
TEST_TIME 46 9422
TEST_TIME 47 9411
TEST_TIME 48 9483
TEST_TIME 49 9315
TEST_TIME 50 9262
TEST_TIME 51 9159
TEST_TIME 52 9279
TEST_TIME 53 9215
TEST_TIME 54 9249
TEST_TIME 55 9305
TEST_TIME 56 9298
TEST_TIME 57 9229
TEST_TIME 58 9288
TEST_TIME 59 9318
TEST_TIME 60 9280
TEST_TIME 61 9390
TEST_TIME 62 9511
TEST_TIME 63 9519
TEST_TIME 64 9476
TEST_TIME 65 9415
TEST_TIME 66 9298
TEST_TIME 67 9246
TEST_TIME 68 9214
TEST_TIME 69 9219
TEST_TIME 70 9155
TEST_TIME 71 9116
TEST_TIME 72 9090
TEST_TIME 73 9017
TEST_TIME 74 9017
TEST_TIME 75 9041
TEST_TIME 76 9009
TEST_TIME 77 8955
TEST_TIME 78 8912
TEST_TIME 79 8989
TEST_TIME 80 8950
TEST_TIME 81 8984
TEST_TIME 82 8991
TEST_TIME 83 9028
TEST_TIME 84 8996
TEST_TIME 85 9002
TEST_TIME 86 9124
TEST_TIME 87 9100
TEST_TIME 88 9155
TEST_TIME 89 9154
TEST_TIME 90 9088
TEST_TIME 91 9061
TEST_TIME 92 9061
TEST_TIME 93 8998
TEST_TIME 94 8989
TEST_TIME 95 8950
TEST_TIME 96 8982
TEST_TIME 97 9049
TEST_TIME 98 9005
TEST_TIME 99 9016
TEST_TIME 100 8980
TEST_TIME 101 9078
TEST_TIME 102 9094
TEST_TIME 103 9061
TEST_TIME 104 9115
TEST_TIME 105 9168
TEST_TIME 106 9139
TEST_TIME 107 9157
TEST_TIME 108 9174
TEST_TIME 109 9188
TEST_TIME 110 9119
TEST_TIME 111 9093
TEST_TIME 112 9068
TEST_TIME 113 9074
TEST_TIME 114 9038
TEST_TIME 115 9014
TEST_TIME 116 8979
TEST_TIME 117 8956
TEST_TIME 118 8929
TEST_TIME 119 8916
TEST_TIME 120 8850

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 15-11-2021 10:38:28 to 15-11-2021 10:38:31.

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 1476388 1476388 0 0.00 0 19136 7 0 0
doc_list_biud 1476368 1476368 0 0.00 0 63802 31 0 0
sp_add_invoice_to_stock 4949 4861 88 1.77 21 242258 19748 0 88
sp_cancel_adding_invoice 5490 5490 0 0.00 6 104152 922 0 0
sp_cancel_client_order 1715 1715 0 0.00 5 16546 411 0 0
sp_cancel_customer_reserve 531332 531332 0 0.00 1 63803 66 0 0
sp_cancel_pay_from_customer 669 669 0 0.00 3 5515 99 0 0
sp_cancel_pay_to_supplier 427 427 0 0.00 3 3515 86 0 0
sp_cancel_supplier_invoice 6148 6143 5 0.08 2 58319 1034 0 5
sp_cancel_supplier_order 407 407 0 0.00 7 1242895 112251 0 0
sp_cancel_write_off 10334 10334 0 0.00 1 18225 122 0 0
sp_client_order 20159 20159 0 0.00 4 21889 421 0 0
sp_customer_reserve 446155 446155 0 0.00 0 58515 197 0 0
sp_fill_shopping_cart 30740 30740 0 0.00 1 18510 44 0 0
sp_fill_shopping_cart_clo_res 446155 446155 0 0.00 0 12114 15 0 0
sp_get_clo_for_invoice 4861 4861 0 0.00 0 15741 296 0 0
sp_kill_cost_storno 16920 16920 0 0.00 0 8982 65 0 0
sp_kill_qstorno_ret_qs2qd 1079548 1079548 0 0.00 0 63791 37 0 0
sp_kill_qty_storno 569929 569929 0 0.00 0 63802 80 0 0
sp_lock_selected_doc 20204 20204 0 0.00 0 7966 6 0 0
sp_make_cost_storno 27253 27253 0 0.00 0 19124 65 0 0
sp_multiply_rows_for_pdistr 27244 27244 0 0.00 0 6975 35 0 0
sp_multiply_rows_for_qdistr 20159 20159 0 0.00 0 15270 191 0 0
sp_pay_from_customer 6936 6936 0 0.00 4 14517 135 0 0
sp_pay_to_supplier 5789 5789 0 0.00 5 10373 154 0 0
sp_payment_common 12725 12725 0 0.00 1 14027 30 0 0
sp_qd_handle_on_cancel_clo 1715 1715 0 0.00 0 16540 376 0 0
sp_qd_handle_on_invoice_upd_sts 10340 10340 0 0.00 0 25823 406 0 0
sp_qd_handle_on_reserve_upd_sts 9658 9658 0 0.00 0 14594 67 0 0
sp_reserve_write_off 9667 9667 0 0.00 7 14672 209 0 0
sp_supplier_invoice 5357 5357 0 0.00 6 156500 3975 0 0
sp_supplier_order 5226 5226 0 0.00 5 34555 1493 0 0
srv_aggregate_perf_data 123 123 0 0.00 2305 114390 14859 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 924 115691 42687 0 0
srv_find_qd_qs_mism 1016239 1016239 0 0.00 0 18022 6 0 0
srv_make_invnt_saldo 410 410 0 0.00 82 125505 4141 0 0
srv_make_money_saldo 740 740 0 0.00 1 14465 506 0 0
srv_recalc_idx_stat 5 5 0 0.00 161118 436239 316523 0 0
t$perf-abend:sp_add_invoice_to_stock 88 88 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4861 4861 0 0.00 0 12084 190 0 0
t$perf-norm:sp_cancel_adding_invoice 401 401 0 0.00 1 9627 112 0 0
t$perf-norm:sp_cancel_client_order 1715 1715 0 0.00 0 6981 21 0 0
t$perf-norm:sp_cancel_customer_reserve 668 668 0 0.00 0 1949 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 669 669 0 0.00 0 1093 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 427 427 0 0.00 0 549 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 412 412 0 0.00 0 1060 18 0 0
t$perf-norm:sp_cancel_supplier_order 407 407 0 0.00 0 16227 1546 0 0
t$perf-norm:sp_cancel_write_off 997 997 0 0.00 0 989 7 0 0
t$perf-norm:sp_client_order 20159 20159 0 0.00 0 11634 21 0 0
t$perf-norm:sp_customer_reserve 1016 1016 0 0.00 0 8263 21 0 0
t$perf-norm:sp_pay_from_customer 6936 6936 0 0.00 0 5598 11 0 0
t$perf-norm:sp_pay_to_supplier 5789 5789 0 0.00 0 11614 15 0 0
t$perf-norm:sp_reserve_write_off 9667 9667 0 0.00 0 10966 13 0 0
t$perf-norm:sp_supplier_invoice 5357 5357 0 0.00 0 5552 23 0 0
t$perf-norm:sp_supplier_order 5226 5226 0 0.00 0 3969 16 0 0
t$perf-norm:srv_aggregate_perf_data 123 123 0 0.00 0 419 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 24 1 0 0
t$perf-norm:srv_make_invnt_saldo 410 410 0 0.00 0 420 8 0 0
t$perf-norm:srv_make_money_saldo 740 740 0 0.00 0 1916 11 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 9876 9700 176 1.78 0 8270 19 0 176
v_all_customers 36580 36580 0 0.00 0 17 1 0 0
v_all_suppliers 29774 29774 0 0.00 0 20 1 0 0
v_all_wares 40314 40314 0 0.00 0 87 1 0 0
v_cancel_adding_invoice 802 802 0 0.00 0 1738 9 0 0
v_cancel_client_order 3430 3430 0 0.00 0 2143 6 0 0
v_cancel_customer_prepayment 1338 1338 0 0.00 0 4040 12 0 0
v_cancel_customer_reserve 1336 1336 0 0.00 0 7888 15 0 0
v_cancel_payment_to_supplier 854 854 0 0.00 0 2215 6 0 0
v_cancel_supplier_invoice 834 824 10 1.19 0 1927 15 0 10
v_cancel_supplier_order 814 814 0 0.00 0 4904 9 0 0
v_cancel_write_off 1994 1994 0 0.00 0 715 13 0 0
v_max_id_clo_ord 5226 5226 0 0.00 0 1159 2 0 0
v_max_id_clo_res 1016 1016 0 0.00 0 2381 11 0 0
v_max_id_ord_sup 5357 5357 0 0.00 0 2889 4 0 0
v_max_non_paid_invoice 1485 1485 0 0.00 0 355 2 0 0
v_max_non_paid_realizn 3728 3728 0 0.00 0 2415 4 0 0
v_min_id_clo_ord 5226 5226 0 0.00 0 11888 8 0 0
v_min_id_clo_res 1016 1016 0 0.00 0 3948 8 0 0
v_min_id_ord_sup 5357 5357 0 0.00 0 4584 7 0 0
v_min_non_paid_invoice 5789 5789 0 0.00 0 7722 21 0 0
v_min_non_paid_realizn 6936 6936 0 0.00 0 5324 22 0 0
v_our_firm 10150 10150 0 0.00 0 22 1 0 0
v_reserve_write_off 19316 19316 0 0.00 0 7736 8 0 0
x_lock_depdocs_on_canc_invoice 11221 11221 0 0.00 0 19183 118 0 0
x_lock_depdocs_on_canc_sup_ord 407 407 0 0.00 0 6087 335 0 0
x_make_qstorno_1000_1200 5226 5226 0 0.00 2 34539 1406 0 0
x_make_qstorno_1200_2000 5357 5357 0 0.00 2 148966 3809 0 0
x_make_qstorno_2100_3300 446155 446155 0 0.00 0 58512 179 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-11-2021 10:38:32 to 15-11-2021 10:38:36.

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
08:07:11 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:12 24723 8192000000 9719624928 10014175232 795096000 8.847 100 89 178 0 824591536 7726352 21769184
08:09:43 23401 8192000000 9755581632 10061070336 828409952 9.183 100 91 182 0 863317184 13055792 21851440
08:11:14 17995 8192000000 9799648816 10116825088 861952208 9.520 100 90 180 0 905136544 22083792 21100544
08:12:35 12552 8192000000 9870400384 10202226688 916184384 10.058 100 77 154 1 978761920 45834176 16743360
08:13:53 5985 8192000000 9890999872 10239455232 953145264 10.422 100 65 129 0 1009355024 41981840 14227920
08:15:01 5809 8192000000 9936589440 10295050240 993587520 10.816 100 68 136 1 1052548080 45184304 13776256
08:16:09 6870 8192000000 10003325136 10366672896 1017623744 11.049 100 77 154 0 1112380480 78436608 16320128
08:17:19 2596 8192000000 10021950160 10385674240 1044486144 11.308 100 82 163 0 1133595040 72005104 17103792
08:18:22 1736 8192000000 10053447600 10424082432 1063119024 11.486 100 93 186 1 1158185424 75392096 19674304
08:19:25 10839 8192000000 10007477360 10410143744 1068822224 11.541 100 51 101 0 1139578192 60148368 10607600
08:20:36 6661 8192000000 9997007584 10392768512 1075355520 11.603 100 37 74 2 1122102832 39231328 7515984
08:21:45 4921 8192000000 10000360464 10399580160 1084714992 11.692 100 35 72 1 1124403616 32645008 7043616
08:22:51 48114 8192000000 10033295360 10417127424 1093032528 11.771 100 61 123 1 1127851136 21443952 13374656
08:24:39 8062 8192000000 10007613456 10409103360 1098100656 11.820 100 28 56 0 1123327280 18701792 6524832
08:25:52 9972 8192000000 10014515952 10424152064 1101786432 11.855 100 35 71 2 1135980448 26392544 7801472
08:27:03 7152 8192000000 10001585376 10400382976 1069255056 11.545 97 24 49 0 1092897872 18453584 5189232
08:28:10 62494 8192000000 9980071696 10381758464 1006543392 10.942 92 18 36 0 1023996288 13698352 3754544
08:30:15 5707 8192000000 9946420656 10331439104 1013458624 11.009 98 36 74 1 1039680256 18158352 8063280
08:31:21 4283 8192000000 9955725616 10328330240 1028771344 11.157 100 48 92 2 1063447888 23716544 10960000
08:32:29 8249 8192000000 9942007248 10321731584 1027368048 11.143 100 31 62 0 1045773072 10776880 7628144
08:33:43 25180 8192000000 9933981568 10331815936 1014258800 11.017 98 20 40 1 1044099696 25726480 4114416
08:35:10 3223 8192000000 9967652928 10351452160 1049774416 11.359 100 56 110 4 1076264992 13813904 12676672
08:36:18 8413 8192000000 9978989184 10381172736 1065523104 11.509 100 35 70 1 1091881440 18581328 7777008
08:37:27 91619 8192000000 10029318720 10432659456 1094056816 11.781 100 40 79 1 1132506480 29348160 9101504
08:40:05 24933 8192000000 10069102720 10478936064 1108189744 11.915 100 35 70 0 1178269072 62974192 7105136
08:41:38 19061 8192000000 10088080416 10491789312 1123030048 12.056 100 59 118 2 1193110496 57364576 12715872
08:43:01 17333 8192000000 10137684256 10540515328 1139122560 12.207 100 65 128 1 1245533152 92269488 14141104
08:44:25 20899 8192000000 10153456736 10562777088 1148994096 12.300 100 43 86 1 1256152368 96687360 10470912
08:46:07 11502 8192000000 10203094592 10620592128 1164308704 12.444 100 38 75 1 1317914544 144074160 9531680
08:47:25 6457 8192000000 10229570608 10639511552 1171381536 12.510 100 69 138 1 1330920704 144114176 15424992
08:48:40 4241 8192000000 10275218608 10683580416 1185243984 12.639 100 75 150 1 1377800688 175882736 16673968
08:49:52 1728 8192000000 10300144896 10703646720 1192292384 12.705 100 91 182 2 1400990928 189029040 19669504
08:50:56 4405 8192000000 10299199312 10710892544 1177372400 12.566 99 70 139 0 1393038912 200546624 15119888
08:52:06 2594 8192000000 10349236464 10758295552 1196588560 12.745 100 80 157 2 1450513552 236102224 17822768
08:53:18 1202 8192000000 10363600400 10772918272 1164436128 12.445 100 82 165 0 1460561552 277099296 19026128
08:54:24 1644 8192000000 10407694912 10806407168 1160281584 12.406 100 90 180 3 1502284528 321171456 20831488
08:55:31 7903 8192000000 10413458336 10846842880 1167060112 12.469 100 46 92 0 1525874128 347342176 11471840
08:56:45 3144 8192000000 10448854096 10850689024 1145558528 12.268 99 88 177 0 1537269424 370900064 20810832
08:57:52 7509 8192000000 10390122448 10802487296 1140845904 12.223 100 45 90 1 1484210832 331525840 11839088
08:59:04 919 8192000000 10491028352 10891812864 1149998480 12.309 99 81 163 0 1581226112 411962592 19265040
09:00:08 13454 8192000000 10448544368 10875281408 1151735184 12.326 100 47 94 0 1552354144 388382224 12236736
09:01:25 13471 8192000000 10406691120 10840121344 1144497616 12.258 100 43 86 0 1506914512 351298240 11118656
09:02:47 12414 8192000000 10321498272 10779488256 1140654128 12.222 100 25 50 1 1423828496 276280480 6893888
09:04:02 33887 8192000000 10247622992 10743214080 1111551904 11.947 100 18 36 1 1370057568 253878528 4627136
09:05:39 22197 8192000000 10131247920 10588528640 1111864992 11.950 100 23 43 2 1267495104 150661552 4968560
09:07:04 51635 8192000000 10072618672 10489282560 1098130832 11.820 99 25 46 1 1139123024 35871072 5121120
09:08:58 18680 8192000000 10084165088 10501812224 1129155424 12.113 100 45 88 2 1171067760 32093376 9818960
09:10:24 80978 8192000000 10107025504 10581815296 1114126256 11.971 98 13 26 0 1183899248 66728256 3044736
09:12:45 100294 8192000000 10081765712 10573742080 1090640384 11.749 95 10 21 0 1205321872 112722720 1958768
09:15:40 97843 8192000000 10077204672 10513305600 1113846704 11.969 98 39 77 1 1168406560 45559120 9000736
09:18:20 42456 8192000000 10162276400 10602303488 1135929584 12.177 99 38 77 0 1229926080 85193984 8802512
09:20:16 21733 8192000000 10092203424 10549915648 1146361520 12.275 100 33 65 0 1197877632 44468672 7047440
09:21:44 60832 8192000000 10124491504 10581614592 1159171248 12.396 100 18 35 1 1213233120 50002592 4059280
09:23:49 38502 8192000000 10162593328 10624905216 1166222064 12.462 100 14 28 0 1270277344 100868320 3186960
09:25:29 36198 8192000000 10074571872 10589048832 1152942128 12.337 100 10 20 0 1182761456 28071920 1747408
09:27:08 25886 8192000000 10109305008 10580135936 1149870672 12.308 99 7 14 0 1197592976 46343312 1378992
09:28:34 63692 8192000000 10100422944 10625286144 1153291472 12.340 99 12 24 0 1238574112 82457408 2825232
09:30:43 35680 8192000000 10119609312 10566815744 1137053568 12.188 98 39 78 2 1191448976 45323776 9071632
09:32:27 73794 8192000000 10059816416 10539294720 1087970816 11.723 96 18 36 1 1113187232 21718544 3497872
09:34:47 26654 8192000000 10126758128 10569654272 1130278672 12.124 100 40 80 1 1213691728 75118656 8294400
09:36:13 37722 8192000000 10081691296 10513616896 1124858592 12.073 99 31 62 2 1167830112 36076576 6894944
09:37:55 53219 8192000000 10134984096 10639056896 1129420800 12.116 99 30 60 1 1266005424 130119280 6465344
09:39:50 96472 8192000000 10074803600 10539388928 1092758192 11.769 96 9 17 1 1136401376 41722288 1920896
09:42:27 81553 8192000000 10070891664 10511740928 1095344528 11.793 96 27 53 1 1127621568 27084688 5192352
09:45:01 17981 8192000000 10060490528 10485698560 1112709440 11.958 99 43 86 1 1154314496 32835408 8769648
09:46:19 54306 8192000000 10042072160 10500444160 1112739760 11.958 99 10 20 0 1150077824 35596944 1741120
09:48:13 44774 8192000000 10068330224 10516742144 1120312832 12.030 99 13 26 1 1155061616 32204960 2543824
09:50:39 56536 8192000000 10082999664 10575937536 1109795696 11.930 98 15 30 0 1163233280 50279008 3158576
09:53:10 20895 8192000000 10053796960 10499387392 1114238048 11.973 99 49 96 3 1145766320 21247968 10280304
09:54:45 59811 8192000000 10083123312 10529841152 1081552784 11.662 96 12 24 0 1111982816 27989648 2440384
09:57:33 19052 8192000000 10082165472 10511241216 1120946096 12.036 100 34 66 2 1176878560 48944336 6988128
09:58:58 51360 8192000000 10084673056 10524905472 1114623440 11.976 99 37 74 0 1181015920 59192672 7199808
10:00:53 61990 8192000000 10120043920 10552745984 1107846128 11.912 98 34 68 0 1158663168 43829584 6987456
10:02:57 115691 8192000000 10089322928 10546044928 1103747824 11.873 98 18 36 0 1212477264 104735840 3993600
10:05:59 62656 8192000000 10126005376 10560212992 1124932576 12.074 99 21 40 1 1205078576 75048528 5097472
10:08:24 63834 8192000000 10076932848 10517610496 1115811408 11.987 98 31 61 1 1167961264 46023168 6126688
10:10:42 71823 8192000000 10088140576 10574462976 1140386928 12.219 100 6 12 0 1201937584 60265456 1285200
10:12:58 69630 8192000000 10107093824 10586615808 1131905824 12.139 99 24 48 0 1180600608 43307008 5387776
10:16:09 59061 8192000000 10126841040 10594189312 1142877728 12.243 99 22 44 0 1204245264 56988288 4379248
10:18:38 80182 8192000000 10080988992 10531213312 1132882048 12.149 98 12 24 1 1187035728 51977344 2176336
10:21:02 62384 8192000000 10157639520 10664423424 1147626928 12.287 99 31 62 1 1277543280 123058608 6857744
10:23:05 68928 8192000000 10082489536 10522914816 1071928304 11.570 93 25 50 3 1141242288 63961456 5352528
10:25:17 33933 8192000000 10093284048 10552471552 1141134368 12.226 100 36 72 2 1212097360 63448240 7514752
10:26:52 103017 8192000000 10073112336 10516766720 1115116112 11.981 98 19 38 1 1167984496 48807472 4060912
10:29:59 92124 8192000000 10060688320 10550554624 1080865216 11.656 95 18 35 0 1135560560 50986688 3708656
10:32:31 34172 8192000000 10041939584 10503270400 1103577600 11.872 98 11 22 1 1145967168 40253824 2135744
10:34:33 62384 8192000000 10062829184 10534481920 1099476800 11.833 97 7 14 0 1130885248 30017024 1391424
10:36:35 41705 8192000000 9205852880 9490542592 352198080 4.122 31 8 16 0 368991968 15077520 1716368

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 15-11-2021 10:38:38 to 15-11-2021 10:38:53.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 88 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-11-2021 10:38:54 to 15-11-2021 10:38:56.

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          232668
MON$OLDEST_ACTIVE               233220
MON$OLDEST_SNAPSHOT             233220
MON$NEXT_TRANSACTION            233220
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                       2051488
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             797
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2660 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2660 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2660 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2660 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 ---
:111: 
:112: mscs-test-161.budzdorov.ru    Mon Nov 15 10:37:20 2021
:113:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:114: 
--- end of diff output ---

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

15.11.21 10:38:57. 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-11-15 10:38:59 No severe PSQL-related problems occured
15.11.21 10:39:00. End of report.