Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.01.2023 16:40
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 15532856 kB
MemAvailable 30420820 kB
Buffers 0 kB
Cached 16214288 kB
SwapCached 2592 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-18 16:38:38
DTS_END                         2023-01-18 16:38:38.5100
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(131:22) ==> SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/49152
TRN_ID                          235699
ATT_ID                          699
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: 8860.43 25489 1063252
customer order: creation 166.28 359 19953
customer order: refuse 14.68 369 1762
order to supplier: creation 45.00 1227 5400
order to supplier: removal 3.31 97703 397
invoice (draft): creation 43.72 3485 5246
invoice (draft): removal 52.51 787 6301
invoice accept: apply 39.92 17380 4790
invoice accept: cancel 46.81 761 5617
customer reserve: creation 3640.76 174 436891
customer reserve: removal 4519.84 55 542381
realization accept: apply 80.18 195 9622
realization accept: cancel 82.14 125 9857
payment from customer: creation 57.39 145 6887
payment from customer: removal 5.33 81 639
payment to supplier: creation 47.41 164 5689
payment to supplier: removal 3.38 64 405
service: total inventory turnovers 4.67 1147 560
service: total monetary turnovers 6.38 22 765
service: aggregate perf. data 0.72 23333 86
service: refresh index statistics 0.03 362200 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-01-2023 16:40:27 to 18-01-2023 16:40:29.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2987
TEST_TIME 2 3317
TEST_TIME 3 4192
TEST_TIME 4 4430
TEST_TIME 5 4783
TEST_TIME 6 5104
TEST_TIME 7 5729
TEST_TIME 8 5449
TEST_TIME 9 5604
TEST_TIME 10 5755
TEST_TIME 11 5524
TEST_TIME 12 5435
TEST_TIME 13 5575
TEST_TIME 14 5859
TEST_TIME 15 6099
TEST_TIME 16 6289
TEST_TIME 17 6506
TEST_TIME 18 6621
TEST_TIME 19 6960
TEST_TIME 20 7055
TEST_TIME 21 7075
TEST_TIME 22 7655
TEST_TIME 23 7842
TEST_TIME 24 7977
TEST_TIME 25 8457
TEST_TIME 26 8676
TEST_TIME 27 9016
TEST_TIME 28 9499
TEST_TIME 29 10008
TEST_TIME 30 10037
TEST_TIME 31 9802
TEST_TIME 32 9770
TEST_TIME 33 9655
TEST_TIME 34 9578
TEST_TIME 35 9504
TEST_TIME 36 9477
TEST_TIME 37 9351
TEST_TIME 38 9216
TEST_TIME 39 9121
TEST_TIME 40 9100
TEST_TIME 41 9048
TEST_TIME 42 9032
TEST_TIME 43 8928
TEST_TIME 44 8819
TEST_TIME 45 8933
TEST_TIME 46 8818
TEST_TIME 47 8643
TEST_TIME 48 8799
TEST_TIME 49 8690
TEST_TIME 50 8772
TEST_TIME 51 8732
TEST_TIME 52 8702
TEST_TIME 53 8730
TEST_TIME 54 8678
TEST_TIME 55 8756
TEST_TIME 56 8818
TEST_TIME 57 8907
TEST_TIME 58 8845
TEST_TIME 59 8913
TEST_TIME 60 8866
TEST_TIME 61 8972
TEST_TIME 62 9030
TEST_TIME 63 9080
TEST_TIME 64 9155
TEST_TIME 65 9227
TEST_TIME 66 9216
TEST_TIME 67 9121
TEST_TIME 68 9120
TEST_TIME 69 9046
TEST_TIME 70 9119
TEST_TIME 71 9053
TEST_TIME 72 9014
TEST_TIME 73 8956
TEST_TIME 74 8891
TEST_TIME 75 8897
TEST_TIME 76 8875
TEST_TIME 77 8825
TEST_TIME 78 8791
TEST_TIME 79 8820
TEST_TIME 80 8824
TEST_TIME 81 8888
TEST_TIME 82 8919
TEST_TIME 83 8944
TEST_TIME 84 8903
TEST_TIME 85 9023
TEST_TIME 86 9003
TEST_TIME 87 8995
TEST_TIME 88 9148
TEST_TIME 89 9204
TEST_TIME 90 9232
TEST_TIME 91 9173
TEST_TIME 92 9103
TEST_TIME 93 9038
TEST_TIME 94 9058
TEST_TIME 95 9010
TEST_TIME 96 8992
TEST_TIME 97 8945
TEST_TIME 98 9056
TEST_TIME 99 9024
TEST_TIME 100 9023
TEST_TIME 101 9005
TEST_TIME 102 8997
TEST_TIME 103 9047
TEST_TIME 104 9030
TEST_TIME 105 9057
TEST_TIME 106 9076
TEST_TIME 107 9076
TEST_TIME 108 9101
TEST_TIME 109 9183
TEST_TIME 110 9116
TEST_TIME 111 9095
TEST_TIME 112 9054
TEST_TIME 113 9035
TEST_TIME 114 9005
TEST_TIME 115 8989
TEST_TIME 116 8962
TEST_TIME 117 8946
TEST_TIME 118 8896
TEST_TIME 119 8918
TEST_TIME 120 8863

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 18-01-2023 16:40:31 to 18-01-2023 16:40:34.

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 1469718 1469718 0 0.00 0 30059 6 0 0
doc_list_biud 1469715 1469715 0 0.00 0 42365 26 0 0
sp_add_invoice_to_stock 4915 4790 125 2.54 37 164488 17381 0 125
sp_cancel_adding_invoice 5617 5617 0 0.00 4 69927 761 0 0
sp_cancel_client_order 1762 1762 0 0.00 4 12411 369 0 0
sp_cancel_customer_reserve 542381 542381 0 0.00 0 42366 55 0 0
sp_cancel_pay_from_customer 639 639 0 0.00 2 7177 81 0 0
sp_cancel_pay_to_supplier 405 405 0 0.00 3 2070 64 0 0
sp_cancel_supplier_invoice 6309 6301 8 0.12 1 38140 787 0 8
sp_cancel_supplier_order 397 397 0 0.00 14 862706 97703 0 0
sp_cancel_write_off 9857 9857 0 0.00 1 14913 125 0 0
sp_client_order 19953 19953 0 0.00 3 66749 359 0 0
sp_customer_reserve 436891 436891 0 0.00 0 50669 174 0 0
sp_fill_shopping_cart 30595 30595 0 0.00 1 20148 42 0 0
sp_fill_shopping_cart_clo_res 436887 436887 0 0.00 0 13717 14 0 0
sp_get_clo_for_invoice 4786 4786 0 0.00 0 29648 334 0 0
sp_kill_cost_storno 16518 16518 0 0.00 0 11735 60 0 0
sp_kill_qstorno_ret_qs2qd 1101317 1101317 0 0.00 0 42362 31 0 0
sp_kill_qty_storno 580722 580722 0 0.00 0 42365 66 0 0
sp_lock_selected_doc 20021 20021 0 0.00 0 8890 6 0 0
sp_make_cost_storno 26983 26983 0 0.00 0 30057 60 0 0
sp_multiply_rows_for_pdistr 26982 26982 0 0.00 0 20897 34 0 0
sp_multiply_rows_for_qdistr 19953 19953 0 0.00 0 66740 146 0 0
sp_pay_from_customer 6887 6887 0 0.00 4 20905 145 0 0
sp_pay_to_supplier 5689 5689 0 0.00 4 30064 165 0 0
sp_payment_common 12575 12575 0 0.00 1 13405 43 0 0
sp_qd_handle_on_cancel_clo 1762 1762 0 0.00 0 12384 332 0 0
sp_qd_handle_on_invoice_upd_sts 10403 10403 0 0.00 0 15845 300 0 0
sp_qd_handle_on_reserve_upd_sts 9621 9621 0 0.00 0 14488 64 0 0
sp_reserve_write_off 9622 9622 0 0.00 6 14914 196 0 0
sp_supplier_invoice 5246 5246 0 0.00 7 81870 3485 0 0
sp_supplier_order 5400 5400 0 0.00 5 51916 1227 0 0
srv_aggregate_perf_data 86 86 0 0.00 2912 705361 23333 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2225 172200 46202 0 0
srv_find_qd_qs_mism 1018412 1018412 0 0.00 0 17695 5 0 0
srv_make_invnt_saldo 560 560 0 0.00 43 103794 1148 0 0
srv_make_money_saldo 765 765 0 0.00 1 779 22 0 0
srv_recalc_idx_stat 4 4 0 0.00 155420 463162 362201 0 0
t$perf-abend:sp_add_invoice_to_stock 125 125 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4791 4791 0 0.00 0 9828 161 0 0
t$perf-norm:sp_cancel_adding_invoice 395 395 0 0.00 0 4920 127 0 0
t$perf-norm:sp_cancel_client_order 1762 1762 0 0.00 0 4743 17 0 0
t$perf-norm:sp_cancel_customer_reserve 637 637 0 0.00 0 4746 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 639 639 0 0.00 0 531 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 405 405 0 0.00 0 134 1 0 0
t$perf-norm:sp_cancel_supplier_invoice 400 400 0 0.00 0 1015 15 0 0
t$perf-norm:sp_cancel_supplier_order 397 397 0 0.00 0 15078 1418 0 0
t$perf-norm:sp_cancel_write_off 980 980 0 0.00 0 5033 11 0 0
t$perf-norm:sp_client_order 19953 19953 0 0.00 0 10627 18 0 0
t$perf-norm:sp_customer_reserve 983 983 0 0.00 0 1035 5 0 0
t$perf-norm:sp_pay_from_customer 6887 6887 0 0.00 0 8394 12 0 0
t$perf-norm:sp_pay_to_supplier 5689 5689 0 0.00 0 5358 12 0 0
t$perf-norm:sp_reserve_write_off 9622 9622 0 0.00 0 7474 11 0 0
t$perf-norm:sp_supplier_invoice 5246 5246 0 0.00 0 4847 17 0 0
t$perf-norm:sp_supplier_order 5400 5400 0 0.00 0 5833 17 0 0
t$perf-norm:srv_aggregate_perf_data 86 86 0 0.00 0 31 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 131 3 0 0
t$perf-norm:srv_make_invnt_saldo 560 560 0 0.00 0 2386 24 0 0
t$perf-norm:srv_make_money_saldo 765 765 0 0.00 0 865 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 84 23 0 0
v_add_invoice_to_stock 9820 9570 250 2.54 0 11091 24 0 250
v_all_customers 36318 36318 0 0.00 0 19 0 0 0
v_all_suppliers 29598 29598 0 0.00 0 96 1 0 0
v_all_wares 39906 39906 0 0.00 0 26 1 0 0
v_cancel_adding_invoice 790 790 0 0.00 0 11353 22 0 0
v_cancel_client_order 3524 3524 0 0.00 0 8084 9 0 0
v_cancel_customer_prepayment 1278 1278 0 0.00 0 1608 5 0 0
v_cancel_customer_reserve 1274 1274 0 0.00 0 510 3 0 0
v_cancel_payment_to_supplier 810 810 0 0.00 0 547 5 0 0
v_cancel_supplier_invoice 816 800 16 1.96 0 7842 32 0 16
v_cancel_supplier_order 794 794 0 0.00 0 10781 19 0 0
v_cancel_write_off 1960 1960 0 0.00 0 4294 14 0 0
v_max_id_clo_ord 5399 5399 0 0.00 0 1657 1 0 0
v_max_id_clo_res 983 983 0 0.00 0 4150 10 0 0
v_max_id_ord_sup 5243 5243 0 0.00 0 5866 7 0 0
v_max_non_paid_invoice 1531 1531 0 0.00 0 718 3 0 0
v_max_non_paid_realizn 3574 3574 0 0.00 0 7793 8 0 0
v_min_id_clo_ord 5399 5399 0 0.00 0 5056 6 0 0
v_min_id_clo_res 983 983 0 0.00 0 7321 12 0 0
v_min_id_ord_sup 5243 5243 0 0.00 0 7635 9 0 0
v_min_non_paid_invoice 5688 5688 0 0.00 0 11326 32 0 0
v_min_non_paid_realizn 6887 6887 0 0.00 0 11514 33 0 0
v_our_firm 10214 10214 0 0.00 0 19 1 0 0
v_reserve_write_off 19242 19242 0 0.00 0 14883 7 0 0
x_lock_depdocs_on_canc_invoice 11518 11518 0 0.00 0 12119 114 0 0
x_lock_depdocs_on_canc_sup_ord 397 397 0 0.00 0 9015 412 0 0
x_make_qstorno_1000_1200 5400 5400 0 0.00 1 51626 1153 0 0
x_make_qstorno_1200_2000 5246 5246 0 0.00 4 81822 3320 0 0
x_make_qstorno_2100_3300 436891 436891 0 0.00 0 43909 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-01-2023 16:40:35 to 18-01-2023 16:40:39.

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:42 12 8192000000 8809419856 8824459264 14292576 0.174 4 4 4 0 14479696 79984 107136
14:09:43 23115 8192000000 9908893360 10221391872 999409824 10.873 100 89 178 0 1028360688 5599184 23351680
14:11:10 20989 8192000000 9953962688 10273939456 1034578512 11.213 100 87 174 0 1064688592 7729600 22380480
14:12:35 17946 8192000000 9985682160 10317783040 1068030880 11.533 100 85 168 0 1097774256 9042864 20700512
14:13:57 10571 8192000000 10029163888 10376171520 1116613552 11.995 100 74 148 0 1144481648 10200048 17668048
14:15:13 7999 8192000000 10079260080 10436984832 1166377120 12.463 100 77 154 1 1195434160 11635840 17421200
14:16:25 3864 8192000000 10125728960 10489229312 1209346576 12.863 100 82 162 0 1241704032 14003952 18353504
14:17:30 2081 8192000000 10150133312 10525208576 1239602416 13.143 100 90 180 1 1274465760 15301664 19561680
14:18:34 1304 8192000000 10183083712 10564243456 1268940304 13.412 100 81 159 0 1299841776 12660304 18241168
14:19:36 796 8192000000 10195634976 10584440832 1292249792 13.625 100 86 170 1 1324875488 13568784 19056912
14:20:38 39099 8192000000 10191646256 10597588992 1297902672 13.676 100 47 94 1 1317306112 8294432 11109008
14:22:17 9170 8192000000 10193833840 10609242112 1312687904 13.810 100 24 46 0 1323864896 5885424 5291568
14:23:27 10520 8192000000 10202136368 10623541248 1323708208 13.910 100 16 33 0 1332322720 4769792 3844720
14:24:38 10865 8192000000 10206531152 10632298496 1328531600 13.954 100 18 34 0 1338776336 6090752 4153984
14:25:55 58890 8192000000 10233925008 10652962816 1331945456 13.985 100 43 84 0 1347721040 4956112 10819472
14:28:01 4432 8192000000 10235193568 10656534528 1338508976 14.044 100 32 64 0 1351675216 5797424 7368816
14:29:07 11966 8192000000 10190025584 10603651072 1267410480 13.398 96 16 33 1 1276195856 5336832 3448544
14:30:19 66734 8192000000 10146617664 10543583232 1152649600 12.334 90 21 42 1 1162098624 4543536 4905488
14:32:31 6847 8192000000 10131103520 10530762752 1205008224 12.823 96 25 50 1 1216980112 5976752 5995136
14:33:45 6794 8192000000 10166520016 10565656576 1254251552 13.277 99 37 74 0 1270091120 7493840 8345728
14:34:52 12158 8192000000 10173095856 10582241280 1282884544 13.539 100 9 19 0 1289991296 5132832 1973920
14:36:06 20797 8192000000 10175379184 10593558528 1291700144 13.620 100 18 36 0 1301635824 5698032 4237648
14:37:28 4338 8192000000 10209321936 10616827904 1305078128 13.741 100 29 58 0 1320884080 8990192 6815760
14:38:32 9262 8192000000 10219479376 10634166272 1328355360 13.952 100 16 32 0 1336547040 4831344 3360336
14:39:48 26953 8192000000 10250547040 10662461440 1342738672 14.082 100 39 78 0 1360109280 8042064 9328544
14:41:30 81436 8192000000 10260968976 10686898176 1357531824 14.215 100 32 64 0 1375256912 9571136 8153952
14:43:56 9624 8192000000 10290623200 10706255872 1373506000 14.358 100 59 116 1 1397815552 10852096 13457456
14:45:14 15547 8192000000 10291640256 10718965760 1381457040 14.430 100 51 102 0 1407756352 14837216 11462096
14:46:39 6419 8192000000 10318336016 10740359168 1398135696 14.578 100 64 127 3 1427891808 15290800 14465312
14:47:56 38861 8192000000 10315849648 10751320064 1405694640 14.646 100 31 62 0 1432365120 18949344 7721136
14:49:39 19994 8192000000 10325951168 10760876032 1406164176 14.650 99 38 75 0 1433301360 17840112 9297072
14:51:04 4446 8192000000 10357556528 10779181056 1423910336 14.807 100 71 141 0 1464249904 23534016 16805552
14:52:16 2997 8192000000 10354868512 10770862080 1410074640 14.685 100 75 146 0 1460368928 30893152 19401136
14:53:24 4824 8192000000 10348789808 10771324928 1409921120 14.683 100 54 108 0 1453272240 29771136 13579984
14:54:37 5524 8192000000 10335516752 10757816320 1398136304 14.578 100 55 109 0 1441412592 29461008 13815280
14:55:47 2225 8192000000 10355309104 10768850944 1405268400 14.642 100 74 145 0 1458204496 35513808 17422288
14:56:53 2712 8192000000 10332690080 10746925056 1386934400 14.479 100 65 127 1 1442780464 38954992 16891072
14:58:00 7300 8192000000 10316882272 10735120384 1367718416 14.307 99 40 80 0 1418744560 39797984 11228160
14:59:12 3133 8192000000 10319021680 10728505344 1352729984 14.172 99 64 124 0 1412676144 43740176 16205984
15:00:20 16328 8192000000 10325991152 10753331200 1378133552 14.400 100 31 60 0 1430880208 44332080 8414576
15:01:40 17655 8192000000 10320898464 10749632512 1345783568 14.110 97 31 62 0 1400871232 46524576 8563088
15:03:00 21272 8192000000 10313169328 10736869376 1342338384 14.078 98 32 62 0 1395225104 43761792 9124928
15:04:23 8994 8192000000 10309995776 10734571520 1349492688 14.143 99 45 90 0 1402401888 41070800 11838400
15:05:35 32682 8192000000 10292471632 10730045440 1337194416 14.032 98 28 56 0 1383966336 38803472 7968448
15:07:09 56437 8192000000 10278016176 10717515776 1333440160 13.998 98 17 34 0 1358586864 20872144 4274560
15:09:07 45041 8192000000 10281451040 10731229184 1382194016 14.436 100 11 20 0 1394120240 9697584 2228640
15:10:53 128959 8192000000 10267850992 10726297600 1347837200 14.128 98 24 48 0 1364837408 11690896 5309312
15:14:23 48945 8192000000 10242283616 10692423680 1316441904 13.844 97 7 14 0 1324183392 6501024 1240464
15:16:14 7664 8192000000 10293229904 10718793728 1371616496 14.342 100 75 144 5 1394075840 5950096 16509248
15:17:23 47204 8192000000 10277300112 10712776704 1350501408 14.152 98 49 97 2 1370696720 8540352 11654960
15:19:15 57348 8192000000 10274683504 10731376640 1370690384 14.333 100 15 30 0 1386307344 12147456 3469504
15:21:13 34807 8192000000 10296459744 10734063616 1377472256 14.394 100 47 92 0 1398724304 11142752 10109296
15:23:13 48584 8192000000 10278227136 10734346240 1365576288 14.287 99 7 14 1 1382202784 14452928 2173568
15:25:25 53157 8192000000 10278730384 10737385472 1376216640 14.383 99 30 60 0 1397771856 14555648 6999568
15:27:22 17108 8192000000 10288069072 10744160256 1370004448 14.327 99 16 30 0 1388289408 15038064 3246896
15:28:39 39041 8192000000 10281592352 10745085952 1373924384 14.362 99 9 18 0 1393663376 17559552 2179440
15:30:33 58823 8192000000 10290754128 10763046912 1383170608 14.445 100 14 26 0 1409395584 22841248 3383728
15:32:34 52196 8192000000 10293467744 10751242240 1390672128 14.512 100 19 38 0 1407229216 12054112 4502976
15:34:27 87902 8192000000 10280180224 10746728448 1345893408 14.111 97 12 24 0 1362858752 14049872 2915472
15:36:57 38382 8192000000 10293246736 10743566336 1372834720 14.352 99 23 46 0 1387638992 9898848 4905424
15:39:17 75128 8192000000 10294060976 10740060160 1327109488 13.941 95 15 28 0 1343518496 13728144 2680864
15:41:32 172197 8192000000 10290720960 10751463424 1237892880 13.127 89 7 15 0 1251714304 12489680 1331744
15:45:25 108789 8192000000 10271905984 10729410560 1342582336 14.081 97 26 50 0 1362056256 13552416 5921504
15:48:19 60282 8192000000 10257366640 10726162432 1330699632 13.973 97 9 16 0 1348761680 16250752 1811296
15:50:24 63203 8192000000 10246536752 10700804096 1332356496 13.988 98 4 8 0 1342767216 9569120 841600
15:52:31 85586 8192000000 10245702800 10704637952 1336947472 14.030 98 20 40 0 1357402688 15570304 4884912
15:55:01 54070 8192000000 10243967488 10699120640 1318905840 13.867 97 5 10 0 1327110000 7367824 836336
15:57:45 21019 8192000000 10283425088 10721026048 1354198800 14.185 99 43 85 0 1372393648 8008384 10186464
15:59:06 59400 8192000000 10268684560 10717085696 1341464288 14.071 98 18 36 0 1355619776 9548256 4607232
16:01:08 43986 8192000000 10265289904 10715947008 1326861776 13.939 97 23 46 0 1342161904 10106224 5193904
16:02:52 33994 8192000000 10309016144 10748350464 1390940832 14.514 100 41 82 1 1412940192 12716128 9283232
16:04:29 28416 8192000000 10290766832 10731511808 1364059872 14.274 99 29 56 0 1378414176 8495216 5859088
16:05:58 103683 8192000000 10291793472 10764423168 1371819712 14.343 99 21 42 0 1400238288 23544816 4873760
16:08:47 98315 8192000000 10289454784 10744770560 1371017424 14.336 99 19 38 0 1389781792 14749920 4014448
16:12:54 75604 8192000000 10279197936 10741968896 1370019760 14.327 99 10 20 0 1380910880 8737472 2153648
16:15:10 48356 8192000000 10288501136 10740006912 1357524128 14.215 97 19 38 0 1377249968 15127600 4598240
16:17:35 60126 8192000000 10287921040 10748702720 1364902912 14.281 98 17 34 0 1377732576 9152144 3677520
16:19:36 45667 8192000000 10311111296 10758045696 1373645232 14.360 98 32 62 0 1390301360 9709376 6946752
16:21:25 108631 8192000000 10311090192 10764115968 1386196720 14.472 99 16 32 0 1402766864 13146352 3423792
16:24:14 42105 8192000000 10290303376 10736132096 1348087536 14.130 97 19 38 0 1363907856 11781616 4038704
16:25:58 33519 8192000000 10302318080 10749460480 1371910368 14.344 99 30 60 0 1392279632 14329264 6040000
16:27:37 132628 8192000000 10285474736 10732482560 1347844912 14.128 98 15 30 0 1366161008 14910560 3405536
16:30:51 28235 8192000000 10277767264 10733117440 1374613856 14.368 99 12 22 1 1383706528 6672864 2419808
16:32:25 43941 8192000000 10272272864 10729209856 1347434144 14.124 97 11 20 0 1357294368 7614224 2246000
16:34:25 64628 8192000000 10254630352 10705297408 1299466560 13.690 94 19 38 0 1312871200 9266400 4138240
16:36:41 59362 8192000000 10282566768 10737008640 1356382736 14.205 99 17 31 1 1376315936 16789632 3143568

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 18-01-2023 16:40:41 to 18-01-2023 16:40:56.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 125 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

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

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          235356
MON$OLDEST_ACTIVE               236403
MON$OLDEST_SNAPSHOT             236403
MON$NEXT_TRANSACTION            236403
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                       2006528
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.33654 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33654 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33654 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33654 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 ---
:78: 
:79: mscs-test-161.budzdorov.ru    Wed Jan 18 16:38:39 2023
:80:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:81: 
:82: 
:83: mscs-test-161.budzdorov.ru    Wed Jan 18 16:38:57 2023
:84:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:85: 
:86: 
:87: mscs-test-161.budzdorov.ru    Wed Jan 18 16:38:57 2023
:88:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:89: 
--- end of diff output ---

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

18.01.23 16:41:00. 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-18 16:41:03 No severe PSQL-related problems occured
18.01.23 16:41:04. End of report.