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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-06-02 01:38:14
DTS_END                         2022-06-02 01:38:14.1310
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(57:17)
IP                              ::1/56000
TRN_ID                          246392
ATT_ID                          676
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: 9479.64 26051 1137557
customer order: creation 172.38 347 20685
customer order: refuse 14.99 349 1799
order to supplier: creation 46.29 1270 5555
order to supplier: removal 3.59 103410 431
invoice (draft): creation 46.57 3734 5588
invoice (draft): removal 54.65 802 6558
invoice accept: apply 42.35 18228 5082
invoice accept: cancel 49.53 744 5944
customer reserve: creation 3878.87 182 465464
customer reserve: removal 4869.11 59 584293
realization accept: apply 83.83 190 10059
realization accept: cancel 84.03 107 10084
payment from customer: creation 61.03 108 7324
payment from customer: removal 6.13 109 736
payment to supplier: creation 50.11 137 6013
payment to supplier: removal 3.38 114 405
service: total inventory turnovers 5.18 1110 622
service: total monetary turnovers 6.71 31 805
service: aggregate perf. data 0.88 21186 106
service: refresh index statistics 0.03 368803 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 02-06-2022 01:39:46 to 02-06-2022 01:39:48.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4679
TEST_TIME 2 2933
TEST_TIME 3 3715
TEST_TIME 4 5416
TEST_TIME 5 5182
TEST_TIME 6 5011
TEST_TIME 7 5315
TEST_TIME 8 5290
TEST_TIME 9 5591
TEST_TIME 10 6039
TEST_TIME 11 5971
TEST_TIME 12 5811
TEST_TIME 13 6067
TEST_TIME 14 6234
TEST_TIME 15 6489
TEST_TIME 16 6660
TEST_TIME 17 6909
TEST_TIME 18 7083
TEST_TIME 19 7074
TEST_TIME 20 7534
TEST_TIME 21 7793
TEST_TIME 22 8178
TEST_TIME 23 8510
TEST_TIME 24 8476
TEST_TIME 25 8931
TEST_TIME 26 9133
TEST_TIME 27 9153
TEST_TIME 28 9869
TEST_TIME 29 10386
TEST_TIME 30 10736
TEST_TIME 31 10619
TEST_TIME 32 10696
TEST_TIME 33 10860
TEST_TIME 34 10737
TEST_TIME 35 10713
TEST_TIME 36 10498
TEST_TIME 37 10340
TEST_TIME 38 10250
TEST_TIME 39 10313
TEST_TIME 40 10300
TEST_TIME 41 10145
TEST_TIME 42 10148
TEST_TIME 43 10075
TEST_TIME 44 10077
TEST_TIME 45 10224
TEST_TIME 46 10150
TEST_TIME 47 10050
TEST_TIME 48 10033
TEST_TIME 49 9998
TEST_TIME 50 9865
TEST_TIME 51 9760
TEST_TIME 52 9667
TEST_TIME 53 9795
TEST_TIME 54 9804
TEST_TIME 55 9758
TEST_TIME 56 9797
TEST_TIME 57 9860
TEST_TIME 58 9786
TEST_TIME 59 9851
TEST_TIME 60 9880
TEST_TIME 61 10015
TEST_TIME 62 9967
TEST_TIME 63 10076
TEST_TIME 64 10076
TEST_TIME 65 10082
TEST_TIME 66 10116
TEST_TIME 67 10019
TEST_TIME 68 9942
TEST_TIME 69 9992
TEST_TIME 70 9958
TEST_TIME 71 9909
TEST_TIME 72 9872
TEST_TIME 73 9892
TEST_TIME 74 9991
TEST_TIME 75 9951
TEST_TIME 76 9877
TEST_TIME 77 9907
TEST_TIME 78 10017
TEST_TIME 79 9965
TEST_TIME 80 10045
TEST_TIME 81 10042
TEST_TIME 82 10016
TEST_TIME 83 10059
TEST_TIME 84 9971
TEST_TIME 85 9895
TEST_TIME 86 9847
TEST_TIME 87 9801
TEST_TIME 88 9796
TEST_TIME 89 9810
TEST_TIME 90 9776
TEST_TIME 91 9763
TEST_TIME 92 9708
TEST_TIME 93 9682
TEST_TIME 94 9703
TEST_TIME 95 9697
TEST_TIME 96 9735
TEST_TIME 97 9711
TEST_TIME 98 9718
TEST_TIME 99 9700
TEST_TIME 100 9698
TEST_TIME 101 9738
TEST_TIME 102 9730
TEST_TIME 103 9720
TEST_TIME 104 9790
TEST_TIME 105 9840
TEST_TIME 106 9829
TEST_TIME 107 9883
TEST_TIME 108 9858
TEST_TIME 109 9784
TEST_TIME 110 9735
TEST_TIME 111 9699
TEST_TIME 112 9691
TEST_TIME 113 9678
TEST_TIME 114 9625
TEST_TIME 115 9601
TEST_TIME 116 9569
TEST_TIME 117 9523
TEST_TIME 118 9526
TEST_TIME 119 9551
TEST_TIME 120 9483

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 02-06-2022 01:39:50 to 02-06-2022 01:39:53.

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 1568149 1568149 0 0.00 0 31280 6 0 0
doc_list_biud 1568149 1568149 0 0.00 0 37598 28 0 0
sp_add_invoice_to_stock 5197 5082 115 2.21 35 181167 18228 0 115
sp_cancel_adding_invoice 5944 5944 0 0.00 5 63812 745 0 0
sp_cancel_client_order 1799 1799 0 0.00 4 7781 349 0 0
sp_cancel_customer_reserve 584293 584293 0 0.00 1 37157 60 0 0
sp_cancel_pay_from_customer 736 736 0 0.00 2 8957 109 0 0
sp_cancel_pay_to_supplier 405 405 0 0.00 2 15370 114 0 0
sp_cancel_supplier_invoice 6567 6558 9 0.13 2 21206 803 0 9
sp_cancel_supplier_order 431 431 0 0.00 5 1258602 103410 0 0
sp_cancel_write_off 10084 10084 0 0.00 1 11304 107 0 0
sp_client_order 20685 20685 0 0.00 3 50936 348 0 0
sp_customer_reserve 465464 465464 0 0.00 0 85854 182 0 0
sp_fill_shopping_cart 31827 31827 0 0.00 1 18627 44 0 0
sp_fill_shopping_cart_clo_res 465460 465460 0 0.00 0 23935 14 0 0
sp_get_clo_for_invoice 5077 5077 0 0.00 0 31616 316 0 0
sp_kill_cost_storno 17169 17169 0 0.00 0 11301 57 0 0
sp_kill_qstorno_ret_qs2qd 1185659 1185659 0 0.00 0 37137 32 0 0
sp_kill_qty_storno 624242 624242 0 0.00 0 37597 69 0 0
sp_lock_selected_doc 21048 21048 0 0.00 0 8535 6 0 0
sp_make_cost_storno 28470 28470 0 0.00 0 30992 60 0 0
sp_multiply_rows_for_pdistr 28470 28470 0 0.00 0 17145 30 0 0
sp_multiply_rows_for_qdistr 20685 20685 0 0.00 0 49619 150 0 0
sp_pay_from_customer 7324 7324 0 0.00 4 17068 109 0 0
sp_pay_to_supplier 6013 6013 0 0.00 4 18456 137 0 0
sp_payment_common 13337 13337 0 0.00 1 17042 28 0 0
sp_qd_handle_on_cancel_clo 1799 1799 0 0.00 0 7762 323 0 0
sp_qd_handle_on_invoice_upd_sts 11018 11018 0 0.00 0 37594 319 0 0
sp_qd_handle_on_reserve_upd_sts 10059 10059 0 0.00 0 19741 63 0 0
sp_reserve_write_off 10059 10059 0 0.00 6 19771 191 0 0
sp_supplier_invoice 5588 5588 0 0.00 6 128066 3734 0 0
sp_supplier_order 5555 5555 0 0.00 5 54752 1270 0 0
srv_aggregate_perf_data 106 106 0 0.00 2566 253201 21186 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1433 154002 44285 0 0
srv_find_qd_qs_mism 1088958 1088958 0 0.00 0 10028 5 0 0
srv_make_invnt_saldo 622 622 0 0.00 27 71680 1111 0 0
srv_make_money_saldo 805 805 0 0.00 0 3983 32 0 0
srv_recalc_idx_stat 4 4 0 0.00 214668 684150 368803 0 0
t$perf-abend:sp_add_invoice_to_stock 115 115 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5082 5082 0 0.00 0 12879 170 0 0
t$perf-norm:sp_cancel_adding_invoice 413 413 0 0.00 0 4900 77 0 0
t$perf-norm:sp_cancel_client_order 1799 1799 0 0.00 0 5189 14 0 0
t$perf-norm:sp_cancel_customer_reserve 704 704 0 0.00 0 5871 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 736 736 0 0.00 0 497 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 405 405 0 0.00 0 3586 25 0 0
t$perf-norm:sp_cancel_supplier_invoice 413 413 0 0.00 0 4208 20 0 0
t$perf-norm:sp_cancel_supplier_order 431 431 0 0.00 0 26830 1445 0 0
t$perf-norm:sp_cancel_write_off 1014 1014 0 0.00 0 5981 23 0 0
t$perf-norm:sp_client_order 20685 20685 0 0.00 0 9612 18 0 0
t$perf-norm:sp_customer_reserve 1066 1066 0 0.00 0 364 3 0 0
t$perf-norm:sp_pay_from_customer 7324 7324 0 0.00 0 11494 11 0 0
t$perf-norm:sp_pay_to_supplier 6013 6013 0 0.00 0 8978 17 0 0
t$perf-norm:sp_reserve_write_off 10059 10059 0 0.00 0 8815 11 0 0
t$perf-norm:sp_supplier_invoice 5588 5588 0 0.00 0 5117 18 0 0
t$perf-norm:sp_supplier_order 5555 5555 0 0.00 0 11196 16 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 63 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 115 4 0 0
t$perf-norm:srv_make_invnt_saldo 622 622 0 0.00 0 3033 21 0 0
t$perf-norm:srv_make_money_saldo 805 805 0 0.00 0 2080 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 20 9 0 0
v_add_invoice_to_stock 10378 10148 230 2.21 0 8288 19 0 230
v_all_customers 37744 37744 0 0.00 0 17 1 0 0
v_all_suppliers 30900 30900 0 0.00 0 43 1 0 0
v_all_wares 41370 41370 0 0.00 0 116 1 0 0
v_cancel_adding_invoice 826 826 0 0.00 0 7865 24 0 0
v_cancel_client_order 3598 3598 0 0.00 0 3941 6 0 0
v_cancel_customer_prepayment 1472 1472 0 0.00 0 2273 6 0 0
v_cancel_customer_reserve 1408 1408 0 0.00 0 15194 22 0 0
v_cancel_payment_to_supplier 810 810 0 0.00 0 15317 25 0 0
v_cancel_supplier_invoice 844 826 18 2.13 0 3730 20 0 18
v_cancel_supplier_order 862 862 0 0.00 0 2097 5 0 0
v_cancel_write_off 2028 2028 0 0.00 0 8285 18 0 0
v_max_id_clo_ord 5555 5555 0 0.00 0 321 1 0 0
v_max_id_clo_res 1066 1066 0 0.00 0 4479 13 0 0
v_max_id_ord_sup 5587 5587 0 0.00 0 3624 3 0 0
v_max_non_paid_invoice 1705 1705 0 0.00 0 250 2 0 0
v_max_non_paid_realizn 4011 4011 0 0.00 0 8691 5 0 0
v_min_id_clo_ord 5555 5555 0 0.00 0 9780 13 0 0
v_min_id_clo_res 1066 1066 0 0.00 0 22073 31 0 0
v_min_id_ord_sup 5587 5587 0 0.00 0 6715 11 0 0
v_min_non_paid_invoice 6013 6013 0 0.00 0 16568 26 0 0
v_min_non_paid_realizn 7324 7324 0 0.00 0 14896 19 0 0
v_our_firm 10252 10252 0 0.00 0 13 1 0 0
v_reserve_write_off 20118 20118 0 0.00 0 5689 5 0 0
x_lock_depdocs_on_canc_invoice 12089 12089 0 0.00 0 20992 131 0 0
x_lock_depdocs_on_canc_sup_ord 431 431 0 0.00 0 9338 335 0 0
x_make_qstorno_1000_1200 5555 5555 0 0.00 1 54734 1179 0 0
x_make_qstorno_1200_2000 5588 5588 0 0.00 3 128047 3582 0 0
x_make_qstorno_2100_3300 465464 465464 0 0.00 0 85853 166 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-06-2022 01:39:54 to 02-06-2022 01:39:58.

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
23:08:18 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:18 8378 8192000000 9883410608 10189033472 968713792 10.574 100 89 178 0 997506656 5465088 23327776
23:10:36 4736 8192000000 9924924992 10242760704 1011353648 10.988 100 86 172 0 1041745584 7886224 22505712
23:11:49 16629 8192000000 9961078992 10289393664 1045183632 11.314 100 82 164 1 1075842528 9565280 21093616
23:13:09 18681 8192000000 10018450624 10360745984 1096756256 11.807 100 75 150 0 1124863104 10119776 17987072
23:14:31 8501 8192000000 10071017888 10427023360 1160622704 12.409 100 81 162 1 1190711936 11586240 18502992
23:15:43 7191 8192000000 10114317856 10482552832 1200366720 12.780 100 78 156 0 1230233232 11917792 17948720
23:16:53 4862 8192000000 10154212800 10532438016 1247806400 13.218 100 78 156 0 1277227056 12329408 17091248
23:17:59 2344 8192000000 10203846176 10582261760 1280105936 13.514 100 91 181 1 1316373296 16437600 19829760
23:19:02 2114 8192000000 10213470288 10597953536 1299987792 13.695 100 92 185 0 1333217200 13049168 20180240
23:20:06 22460 8192000000 10217317952 10612604928 1309274832 13.779 100 73 148 2 1333310864 7176608 16859424
23:21:30 6822 8192000000 10208961856 10621444096 1320345648 13.880 100 33 66 1 1335022848 7066336 7610864
23:22:39 7106 8192000000 10217369120 10634518528 1331311072 13.979 100 20 40 0 1342407296 6493712 4602512
23:23:49 12071 8192000000 10217465024 10639306752 1334852288 14.011 100 16 32 0 1344238272 6079312 3306672
23:25:02 51129 8192000000 10224608816 10651627520 1342082080 14.076 100 21 42 0 1351933536 5343664 4507792
23:26:54 4277 8192000000 10249133584 10663985152 1347206736 14.122 100 43 86 3 1366845376 9680672 9957968
23:28:00 9856 8192000000 10231689424 10651693056 1339912944 14.057 100 17 34 0 1348781664 5354288 3514432
23:29:13 59978 8192000000 10190692816 10586406912 1248222176 13.222 96 40 78 2 1264405312 6278864 9904272
23:31:13 4900 8192000000 10150737552 10554159104 1261095120 13.340 100 20 40 0 1271293104 5917232 4280752
23:32:18 8078 8192000000 10154813552 10559111168 1256456864 13.298 99 19 38 1 1266520640 5423504 4640272
23:33:27 11811 8192000000 10172136544 10574581760 1264612720 13.372 98 25 50 1 1275948480 5339088 5996672
23:34:47 7201 8192000000 10177655936 10589102080 1280133328 13.514 99 27 54 1 1293437600 7667200 5637072
23:35:55 8076 8192000000 10205508000 10610556928 1304173488 13.733 100 29 58 1 1318065504 7202224 6689792
23:37:08 8923 8192000000 10206621552 10624860160 1316822240 13.848 100 24 46 4 1327769216 5444208 5502768
23:38:20 58536 8192000000 10247397968 10659082240 1335499776 14.017 100 44 88 0 1355749408 10639504 9610128
23:40:23 49073 8192000000 10254502112 10672885760 1343241760 14.087 99 41 82 1 1362930352 10582496 9106096
23:42:25 38538 8192000000 10284418976 10717483008 1370372336 14.330 100 24 48 0 1395486928 19032032 6082560
23:44:07 21017 8192000000 10309986144 10733236224 1392628896 14.529 100 56 112 1 1425592576 20114416 12849264
23:45:35 12777 8192000000 10324396016 10748825600 1402010096 14.613 100 52 104 1 1436365600 21194576 13160928
23:46:58 4630 8192000000 10337385696 10760048640 1398216192 14.579 99 64 127 0 1439914080 26450560 15247328
23:48:08 8271 8192000000 10339032736 10770980864 1410263824 14.686 100 53 106 0 1451869536 28975552 12630160
23:49:23 5947 8192000000 10337796016 10756747264 1399451536 14.590 100 66 132 0 1443550256 27950784 16147936
23:50:36 8214 8192000000 10337637184 10756542464 1398645552 14.583 100 65 130 1 1448018256 33454016 15918688
23:51:52 3054 8192000000 10337028160 10754211840 1397827232 14.576 100 61 122 2 1448594080 35147584 15619264
23:52:59 2044 8192000000 10360089792 10776576000 1384102800 14.453 98 72 144 3 1438804192 35466480 19234912
23:54:05 3607 8192000000 10340962752 10749722624 1388886512 14.496 100 79 156 2 1448197728 40084800 19226416
23:55:15 2790 8192000000 10352193632 10755477504 1381682992 14.432 99 78 154 1 1444696736 43713872 19299872
23:56:23 2660 8192000000 10338825984 10742419456 1373839104 14.361 100 78 156 1 1440965200 46982768 20143328
23:57:32 1718 8192000000 10357426176 10755960832 1379350128 14.411 99 89 179 0 1456827552 55040416 22437008
23:58:39 5107 8192000000 10358126912 10778923008 1396780352 14.566 100 63 126 1 1469108096 54914688 17413056
23:59:48 3916 8192000000 10341113376 10763780096 1380514544 14.421 100 43 86 0 1448936960 56479248 11943168
00:01:00 1431 8192000000 10365774288 10771746816 1384090624 14.453 100 82 164 3 1461372896 56480720 20801552
00:02:05 6381 8192000000 10365752608 10778320896 1384146240 14.454 99 68 135 1 1453786160 52216400 17423520
00:03:21 16337 8192000000 10360243472 10796294144 1403979696 14.630 100 32 64 0 1471287776 58595536 8712544
00:04:51 35492 8192000000 10339608176 10782683136 1374986944 14.372 99 20 40 2 1432387872 51834720 5566208
00:06:38 40656 8192000000 10307076880 10755792896 1359768288 14.235 99 15 30 2 1422154416 57825712 4560416
00:08:27 52069 8192000000 10284294256 10724724736 1348586464 14.135 98 15 30 2 1362061888 10057104 3418320
00:10:31 85893 8192000000 10306712608 10764636160 1383465904 14.448 99 8 16 0 1402401104 17105360 1829840
00:13:40 33634 8192000000 10307708688 10745221120 1389272640 14.499 100 43 83 5 1404793232 5774512 9746080
00:15:16 154001 8192000000 10296713168 10745794560 1357071072 14.211 97 38 76 2 1377786288 11610528 9104688
00:18:52 59180 8192000000 10290470080 10750226432 1361965104 14.255 97 18 36 1 1382322688 16365808 3991776
00:20:52 35579 8192000000 10304597536 10757070848 1395767856 14.557 100 23 46 0 1412716320 11278896 5669568
00:22:31 26800 8192000000 10302896128 10747039744 1374434176 14.367 99 52 103 3 1399079840 11070672 13574992
00:24:04 18272 8192000000 10321238800 10757050368 1394573088 14.547 100 57 113 1 1415908976 8596576 12739312
00:25:24 77672 8192000000 10271942704 10732863488 1312361088 13.807 94 10 19 0 1323739456 8851888 2526480
00:27:42 22596 8192000000 10274665856 10742661120 1379128640 14.409 100 11 22 2 1390376048 8581664 2665744
00:29:07 17905 8192000000 10288395040 10737184768 1374248768 14.365 99 25 50 2 1394173312 13326352 6598192
00:30:27 69898 8192000000 10291786480 10755768320 1375035200 14.372 99 14 28 0 1394801696 15815792 3950704
00:32:40 40911 8192000000 10294234128 10739470336 1346291888 14.114 97 31 60 1 1361205632 8316576 6597168
00:34:22 54324 8192000000 10288146176 10748735488 1359685392 14.235 98 22 44 0 1373251040 8522576 5043072
00:36:17 46323 8192000000 10301773184 10750562304 1373564656 14.359 99 22 43 0 1394413840 15627504 5221680
00:38:20 85854 8192000000 10294178464 10752348160 1303585680 13.728 94 15 30 0 1324915904 17982816 3347408
00:40:53 59704 8192000000 10284493040 10737479680 1326893040 13.939 96 14 28 2 1341473808 11717744 2863024
00:42:54 47614 8192000000 10267389024 10711027712 1314104944 13.823 96 18 36 0 1328956992 11391792 3460256
00:44:43 91275 8192000000 10267913840 10710556672 1321977488 13.895 96 14 28 0 1337230752 12168688 3084576
00:47:23 106501 8192000000 10248813184 10702151680 1285481664 13.563 94 7 14 1 1298790288 12534352 774272
00:50:34 51690 8192000000 10300179456 10757332992 1383844352 14.451 100 17 33 0 1410774288 23053568 3876368
00:53:30 39096 8192000000 10309741440 10751774720 1374731120 14.369 99 32 64 0 1393296368 11613920 6951328
00:55:13 36125 8192000000 10326520704 10772353024 1398732096 14.584 100 47 94 0 1427278096 17310000 11236000
00:56:58 57709 8192000000 10319479776 10768998400 1389277216 14.499 99 30 59 3 1410461216 14468608 6715392
00:58:56 67368 8192000000 10305631008 10763522048 1382324048 14.437 99 11 23 0 1394917440 10444880 2148512
01:01:04 29818 8192000000 10304844464 10751717376 1377358816 14.393 99 28 56 0 1394744960 10725968 6660176
01:02:38 86497 8192000000 10288101024 10746650624 1369573456 14.323 98 14 28 0 1380859888 8545104 2741328
01:05:05 33395 8192000000 10299514416 10754002944 1391676976 14.521 100 30 60 1 1409630432 11576448 6377008
01:06:39 36686 8192000000 10278303760 10742239232 1351594592 14.162 97 13 26 2 1366578080 11569568 3413920
01:08:22 76398 8192000000 10302283808 10772611072 1383699360 14.450 99 11 22 0 1406493616 20495792 2298464
01:10:39 66702 8192000000 10275864944 10743336960 1358229776 14.221 97 12 24 0 1373004608 12236512 2538320
01:13:17 82938 8192000000 10294675920 10765512704 1378233760 14.401 99 13 25 0 1403044240 21630976 3179504
01:15:43 45800 8192000000 10297199728 10750742528 1338085584 14.040 96 38 74 0 1355260160 8496512 8678064
01:17:29 28148 8192000000 10290451456 10739568640 1372485504 14.349 99 28 56 1 1388088848 9630416 5972928
01:18:57 90756 8192000000 10289802656 10751815680 1347132480 14.122 97 18 36 2 1369735664 18639232 3963952
01:22:07 131277 8192000000 10297502832 10763497472 1384760560 14.459 100 14 28 0 1407335824 19625712 2949552
01:25:25 101447 8192000000 10283800976 10747129856 1369369104 14.321 99 23 46 1 1391485408 16527008 5589296
01:28:58 44905 8192000000 10290883216 10729848832 1351454672 14.161 98 40 79 0 1369003376 8933296 8615408
01:31:26 51070 8192000000 10253629584 10717466624 1347188144 14.122 99 7 14 1 1356654656 7810240 1656272
01:33:28 71417 8192000000 10268507120 10724724736 1344505568 14.098 98 19 37 1 1366436592 17302144 4628880
01:35:53 61143 8192000000 10261787184 10725498880 1349702480 14.145 99 15 30 0 1367161200 13629616 3829104
01:37:55 25568 8192000000 9093248512 9333501952 263695792 3.118 20 4 8 0 268284544 3690464 898288

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 02-06-2022 01:40:00 to 02-06-2022 01:40:15.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 115 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 02-06-2022 01:40:16 to 02-06-2022 01:40:18.

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          246712
MON$OLDEST_ACTIVE               246713
MON$OLDEST_SNAPSHOT             246713
MON$NEXT_TRANSACTION            246713
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                       2067424
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33598 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33598 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33598 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33598 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 ---
:41: 
:42: mscs-test-161.budzdorov.ru    Thu Jun  2 01:38:22 2022
:43:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:44: 
--- end of diff output ---

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

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