Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.02.2023 19: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 15679120 kB
MemAvailable 30475016 kB
Buffers 0 kB
Cached 16066936 kB
SwapCached 3308 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-02-17 19:38:40
DTS_END                         2023-02-17 19:38:40.2320
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/59768
TRN_ID                          232102
ATT_ID                          644
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: 8932.88 20486 1071946
customer order: creation 166.94 368 20033
customer order: refuse 14.47 378 1736
order to supplier: creation 45.38 1193 5445
order to supplier: removal 3.61 86233 433
invoice (draft): creation 44.18 3395 5301
invoice (draft): removal 51.38 736 6166
invoice accept: apply 40.41 18554 4849
invoice accept: cancel 46.26 764 5551
customer reserve: creation 3712.60 184 445512
customer reserve: removal 4521.87 54 542624
realization accept: apply 80.89 192 9707
realization accept: cancel 80.27 116 9632
payment from customer: creation 56.41 113 6769
payment from customer: removal 5.18 91 622
payment to supplier: creation 48.23 131 5788
payment to supplier: removal 3.26 68 391
service: total inventory turnovers 4.58 942 549
service: total monetary turnovers 6.09 25 731
service: aggregate perf. data 0.86 18436 103
service: refresh index statistics 0.03 277745 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-02-2023 19:40:42 to 17-02-2023 19:40:44.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2238
TEST_TIME 2 3495
TEST_TIME 3 4521
TEST_TIME 4 5057
TEST_TIME 5 4913
TEST_TIME 6 5084
TEST_TIME 7 5161
TEST_TIME 8 5240
TEST_TIME 9 5239
TEST_TIME 10 5662
TEST_TIME 11 6138
TEST_TIME 12 6028
TEST_TIME 13 6358
TEST_TIME 14 6957
TEST_TIME 15 7166
TEST_TIME 16 7154
TEST_TIME 17 7196
TEST_TIME 18 7290
TEST_TIME 19 7307
TEST_TIME 20 7353
TEST_TIME 21 7733
TEST_TIME 22 8284
TEST_TIME 23 8320
TEST_TIME 24 8476
TEST_TIME 25 8537
TEST_TIME 26 8975
TEST_TIME 27 9334
TEST_TIME 28 9914
TEST_TIME 29 9964
TEST_TIME 30 9780
TEST_TIME 31 9798
TEST_TIME 32 9717
TEST_TIME 33 9483
TEST_TIME 34 9394
TEST_TIME 35 9335
TEST_TIME 36 9472
TEST_TIME 37 9470
TEST_TIME 38 9442
TEST_TIME 39 9548
TEST_TIME 40 9427
TEST_TIME 41 9282
TEST_TIME 42 9129
TEST_TIME 43 9191
TEST_TIME 44 9166
TEST_TIME 45 9084
TEST_TIME 46 9004
TEST_TIME 47 8988
TEST_TIME 48 8959
TEST_TIME 49 8948
TEST_TIME 50 8856
TEST_TIME 51 8820
TEST_TIME 52 8738
TEST_TIME 53 8752
TEST_TIME 54 8915
TEST_TIME 55 8911
TEST_TIME 56 8908
TEST_TIME 57 8970
TEST_TIME 58 9030
TEST_TIME 59 9010
TEST_TIME 60 9055
TEST_TIME 61 9144
TEST_TIME 62 9225
TEST_TIME 63 9240
TEST_TIME 64 9274
TEST_TIME 65 9310
TEST_TIME 66 9345
TEST_TIME 67 9259
TEST_TIME 68 9191
TEST_TIME 69 9147
TEST_TIME 70 9165
TEST_TIME 71 9137
TEST_TIME 72 9107
TEST_TIME 73 9149
TEST_TIME 74 9073
TEST_TIME 75 9024
TEST_TIME 76 8987
TEST_TIME 77 8970
TEST_TIME 78 8914
TEST_TIME 79 8939
TEST_TIME 80 8906
TEST_TIME 81 8896
TEST_TIME 82 9013
TEST_TIME 83 8992
TEST_TIME 84 9039
TEST_TIME 85 9038
TEST_TIME 86 9057
TEST_TIME 87 9077
TEST_TIME 88 9075
TEST_TIME 89 9074
TEST_TIME 90 9162
TEST_TIME 91 9152
TEST_TIME 92 9169
TEST_TIME 93 9085
TEST_TIME 94 9025
TEST_TIME 95 9011
TEST_TIME 96 8966
TEST_TIME 97 8946
TEST_TIME 98 8900
TEST_TIME 99 8917
TEST_TIME 100 8912
TEST_TIME 101 8923
TEST_TIME 102 8915
TEST_TIME 103 8950
TEST_TIME 104 8922
TEST_TIME 105 8939
TEST_TIME 106 8994
TEST_TIME 107 9033
TEST_TIME 108 9015
TEST_TIME 109 9118
TEST_TIME 110 9093
TEST_TIME 111 9095
TEST_TIME 112 9076
TEST_TIME 113 9014
TEST_TIME 114 9000
TEST_TIME 115 8962
TEST_TIME 116 8919
TEST_TIME 117 8923
TEST_TIME 118 8907
TEST_TIME 119 8860
TEST_TIME 120 8934

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 17-02-2023 19:40:45 to 17-02-2023 19:40:49.

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 1485029 1485029 0 0.00 0 18368 6 0 0
doc_list_biud 1485029 1485029 0 0.00 0 74965 25 0 0
sp_add_invoice_to_stock 4944 4849 95 1.92 34 208118 18554 0 95
sp_cancel_adding_invoice 5551 5551 0 0.00 3 76275 765 0 0
sp_cancel_client_order 1736 1736 0 0.00 4 11812 379 0 0
sp_cancel_customer_reserve 542624 542624 0 0.00 0 21288 54 0 0
sp_cancel_pay_from_customer 622 622 0 0.00 3 7011 91 0 0
sp_cancel_pay_to_supplier 391 391 0 0.00 3 2435 69 0 0
sp_cancel_supplier_invoice 6168 6166 2 0.03 2 74967 736 0 2
sp_cancel_supplier_order 433 433 0 0.00 6 897022 86233 0 0
sp_cancel_write_off 9632 9632 0 0.00 1 14499 116 0 0
sp_client_order 20033 20033 0 0.00 3 39945 368 0 0
sp_customer_reserve 445512 445512 0 0.00 0 62045 185 0 0
sp_fill_shopping_cart 30752 30752 0 0.00 1 12395 38 0 0
sp_fill_shopping_cart_clo_res 445505 445505 0 0.00 0 16711 17 0 0
sp_get_clo_for_invoice 4842 4842 0 0.00 0 38483 366 0 0
sp_kill_cost_storno 16196 16196 0 0.00 0 14498 60 0 0
sp_kill_qstorno_ret_qs2qd 1101479 1101479 0 0.00 0 74965 29 0 0
sp_kill_qty_storno 580690 580690 0 0.00 0 74965 64 0 0
sp_lock_selected_doc 20130 20130 0 0.00 0 10536 7 0 0
sp_make_cost_storno 27105 27105 0 0.00 0 16607 58 0 0
sp_multiply_rows_for_pdistr 27105 27105 0 0.00 0 11949 28 0 0
sp_multiply_rows_for_qdistr 20033 20033 0 0.00 0 15903 178 0 0
sp_pay_from_customer 6769 6769 0 0.00 4 12739 113 0 0
sp_pay_to_supplier 5788 5788 0 0.00 4 17002 132 0 0
sp_payment_common 12557 12557 0 0.00 1 13801 27 0 0
sp_qd_handle_on_cancel_clo 1736 1736 0 0.00 0 11800 345 0 0
sp_qd_handle_on_invoice_upd_sts 10392 10392 0 0.00 0 15532 292 0 0
sp_qd_handle_on_reserve_upd_sts 9707 9707 0 0.00 0 17252 61 0 0
sp_reserve_write_off 9707 9707 0 0.00 6 18388 192 0 0
sp_supplier_invoice 5301 5301 0 0.00 6 84716 3396 0 0
sp_supplier_order 5445 5445 0 0.00 5 22089 1194 0 0
srv_aggregate_perf_data 103 103 0 0.00 3067 308729 18437 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1459 122699 47944 0 0
srv_find_qd_qs_mism 1026089 1026089 0 0.00 0 11614 5 0 0
srv_make_invnt_saldo 549 549 0 0.00 33 22065 942 0 0
srv_make_money_saldo 731 731 0 0.00 1 764 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 128619 463308 277745 0 0
t$perf-abend:sp_add_invoice_to_stock 95 95 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 2 2 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4849 4849 0 0.00 0 14858 176 0 0
t$perf-norm:sp_cancel_adding_invoice 408 408 0 0.00 0 13434 186 0 0
t$perf-norm:sp_cancel_client_order 1736 1736 0 0.00 0 2429 13 0 0
t$perf-norm:sp_cancel_customer_reserve 680 680 0 0.00 0 1677 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 622 622 0 0.00 0 1493 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 391 391 0 0.00 0 1500 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 378 378 0 0.00 0 493 7 0 0
t$perf-norm:sp_cancel_supplier_order 433 433 0 0.00 0 25597 1508 0 0
t$perf-norm:sp_cancel_write_off 935 935 0 0.00 0 2926 19 0 0
t$perf-norm:sp_client_order 20033 20033 0 0.00 0 10575 18 0 0
t$perf-norm:sp_customer_reserve 956 956 0 0.00 0 1973 7 0 0
t$perf-norm:sp_pay_from_customer 6769 6769 0 0.00 0 4866 9 0 0
t$perf-norm:sp_pay_to_supplier 5788 5788 0 0.00 0 6086 11 0 0
t$perf-norm:sp_reserve_write_off 9707 9707 0 0.00 0 16961 18 0 0
t$perf-norm:sp_supplier_invoice 5301 5301 0 0.00 0 7784 19 0 0
t$perf-norm:sp_supplier_order 5445 5445 0 0.00 0 7206 15 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 585 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 53 1 0 0
t$perf-norm:srv_make_invnt_saldo 549 549 0 0.00 0 1382 25 0 0
t$perf-norm:srv_make_money_saldo 731 731 0 0.00 0 1174 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 11 5 0 0
v_add_invoice_to_stock 9870 9680 190 1.92 0 5926 22 0 190
v_all_customers 36706 36706 0 0.00 0 26 0 0 0
v_all_suppliers 29934 29934 0 0.00 0 24 1 0 0
v_all_wares 40066 40066 0 0.00 0 23 1 0 0
v_cancel_adding_invoice 816 816 0 0.00 0 2110 13 0 0
v_cancel_client_order 3472 3472 0 0.00 0 7196 9 0 0
v_cancel_customer_prepayment 1244 1244 0 0.00 0 5073 8 0 0
v_cancel_customer_reserve 1360 1360 0 0.00 0 2870 10 0 0
v_cancel_payment_to_supplier 782 782 0 0.00 0 1611 6 0 0
v_cancel_supplier_invoice 760 756 4 0.52 0 943 13 0 4
v_cancel_supplier_order 866 866 0 0.00 0 3624 16 0 0
v_cancel_write_off 1870 1870 0 0.00 0 5925 18 0 0
v_max_id_clo_ord 5445 5445 0 0.00 0 230 1 0 0
v_max_id_clo_res 956 956 0 0.00 0 4286 16 0 0
v_max_id_ord_sup 5274 5274 0 0.00 0 6509 5 0 0
v_max_non_paid_invoice 1540 1540 0 0.00 0 1423 3 0 0
v_max_non_paid_realizn 3499 3499 0 0.00 0 4714 7 0 0
v_min_id_clo_ord 5445 5445 0 0.00 0 4339 7 0 0
v_min_id_clo_res 956 956 0 0.00 0 6145 17 0 0
v_min_id_ord_sup 5274 5274 0 0.00 0 5002 6 0 0
v_min_non_paid_invoice 5788 5788 0 0.00 0 13797 19 0 0
v_min_non_paid_realizn 6769 6769 0 0.00 0 11999 23 0 0
v_our_firm 9900 9900 0 0.00 0 16 1 0 0
v_reserve_write_off 19414 19414 0 0.00 0 7227 8 0 0
x_lock_depdocs_on_canc_invoice 11339 11339 0 0.00 0 20733 114 0 0
x_lock_depdocs_on_canc_sup_ord 433 433 0 0.00 0 7498 342 0 0
x_make_qstorno_1000_1200 5445 5445 0 0.00 1 22077 1123 0 0
x_make_qstorno_1200_2000 5301 5301 0 0.00 3 84703 3246 0 0
x_make_qstorno_2100_3300 445512 445512 0 0.00 0 62043 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-02-2023 19:40:50 to 17-02-2023 19:40:54.

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
17:08:43 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:44 29160 8192000000 9928067776 10246025216 1006572992 10.942 100 86 172 0 1035698560 7092192 22033376
17:11:18 14156 8192000000 9961232032 10290360320 1048527072 11.347 100 88 174 0 1081174832 10008656 22639104
17:12:38 9872 8192000000 9993035264 10330181632 1080480560 11.652 100 88 176 0 1113296144 10819552 21996032
17:13:53 8228 8192000000 10033905664 10378776576 1113003552 11.961 100 80 158 1 1143664768 11464048 19197168
17:15:05 7224 8192000000 10058578512 10413199360 1147677360 12.288 100 85 170 0 1180761888 13001392 20083136
17:16:19 3785 8192000000 10100181888 10468102144 1197045984 12.749 100 78 158 1 1226411600 11880032 17485584
17:17:25 5660 8192000000 10137445280 10512281600 1232018080 13.073 100 74 149 1 1261979520 14066240 15895200
17:18:33 1904 8192000000 10177256096 10562686976 1270607536 13.427 100 82 160 3 1299686288 11970624 17108128
17:19:36 2425 8192000000 10190692288 10580656128 1289167872 13.597 100 78 153 1 1316790928 10902464 16720592
17:20:41 18201 8192000000 10205137712 10594017280 1297446960 13.672 100 71 138 0 1322218960 9234480 15537520
17:22:01 3411 8192000000 10199800096 10608107520 1311983920 13.804 100 37 74 0 1328560960 8772656 7804384
17:23:09 2495 8192000000 10210578688 10622169088 1320316896 13.880 100 36 73 1 1334654880 6516816 7821168
17:24:13 15644 8192000000 10211651904 10638086144 1329521328 13.963 100 29 58 0 1342620128 6304848 6793952
17:25:29 827 8192000000 10251414800 10651451392 1335837904 14.020 100 83 164 3 1364346064 10208976 18299184
17:26:31 8400 8192000000 10228715664 10652475392 1337273248 14.033 100 26 53 0 1349569168 6439744 5856176
17:27:42 9546 8192000000 10230668592 10649964544 1328837888 13.957 99 27 54 0 1341192976 6254352 6100736
17:28:53 46946 8192000000 10216040096 10632634368 1245358176 13.196 93 25 50 0 1256215104 5824656 5032272
17:30:40 8192 8192000000 10177310624 10581528576 1214314736 12.909 93 17 34 0 1223434816 5417936 3702144
17:31:52 11838 8192000000 10136017104 10532564992 1196485760 12.744 95 22 44 0 1206991568 5167888 5337920
17:33:04 63784 8192000000 10144386784 10551574528 1234091712 13.092 98 24 48 0 1244761776 5372176 5297888
17:35:08 4411 8192000000 10187349264 10586615808 1280827808 13.521 100 41 82 0 1296319552 6289728 9202016
17:36:17 7515 8192000000 10189038768 10601439232 1297708944 13.674 100 35 68 0 1311400944 5428432 8263568
17:37:30 20747 8192000000 10196618272 10611965952 1308430096 13.772 100 20 39 3 1317875584 4620288 4825200
17:38:57 30890 8192000000 10224447680 10636546048 1323968880 13.913 100 46 91 0 1341107776 6008400 11130496
17:40:44 17606 8192000000 10248344480 10666549248 1342698608 14.082 100 43 86 0 1362466160 10271040 9496512
17:42:08 19548 8192000000 10262116560 10683404288 1357935296 14.219 100 38 74 2 1373686192 7788432 7962464
17:43:40 5399 8192000000 10273979840 10699444224 1370730608 14.334 100 35 70 1 1388391040 9335840 8324592
17:44:50 11082 8192000000 10298740608 10721681408 1386609056 14.476 100 49 98 1 1410493936 12362992 11521888
17:46:13 44955 8192000000 10300432784 10735468544 1392840000 14.531 100 31 62 0 1415191408 14833104 7518304
17:48:05 5604 8192000000 10316647696 10745118720 1401170016 14.605 100 54 103 0 1429636960 16068496 12398448
17:49:13 3204 8192000000 10330778832 10755727360 1405357856 14.643 100 58 113 1 1438317168 19290896 13668416
17:50:24 11327 8192000000 10329594352 10758410240 1407747120 14.664 100 59 118 0 1445868496 23623152 14498224
17:51:41 4318 8192000000 10346441696 10768949248 1407254672 14.660 100 72 140 0 1454615056 29880032 17480352
17:52:56 12668 8192000000 10351942272 10783125504 1417634272 14.752 100 39 78 0 1454334496 26503344 10196880
17:54:15 3480 8192000000 10347852752 10777366528 1417870000 14.754 100 54 108 0 1459538320 28907840 12760480
17:55:24 13035 8192000000 10338792864 10773696512 1405244256 14.642 99 37 74 0 1447693584 32746848 9702480
17:56:41 3471 8192000000 10348108768 10772066304 1388844768 14.496 99 72 142 1 1442143728 36193760 17105200
17:57:48 6692 8192000000 10356938368 10772021248 1401090256 14.605 100 76 152 0 1462210592 42612272 18508064
17:59:09 3031 8192000000 10363746256 10782474240 1403956672 14.630 100 80 158 0 1467467008 43610944 19899392
18:00:19 1456 8192000000 10321915856 10735071232 1360595152 14.243 99 67 133 1 1423117136 44798592 17723392
18:01:25 17798 8192000000 10303794288 10728144896 1329844320 13.966 96 29 58 0 1374683520 37148544 7690656
18:03:05 19320 8192000000 10309625056 10740260864 1355020208 14.193 99 30 60 0 1407821840 44484592 8317040
18:04:31 41956 8192000000 10296530048 10744213504 1362416976 14.259 100 21 42 0 1416542096 47803696 6321424
18:06:14 59325 8192000000 10250875024 10683449344 1294690576 13.647 96 27 52 1 1326043776 25074800 6278400
18:09:01 116277 8192000000 10230372784 10669064192 1321763680 13.893 98 22 41 0 1336142048 10237392 4140976
18:12:37 64963 8192000000 10268965152 10712588288 1345220800 14.104 98 12 24 0 1364041664 16037184 2783680
18:14:43 59677 8192000000 10262823696 10719440896 1347445456 14.124 99 18 37 1 1368786400 16973296 4367648
18:16:48 68650 8192000000 10253745808 10715484160 1340148368 14.059 98 18 35 0 1359229808 15203056 3878384
18:19:05 29521 8192000000 10283600736 10727145472 1378558432 14.404 100 46 90 2 1400538480 10949200 11030848
18:20:53 85654 8192000000 10283589328 10730029056 1359462432 14.233 99 31 62 0 1382768656 15874976 7431248
18:23:37 32363 8192000000 10293550880 10744279040 1379842224 14.415 100 23 47 1 1398387392 13674736 4870432
18:25:15 81714 8192000000 10260886608 10719723520 1366721872 14.298 99 17 34 0 1378406560 7984992 3699696
18:27:42 70111 8192000000 10285530448 10743902208 1391965168 14.523 100 13 26 0 1403276528 8491600 2819760
18:29:52 43529 8192000000 10288895008 10753265664 1399094960 14.587 100 26 51 0 1416585744 11684592 5806192
18:31:43 55437 8192000000 10299219328 10775203840 1401098608 14.605 100 10 20 0 1423813536 20491312 2223616
18:33:55 77062 8192000000 10306824928 10754850816 1386224048 14.472 99 13 26 0 1401545296 12138384 3182864
18:36:13 60548 8192000000 10301969504 10757361664 1397489312 14.573 100 8 16 0 1410497264 11248448 1759504
18:38:16 67559 8192000000 10308978032 10772168704 1394859408 14.549 99 16 32 0 1413988256 15565024 3563824
18:40:27 53426 8192000000 10309805120 10775781376 1408385008 14.670 100 12 24 0 1422866080 11966096 2514976
18:42:35 79612 8192000000 10293080496 10754584576 1360721504 14.244 97 24 48 0 1378412176 11970160 5720512
18:44:55 65026 8192000000 10275271152 10714681344 1284915552 13.558 92 34 66 0 1306250848 13751152 7584144
18:47:03 85339 8192000000 10272829728 10727215104 1328152832 13.950 96 30 57 1 1344756064 9712640 6890592
18:49:31 49739 8192000000 10252260512 10712100864 1357994192 14.219 99 31 64 1 1377364320 12147968 7222160
18:51:26 41840 8192000000 10230857552 10690301952 1311740016 13.802 97 6 12 0 1324123456 10978656 1404784
18:53:12 83167 8192000000 10243683440 10692280320 1330526432 13.972 98 12 24 1 1343745488 9789280 3429776
18:55:36 26646 8192000000 10232652672 10683322368 1311003424 13.795 97 22 44 2 1324817520 8217632 5596464
18:57:07 49407 8192000000 10229531584 10674884608 1293313312 13.634 96 7 14 0 1301316944 6759136 1244496
18:59:00 42516 8192000000 10254215152 10687770624 1332074336 13.986 99 42 85 0 1356202368 14893968 9234064
19:00:42 57571 8192000000 10234453600 10678022144 1290968640 13.613 96 12 23 0 1307568784 14052608 2547536
19:02:56 13622 8192000000 10238282208 10672308224 1312281424 13.807 98 38 77 0 1328073616 7064288 8727904
19:04:11 58384 8192000000 10248497040 10686656512 1295034976 13.650 96 23 46 0 1310315680 10455504 4825200
19:06:10 42553 8192000000 10253991424 10690105344 1338979232 14.048 99 21 42 0 1355651168 12294560 4377376
19:07:53 90608 8192000000 10270618880 10727931904 1329863392 13.966 98 19 38 0 1349743216 15860752 4019072
19:10:24 29934 8192000000 10297576464 10730754048 1371789152 14.343 100 45 90 0 1394673136 13394480 9489504
19:13:07 54942 8192000000 10274036144 10730496000 1381110800 14.426 100 21 42 0 1393245712 7968320 4166592
19:15:03 63682 8192000000 10280759984 10743095296 1376859840 14.388 99 6 12 0 1385633616 7389776 1384000
19:17:17 93872 8192000000 10290986272 10746830848 1375460896 14.376 99 16 32 0 1387628336 8257088 3910352
19:20:26 63433 8192000000 10300900256 10763345920 1391347104 14.518 99 13 26 0 1405879152 11779184 2752864
19:22:30 108138 8192000000 10320590160 10779078656 1392012336 14.524 99 18 36 0 1411005600 14909616 4083648
19:25:18 74815 8192000000 10325255296 10763522048 1350958976 14.156 96 19 37 0 1366537952 11347856 4231120
19:27:38 122699 8192000000 10289341984 10733092864 1318771808 13.866 94 19 36 1 1335342832 12364432 4206592
19:30:41 85972 8192000000 10289282080 10738991104 1384731872 14.459 100 35 70 0 1409094976 16607568 7755536
19:33:09 29222 8192000000 10291980336 10741243904 1359142480 14.230 98 15 30 1 1377274016 14514416 3617120
19:34:41 94294 8192000000 10299767520 10752294912 1377313920 14.393 99 13 25 0 1395781376 15828784 2638672
19:37:24 62680 8192000000 10276858880 10735788032 1364017632 14.273 99 19 39 0 1384245600 15692144 4535824

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 17-02-2023 19:40:56 to 17-02-2023 19:41:11.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 95 v_add_invoice_to_stock 335544517
except 2 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-02-2023 19:41:12 to 17-02-2023 19:41:14.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          230994
MON$OLDEST_ACTIVE               232226
MON$OLDEST_SNAPSHOT             232226
MON$NEXT_TRANSACTION            232226
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                       1991296
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.33665 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33665 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33665 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33665 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 ---
:111: 
:112: mscs-test-161.budzdorov.ru    Fri Feb 17 19:38:43 2023
:113:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:114: 
:115: 
:116: mscs-test-161.budzdorov.ru    Fri Feb 17 19:38:44 2023
:117:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:118: 
:119: 
:120: mscs-test-161.budzdorov.ru    Fri Feb 17 19:38:52 2023
:121:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:122: 
:123: 
:124: mscs-test-161.budzdorov.ru    Fri Feb 17 19:39:05 2023
:125:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:126: 
:127: 
:128: mscs-test-161.budzdorov.ru    Fri Feb 17 19:39:06 2023
:129:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:130: 
--- end of diff output ---

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

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