Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.01.2023 22: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 15479244 kB
MemAvailable 30416956 kB
Buffers 0 kB
Cached 16217684 kB
SwapCached 4332 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.11
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 2023-01-29 22:38:39
DTS_END                         2023-01-29 22:38:39.8580
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/51886
TRN_ID                          242144
ATT_ID                          712
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: 9087.13 21259 1090456
customer order: creation 174.88 382 20986
customer order: refuse 15.15 355 1818
order to supplier: creation 45.19 1207 5423
order to supplier: removal 3.28 95544 393
invoice (draft): creation 46.43 3313 5571
invoice (draft): removal 50.06 719 6007
invoice accept: apply 41.89 19115 5027
invoice accept: cancel 45.28 738 5433
customer reserve: creation 3914.63 187 469756
customer reserve: removal 4461.60 54 535392
realization accept: apply 84.48 174 10138
realization accept: cancel 76.19 111 9143
payment from customer: creation 59.15 103 7098
payment from customer: removal 5.71 74 685
payment to supplier: creation 47.29 148 5675
payment to supplier: removal 3.48 87 417
service: total inventory turnovers 4.78 1009 573
service: total monetary turnovers 6.79 39 815
service: aggregate perf. data 0.85 16332 102
service: refresh index statistics 0.03 285480 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 29-01-2023 22:39:15 to 29-01-2023 22:39:17.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1542
TEST_TIME 2 3142
TEST_TIME 3 3946
TEST_TIME 4 4267
TEST_TIME 5 4266
TEST_TIME 6 4801
TEST_TIME 7 4721
TEST_TIME 8 5017
TEST_TIME 9 5018
TEST_TIME 10 5199
TEST_TIME 11 5516
TEST_TIME 12 6265
TEST_TIME 13 6398
TEST_TIME 14 6865
TEST_TIME 15 7433
TEST_TIME 16 7886
TEST_TIME 17 7939
TEST_TIME 18 8495
TEST_TIME 19 8394
TEST_TIME 20 8586
TEST_TIME 21 8858
TEST_TIME 22 9029
TEST_TIME 23 9421
TEST_TIME 24 9458
TEST_TIME 25 9519
TEST_TIME 26 9757
TEST_TIME 27 9694
TEST_TIME 28 9625
TEST_TIME 29 10029
TEST_TIME 30 10275
TEST_TIME 31 10050
TEST_TIME 32 9777
TEST_TIME 33 9878
TEST_TIME 34 9867
TEST_TIME 35 9960
TEST_TIME 36 9899
TEST_TIME 37 9979
TEST_TIME 38 9873
TEST_TIME 39 9831
TEST_TIME 40 9655
TEST_TIME 41 9536
TEST_TIME 42 9570
TEST_TIME 43 9462
TEST_TIME 44 9389
TEST_TIME 45 9295
TEST_TIME 46 9222
TEST_TIME 47 9259
TEST_TIME 48 9119
TEST_TIME 49 9115
TEST_TIME 50 9067
TEST_TIME 51 9080
TEST_TIME 52 8972
TEST_TIME 53 8933
TEST_TIME 54 8952
TEST_TIME 55 8962
TEST_TIME 56 8884
TEST_TIME 57 8812
TEST_TIME 58 8828
TEST_TIME 59 8898
TEST_TIME 60 8879
TEST_TIME 61 8980
TEST_TIME 62 8951
TEST_TIME 63 8938
TEST_TIME 64 8871
TEST_TIME 65 9144
TEST_TIME 66 9262
TEST_TIME 67 9282
TEST_TIME 68 9312
TEST_TIME 69 9362
TEST_TIME 70 9307
TEST_TIME 71 9268
TEST_TIME 72 9223
TEST_TIME 73 9132
TEST_TIME 74 9212
TEST_TIME 75 9213
TEST_TIME 76 9187
TEST_TIME 77 9130
TEST_TIME 78 9079
TEST_TIME 79 9065
TEST_TIME 80 8993
TEST_TIME 81 9009
TEST_TIME 82 8962
TEST_TIME 83 8913
TEST_TIME 84 8958
TEST_TIME 85 8970
TEST_TIME 86 9009
TEST_TIME 87 9015
TEST_TIME 88 8994
TEST_TIME 89 9015
TEST_TIME 90 9003
TEST_TIME 91 9071
TEST_TIME 92 9082
TEST_TIME 93 9067
TEST_TIME 94 9062
TEST_TIME 95 9106
TEST_TIME 96 9112
TEST_TIME 97 9147
TEST_TIME 98 9149
TEST_TIME 99 9096
TEST_TIME 100 9089
TEST_TIME 101 9036
TEST_TIME 102 9030
TEST_TIME 103 9007
TEST_TIME 104 8979
TEST_TIME 105 8993
TEST_TIME 106 8993
TEST_TIME 107 8960
TEST_TIME 108 8938
TEST_TIME 109 8948
TEST_TIME 110 8942
TEST_TIME 111 8976
TEST_TIME 112 8934
TEST_TIME 113 8930
TEST_TIME 114 9026
TEST_TIME 115 9036
TEST_TIME 116 9015
TEST_TIME 117 9078
TEST_TIME 118 9085
TEST_TIME 119 9134
TEST_TIME 120 9092

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 29-01-2023 22:39:19 to 29-01-2023 22:39:22.

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 1523637 1523637 0 0.00 0 26316 6 0 0
doc_list_biud 1523637 1523637 0 0.00 0 72867 25 0 0
sp_add_invoice_to_stock 5157 5027 130 2.52 31 163602 19116 0 130
sp_cancel_adding_invoice 5433 5433 0 0.00 6 46017 738 0 0
sp_cancel_client_order 1818 1818 0 0.00 4 9425 355 0 0
sp_cancel_customer_reserve 535392 535392 0 0.00 0 48830 55 0 0
sp_cancel_pay_from_customer 685 685 0 0.00 2 5030 75 0 0
sp_cancel_pay_to_supplier 417 417 0 0.00 2 3949 88 0 0
sp_cancel_supplier_invoice 6016 6007 9 0.14 2 72970 720 0 9
sp_cancel_supplier_order 393 393 0 0.00 6 1009975 95545 0 0
sp_cancel_write_off 9143 9143 0 0.00 1 17080 112 0 0
sp_client_order 20986 20986 0 0.00 3 35039 382 0 0
sp_customer_reserve 469756 469756 0 0.00 0 39175 187 0 0
sp_fill_shopping_cart 31975 31975 0 0.00 1 25685 40 0 0
sp_fill_shopping_cart_clo_res 469746 469746 0 0.00 0 14136 14 0 0
sp_get_clo_for_invoice 5017 5017 0 0.00 0 28108 340 0 0
sp_kill_cost_storno 15678 15678 0 0.00 0 17068 56 0 0
sp_kill_qstorno_ret_qs2qd 1086327 1086327 0 0.00 0 72865 30 0 0
sp_kill_qty_storno 573341 573341 0 0.00 0 72866 65 0 0
sp_lock_selected_doc 20957 20957 0 0.00 0 7970 6 0 0
sp_make_cost_storno 27928 27928 0 0.00 0 26306 59 0 0
sp_multiply_rows_for_pdistr 27928 27928 0 0.00 0 15901 30 0 0
sp_multiply_rows_for_qdistr 20986 20986 0 0.00 0 35017 170 0 0
sp_pay_from_customer 7098 7098 0 0.00 4 11576 103 0 0
sp_pay_to_supplier 5675 5675 0 0.00 5 31054 148 0 0
sp_payment_common 12773 12773 0 0.00 1 30950 34 0 0
sp_qd_handle_on_cancel_clo 1818 1818 0 0.00 0 9416 330 0 0
sp_qd_handle_on_invoice_upd_sts 10450 10450 0 0.00 0 24728 344 0 0
sp_qd_handle_on_reserve_upd_sts 10137 10137 0 0.00 0 16560 60 0 0
sp_reserve_write_off 10138 10138 0 0.00 6 18435 175 0 0
sp_supplier_invoice 5571 5571 0 0.00 5 86354 3313 0 0
sp_supplier_order 5423 5423 0 0.00 5 38172 1208 0 0
srv_aggregate_perf_data 102 102 0 0.00 2278 109915 16332 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1290 134540 42923 0 0
srv_find_qd_qs_mism 1041555 1041555 0 0.00 0 12562 6 0 0
srv_make_invnt_saldo 573 573 0 0.00 40 31834 1010 0 0
srv_make_money_saldo 815 815 0 0.00 1 13191 40 0 0
srv_recalc_idx_stat 4 4 0 0.00 80686 594467 285480 0 0
t$perf-abend:sp_add_invoice_to_stock 130 130 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 5028 5028 0 0.00 0 9914 178 0 0
t$perf-norm:sp_cancel_adding_invoice 386 386 0 0.00 0 6035 132 0 0
t$perf-norm:sp_cancel_client_order 1818 1818 0 0.00 0 2192 17 0 0
t$perf-norm:sp_cancel_customer_reserve 688 688 0 0.00 0 1102 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 685 685 0 0.00 0 1887 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 417 417 0 0.00 0 4176 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 411 411 0 0.00 0 3026 28 0 0
t$perf-norm:sp_cancel_supplier_order 393 393 0 0.00 0 27802 1741 0 0
t$perf-norm:sp_cancel_write_off 1007 1007 0 0.00 0 5744 11 0 0
t$perf-norm:sp_client_order 20986 20986 0 0.00 0 6776 17 0 0
t$perf-norm:sp_customer_reserve 1054 1054 0 0.00 0 4243 12 0 0
t$perf-norm:sp_pay_from_customer 7098 7098 0 0.00 0 4418 9 0 0
t$perf-norm:sp_pay_to_supplier 5675 5675 0 0.00 0 12538 19 0 0
t$perf-norm:sp_reserve_write_off 10138 10138 0 0.00 0 6198 9 0 0
t$perf-norm:sp_supplier_invoice 5571 5571 0 0.00 0 5137 18 0 0
t$perf-norm:sp_supplier_order 5423 5423 0 0.00 0 7279 16 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 256 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 301 10 0 0
t$perf-norm:srv_make_invnt_saldo 573 573 0 0.00 0 952 17 0 0
t$perf-norm:srv_make_money_saldo 815 815 0 0.00 0 930 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 4 0 0
v_add_invoice_to_stock 10290 10030 260 2.52 0 7220 18 0 260
v_all_customers 37796 37796 0 0.00 0 22 1 0 0
v_all_suppliers 29708 29708 0 0.00 0 20 1 0 0
v_all_wares 41972 41972 0 0.00 0 39 1 0 0
v_cancel_adding_invoice 772 772 0 0.00 0 1515 9 0 0
v_cancel_client_order 3636 3636 0 0.00 0 1877 4 0 0
v_cancel_customer_prepayment 1370 1370 0 0.00 0 2668 7 0 0
v_cancel_customer_reserve 1376 1376 0 0.00 0 2495 5 0 0
v_cancel_payment_to_supplier 834 834 0 0.00 0 2067 7 0 0
v_cancel_supplier_invoice 840 822 18 2.14 0 6211 24 0 18
v_cancel_supplier_order 786 786 0 0.00 0 1207 5 0 0
v_cancel_write_off 2014 2014 0 0.00 0 1517 12 0 0
v_max_id_clo_ord 5421 5421 0 0.00 0 8621 4 0 0
v_max_id_clo_res 1054 1054 0 0.00 0 8452 29 0 0
v_max_id_ord_sup 5568 5568 0 0.00 0 6890 5 0 0
v_max_non_paid_invoice 1810 1810 0 0.00 0 5248 6 0 0
v_max_non_paid_realizn 3877 3877 0 0.00 0 366 3 0 0
v_min_id_clo_ord 5421 5421 0 0.00 0 3652 7 0 0
v_min_id_clo_res 1054 1054 0 0.00 0 6824 23 0 0
v_min_id_ord_sup 5568 5568 0 0.00 0 6671 8 0 0
v_min_non_paid_invoice 5675 5675 0 0.00 0 30938 33 0 0
v_min_non_paid_realizn 7098 7098 0 0.00 0 9896 24 0 0
v_our_firm 10618 10618 0 0.00 0 20 1 0 0
v_reserve_write_off 20272 20272 0 0.00 0 3139 5 0 0
x_lock_depdocs_on_canc_invoice 11029 11029 0 0.00 0 16399 111 0 0
x_lock_depdocs_on_canc_sup_ord 393 393 0 0.00 0 8749 321 0 0
x_make_qstorno_1000_1200 5423 5423 0 0.00 2 38165 1130 0 0
x_make_qstorno_1200_2000 5571 5571 0 0.00 2 85999 3162 0 0
x_make_qstorno_2100_3300 469756 469756 0 0.00 0 33163 170 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-01-2023 22:39:23 to 29-01-2023 22:39:27.

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
20:08:43 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:44 21330 8192000000 9883063184 10189565952 968848592 10.575 100 87 174 0 997351952 5658624 22844736
20:11:13 15166 8192000000 9942498688 10261565440 1024183712 11.112 100 88 176 0 1055065792 8095888 22786192
20:12:44 13135 8192000000 9989367248 10320953344 1069943312 11.552 100 86 172 0 1102082544 10187936 21951296
20:14:01 13631 8192000000 10036595856 10387783680 1134789504 12.166 100 82 164 0 1165753552 11790304 19173744
20:15:19 8388 8192000000 10090008416 10451542016 1182571216 12.614 100 84 168 0 1214485488 12726848 19187424
20:16:29 3254 8192000000 10119728400 10490343424 1215876096 12.924 100 81 157 2 1246842144 13596816 17369232
20:17:34 1154 8192000000 10161771696 10534739968 1246900240 13.210 100 90 172 0 1278335456 13043328 18391888
20:18:37 1302 8192000000 10183077008 10565996544 1275562048 13.472 100 80 158 1 1305736416 12839664 17334704
20:19:40 1385 8192000000 10216042544 10604023808 1302159824 13.715 100 87 174 2 1334150560 12823936 19166800
20:20:42 2857 8192000000 10208055376 10605240320 1304984880 13.741 100 69 136 0 1331038688 10634416 15419392
20:21:48 11106 8192000000 10195199152 10616815616 1315677136 13.838 100 19 38 0 1326232800 6310304 4245360
20:23:02 69650 8192000000 10222348576 10643582976 1332738848 13.992 100 29 59 1 1344554128 5608960 6206320
20:25:14 3816 8192000000 10232214496 10651951104 1340387776 14.061 100 28 55 0 1353435360 6803920 6243664
20:26:18 8242 8192000000 10234969104 10658316288 1343650960 14.090 100 21 42 0 1354301376 5849408 4801008
20:27:26 64539 8192000000 10203671168 10627358720 1280011408 13.513 96 22 44 0 1290407088 5607904 4787776
20:29:33 5629 8192000000 10177101856 10584883200 1263441152 13.362 97 21 40 1 1273476832 5160272 4875408
20:30:39 9757 8192000000 10134634272 10536476672 1184646352 12.634 93 12 24 1 1192442672 5088880 2707440
20:31:52 61858 8192000000 10121895792 10520682496 1150491680 12.314 92 34 64 0 1164511488 4850352 9169456
20:33:56 6351 8192000000 10155648208 10560757760 1266417072 13.389 100 29 58 0 1280247600 7761360 6069168
20:35:04 5155 8192000000 10185544992 10593157120 1291242832 13.616 100 29 59 0 1303536528 5256224 7037472
20:36:10 7671 8192000000 10188750768 10604994560 1300187904 13.697 100 19 37 0 1310038240 5416256 4434080
20:37:20 30050 8192000000 10192239408 10611900416 1308391088 13.771 100 27 54 0 1320562080 6032352 6138640
20:38:55 30566 8192000000 10241357984 10651205632 1337063792 14.031 100 43 86 1 1353183792 7138512 8981488
20:40:36 17631 8192000000 10268200640 10683977728 1353894240 14.183 100 50 100 1 1384690832 19322960 11473632
20:41:58 10673 8192000000 10276544752 10696110080 1366586272 14.296 100 44 87 1 1395237056 18893040 9757744
20:43:13 12023 8192000000 10292304624 10706624512 1361646992 14.252 99 56 112 1 1395803232 21039168 13117072
20:44:33 14102 8192000000 10294830336 10718064640 1376129808 14.382 100 32 62 0 1405378464 22028784 7219872
20:46:01 10832 8192000000 10309225632 10730713088 1389656016 14.503 100 48 93 2 1421689280 20310224 11723040
20:47:30 17176 8192000000 10310277600 10742947840 1396974624 14.568 100 37 70 0 1426892768 21443600 8474544
20:48:55 4024 8192000000 10332173824 10753753088 1404391104 14.634 100 52 104 0 1442743296 26258640 12093552
20:50:07 2336 8192000000 10333417056 10753351680 1395926896 14.559 100 77 154 0 1446740976 31546336 19267744
20:51:16 3374 8192000000 10312109520 10729455616 1361798672 14.254 99 63 125 1 1406041424 29161248 15081504
20:52:23 1289 8192000000 10327763744 10740891648 1376515216 14.385 99 76 151 1 1430662720 35398256 18749248
20:53:28 2378 8192000000 10347918544 10764300288 1394868416 14.549 100 71 142 2 1450549744 38235360 17445968
20:54:35 2096 8192000000 10312643584 10730598400 1375511200 14.376 100 61 122 1 1419656576 28815216 15330160
20:55:42 7889 8192000000 10311708240 10728931328 1358247408 14.222 99 59 115 0 1406998352 33828624 14922320
20:56:59 6485 8192000000 10290656000 10706997248 1363648928 14.270 100 59 118 1 1405015792 26418128 14948736
20:58:14 12975 8192000000 10305777936 10727489536 1352229776 14.168 98 46 92 0 1394881696 30350544 12301376
20:59:34 14020 8192000000 10302180560 10733170688 1363316432 14.267 99 39 76 1 1405589920 32126752 10146736
21:00:51 11480 8192000000 10302088864 10728718336 1362394688 14.259 99 32 62 1 1402734576 31558240 8781648
21:02:05 14951 8192000000 10306689440 10726895616 1359142320 14.230 99 50 100 4 1399292912 27566080 12584512
21:03:21 33600 8192000000 10290536752 10728521728 1348699376 14.136 99 27 55 0 1390153568 34345536 7108656
21:05:15 29166 8192000000 10280055488 10716692480 1348309280 14.132 99 48 95 1 1387411088 26692816 12408992
21:07:02 8643 8192000000 10284603824 10713399296 1336564864 14.026 99 44 87 0 1378174608 31158624 10451120
21:08:32 33523 8192000000 10262214096 10710315008 1354982384 14.192 100 15 31 0 1372356272 13746144 3627744
21:10:47 83234 8192000000 10288682448 10746785792 1377080560 14.390 100 28 56 0 1405468816 22116336 6271920
21:13:11 49673 8192000000 10278953920 10728988672 1359961072 14.237 99 13 26 0 1379417760 16816544 2640144
21:15:01 80614 8192000000 10281680752 10716143616 1361520592 14.251 99 24 47 0 1380440128 13789440 5130096
21:17:22 67083 8192000000 10294984144 10740080640 1368210240 14.311 99 16 29 0 1380526848 9355888 2960720
21:19:30 57656 8192000000 10292108192 10739744768 1364637472 14.279 98 34 66 0 1380236800 8337952 7261376
21:21:35 54317 8192000000 10278929280 10732871680 1375310688 14.375 99 7 14 0 1386655680 9747776 1597216
21:24:16 87371 8192000000 10290443856 10754981888 1382727488 14.441 99 13 26 0 1398727088 12953216 3046384
21:26:50 94688 8192000000 10305859696 10760179712 1398799952 14.584 100 33 64 0 1417352512 10943584 7608976
21:29:31 74432 8192000000 10304576448 10762805248 1395253024 14.553 99 14 28 0 1407196112 8346640 3596448
21:31:48 49438 8192000000 10304084304 10769747968 1411035072 14.693 100 20 41 0 1423488320 7694656 4758592
21:33:53 62107 8192000000 10287784448 10745597952 1374304400 14.366 98 20 41 0 1388179856 8319520 5555936
21:35:56 63302 8192000000 10288839824 10762104832 1384862032 14.460 99 22 44 0 1402842784 12521248 5459504
21:38:12 40462 8192000000 10295989312 10741567488 1330524160 13.972 95 15 30 0 1340277904 6663056 3090688
21:39:59 104106 8192000000 10304011792 10756427776 1324524448 13.918 95 13 24 0 1336393392 9307088 2561856
21:42:52 63784 8192000000 10294045296 10769952768 1352720016 14.172 97 20 40 0 1383038240 25662144 4656080
21:45:06 134540 8192000000 10269537280 10726096896 1257053984 13.303 90 3 6 0 1277629328 19970256 605088
21:48:22 57752 8192000000 10282306288 10716454912 1310553216 13.791 96 26 51 0 1327885456 12120016 5212224
21:50:21 11684 8192000000 10258709760 10686939136 1332152384 13.987 99 42 85 1 1359205136 16946240 10106512
21:52:22 28514 8192000000 10241191008 10682585088 1329982720 13.967 99 36 70 0 1358050256 20605664 7461872
21:53:53 43292 8192000000 10205617296 10652876800 1253313200 13.269 94 14 27 0 1263606752 7370464 2923088
21:55:39 50679 8192000000 10224671824 10672951296 1326666816 13.937 100 8 14 1 1335843136 7314624 1861696
21:57:38 43587 8192000000 10232798848 10683056128 1339161856 14.050 100 14 26 0 1352414704 10179120 3073728
21:59:46 58709 8192000000 10244797296 10696024064 1305672560 13.747 97 16 32 0 1325738432 16933504 3132368
22:01:45 58351 8192000000 10268559840 10718097408 1356705520 14.208 100 17 34 0 1376446272 16008544 3732208
22:03:47 61222 8192000000 10266052016 10703908864 1345642592 14.108 99 26 52 0 1366344064 14555888 6145584
22:05:48 33020 8192000000 10255236256 10695565312 1346141344 14.113 99 39 75 0 1362557104 7773120 8642640
22:07:30 56155 8192000000 10260593040 10701615104 1328038704 13.949 98 18 36 0 1349948240 17921168 3988368
22:09:31 87117 8192000000 10261066240 10705772544 1310269664 13.789 96 11 22 0 1320727328 8329168 2128496
22:12:01 61813 8192000000 10293106608 10728357888 1367513104 14.305 100 27 54 0 1390737184 17357136 5866944
22:14:07 50571 8192000000 10288744576 10728464384 1370034736 14.327 99 27 54 0 1388888496 12971520 5882240
22:16:05 64813 8192000000 10299274864 10741235712 1371778624 14.343 99 25 51 0 1387215232 9701776 5734832
22:18:10 66923 8192000000 10288270592 10744721408 1389042080 14.497 100 21 43 0 1401902384 8305776 4554528
22:20:24 49070 8192000000 10283205280 10736111616 1373009056 14.354 99 30 59 0 1389005824 9012016 6984752
22:22:38 54239 8192000000 10287432640 10758213632 1394059312 14.542 100 9 18 0 1410438144 14625920 1752912
22:24:32 60705 8192000000 10276488224 10735185920 1374872960 14.371 98 15 30 0 1386350576 8155840 3321776
22:26:33 56585 8192000000 10293331664 10750976000 1379456720 14.412 99 18 35 0 1393731680 10285216 3989744
22:28:31 38362 8192000000 10291305984 10740572160 1378354704 14.402 99 23 46 0 1392094448 8516144 5223600
22:30:25 33130 8192000000 10281585968 10716618752 1333808384 14.002 96 32 63 0 1354452336 13785056 6858896
22:32:00 73611 8192000000 10249760032 10699014144 1312571648 13.809 96 13 26 0 1328487696 13108688 2807360
22:34:14 21276 8192000000 10266308928 10696847360 1347147264 14.122 100 40 79 1 1366563984 10704240 8712480
22:35:38 25487 8192000000 10270584416 10714415104 1324432640 13.917 97 23 46 0 1342740928 13300256 5008032
22:37:05 75163 8192000000 10251949568 10699431936 1276432064 13.480 94 18 37 0 1294655968 14523440 3700464

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 29-01-2023 22:39:29 to 29-01-2023 22:39:44.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 130 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 29-01-2023 22:39:45 to 29-01-2023 22:39:47.

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          241474
MON$OLDEST_ACTIVE               242415
MON$OLDEST_SNAPSHOT             242415
MON$NEXT_TRANSACTION            242415
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                       2013184
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33657 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33657 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33657 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33657 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 ---
:982: 
:983: mscs-test-161.budzdorov.ru    Sun Jan 29 22:38:44 2023
:984:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:985: 
:986: 
:987: mscs-test-161.budzdorov.ru    Sun Jan 29 22:38:44 2023
:988:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:989: 
--- end of diff output ---

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

29.01.23 22:39:48. 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
2023-01-29 22:39:51 No severe PSQL-related problems occured
29.01.23 22:39:52. End of report.