Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.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 15052304 kB
MemAvailable 30403496 kB
Buffers 0 kB
Cached 16631616 kB
SwapCached 1648 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-21 22:38:38
DTS_END                         2023-01-21 22:38:38.1330
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/43012
TRN_ID                          248335
ATT_ID                          673
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: 9987.50 17964 1198500
customer order: creation 183.41 321 22009
customer order: refuse 14.91 326 1789
order to supplier: creation 48.53 1173 5823
order to supplier: removal 3.83 93223 459
invoice (draft): creation 47.98 3010 5757
invoice (draft): removal 58.53 706 7023
invoice accept: apply 43.81 15920 5257
invoice accept: cancel 52.54 746 6305
customer reserve: creation 4057.66 158 486919
customer reserve: removal 5154.73 54 618567
realization accept: apply 88.53 166 10623
realization accept: cancel 97.88 103 11745
payment from customer: creation 61.82 110 7418
payment from customer: removal 6.09 84 731
payment to supplier: creation 51.23 106 6148
payment to supplier: removal 3.48 85 418
service: total inventory turnovers 4.74 1003 569
service: total monetary turnovers 6.98 29 838
service: aggregate perf. data 0.82 23151 98
service: refresh index statistics 0.03 218815 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-01-2023 22:39:26 to 21-01-2023 22:39:28.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3827
TEST_TIME 2 4796
TEST_TIME 3 4834
TEST_TIME 4 4195
TEST_TIME 5 4525
TEST_TIME 6 4455
TEST_TIME 7 4971
TEST_TIME 8 5412
TEST_TIME 9 5371
TEST_TIME 10 5727
TEST_TIME 11 6018
TEST_TIME 12 5999
TEST_TIME 13 6265
TEST_TIME 14 6272
TEST_TIME 15 6524
TEST_TIME 16 6735
TEST_TIME 17 6709
TEST_TIME 18 7022
TEST_TIME 19 7095
TEST_TIME 20 7567
TEST_TIME 21 7652
TEST_TIME 22 7946
TEST_TIME 23 8174
TEST_TIME 24 8794
TEST_TIME 25 9232
TEST_TIME 26 9804
TEST_TIME 27 10014
TEST_TIME 28 10896
TEST_TIME 29 11008
TEST_TIME 30 10907
TEST_TIME 31 10962
TEST_TIME 32 11050
TEST_TIME 33 10937
TEST_TIME 34 10809
TEST_TIME 35 10772
TEST_TIME 36 10730
TEST_TIME 37 10757
TEST_TIME 38 10668
TEST_TIME 39 10675
TEST_TIME 40 10654
TEST_TIME 41 10540
TEST_TIME 42 10359
TEST_TIME 43 10331
TEST_TIME 44 10401
TEST_TIME 45 10279
TEST_TIME 46 10218
TEST_TIME 47 10195
TEST_TIME 48 10130
TEST_TIME 49 10112
TEST_TIME 50 10170
TEST_TIME 51 10221
TEST_TIME 52 10307
TEST_TIME 53 10179
TEST_TIME 54 10056
TEST_TIME 55 10149
TEST_TIME 56 10084
TEST_TIME 57 10020
TEST_TIME 58 10080
TEST_TIME 59 10076
TEST_TIME 60 10149
TEST_TIME 61 10143
TEST_TIME 62 10176
TEST_TIME 63 10219
TEST_TIME 64 10325
TEST_TIME 65 10288
TEST_TIME 66 10313
TEST_TIME 67 10347
TEST_TIME 68 10329
TEST_TIME 69 10280
TEST_TIME 70 10425
TEST_TIME 71 10478
TEST_TIME 72 10461
TEST_TIME 73 10322
TEST_TIME 74 10341
TEST_TIME 75 10236
TEST_TIME 76 10194
TEST_TIME 77 10229
TEST_TIME 78 10203
TEST_TIME 79 10157
TEST_TIME 80 10091
TEST_TIME 81 10047
TEST_TIME 82 9997
TEST_TIME 83 10057
TEST_TIME 84 10088
TEST_TIME 85 10083
TEST_TIME 86 10015
TEST_TIME 87 10133
TEST_TIME 88 10117
TEST_TIME 89 10180
TEST_TIME 90 10159
TEST_TIME 91 10185
TEST_TIME 92 10181
TEST_TIME 93 10217
TEST_TIME 94 10202
TEST_TIME 95 10167
TEST_TIME 96 10255
TEST_TIME 97 10274
TEST_TIME 98 10221
TEST_TIME 99 10128
TEST_TIME 100 10112
TEST_TIME 101 10080
TEST_TIME 102 10071
TEST_TIME 103 10047
TEST_TIME 104 10028
TEST_TIME 105 10004
TEST_TIME 106 9974
TEST_TIME 107 9914
TEST_TIME 108 9886
TEST_TIME 109 9888
TEST_TIME 110 9849
TEST_TIME 111 9894
TEST_TIME 112 9859
TEST_TIME 113 9874
TEST_TIME 114 9888
TEST_TIME 115 9917
TEST_TIME 116 9886
TEST_TIME 117 9905
TEST_TIME 118 10006
TEST_TIME 119 9992
TEST_TIME 120 9992

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 21-01-2023 22:39:29 to 21-01-2023 22:39:33.

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 1649631 1649631 0 0.00 0 11613 5 0 0
doc_list_biud 1649631 1649631 0 0.00 0 56819 26 0 0
sp_add_invoice_to_stock 5397 5257 140 2.59 22 168997 15921 0 140
sp_cancel_adding_invoice 6305 6305 0 0.00 4 74272 746 0 0
sp_cancel_client_order 1789 1789 0 0.00 4 18711 326 0 0
sp_cancel_customer_reserve 618567 618567 0 0.00 0 56821 55 0 0
sp_cancel_pay_from_customer 731 731 0 0.00 2 7128 85 0 0
sp_cancel_pay_to_supplier 418 418 0 0.00 2 10441 85 0 0
sp_cancel_supplier_invoice 7035 7023 12 0.17 2 25974 707 0 12
sp_cancel_supplier_order 459 459 0 0.00 6 1165303 93224 0 0
sp_cancel_write_off 11745 11745 0 0.00 1 10196 104 0 0
sp_client_order 22009 22009 0 0.00 3 19247 322 0 0
sp_customer_reserve 486919 486919 0 0.00 0 76874 158 0 0
sp_fill_shopping_cart 33577 33577 0 0.00 1 20868 35 0 0
sp_fill_shopping_cart_clo_res 486914 486914 0 0.00 0 18393 11 0 0
sp_get_clo_for_invoice 5251 5251 0 0.00 0 13910 232 0 0
sp_kill_cost_storno 19199 19199 0 0.00 0 10429 52 0 0
sp_kill_qstorno_ret_qs2qd 1256361 1256361 0 0.00 0 56815 29 0 0
sp_kill_qty_storno 661761 661761 0 0.00 0 56818 64 0 0
sp_lock_selected_doc 21915 21915 0 0.00 0 10205 5 0 0
sp_make_cost_storno 29439 29439 0 0.00 0 10809 50 0 0
sp_multiply_rows_for_pdistr 29439 29439 0 0.00 0 11241 27 0 0
sp_multiply_rows_for_qdistr 22009 22009 0 0.00 0 19240 153 0 0
sp_pay_from_customer 7418 7418 0 0.00 4 11178 111 0 0
sp_pay_to_supplier 6148 6148 0 0.00 4 10893 107 0 0
sp_payment_common 13566 13566 0 0.00 2 10811 31 0 0
sp_qd_handle_on_cancel_clo 1789 1789 0 0.00 0 18704 300 0 0
sp_qd_handle_on_invoice_upd_sts 11555 11555 0 0.00 0 26176 315 0 0
sp_qd_handle_on_reserve_upd_sts 10623 10623 0 0.00 0 17236 61 0 0
sp_reserve_write_off 10623 10623 0 0.00 6 17251 167 0 0
sp_supplier_invoice 5757 5757 0 0.00 6 100995 3010 0 0
sp_supplier_order 5823 5823 0 0.00 5 26578 1173 0 0
srv_aggregate_perf_data 98 98 0 0.00 2987 263976 23152 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1125 156249 44561 0 0
srv_find_qd_qs_mism 1148347 1148347 0 0.00 0 10636 4 0 0
srv_make_invnt_saldo 569 569 0 0.00 32 74185 1003 0 0
srv_make_money_saldo 838 838 0 0.00 1 2775 29 0 0
srv_recalc_idx_stat 4 4 0 0.00 77987 377975 218815 0 0
t$perf-abend:sp_add_invoice_to_stock 140 140 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5257 5257 0 0.00 0 17661 178 0 0
t$perf-norm:sp_cancel_adding_invoice 412 412 0 0.00 0 5536 133 0 0
t$perf-norm:sp_cancel_client_order 1789 1789 0 0.00 0 4389 15 0 0
t$perf-norm:sp_cancel_customer_reserve 713 713 0 0.00 0 1572 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 731 731 0 0.00 0 12919 27 0 0
t$perf-norm:sp_cancel_pay_to_supplier 418 418 0 0.00 0 3048 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 426 426 0 0.00 0 1258 16 0 0
t$perf-norm:sp_cancel_supplier_order 459 459 0 0.00 0 23161 1691 0 0
t$perf-norm:sp_cancel_write_off 1096 1096 0 0.00 0 5046 12 0 0
t$perf-norm:sp_client_order 22009 22009 0 0.00 0 10062 15 0 0
t$perf-norm:sp_customer_reserve 1119 1119 0 0.00 0 5439 11 0 0
t$perf-norm:sp_pay_from_customer 7418 7418 0 0.00 0 8314 13 0 0
t$perf-norm:sp_pay_to_supplier 6148 6148 0 0.00 0 5078 10 0 0
t$perf-norm:sp_reserve_write_off 10623 10623 0 0.00 0 6326 13 0 0
t$perf-norm:sp_supplier_invoice 5757 5757 0 0.00 0 7301 19 0 0
t$perf-norm:sp_supplier_order 5823 5823 0 0.00 0 7846 15 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 199 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 21 1 0 0
t$perf-norm:srv_make_invnt_saldo 569 569 0 0.00 0 9221 44 0 0
t$perf-norm:srv_make_money_saldo 838 838 0 0.00 0 2172 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 11 5 0 0
v_add_invoice_to_stock 10778 10498 280 2.59 0 5750 16 0 280
v_all_customers 39914 39914 0 0.00 0 19 1 0 0
v_all_suppliers 32266 32266 0 0.00 0 30 1 0 0
v_all_wares 44016 44016 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 824 824 0 0.00 0 2417 10 0 0
v_cancel_client_order 3578 3578 0 0.00 0 2166 4 0 0
v_cancel_customer_prepayment 1462 1462 0 0.00 0 2849 10 0 0
v_cancel_customer_reserve 1426 1426 0 0.00 0 2371 6 0 0
v_cancel_payment_to_supplier 836 836 0 0.00 0 2001 7 0 0
v_cancel_supplier_invoice 876 852 24 2.73 0 2378 19 0 24
v_cancel_supplier_order 918 918 0 0.00 0 392 4 0 0
v_cancel_write_off 2192 2192 0 0.00 0 2998 15 0 0
v_max_id_clo_ord 5821 5821 0 0.00 0 876 1 0 0
v_max_id_clo_res 1119 1119 0 0.00 0 2326 18 0 0
v_max_id_ord_sup 5748 5748 0 0.00 0 7611 6 0 0
v_max_non_paid_invoice 1584 1584 0 0.00 0 2496 4 0 0
v_max_non_paid_realizn 3892 3892 0 0.00 0 2854 5 0 0
v_min_id_clo_ord 5821 5821 0 0.00 0 9482 17 0 0
v_min_id_clo_res 1119 1119 0 0.00 0 10925 14 0 0
v_min_id_ord_sup 5748 5748 0 0.00 0 4539 6 0 0
v_min_non_paid_invoice 6148 6148 0 0.00 0 6886 19 0 0
v_min_non_paid_realizn 7418 7418 0 0.00 0 10806 24 0 0
v_our_firm 11154 11154 0 0.00 0 15 1 0 0
v_reserve_write_off 21244 21244 0 0.00 0 3253 4 0 0
x_lock_depdocs_on_canc_invoice 12902 12902 0 0.00 0 23347 111 0 0
x_lock_depdocs_on_canc_sup_ord 459 459 0 0.00 0 12778 349 0 0
x_make_qstorno_1000_1200 5823 5823 0 0.00 1 26573 1089 0 0
x_make_qstorno_1200_2000 5757 5757 0 0.00 2 100949 2892 0 0
x_make_qstorno_2100_3300 486919 486919 0 0.00 0 76872 145 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-01-2023 22:39:34 to 21-01-2023 22:39:38.

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:42 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:43 16463 8192000000 9898127696 10209484800 988548320 10.767 100 84 168 0 1016709712 6011296 22150096
20:11:09 14338 8192000000 9948141920 10272116736 1032710768 11.195 100 87 174 0 1064187376 8547776 22928832
20:12:31 18386 8192000000 9983759072 10316365824 1068443488 11.537 100 85 170 0 1100085584 10494624 21147472
20:13:54 9502 8192000000 10029655568 10373185536 1111685488 11.948 100 81 162 0 1142574912 11640832 19248592
20:15:05 5746 8192000000 10079498144 10435596288 1163156960 12.433 100 83 164 0 1194990960 12261904 19572096
20:16:13 3316 8192000000 10115684736 10485272576 1205744512 12.830 100 74 146 0 1233901024 11992896 16163616
20:17:19 2597 8192000000 10152827120 10529411072 1243475280 13.178 100 83 167 0 1276214256 13573760 19165216
20:18:23 1935 8192000000 10183086656 10563760128 1270291360 13.424 100 78 156 0 1300110192 12619632 17199200
20:19:27 858 8192000000 10208686448 10594496512 1296100224 13.660 100 78 156 0 1326255408 12722336 17432848
20:20:30 39769 8192000000 10201868816 10601807872 1302681856 13.720 100 39 76 1 1318091584 6124880 9284848
20:22:10 6387 8192000000 10203172560 10616799232 1315333104 13.834 100 32 62 0 1329626880 6943952 7349824
20:23:17 12886 8192000000 10199997248 10621550592 1321420224 13.890 100 26 52 0 1334671248 6901568 6349456
20:24:30 9422 8192000000 10210086080 10628083712 1325588304 13.927 100 10 20 1 1333619376 5874976 2156096
20:25:41 76223 8192000000 10219991088 10632327168 1320762256 13.884 99 24 48 1 1331631952 5568176 5301520
20:27:59 7589 8192000000 10219052704 10636853248 1304761552 13.739 98 27 54 0 1317029488 6351200 5916736
20:29:07 4918 8192000000 10200126832 10612916224 1274369520 13.462 97 18 36 1 1284692048 5649312 4673216
20:30:13 9208 8192000000 10157074896 10568601600 1222178128 12.982 95 15 30 0 1230830096 5750992 2900976
20:31:24 28764 8192000000 10130809104 10534850560 1179789904 12.588 93 37 76 0 1198649056 10598256 8260896
20:32:53 4926 8192000000 10146670000 10550444032 1249348944 13.232 99 28 56 0 1262170608 6128112 6693552
20:34:01 8310 8192000000 10167762624 10570809344 1273525536 13.454 100 20 40 0 1284100480 5826576 4748368
20:35:12 7311 8192000000 10178462112 10584174592 1283547760 13.545 100 25 47 1 1294179584 5618560 5013264
20:36:26 29129 8192000000 10179815792 10594394112 1292864240 13.630 100 21 43 0 1307921168 9616704 5440224
20:37:58 6125 8192000000 10200716976 10615439360 1308830480 13.775 100 24 44 1 1319421088 5316960 5273648
20:39:06 12228 8192000000 10246865536 10654269440 1336023888 14.022 100 45 90 1 1357424896 11267584 10133424
20:40:25 11257 8192000000 10260420688 10672586752 1353789792 14.182 100 41 82 0 1374202640 11664032 8748816
20:41:42 98493 8192000000 10274816112 10698907648 1369599616 14.323 100 37 72 0 1390701824 12392288 8709920
20:44:26 41506 8192000000 10286415648 10717425664 1378228368 14.401 100 31 61 0 1399229856 13932128 7069360
20:46:11 5677 8192000000 10311506352 10732441600 1388676064 14.494 100 49 95 0 1422340208 22442064 11222080
20:47:21 7370 8192000000 10322751408 10747949056 1398680720 14.583 100 61 122 1 1434869504 21956592 14232192
20:48:39 1280 8192000000 10349022000 10766282752 1409780304 14.682 100 76 151 0 1454843232 27424048 17638880
20:49:46 3259 8192000000 10334902896 10754543616 1398484000 14.581 100 70 140 0 1444731552 29306128 16941424
20:50:54 7689 8192000000 10323489920 10749435904 1391347200 14.518 100 43 85 0 1433369840 31100704 10921936
20:52:09 2478 8192000000 10353888240 10776682496 1409239504 14.677 100 64 125 0 1456630544 32600448 14790592
20:53:17 4227 8192000000 10360834288 10772377600 1406971008 14.657 100 80 160 1 1465511744 39005136 19535600
20:54:26 3504 8192000000 10357492320 10772840448 1404562480 14.636 100 62 124 1 1460087760 39540640 15984640
20:55:35 1401 8192000000 10365361936 10768805888 1393105312 14.534 99 88 176 2 1459460080 45086240 21268528
20:56:41 4413 8192000000 10364514464 10784833536 1405904480 14.648 100 53 103 1 1472561168 52522592 14134096
20:57:50 1125 8192000000 10360320688 10766659584 1384140832 14.454 100 78 155 0 1461122768 57140848 19841088
20:58:56 1402 8192000000 10355847984 10765905920 1385294192 14.464 100 73 141 0 1463292944 59454768 18543984
21:00:15 9747 8192000000 10355804496 10782150656 1388333088 14.491 100 45 90 0 1466721776 66015600 12373088
21:01:31 7874 8192000000 10333328976 10747748352 1344528160 14.098 98 40 80 0 1417173040 61077616 11567264
21:02:45 9769 8192000000 10345087712 10772168704 1380344336 14.420 100 49 99 0 1451233744 57651184 13238224
21:04:01 28648 8192000000 10313877024 10754363392 1354358432 14.187 99 26 52 0 1421534912 59284624 7891856
21:05:57 35542 8192000000 10292012336 10736750592 1361986064 14.255 100 20 40 0 1413532016 45646688 5899264
21:07:34 42488 8192000000 10266906320 10705588224 1340485472 14.062 98 8 16 0 1352769680 10299152 1985056
21:09:18 40643 8192000000 10275544160 10721525760 1364267728 14.276 100 18 36 0 1377953056 10246400 3438928
21:11:03 83240 8192000000 10266397072 10705305600 1295266848 13.652 94 7 13 0 1305628384 9451328 910208
21:13:28 40906 8192000000 10282055728 10731220992 1374308032 14.366 100 10 20 1 1386534944 10078496 2148416
21:15:19 109188 8192000000 10307403904 10749128704 1350203488 14.149 97 15 30 0 1364314224 10669856 3440880
21:18:09 93243 8192000000 10312054736 10752475136 1392276576 14.526 100 11 22 0 1405953376 10923568 2753232
21:20:44 49853 8192000000 10307376096 10750189568 1384169888 14.454 99 42 84 0 1401952432 7671296 10111248
21:22:38 50981 8192000000 10303577664 10756182016 1396196288 14.561 100 21 42 0 1415430800 15041312 4193200
21:24:31 14948 8192000000 10324478208 10771427328 1406228784 14.650 100 48 94 0 1430447024 13349552 10868688
21:25:47 12487 8192000000 10321153168 10770616320 1400081600 14.596 100 36 72 0 1433867904 25821776 7964528
21:27:05 67102 8192000000 10306776464 10762002432 1357645024 14.216 97 47 93 4 1390331744 21671216 11015504
21:29:37 26504 8192000000 10278392272 10736558080 1375914960 14.380 100 15 30 0 1393299584 13756880 3627744
21:31:05 53216 8192000000 10281720608 10730336256 1344335712 14.096 97 17 34 0 1357599248 9601456 3662080
21:32:59 58861 8192000000 10255964656 10726043648 1342227120 14.077 98 7 14 0 1355244064 10901888 2115056
21:34:58 60868 8192000000 10274989472 10734587904 1360720160 14.244 99 11 22 0 1376647152 13272592 2654400
21:37:04 34078 8192000000 10291116400 10742820864 1383073792 14.444 100 36 72 0 1404562576 12483648 9005136
21:38:49 31850 8192000000 10263402944 10710609920 1320364048 13.880 96 14 28 1 1332650528 9485808 2800672
21:40:27 80623 8192000000 10292224672 10748850176 1349505856 14.143 98 13 26 0 1366611440 14202672 2902912
21:42:57 35017 8192000000 10268474944 10713214976 1303502992 13.727 95 21 42 0 1317106832 8959136 4644704
21:44:33 36473 8192000000 10240257888 10672627712 1268808032 13.411 95 29 58 0 1286465248 11501616 6155600
21:46:10 53165 8192000000 10230488592 10666090496 1255438992 13.288 95 27 52 1 1273406160 11981328 5985840
21:48:11 58111 8192000000 10255177408 10709262336 1306475408 13.754 98 11 22 0 1327061392 18370528 2215456
21:50:11 116167 8192000000 10259182928 10697740288 1315332912 13.834 98 15 29 0 1334103312 15476336 3294064
21:53:12 63721 8192000000 10271051584 10714693632 1347575968 14.126 100 32 64 2 1369110928 14225824 7309136
21:55:41 35081 8192000000 10250505840 10706792448 1344157312 14.095 100 8 16 0 1361515104 15684336 1673456
21:57:25 67692 8192000000 10271923904 10722357248 1351505824 14.161 99 13 26 0 1367247776 12627712 3114240
21:59:36 72779 8192000000 10268680480 10727325696 1362062816 14.256 100 16 32 0 1383719168 17224384 4431968
22:01:49 73991 8192000000 10282984832 10724720640 1354663632 14.189 99 17 34 0 1373357504 14932448 3761424
22:04:05 92088 8192000000 10298765440 10741776384 1357379984 14.214 98 27 54 1 1375928080 11948176 6599920
22:06:38 39804 8192000000 10284359504 10741686272 1373320016 14.357 100 25 50 0 1387977504 8495024 6162464
22:08:19 42922 8192000000 10302108128 10757955584 1384835056 14.460 100 10 20 0 1401143408 14205664 2102688
22:10:02 73109 8192000000 10297940208 10751197184 1362694768 14.262 98 22 43 1 1375079920 8201776 4183376
22:12:17 17915 8192000000 10294572384 10739769344 1378156256 14.400 99 23 46 0 1388893952 6163824 4573872
22:13:36 19062 8192000000 10307216992 10746888192 1379884592 14.416 100 52 104 1 1401426128 9397856 12143680
22:15:01 156249 8192000000 10283693488 10740121600 1331941632 13.985 96 14 28 0 1356384224 21328880 3113712
22:18:38 32268 8192000000 10275576960 10737176576 1374683456 14.369 100 11 22 0 1385676864 8479424 2513984
22:20:14 83891 8192000000 10269904736 10737475584 1350510288 14.152 98 15 30 0 1367614688 13517072 3587328
22:22:52 102756 8192000000 10269639456 10733486080 1318764736 13.866 95 15 30 1 1332806880 10761040 3281104
22:25:35 95259 8192000000 10284720992 10745516032 1364920592 14.282 99 16 31 0 1382828400 14327344 3580464
22:28:12 73421 8192000000 10263524032 10738978816 1352657760 14.171 99 11 22 1 1370267952 15335248 2274944
22:30:28 62461 8192000000 10262826224 10716495872 1279463040 13.508 94 20 40 0 1298132608 13875584 4793984
22:32:32 48393 8192000000 10270853440 10729713664 1351807552 14.164 99 26 52 0 1373207968 15370800 6029616
22:34:21 25191 8192000000 10269738976 10714566656 1350233376 14.150 99 36 69 0 1372905424 15095984 7576064
22:35:55 63123 8192000000 10262288288 10726014976 1298553312 13.682 96 20 41 1 1321418992 18251328 4614352
22:37:58 50848 8192000000 9148636272 9395892224 302409264 3.560 22 3 6 0 306206672 3651152 146256

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 140 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

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

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          248641
MON$OLDEST_ACTIVE               248642
MON$OLDEST_SNAPSHOT             248642
MON$NEXT_TRANSACTION            248642
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                       2058528
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 ---
--- end of diff output ---

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

21.01.23 22:39:59. 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-21 22:40:02 No severe PSQL-related problems occured
21.01.23 22:40:03. End of report.