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

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.8
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-12-06 16:37:58
DTS_END                         2021-12-06 16:37:58.4380
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/36820
TRN_ID                          233987
ATT_ID                          630
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: 9160.59 20972 1099271
customer order: creation 165.48 359 19857
customer order: refuse 13.58 359 1630
order to supplier: creation 43.07 1336 5168
order to supplier: removal 3.65 115784 438
invoice (draft): creation 44.14 3869 5297
invoice (draft): removal 52.98 979 6358
invoice accept: apply 40.55 18104 4866
invoice accept: cancel 48.20 885 5784
customer reserve: creation 3716.65 180 445998
customer reserve: removal 4750.40 69 570048
realization accept: apply 80.58 200 9669
realization accept: cancel 78.94 110 9473
payment from customer: creation 56.17 130 6740
payment from customer: removal 5.31 84 637
payment to supplier: creation 45.85 150 5502
payment to supplier: removal 3.36 77 403
service: total inventory turnovers 4.40 1219 528
service: total monetary turnovers 6.45 25 774
service: aggregate perf. data 0.81 21743 97
service: refresh index statistics 0.03 253768 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 06-12-2021 16:38:42 to 06-12-2021 16:38:45.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2199
TEST_TIME 2 2463
TEST_TIME 3 3660
TEST_TIME 4 4168
TEST_TIME 5 4307
TEST_TIME 6 4431
TEST_TIME 7 5093
TEST_TIME 8 5118
TEST_TIME 9 5439
TEST_TIME 10 5491
TEST_TIME 11 5984
TEST_TIME 12 6072
TEST_TIME 13 6106
TEST_TIME 14 6266
TEST_TIME 15 6382
TEST_TIME 16 6547
TEST_TIME 17 6646
TEST_TIME 18 6966
TEST_TIME 19 6908
TEST_TIME 20 6930
TEST_TIME 21 7503
TEST_TIME 22 7510
TEST_TIME 23 7849
TEST_TIME 24 8238
TEST_TIME 25 8374
TEST_TIME 26 8321
TEST_TIME 27 8163
TEST_TIME 28 8187
TEST_TIME 29 8514
TEST_TIME 30 9362
TEST_TIME 31 10675
TEST_TIME 32 10724
TEST_TIME 33 10620
TEST_TIME 34 10520
TEST_TIME 35 10433
TEST_TIME 36 10403
TEST_TIME 37 10280
TEST_TIME 38 10068
TEST_TIME 39 9888
TEST_TIME 40 9823
TEST_TIME 41 9723
TEST_TIME 42 9636
TEST_TIME 43 9559
TEST_TIME 44 9551
TEST_TIME 45 9460
TEST_TIME 46 9427
TEST_TIME 47 9494
TEST_TIME 48 9363
TEST_TIME 49 9365
TEST_TIME 50 9333
TEST_TIME 51 9433
TEST_TIME 52 9322
TEST_TIME 53 9180
TEST_TIME 54 9111
TEST_TIME 55 9028
TEST_TIME 56 8991
TEST_TIME 57 8869
TEST_TIME 58 8875
TEST_TIME 59 8834
TEST_TIME 60 8813
TEST_TIME 61 8836
TEST_TIME 62 8836
TEST_TIME 63 8841
TEST_TIME 64 8809
TEST_TIME 65 8981
TEST_TIME 66 9027
TEST_TIME 67 9073
TEST_TIME 68 9106
TEST_TIME 69 9069
TEST_TIME 70 9104
TEST_TIME 71 9115
TEST_TIME 72 9076
TEST_TIME 73 9085
TEST_TIME 74 9210
TEST_TIME 75 9175
TEST_TIME 76 9058
TEST_TIME 77 9028
TEST_TIME 78 9022
TEST_TIME 79 8978
TEST_TIME 80 8912
TEST_TIME 81 8880
TEST_TIME 82 8812
TEST_TIME 83 8729
TEST_TIME 84 8835
TEST_TIME 85 8886
TEST_TIME 86 8967
TEST_TIME 87 8966
TEST_TIME 88 8940
TEST_TIME 89 8977
TEST_TIME 90 9083
TEST_TIME 91 9047
TEST_TIME 92 9067
TEST_TIME 93 9094
TEST_TIME 94 9073
TEST_TIME 95 9131
TEST_TIME 96 9159
TEST_TIME 97 9210
TEST_TIME 98 9277
TEST_TIME 99 9285
TEST_TIME 100 9202
TEST_TIME 101 9211
TEST_TIME 102 9178
TEST_TIME 103 9180
TEST_TIME 104 9174
TEST_TIME 105 9127
TEST_TIME 106 9098
TEST_TIME 107 9082
TEST_TIME 108 9081
TEST_TIME 109 9078
TEST_TIME 110 9049
TEST_TIME 111 8987
TEST_TIME 112 9069
TEST_TIME 113 9080
TEST_TIME 114 9097
TEST_TIME 115 9097
TEST_TIME 116 9150
TEST_TIME 117 9136
TEST_TIME 118 9134
TEST_TIME 119 9124
TEST_TIME 120 9164

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 06-12-2021 16:38:46 to 06-12-2021 16:38:50.

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 1513133 1513133 0 0.00 0 26199 6 0 0
doc_list_biud 1513133 1513133 0 0.00 0 27539 33 0 0
sp_add_invoice_to_stock 4937 4866 71 1.43 20 193286 18105 0 71
sp_cancel_adding_invoice 5784 5784 0 0.00 5 68333 886 0 0
sp_cancel_client_order 1630 1630 0 0.00 4 20105 360 0 0
sp_cancel_customer_reserve 570048 570048 0 0.00 1 24997 69 0 0
sp_cancel_pay_from_customer 637 637 0 0.00 2 10168 84 0 0
sp_cancel_pay_to_supplier 403 403 0 0.00 2 3239 77 0 0
sp_cancel_supplier_invoice 6370 6358 12 0.18 2 23230 979 0 12
sp_cancel_supplier_order 438 438 0 0.00 10 1342614 115785 0 0
sp_cancel_write_off 9473 9473 0 0.00 1 7617 110 0 0
sp_client_order 19857 19857 0 0.00 3 20139 360 0 0
sp_customer_reserve 445998 445998 0 0.00 0 136548 181 0 0
sp_fill_shopping_cart 30299 30299 0 0.00 1 35334 46 0 0
sp_fill_shopping_cart_clo_res 445996 445996 0 0.00 0 14326 14 0 0
sp_get_clo_for_invoice 4862 4862 0 0.00 0 36148 345 0 0
sp_kill_cost_storno 16297 16297 0 0.00 0 15483 66 0 0
sp_kill_qstorno_ret_qs2qd 1156365 1156365 0 0.00 0 24995 38 0 0
sp_kill_qty_storno 608261 608261 0 0.00 0 27538 81 0 0
sp_lock_selected_doc 20021 20021 0 0.00 0 7721 4 0 0
sp_make_cost_storno 26772 26772 0 0.00 0 22294 60 0 0
sp_multiply_rows_for_pdistr 26772 26772 0 0.00 0 8833 34 0 0
sp_multiply_rows_for_qdistr 19857 19857 0 0.00 0 15052 160 0 0
sp_pay_from_customer 6740 6740 0 0.00 4 16426 130 0 0
sp_pay_to_supplier 5502 5502 0 0.00 5 14922 150 0 0
sp_payment_common 12242 12242 0 0.00 1 8723 33 0 0
sp_qd_handle_on_cancel_clo 1630 1630 0 0.00 0 20097 343 0 0
sp_qd_handle_on_invoice_upd_sts 10645 10645 0 0.00 0 27537 383 0 0
sp_qd_handle_on_reserve_upd_sts 9669 9669 0 0.00 0 13761 74 0 0
sp_reserve_write_off 9669 9669 0 0.00 6 15647 200 0 0
sp_supplier_invoice 5297 5297 0 0.00 7 81658 3869 0 0
sp_supplier_order 5168 5168 0 0.00 5 25312 1337 0 0
srv_aggregate_perf_data 98 97 1 1.02 2600 264792 21744 0 1
srv_fill_mon_cache_memory 64 64 0 0.00 1485 232102 45675 0 0
srv_find_qd_qs_mism 1054067 1054067 0 0.00 0 8431 5 0 0
srv_make_invnt_saldo 528 528 0 0.00 24 34604 1219 0 0
srv_make_money_saldo 774 774 0 0.00 0 1377 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 181437 386654 253769 0 0
t$perf-abend:sp_add_invoice_to_stock 71 71 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4866 4866 0 0.00 0 23825 215 0 0
t$perf-norm:sp_cancel_adding_invoice 407 407 0 0.00 1 4121 98 0 0
t$perf-norm:sp_cancel_client_order 1630 1630 0 0.00 0 4210 23 0 0
t$perf-norm:sp_cancel_customer_reserve 661 661 0 0.00 0 2174 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 637 637 0 0.00 0 2011 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 403 403 0 0.00 0 3802 27 0 0
t$perf-norm:sp_cancel_supplier_invoice 362 362 0 0.00 0 1627 14 0 0
t$perf-norm:sp_cancel_supplier_order 438 438 0 0.00 0 27419 1728 0 0
t$perf-norm:sp_cancel_write_off 953 953 0 0.00 0 1326 7 0 0
t$perf-norm:sp_client_order 19857 19857 0 0.00 0 7886 24 0 0
t$perf-norm:sp_customer_reserve 977 977 0 0.00 0 3105 9 0 0
t$perf-norm:sp_pay_from_customer 6740 6740 0 0.00 0 9758 13 0 0
t$perf-norm:sp_pay_to_supplier 5502 5502 0 0.00 0 6156 12 0 0
t$perf-norm:sp_reserve_write_off 9669 9669 0 0.00 0 7265 15 0 0
t$perf-norm:sp_supplier_invoice 5297 5297 0 0.00 0 15863 28 0 0
t$perf-norm:sp_supplier_order 5168 5168 0 0.00 0 5603 19 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 104 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 3966 62 0 0
t$perf-norm:srv_make_invnt_saldo 528 528 0 0.00 0 2505 27 0 0
t$perf-norm:srv_make_money_saldo 774 774 0 0.00 0 2146 21 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 9860 9718 142 1.44 0 2433 17 0 142
v_all_customers 35802 35802 0 0.00 0 18 1 0 0
v_all_suppliers 28652 28652 0 0.00 0 17 1 0 0
v_all_wares 39710 39710 0 0.00 0 179 1 0 0
v_cancel_adding_invoice 814 814 0 0.00 0 3779 10 0 0
v_cancel_client_order 3260 3260 0 0.00 0 682 3 0 0
v_cancel_customer_prepayment 1274 1274 0 0.00 0 2812 9 0 0
v_cancel_customer_reserve 1322 1322 0 0.00 0 1006 4 0 0
v_cancel_payment_to_supplier 806 806 0 0.00 0 1388 5 0 0
v_cancel_supplier_invoice 748 724 24 3.20 0 1024 18 0 24
v_cancel_supplier_order 876 876 0 0.00 0 2439 6 0 0
v_cancel_write_off 1906 1906 0 0.00 0 3815 16 0 0
v_max_id_clo_ord 5165 5165 0 0.00 0 6740 3 0 0
v_max_id_clo_res 977 977 0 0.00 0 4104 26 0 0
v_max_id_ord_sup 5278 5278 0 0.00 0 10325 6 0 0
v_max_non_paid_invoice 1619 1619 0 0.00 0 4890 9 0 0
v_max_non_paid_realizn 3680 3680 0 0.00 0 2606 4 0 0
v_min_id_clo_ord 5165 5165 0 0.00 0 4031 11 0 0
v_min_id_clo_res 977 977 0 0.00 0 449 2 0 0
v_min_id_ord_sup 5278 5278 0 0.00 0 10081 12 0 0
v_min_non_paid_invoice 5502 5502 0 0.00 0 8314 23 0 0
v_min_non_paid_realizn 6740 6740 0 0.00 0 8211 28 0 0
v_our_firm 10028 10028 0 0.00 0 15 1 0 0
v_reserve_write_off 19338 19338 0 0.00 0 3315 6 0 0
x_lock_depdocs_on_canc_invoice 11780 11780 0 0.00 0 15904 130 0 0
x_lock_depdocs_on_canc_sup_ord 438 438 0 0.00 0 3546 349 0 0
x_make_qstorno_1000_1200 5168 5168 0 0.00 1 25260 1247 0 0
x_make_qstorno_1200_2000 5297 5297 0 0.00 3 77358 3696 0 0
x_make_qstorno_2100_3300 445998 445998 0 0.00 0 136546 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-12-2021 16:38:51 to 06-12-2021 16:38:55.

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
14:08:02 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:03 39055 8192000000 9918634320 10232696832 991667840 10.798 100 83 166 0 1019612256 6524256 21420160
14:10:45 27516 8192000000 9947436208 10267955200 1025949072 11.129 100 97 194 0 1059909696 8990240 24970384
14:12:22 19192 8192000000 9979183536 10315476992 1062870976 11.484 100 78 156 0 1092060944 10009088 19180880
14:13:46 19430 8192000000 10050889328 10407030784 1132828080 12.148 100 75 150 0 1161755376 10895664 18031632
14:15:10 9604 8192000000 10103047440 10469937152 1195872304 12.738 100 72 144 3 1224270576 11090368 17307904
14:16:22 3246 8192000000 10147159168 10523250688 1241371584 13.159 100 78 154 0 1270131456 11783104 16976768
14:17:28 2395 8192000000 10191918144 10574311424 1276142624 13.478 100 90 180 0 1309846768 12817440 20886704
14:18:31 1482 8192000000 10206892864 10594439168 1296154208 13.660 100 78 156 0 1327427232 14635552 16637472
14:19:34 961 8192000000 10220633904 10614382592 1309297152 13.780 100 78 155 0 1339144160 12793888 17053120
14:20:36 9951 8192000000 10226991488 10627842048 1322163680 13.896 100 56 113 1 1341635024 6481040 12990304
14:21:47 5507 8192000000 10217481472 10629570560 1322753232 13.902 100 42 84 1 1339397984 7532432 9112320
14:22:53 6389 8192000000 10224065920 10642853888 1327050048 13.940 100 39 78 1 1351848288 16130832 8667408
14:24:00 9424 8192000000 10222185664 10642407424 1331745456 13.983 100 16 32 0 1341189040 5983392 3460192
14:25:10 55823 8192000000 10220337248 10646278144 1336547824 14.026 100 27 54 0 1348678384 6370656 5759904
14:27:09 7347 8192000000 10217791712 10636910592 1320873632 13.885 99 13 26 1 1329500976 5296752 3330592
14:28:20 4645 8192000000 10185160256 10604478464 1300026048 13.695 99 20 40 3 1310658432 5960528 4671856
14:29:26 32846 8192000000 10173799392 10584547328 1240520496 13.151 96 31 63 0 1252374640 5323856 6530288
14:31:26 4743 8192000000 10149481408 10553958400 1256444560 13.297 100 31 61 0 1273855968 10299824 7111584
14:32:32 9444 8192000000 10146483664 10551951360 1249532752 13.234 99 32 64 1 1262862768 5414336 7915680
14:33:42 5078 8192000000 10182360704 10589020160 1282631872 13.537 100 33 66 3 1296026128 5680784 7713472
14:34:53 48249 8192000000 10174869424 10593140736 1290170336 13.606 100 34 68 2 1304455424 5295312 8989776
14:36:45 6559 8192000000 10200497632 10616565760 1305865024 13.749 100 18 36 2 1316113664 6478560 3770080
14:37:54 17930 8192000000 10222022080 10628685824 1317367536 13.853 100 29 58 1 1330024672 5382896 7274240
14:39:36 28721 8192000000 10255061904 10667044864 1348515920 14.134 100 47 94 0 1369238288 8974880 11747488
14:41:14 56197 8192000000 10269162464 10696806400 1365278064 14.285 100 37 74 2 1385338144 11319024 8741056
14:43:32 39181 8192000000 10283316864 10711719936 1365507184 14.287 99 41 81 3 1390663568 14574832 10581552
14:45:15 11315 8192000000 10295180304 10720092160 1382131328 14.436 100 42 84 1 1409486848 17251744 10103776
14:46:30 7362 8192000000 10327618688 10747924480 1399668736 14.592 100 63 121 1 1436066688 22575248 13822704
14:47:48 6497 8192000000 10327834816 10745589760 1399151312 14.587 100 62 123 1 1436132944 22028704 14952928
14:49:01 4157 8192000000 10312203264 10719952896 1372190464 14.347 99 76 150 2 1412115072 21896640 18027968
14:50:09 14189 8192000000 10335419424 10754002944 1388101152 14.489 99 70 139 1 1437417104 32872736 16443216
14:51:32 5000 8192000000 10339100960 10756550656 1404734128 14.637 100 63 126 2 1448789840 28280960 15774752
14:52:43 4830 8192000000 10358638432 10775547904 1408752192 14.673 100 72 144 2 1461407472 35195648 17459632
14:53:55 5748 8192000000 10350600816 10769657856 1396052160 14.560 99 58 116 1 1450594016 38947408 15594448
14:55:06 2678 8192000000 10362321424 10771890176 1402338832 14.616 100 80 157 1 1463098848 41257984 19502032
14:56:15 1738 8192000000 10366265312 10777522176 1404361360 14.634 100 82 165 2 1472106848 46383424 21362064
14:57:20 2537 8192000000 10376366320 10779299840 1397540000 14.573 100 79 159 0 1473897136 55779712 20577424
14:58:26 1984 8192000000 10372106400 10780639232 1375151216 14.373 98 81 162 1 1457762464 61778336 20832912
14:59:35 1482 8192000000 10368519504 10769002496 1385653344 14.467 100 90 180 1 1467833440 58711296 23468800
15:00:47 5635 8192000000 10376973072 10794577920 1402614080 14.618 100 53 106 2 1479635008 62738272 14282656
15:01:57 7883 8192000000 10371972640 10798354432 1398205632 14.579 99 49 99 0 1471949520 60787488 12956400
15:03:10 19015 8192000000 10368985264 10796621824 1391175312 14.516 99 40 80 0 1468029600 65538384 11315904
15:04:36 19843 8192000000 10377566336 10804334592 1398553616 14.582 100 35 70 0 1480769072 71911024 10304432
15:05:58 14879 8192000000 10366530144 10799964160 1369855712 14.326 100 35 68 0 1472933632 92204448 10873472
15:07:15 24758 8192000000 10333138112 10777792512 1342574896 14.081 99 28 56 1 1442536928 91254400 8707632
15:08:43 20471 8192000000 10272609872 10723946496 1346991728 14.120 99 19 38 0 1385717152 33600896 5124528
15:10:04 24874 8192000000 10273164512 10706681856 1356836160 14.209 100 49 94 0 1379798560 12077280 10885120
15:11:39 56218 8192000000 10272006896 10721660928 1365093856 14.283 100 7 15 0 1375938608 9024272 1820480
15:13:35 59035 8192000000 10250426592 10698354688 1347727376 14.127 99 7 15 0 1356139776 7471280 941120
15:15:42 123621 8192000000 10294440176 10733486080 1366517488 14.296 99 30 60 1 1382277440 8706976 7052976
15:18:46 16874 8192000000 10291182112 10730651648 1379877104 14.415 100 33 65 0 1394165120 6845824 7442192
15:20:21 59494 8192000000 10298219520 10749022208 1363123392 14.265 98 20 39 1 1379755184 11696496 4935296
15:22:21 232071 8192000000 10318923696 10756366336 1354355104 14.187 97 20 38 1 1374280784 15747872 4177808
15:27:16 61352 8192000000 10286950880 10758373376 1370189744 14.329 98 11 22 0 1384631712 11902416 2539552
15:29:20 69221 8192000000 10266010560 10721619968 1340286800 14.060 96 13 26 1 1350870240 6915952 3667488
15:31:36 27434 8192000000 10295372368 10748489728 1397981376 14.577 100 17 33 1 1406633296 5597440 3054480
15:33:18 47574 8192000000 10259285248 10726563840 1330923264 13.975 96 7 14 1 1341095808 8813088 1359456
15:35:05 74741 8192000000 10276555648 10744582144 1359959776 14.237 98 13 26 2 1371831312 9145216 2726320
15:38:13 67835 8192000000 10301642928 10768011264 1391004032 14.515 99 24 48 2 1410369792 14264256 5101504
15:40:23 58183 8192000000 10310660112 10772254720 1405217952 14.641 100 22 43 1 1422451344 12662752 4570640
15:42:22 52991 8192000000 10305230544 10751000576 1358781360 14.226 97 31 62 3 1378258960 12061104 7416496
15:44:16 49010 8192000000 10306864448 10762457088 1379140976 14.409 99 8 16 1 1395634224 14515216 1978032
15:46:05 43588 8192000000 10258121008 10698547200 1294755408 13.648 94 24 48 0 1308111568 8051792 5304368
15:48:21 47414 8192000000 10270470048 10719084544 1344173232 14.095 98 18 36 0 1356802704 8844672 3784800
15:50:10 55800 8192000000 10280144288 10720174080 1334731216 14.010 98 37 74 0 1358336304 15641136 7963952
15:52:13 101400 8192000000 10276963584 10729545728 1374849920 14.370 100 19 38 1 1394220896 14797376 4573600
15:55:00 34717 8192000000 10263810528 10714701824 1357354048 14.214 99 13 26 0 1366832928 6797088 2681792
15:56:38 44101 8192000000 10262098176 10725322752 1366595824 14.297 100 5 10 0 1379580496 11926176 1058496
15:58:43 87688 8192000000 10295497456 10750885888 1384874112 14.460 100 17 36 0 1402442176 13217296 4350768
16:01:57 45397 8192000000 10296281392 10754027520 1367190416 14.302 99 24 48 0 1392038848 19162480 5685952
16:03:43 62274 8192000000 10284191024 10739585024 1352485600 14.170 98 17 34 1 1364052992 7940896 3626496
16:05:47 34935 8192000000 10296148624 10734313472 1338782624 14.046 97 37 74 0 1359413600 11665088 8965888
16:07:22 42906 8192000000 10255672208 10716954624 1308589600 13.773 96 10 20 0 1324678704 13897648 2191456
16:09:07 69011 8192000000 10292305280 10742239232 1362080208 14.256 98 18 36 0 1381012464 15570096 3362160
16:11:16 50838 8192000000 10307977728 10751471616 1378294096 14.401 99 30 60 2 1397213296 12482512 6436688
16:13:14 47812 8192000000 10310646368 10753986560 1379911968 14.416 99 33 63 1 1400508144 13795040 6801136
16:15:07 140151 8192000000 10303452720 10773397504 1367376768 14.304 98 4 8 0 1387349712 19274320 698624
16:18:51 71932 8192000000 10300208240 10752741376 1342671840 14.081 96 18 36 1 1355390320 8727264 3991216
16:21:04 31361 8192000000 10293322464 10754629632 1380872896 14.424 99 20 40 0 1395975872 9907600 5195376
16:22:36 74902 8192000000 10287381136 10739470336 1351399136 14.160 97 15 30 0 1364540640 9663856 3477648
16:24:53 69067 8192000000 10282802144 10746433536 1383699744 14.450 100 12 24 0 1401499632 14789504 3010384
16:27:10 91065 8192000000 10294647728 10749620224 1379547248 14.413 99 16 32 0 1394822000 11460176 3814576
16:30:27 78866 8192000000 10295901200 10748403712 1381623136 14.431 99 13 26 0 1393246160 8819744 2803280
16:32:51 130811 8192000000 10296182608 10760626176 1354193776 14.185 97 7 14 0 1370395520 14723584 1478160
16:36:04 43077 8192000000 10317992576 10760232960 1384601936 14.458 99 28 56 0 1401031024 10077232 6351856
16:37:49 19371 8192000000 9024995440 9236406272 187936896 2.242 14 2 4 0 196715376 8682992 95488

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 06-12-2021 16:38:57 to 06-12-2021 16:39:12.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 71 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-12-2021 16:39:12 to 06-12-2021 16:39:14.

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          234272
MON$OLDEST_ACTIVE               234273
MON$OLDEST_SNAPSHOT             234273
MON$NEXT_TRANSACTION            234273
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                       2036896
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33541 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33541 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33541 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33541 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 ---
:94: 
:95: mscs-test-161.budzdorov.ru    Mon Dec  6 16:38:11 2021
:96:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:97: 
:98: 
:99: mscs-test-161.budzdorov.ru    Mon Dec  6 16:38:11 2021
:100:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:101: 
--- end of diff output ---

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

06.12.21 16:39:15. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-12-06 16:39:18 No severe PSQL-related problems occured
06.12.21 16:39:19. End of report.