Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 01.01.2023 22:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15461064 kB
MemAvailable 30416612 kB
Buffers 0 kB
Cached 16272732 kB
SwapCached 9232 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-01 22:38:33
DTS_END                         2023-01-01 22:38:33.2150
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_MAKE_MONEY_SALDO(33:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/45786
TRN_ID                          233412
ATT_ID                          631
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: 8902.75 22500 1068330
customer order: creation 166.23 385 19947
customer order: refuse 14.43 362 1731
order to supplier: creation 44.57 1267 5348
order to supplier: removal 3.53 90645 423
invoice (draft): creation 45.12 3571 5414
invoice (draft): removal 52.43 754 6292
invoice accept: apply 39.62 18581 4754
invoice accept: cancel 46.54 713 5585
customer reserve: creation 3681.76 183 441811
customer reserve: removal 4526.52 55 543182
realization accept: apply 79.93 189 9592
realization accept: cancel 77.45 100 9294
payment from customer: creation 57.69 120 6923
payment from customer: removal 5.49 67 659
payment to supplier: creation 46.81 126 5617
payment to supplier: removal 3.46 73 415
service: total inventory turnovers 4.33 1206 520
service: total monetary turnovers 6.03 42 724
service: aggregate perf. data 0.79 20299 95
service: refresh index statistics 0.03 311256 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 01-01-2023 22:40:07 to 01-01-2023 22:40:09.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5259
TEST_TIME 2 4388
TEST_TIME 3 5083
TEST_TIME 4 5271
TEST_TIME 5 5299
TEST_TIME 6 5379
TEST_TIME 7 5544
TEST_TIME 8 5639
TEST_TIME 9 6264
TEST_TIME 10 6461
TEST_TIME 11 6591
TEST_TIME 12 6855
TEST_TIME 13 7489
TEST_TIME 14 7569
TEST_TIME 15 7603
TEST_TIME 16 7710
TEST_TIME 17 8195
TEST_TIME 18 8371
TEST_TIME 19 8633
TEST_TIME 20 8619
TEST_TIME 21 8940
TEST_TIME 22 9360
TEST_TIME 23 9918
TEST_TIME 24 10041
TEST_TIME 25 10208
TEST_TIME 26 10076
TEST_TIME 27 10129
TEST_TIME 28 10412
TEST_TIME 29 10664
TEST_TIME 30 10460
TEST_TIME 31 10310
TEST_TIME 32 10172
TEST_TIME 33 9998
TEST_TIME 34 9927
TEST_TIME 35 9826
TEST_TIME 36 9691
TEST_TIME 37 9735
TEST_TIME 38 9516
TEST_TIME 39 9406
TEST_TIME 40 9350
TEST_TIME 41 9297
TEST_TIME 42 9304
TEST_TIME 43 9266
TEST_TIME 44 9147
TEST_TIME 45 9089
TEST_TIME 46 8960
TEST_TIME 47 9083
TEST_TIME 48 9088
TEST_TIME 49 8997
TEST_TIME 50 9032
TEST_TIME 51 8977
TEST_TIME 52 9057
TEST_TIME 53 9058
TEST_TIME 54 9087
TEST_TIME 55 9040
TEST_TIME 56 9006
TEST_TIME 57 9097
TEST_TIME 58 9198
TEST_TIME 59 9253
TEST_TIME 60 9206
TEST_TIME 61 9171
TEST_TIME 62 9158
TEST_TIME 63 9096
TEST_TIME 64 8993
TEST_TIME 65 9005
TEST_TIME 66 8921
TEST_TIME 67 8833
TEST_TIME 68 8873
TEST_TIME 69 8826
TEST_TIME 70 8906
TEST_TIME 71 8876
TEST_TIME 72 8819
TEST_TIME 73 8803
TEST_TIME 74 8875
TEST_TIME 75 8895
TEST_TIME 76 9048
TEST_TIME 77 9023
TEST_TIME 78 9125
TEST_TIME 79 9077
TEST_TIME 80 9095
TEST_TIME 81 9052
TEST_TIME 82 8992
TEST_TIME 83 8955
TEST_TIME 84 8962
TEST_TIME 85 8908
TEST_TIME 86 8939
TEST_TIME 87 8916
TEST_TIME 88 8911
TEST_TIME 89 8859
TEST_TIME 90 8857
TEST_TIME 91 8893
TEST_TIME 92 8875
TEST_TIME 93 8815
TEST_TIME 94 8827
TEST_TIME 95 8817
TEST_TIME 96 8867
TEST_TIME 97 8850
TEST_TIME 98 8827
TEST_TIME 99 8962
TEST_TIME 100 8996
TEST_TIME 101 9006
TEST_TIME 102 9024
TEST_TIME 103 8998
TEST_TIME 104 9027
TEST_TIME 105 9086
TEST_TIME 106 9171
TEST_TIME 107 9150
TEST_TIME 108 9106
TEST_TIME 109 9073
TEST_TIME 110 9056
TEST_TIME 111 9020
TEST_TIME 112 9014
TEST_TIME 113 8975
TEST_TIME 114 8992
TEST_TIME 115 8983
TEST_TIME 116 8949
TEST_TIME 117 8965
TEST_TIME 118 8926
TEST_TIME 119 8904
TEST_TIME 120 8906

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 01-01-2023 22:40:11 to 01-01-2023 22:40:14.

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 1477978 1477978 0 0.00 0 21651 6 0 0
doc_list_biud 1477978 1477978 0 0.00 0 57462 26 0 0
sp_add_invoice_to_stock 4847 4754 93 1.91 31 194530 18582 0 93
sp_cancel_adding_invoice 5585 5585 0 0.00 3 61365 714 0 0
sp_cancel_client_order 1731 1731 0 0.00 4 11644 362 0 0
sp_cancel_customer_reserve 543182 543182 0 0.00 0 57464 55 0 0
sp_cancel_pay_from_customer 659 659 0 0.00 3 5049 68 0 0
sp_cancel_pay_to_supplier 415 415 0 0.00 3 2216 74 0 0
sp_cancel_supplier_invoice 6299 6292 7 0.11 2 27169 755 0 7
sp_cancel_supplier_order 423 423 0 0.00 6 823292 90645 0 0
sp_cancel_write_off 9294 9294 0 0.00 1 17167 101 0 0
sp_client_order 19947 19947 0 0.00 3 31367 386 0 0
sp_customer_reserve 441811 441811 0 0.00 0 66544 184 0 0
sp_fill_shopping_cart 30693 30693 0 0.00 1 12128 39 0 0
sp_fill_shopping_cart_clo_res 441795 441795 0 0.00 0 22831 16 0 0
sp_get_clo_for_invoice 4738 4738 0 0.00 0 45481 342 0 0
sp_kill_cost_storno 15953 15953 0 0.00 0 7098 48 0 0
sp_kill_qstorno_ret_qs2qd 1102373 1102373 0 0.00 0 57458 30 0 0
sp_kill_qty_storno 580837 580837 0 0.00 0 57461 66 0 0
sp_lock_selected_doc 19872 19872 0 0.00 0 5229 3 0 0
sp_make_cost_storno 26870 26870 0 0.00 0 21629 54 0 0
sp_multiply_rows_for_pdistr 26870 26870 0 0.00 0 8834 27 0 0
sp_multiply_rows_for_qdistr 19947 19947 0 0.00 0 9400 163 0 0
sp_pay_from_customer 6923 6923 0 0.00 4 19023 121 0 0
sp_pay_to_supplier 5617 5617 0 0.00 4 9880 127 0 0
sp_payment_common 12540 12540 0 0.00 1 18966 28 0 0
sp_qd_handle_on_cancel_clo 1731 1731 0 0.00 0 11527 331 0 0
sp_qd_handle_on_invoice_upd_sts 10323 10323 0 0.00 0 20034 308 0 0
sp_qd_handle_on_reserve_upd_sts 9592 9592 0 0.00 0 22877 69 0 0
sp_reserve_write_off 9592 9592 0 0.00 6 22925 190 0 0
sp_supplier_invoice 5414 5414 0 0.00 6 83401 3572 0 0
sp_supplier_order 5348 5348 0 0.00 5 27752 1268 0 0
srv_aggregate_perf_data 95 95 0 0.00 2309 174962 20300 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1951 142297 44925 0 0
srv_find_qd_qs_mism 1022611 1022611 0 0.00 0 13459 5 0 0
srv_make_invnt_saldo 520 520 0 0.00 49 59982 1207 0 0
srv_make_money_saldo 724 724 0 0.00 1 6574 42 0 0
srv_recalc_idx_stat 4 4 0 0.00 200126 370699 311257 0 0
t$perf-abend:sp_add_invoice_to_stock 93 93 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4754 4754 0 0.00 0 34259 210 0 0
t$perf-norm:sp_cancel_adding_invoice 377 377 0 0.00 0 6188 119 0 0
t$perf-norm:sp_cancel_client_order 1731 1731 0 0.00 0 27808 32 0 0
t$perf-norm:sp_cancel_customer_reserve 597 597 0 0.00 0 5506 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 659 659 0 0.00 0 2748 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 415 415 0 0.00 0 4008 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 407 407 0 0.00 0 3028 44 0 0
t$perf-norm:sp_cancel_supplier_order 423 423 0 0.00 0 37529 1739 0 0
t$perf-norm:sp_cancel_write_off 935 935 0 0.00 0 6396 18 0 0
t$perf-norm:sp_client_order 19947 19947 0 0.00 0 25283 18 0 0
t$perf-norm:sp_customer_reserve 966 966 0 0.00 0 696 4 0 0
t$perf-norm:sp_pay_from_customer 6923 6923 0 0.00 0 5295 12 0 0
t$perf-norm:sp_pay_to_supplier 5617 5617 0 0.00 0 9153 13 0 0
t$perf-norm:sp_reserve_write_off 9592 9592 0 0.00 0 26881 14 0 0
t$perf-norm:sp_supplier_invoice 5414 5414 0 0.00 0 11194 23 0 0
t$perf-norm:sp_supplier_order 5348 5348 0 0.00 0 10902 15 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 22 0 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 253 12 0 0
t$perf-norm:srv_make_invnt_saldo 520 520 0 0.00 0 10209 45 0 0
t$perf-norm:srv_make_money_saldo 724 724 0 0.00 0 6848 28 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 9658 9472 186 1.92 0 19766 21 0 186
v_all_customers 36194 36194 0 0.00 0 60 1 0 0
v_all_suppliers 29658 29658 0 0.00 0 43 1 0 0
v_all_wares 39894 39894 0 0.00 0 46 1 0 0
v_cancel_adding_invoice 754 754 0 0.00 0 4377 17 0 0
v_cancel_client_order 3462 3462 0 0.00 0 4370 8 0 0
v_cancel_customer_prepayment 1318 1318 0 0.00 0 5042 10 0 0
v_cancel_customer_reserve 1194 1194 0 0.00 0 4267 8 0 0
v_cancel_payment_to_supplier 830 830 0 0.00 0 2181 5 0 0
v_cancel_supplier_invoice 828 814 14 1.69 0 5729 28 0 14
v_cancel_supplier_order 846 846 0 0.00 0 7392 18 0 0
v_cancel_write_off 1870 1870 0 0.00 0 3556 15 0 0
v_max_id_clo_ord 5348 5348 0 0.00 0 601 1 0 0
v_max_id_clo_res 966 966 0 0.00 0 4068 17 0 0
v_max_id_ord_sup 5397 5397 0 0.00 0 2620 4 0 0
v_max_non_paid_invoice 1533 1533 0 0.00 0 2202 5 0 0
v_max_non_paid_realizn 3756 3756 0 0.00 0 1675 4 0 0
v_min_id_clo_ord 5348 5348 0 0.00 0 2035 6 0 0
v_min_id_clo_res 966 966 0 0.00 0 5270 16 0 0
v_min_id_ord_sup 5397 5397 0 0.00 0 3787 4 0 0
v_min_non_paid_invoice 5617 5617 0 0.00 0 4669 14 0 0
v_min_non_paid_realizn 6923 6923 0 0.00 0 18959 23 0 0
v_our_firm 10034 10034 0 0.00 0 20 1 0 0
v_reserve_write_off 19184 19184 0 0.00 0 14312 9 0 0
x_lock_depdocs_on_canc_invoice 11470 11470 0 0.00 0 12258 114 0 0
x_lock_depdocs_on_canc_sup_ord 423 423 0 0.00 0 8682 320 0 0
x_make_qstorno_1000_1200 5348 5348 0 0.00 1 27689 1192 0 0
x_make_qstorno_1200_2000 5414 5414 0 0.00 2 83381 3429 0 0
x_make_qstorno_2100_3300 441811 441811 0 0.00 0 66543 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 01-01-2023 22:40:15 to 01-01-2023 22:40:19.

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:37 8 8192000000 8798879696 8810233856 5307904 0.064 2 1 2 0 5407504 56816 42784
20:09:38 23500 8192000000 9916427376 10228690944 1001420048 10.892 100 93 186 0 1031295280 6150224 23725008
20:11:04 22663 8192000000 9960244000 10284830720 1041458176 11.279 100 85 170 0 1071587232 8360560 21768496
20:12:38 8892 8192000000 9983192384 10318671872 1070970928 11.561 100 85 170 0 1102785408 10400416 21414064
20:13:52 9098 8192000000 10031362032 10377957376 1118306304 12.011 100 79 158 0 1148521680 11003168 19212208
20:15:05 5076 8192000000 10073462608 10429452288 1158680896 12.391 100 79 154 0 1187586112 11311232 17593984
20:16:13 2990 8192000000 10120333520 10484748288 1207919584 12.850 100 88 174 0 1241094544 13213136 19961824
20:17:17 1929 8192000000 10152077104 10528358400 1239603152 13.143 100 74 145 1 1268110752 12798576 15709024
20:18:21 2014 8192000000 10193209744 10573242368 1279707136 13.510 100 86 171 1 1312954768 14624064 18623568
20:19:25 1405 8192000000 10213587552 10600075264 1300813424 13.703 100 89 177 0 1334671904 13457216 20401264
20:20:30 6534 8192000000 10217604240 10615967744 1310942576 13.795 100 61 122 1 1334955920 10409328 13604016
20:21:38 3772 8192000000 10197853248 10612342784 1314615008 13.828 100 34 67 1 1330189856 7516160 8058688
20:22:45 8312 8192000000 10227727264 10632298496 1327228464 13.942 100 26 51 1 1338804416 5505296 6070656
20:23:55 8537 8192000000 10225640512 10638843904 1340511072 14.062 100 31 62 0 1353811936 6083008 7217856
20:25:04 23221 8192000000 10238569712 10652409856 1342061920 14.076 100 48 94 0 1358909872 5491312 11356640
20:26:31 6653 8192000000 10237804992 10659352576 1349042864 14.139 100 21 41 1 1358464624 4753552 4668208
20:27:38 8343 8192000000 10228317568 10650804224 1342992704 14.084 100 16 32 0 1352300528 5534768 3773056
20:28:48 58516 8192000000 10191905168 10596904960 1260858608 13.338 96 31 62 0 1273645616 5214656 7572352
20:30:47 5048 8192000000 10177753520 10587254784 1287794416 13.584 100 31 61 0 1302133856 7482864 6856576
20:31:52 7537 8192000000 10139686160 10541592576 1237195728 13.120 98 27 53 0 1249621216 6083440 6342048
20:33:00 60688 8192000000 10139126848 10534055936 1202937168 12.804 96 42 84 1 1218351008 4881296 10532544
20:35:04 4101 8192000000 10183237344 10581913600 1280562048 13.518 100 34 68 0 1294969232 6462720 7944464
20:36:09 13448 8192000000 10179657216 10592956416 1293757232 13.638 100 16 32 0 1302832224 5456352 3618640
20:37:24 27158 8192000000 10192566480 10607095808 1308713168 13.774 100 27 54 1 1320962704 5813920 6435616
20:39:33 8778 8192000000 10247336848 10656501760 1337364064 14.034 100 46 85 0 1362057696 14983840 9709792
20:40:48 7658 8192000000 10268480576 10680504320 1356892704 14.209 100 65 130 1 1386212368 14447456 14872208
20:42:11 8596 8192000000 10291330976 10706075648 1372664096 14.351 100 54 108 1 1406963344 22269216 12030032
20:43:25 10096 8192000000 10293255584 10713993216 1380566320 14.422 100 58 114 2 1410760352 17339792 12854240
20:44:52 5343 8192000000 10299122272 10722152448 1389072320 14.498 100 47 94 0 1417620224 17464256 11083648
20:46:03 8275 8192000000 10303843328 10728243200 1387179952 14.481 100 52 102 2 1418303392 19041664 12081776
20:47:16 6994 8192000000 10321442832 10745393152 1395000416 14.550 100 51 102 0 1436006400 29124832 11881152
20:48:28 17101 8192000000 10321501808 10746908672 1387117968 14.480 99 45 90 0 1420447552 21642912 11686672
20:49:48 2117 8192000000 10329582448 10744520704 1390377552 14.509 100 75 147 1 1437499808 28581280 18540976
20:50:55 7828 8192000000 10339302720 10754842624 1392293744 14.526 100 73 147 1 1446091200 35774368 18023088
20:52:11 8590 8192000000 10319910336 10741104640 1394460272 14.546 100 49 98 0 1428443056 21451584 12531200
20:53:26 2358 8192000000 10342341840 10754736128 1398252176 14.579 100 70 137 0 1446805984 31366304 17187504
20:54:33 6143 8192000000 10308885328 10710159360 1357138752 14.212 98 76 151 2 1408231008 32241744 18850512
20:55:42 3174 8192000000 10321210416 10731732992 1373706624 14.360 100 69 139 1 1427113520 36140976 17265920
20:56:48 1950 8192000000 10305189296 10712567808 1352966192 14.174 99 81 157 2 1403059040 29939472 20153376
20:57:55 17414 8192000000 10287849136 10709041152 1334754080 14.010 98 33 66 0 1376850736 33004192 9092464
20:59:17 13668 8192000000 10282803664 10701803520 1335299072 14.015 99 41 80 3 1388081136 41191536 11590528
21:00:34 17340 8192000000 10300366496 10719244288 1337771088 14.037 99 37 72 0 1392278240 44257664 10249488
21:01:53 28231 8192000000 10283571520 10720718848 1356827888 14.209 100 29 58 0 1395296224 30900320 7568016
21:04:05 38019 8192000000 10283747072 10715914240 1346809936 14.119 99 26 50 0 1375237920 22188192 6239792
21:05:57 19606 8192000000 10278494320 10720690176 1345393760 14.106 100 20 40 1 1389753776 39023200 5336816
21:07:22 18064 8192000000 10266520512 10707099648 1331420032 13.980 98 37 75 1 1358417056 16960208 10036816
21:08:47 67630 8192000000 10270395840 10708897792 1361123440 14.247 100 28 57 0 1376454752 8963008 6368304
21:10:57 91972 8192000000 10271048736 10715598848 1357712448 14.217 99 22 42 1 1372446432 10227072 4506912
21:13:37 51386 8192000000 10274499648 10715160576 1358261536 14.222 99 24 48 0 1371863760 8178336 5423888
21:15:30 35825 8192000000 10260059376 10715848704 1365728288 14.289 100 20 40 0 1379888384 9373744 4786352
21:17:10 13243 8192000000 10274257760 10709737472 1365819584 14.290 100 54 105 1 1386756592 8253120 12683888
21:18:24 24637 8192000000 10288109312 10729578496 1379121680 14.409 100 54 108 1 1402969296 11993472 11854144
21:19:50 43392 8192000000 10289034000 10747940864 1385489904 14.466 100 17 34 0 1400774272 11389680 3894688
21:21:58 87175 8192000000 10298852224 10760712192 1399789024 14.593 100 18 36 0 1416207936 11811872 4607040
21:24:26 104343 8192000000 10297748096 10766626816 1396930624 14.568 100 18 36 0 1417160240 15677120 4552496
21:27:18 50217 8192000000 10287633504 10749079552 1372286896 14.348 98 27 54 0 1386123536 7711504 6125136
21:29:10 92775 8192000000 10296447664 10752622592 1374184336 14.365 98 20 41 0 1387677888 8749616 4743936
21:31:44 43674 8192000000 10306404864 10756501504 1378334544 14.402 98 25 48 0 1392394656 8966032 5094080
21:33:28 39361 8192000000 10314415024 10766950400 1391744496 14.521 99 21 42 0 1408628432 12184816 4699120
21:35:08 142279 8192000000 10298532352 10762936320 1334514640 14.008 95 12 24 0 1356220000 19001984 2703376
21:38:42 89848 8192000000 10296607104 10750488576 1357416896 14.214 97 26 52 0 1377116128 14029840 5669392
21:41:12 77950 8192000000 10267730000 10719846400 1347998304 14.129 97 23 46 0 1360801824 7307664 5495856
21:43:31 44086 8192000000 10272095232 10730647552 1356106688 14.202 98 9 18 0 1365999424 7950880 1941856
21:45:26 56457 8192000000 10267697024 10719367168 1333352816 13.997 96 22 44 0 1347817328 8742336 5722176
21:47:23 32032 8192000000 10283412080 10727751680 1355477664 14.197 98 40 79 0 1373737744 8953248 9306832
21:48:55 66270 8192000000 10256895680 10702102528 1309035152 13.777 96 16 32 1 1323029216 10374144 3619920
21:51:10 21906 8192000000 10254221472 10693591040 1331805104 13.983 99 26 52 0 1352992640 14995232 6192304
21:52:36 69442 8192000000 10257536336 10714951680 1328390848 13.953 98 17 34 1 1350692656 18310080 3991728
21:54:46 109257 8192000000 10261540160 10715582464 1325228784 13.924 98 7 14 0 1343973216 17255920 1488512
21:58:24 8444 8192000000 10286804016 10709463040 1362911392 14.263 100 56 112 0 1385650896 9573136 13166368
21:59:34 90460 8192000000 10270261472 10710945792 1362556976 14.260 100 48 95 0 1383824976 10781968 10486032
22:02:17 48541 8192000000 10253900144 10696785920 1333798496 14.001 98 25 51 0 1350945648 11235952 5911200
22:04:15 62360 8192000000 10268434672 10723667968 1349988384 14.147 99 25 50 0 1369704576 13418640 6297552
22:06:18 39467 8192000000 10251265984 10707771392 1352033152 14.166 99 9 18 0 1364942768 10906992 2002624
22:07:59 78483 8192000000 10262052912 10725502976 1359893408 14.236 99 19 38 0 1381211680 16495712 4822560
22:10:19 54178 8192000000 10272271248 10727604224 1368212176 14.311 99 8 16 0 1376709232 6684368 1812688
22:12:13 86034 8192000000 10294433360 10753851392 1385318544 14.464 100 9 18 0 1394977696 7828672 1830480
22:15:38 45479 8192000000 10313107024 10760171520 1378010848 14.399 98 27 54 1 1398790320 14689920 6089552
22:17:29 120738 8192000000 10314876000 10771120128 1384360544 14.456 99 8 16 0 1400851040 14864160 1626336
22:20:38 59641 8192000000 10311423584 10752344064 1371708432 14.342 98 28 56 0 1389633456 11821072 6103952
22:22:43 61339 8192000000 10328305888 10783612928 1365729488 14.289 98 17 34 0 1392073968 22500352 3844128
22:24:47 75709 8192000000 10298445568 10738757632 1332535472 13.990 95 18 35 0 1350600528 14338768 3726288
22:27:37 67277 8192000000 10278489760 10720862208 1336604768 14.027 97 27 53 0 1349878384 7855520 5418096
22:29:45 13474 8192000000 10291455824 10735816704 1381847328 14.433 100 45 88 0 1400258848 8144848 10266672
22:31:03 68871 8192000000 10280991008 10732503040 1369088224 14.319 99 8 16 0 1380288304 9173008 2027072
22:34:19 88023 8192000000 10296964640 10752270336 1393352912 14.536 100 10 20 0 1408050848 12188048 2509888
22:36:48 49081 8192000000 10283596352 10742661120 1363270016 14.267 98 17 32 1 1379041024 12153584 3617424

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 01-01-2023 22:40:21 to 01-01-2023 22:40:36.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 93 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 01-01-2023 22:40:37 to 01-01-2023 22:40:39.

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          233011
MON$OLDEST_ACTIVE               233554
MON$OLDEST_SNAPSHOT             233554
MON$NEXT_TRANSACTION            233554
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                       2017472
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.33650 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33650 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33650 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33650 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 ---
--- end of diff output ---

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

01.01.23 22:40:40. 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-01 22:40:43 No severe PSQL-related problems occured
01.01.23 22:40:44. End of report.