Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.06.2022 16: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 16228872 kB
MemAvailable 31268696 kB
Buffers 0 kB
Cached 15513596 kB
SwapCached 2484 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.10
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 2022-06-21 16:38:36
DTS_END                         2022-06-21 16:38:20.1830
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/45338
TRN_ID                          249276
ATT_ID                          274
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: 9622.74 20976 1154729
customer order: creation 181.88 331 21826
customer order: refuse 15.35 341 1842
order to supplier: creation 48.15 1256 5778
order to supplier: removal 3.52 104768 422
invoice (draft): creation 47.63 3202 5716
invoice (draft): removal 54.53 740 6543
invoice accept: apply 44.05 16324 5286
invoice accept: cancel 49.44 740 5933
customer reserve: creation 4100.98 160 492118
customer reserve: removal 4765.84 61 571901
realization accept: apply 87.23 179 10468
realization accept: cancel 87.77 109 10532
payment from customer: creation 62.83 106 7540
payment from customer: removal 5.86 82 703
payment to supplier: creation 51.78 111 6214
payment to supplier: removal 3.83 82 459
service: total inventory turnovers 4.76 1071 571
service: total monetary turnovers 6.47 49 776
service: aggregate perf. data 0.81 22608 97
service: refresh index statistics 0.03 267190 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-06-2022 16:40:11 to 21-06-2022 16:40:13.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2121
TEST_TIME 2 4078
TEST_TIME 3 3870
TEST_TIME 4 4820
TEST_TIME 5 5385
TEST_TIME 6 5460
TEST_TIME 7 5330
TEST_TIME 8 5526
TEST_TIME 9 5547
TEST_TIME 10 5971
TEST_TIME 11 6021
TEST_TIME 12 6174
TEST_TIME 13 6091
TEST_TIME 14 6646
TEST_TIME 15 6704
TEST_TIME 16 6825
TEST_TIME 17 7173
TEST_TIME 18 7844
TEST_TIME 19 8304
TEST_TIME 20 8216
TEST_TIME 21 8648
TEST_TIME 22 8801
TEST_TIME 23 9089
TEST_TIME 24 9134
TEST_TIME 25 9104
TEST_TIME 26 10554
TEST_TIME 27 10482
TEST_TIME 28 11434
TEST_TIME 29 11180
TEST_TIME 30 11283
TEST_TIME 31 11331
TEST_TIME 32 11149
TEST_TIME 33 10906
TEST_TIME 34 10823
TEST_TIME 35 10520
TEST_TIME 36 10748
TEST_TIME 37 10671
TEST_TIME 38 10744
TEST_TIME 39 10559
TEST_TIME 40 10452
TEST_TIME 41 10416
TEST_TIME 42 10290
TEST_TIME 43 10221
TEST_TIME 44 10551
TEST_TIME 45 10439
TEST_TIME 46 10400
TEST_TIME 47 10294
TEST_TIME 48 10183
TEST_TIME 49 10059
TEST_TIME 50 10059
TEST_TIME 51 10058
TEST_TIME 52 9939
TEST_TIME 53 9925
TEST_TIME 54 9891
TEST_TIME 55 9892
TEST_TIME 56 9894
TEST_TIME 57 9818
TEST_TIME 58 9867
TEST_TIME 59 9921
TEST_TIME 60 9996
TEST_TIME 61 9960
TEST_TIME 62 10049
TEST_TIME 63 10050
TEST_TIME 64 10006
TEST_TIME 65 10090
TEST_TIME 66 10225
TEST_TIME 67 10252
TEST_TIME 68 10266
TEST_TIME 69 10202
TEST_TIME 70 10091
TEST_TIME 71 10043
TEST_TIME 72 9985
TEST_TIME 73 10014
TEST_TIME 74 9963
TEST_TIME 75 9889
TEST_TIME 76 9879
TEST_TIME 77 9830
TEST_TIME 78 9829
TEST_TIME 79 9819
TEST_TIME 80 9818
TEST_TIME 81 9803
TEST_TIME 82 9786
TEST_TIME 83 9833
TEST_TIME 84 9889
TEST_TIME 85 9875
TEST_TIME 86 9881
TEST_TIME 87 9910
TEST_TIME 88 9929
TEST_TIME 89 9939
TEST_TIME 90 9935
TEST_TIME 91 9879
TEST_TIME 92 9829
TEST_TIME 93 9777
TEST_TIME 94 9790
TEST_TIME 95 9725
TEST_TIME 96 9754
TEST_TIME 97 9754
TEST_TIME 98 9759
TEST_TIME 99 9765
TEST_TIME 100 9744
TEST_TIME 101 9808
TEST_TIME 102 9858
TEST_TIME 103 9813
TEST_TIME 104 9816
TEST_TIME 105 9854
TEST_TIME 106 9821
TEST_TIME 107 9827
TEST_TIME 108 9851
TEST_TIME 109 9931
TEST_TIME 110 9914
TEST_TIME 111 9852
TEST_TIME 112 9796
TEST_TIME 113 9801
TEST_TIME 114 9789
TEST_TIME 115 9733
TEST_TIME 116 9752
TEST_TIME 117 9730
TEST_TIME 118 9731
TEST_TIME 119 9687
TEST_TIME 120 9623

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 21-06-2022 16:40:14 to 21-06-2022 16:40:18.

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 1609486 1609486 0 0.00 0 13159 6 0 0
doc_list_biud 1609485 1609485 0 0.00 0 63817 27 0 0
sp_add_invoice_to_stock 5445 5286 159 2.92 38 168454 16325 0 159
sp_cancel_adding_invoice 5933 5933 0 0.00 4 58256 741 0 0
sp_cancel_client_order 1842 1842 0 0.00 4 9104 341 0 0
sp_cancel_customer_reserve 571901 571901 0 0.00 1 63819 62 0 0
sp_cancel_pay_from_customer 703 703 0 0.00 3 4121 82 0 0
sp_cancel_pay_to_supplier 459 459 0 0.00 3 6154 82 0 0
sp_cancel_supplier_invoice 6559 6543 16 0.24 2 13154 740 0 16
sp_cancel_supplier_order 422 422 0 0.00 8 1232317 104769 0 0
sp_cancel_write_off 10532 10532 0 0.00 1 9058 109 0 0
sp_client_order 21826 21826 0 0.00 3 17483 332 0 0
sp_customer_reserve 492118 492118 0 0.00 0 39206 161 0 0
sp_fill_shopping_cart 33312 33312 0 0.00 1 15812 34 0 0
sp_fill_shopping_cart_clo_res 492116 492116 0 0.00 0 14569 13 0 0
sp_get_clo_for_invoice 5284 5284 0 0.00 0 23291 280 0 0
sp_kill_cost_storno 17627 17627 0 0.00 0 9056 57 0 0
sp_kill_qstorno_ret_qs2qd 1161299 1161299 0 0.00 0 63795 33 0 0
sp_kill_qty_storno 612924 612924 0 0.00 0 63817 71 0 0
sp_lock_selected_doc 21860 21860 0 0.00 0 6740 6 0 0
sp_make_cost_storno 29506 29506 0 0.00 0 11331 53 0 0
sp_multiply_rows_for_pdistr 29505 29505 0 0.00 0 5747 28 0 0
sp_multiply_rows_for_qdistr 21826 21826 0 0.00 0 15017 153 0 0
sp_pay_from_customer 7540 7540 0 0.00 4 11339 106 0 0
sp_pay_to_supplier 6214 6214 0 0.00 4 11208 111 0 0
sp_payment_common 13754 13754 0 0.00 1 9564 25 0 0
sp_qd_handle_on_cancel_clo 1842 1842 0 0.00 0 9098 313 0 0
sp_qd_handle_on_invoice_upd_sts 11217 11217 0 0.00 0 20397 302 0 0
sp_qd_handle_on_reserve_upd_sts 10467 10467 0 0.00 0 11686 59 0 0
sp_reserve_write_off 10468 10468 0 0.00 6 11698 179 0 0
sp_supplier_invoice 5716 5716 0 0.00 7 86284 3202 0 0
sp_supplier_order 5778 5778 0 0.00 5 71016 1257 0 0
srv_aggregate_perf_data 97 97 0 0.00 3141 357157 22609 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 2309 106085 43088 0 0
srv_find_qd_qs_mism 1103750 1103750 0 0.00 0 12522 5 0 0
srv_make_invnt_saldo 571 571 0 0.00 36 78290 1071 0 0
srv_make_money_saldo 776 776 0 0.00 1 16674 50 0 0
srv_recalc_idx_stat 4 4 0 0.00 143554 371819 267190 0 0
t$perf-abend:sp_add_invoice_to_stock 159 159 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5286 5286 0 0.00 0 16401 171 0 0
t$perf-norm:sp_cancel_adding_invoice 444 444 0 0.00 0 5031 114 0 0
t$perf-norm:sp_cancel_client_order 1842 1842 0 0.00 0 3762 12 0 0
t$perf-norm:sp_cancel_customer_reserve 746 746 0 0.00 0 3642 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 703 703 0 0.00 0 2954 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 459 459 0 0.00 0 4542 23 0 0
t$perf-norm:sp_cancel_supplier_invoice 430 430 0 0.00 0 3327 22 0 0
t$perf-norm:sp_cancel_supplier_order 422 422 0 0.00 0 22929 1698 0 0
t$perf-norm:sp_cancel_write_off 1064 1064 0 0.00 0 7616 22 0 0
t$perf-norm:sp_client_order 21826 21826 0 0.00 0 12163 16 0 0
t$perf-norm:sp_customer_reserve 1033 1033 0 0.00 0 2898 7 0 0
t$perf-norm:sp_pay_from_customer 7540 7540 0 0.00 0 7328 9 0 0
t$perf-norm:sp_pay_to_supplier 6214 6214 0 0.00 0 2895 8 0 0
t$perf-norm:sp_reserve_write_off 10468 10468 0 0.00 0 6639 11 0 0
t$perf-norm:sp_supplier_invoice 5716 5716 0 0.00 0 7390 19 0 0
t$perf-norm:sp_supplier_order 5778 5778 0 0.00 0 5151 15 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 205 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 372 10 0 0
t$perf-norm:srv_make_invnt_saldo 571 571 0 0.00 0 3128 29 0 0
t$perf-norm:srv_make_money_saldo 776 776 0 0.00 0 711 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 10882 10564 318 2.92 0 4949 18 0 318
v_all_customers 39868 39868 0 0.00 0 88 1 0 0
v_all_suppliers 32228 32228 0 0.00 0 16 1 0 0
v_all_wares 43652 43652 0 0.00 0 145 1 0 0
v_cancel_adding_invoice 888 888 0 0.00 0 90 5 0 0
v_cancel_client_order 3684 3684 0 0.00 0 4931 9 0 0
v_cancel_customer_prepayment 1406 1406 0 0.00 0 1066 4 0 0
v_cancel_customer_reserve 1492 1492 0 0.00 0 2830 8 0 0
v_cancel_payment_to_supplier 918 918 0 0.00 0 584 3 0 0
v_cancel_supplier_invoice 892 860 32 3.58 0 322 11 0 32
v_cancel_supplier_order 844 844 0 0.00 0 859 6 0 0
v_cancel_write_off 2128 2128 0 0.00 0 2398 13 0 0
v_max_id_clo_ord 5774 5774 0 0.00 0 1709 1 0 0
v_max_id_clo_res 1033 1033 0 0.00 0 7476 22 0 0
v_max_id_ord_sup 5710 5710 0 0.00 0 2371 4 0 0
v_max_non_paid_invoice 1583 1583 0 0.00 0 517 3 0 0
v_max_non_paid_realizn 3895 3895 0 0.00 0 5603 7 0 0
v_min_id_clo_ord 5774 5774 0 0.00 0 3917 5 0 0
v_min_id_clo_res 1033 1033 0 0.00 0 11609 16 0 0
v_min_id_ord_sup 5710 5710 0 0.00 0 6069 7 0 0
v_min_non_paid_invoice 6214 6214 0 0.00 0 7489 14 0 0
v_min_non_paid_realizn 7540 7540 0 0.00 0 7429 20 0 0
v_our_firm 11074 11074 0 0.00 0 19 1 0 0
v_reserve_write_off 20930 20930 0 0.00 0 3633 5 0 0
x_lock_depdocs_on_canc_invoice 12046 12046 0 0.00 0 19564 112 0 0
x_lock_depdocs_on_canc_sup_ord 422 422 0 0.00 0 22959 444 0 0
x_make_qstorno_1000_1200 5778 5778 0 0.00 2 69022 1188 0 0
x_make_qstorno_1200_2000 5716 5716 0 0.00 3 86275 3079 0 0
x_make_qstorno_2100_3300 492118 492118 0 0.00 0 37872 145 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-06-2022 16:40:19 to 21-06-2022 16:40:23.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
14:08:23 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:24 34805 8192000000 9920841104 10231857152 995699552 10.837 100 90 181 0 1025230400 5946064 23584784
14:11:05 26198 8192000000 9964097904 10288685056 1041976400 11.284 100 83 166 0 1071266896 8250416 21040080
14:12:34 17728 8192000000 9999674464 10333937664 1077704240 11.626 100 79 157 0 1106601568 9506704 19390624
14:13:56 12207 8192000000 10061101040 10413686784 1144901776 12.262 100 78 156 1 1173802080 10827552 18072752
14:15:12 5191 8192000000 10113085072 10478288896 1194904048 12.729 100 76 152 0 1223709520 11452848 17352624
14:16:20 3885 8192000000 10145494896 10521374720 1236000272 13.109 100 78 156 1 1266160144 12998480 17161392
14:17:25 9228 8192000000 10170334512 10553094144 1260607904 13.336 100 74 149 1 1290675888 13605936 16462048
14:18:37 1768 8192000000 10206079760 10586906624 1288419984 13.590 100 93 186 1 1322895008 13392096 21082928
14:19:41 5257 8192000000 10203920944 10593140736 1296808384 13.666 100 82 164 3 1326367808 11013872 18545552
14:20:47 6675 8192000000 10198206016 10598162432 1303792960 13.730 100 37 75 3 1319746304 8060832 7892512
14:21:54 8740 8192000000 10197306976 10615177216 1313726512 13.820 100 20 40 1 1327717680 9889072 4102096
14:23:03 57458 8192000000 10226413280 10638008320 1324556784 13.918 100 18 37 0 1338659600 9393872 4708944
14:25:07 4261 8192000000 10226507296 10646106112 1331997408 13.985 100 32 62 1 1346374992 7065392 7312192
14:26:13 8724 8192000000 10231160960 10652762112 1338677600 14.045 100 17 34 2 1352071504 10072544 3321360
14:27:25 15846 8192000000 10210755792 10624606208 1280901056 13.521 96 39 78 1 1296545968 6738192 8906720
14:28:41 10284 8192000000 10194882240 10613891072 1297612912 13.674 99 30 60 0 1311831952 7678976 6540064
14:29:54 7727 8192000000 10165501152 10565296128 1257701472 13.309 99 27 55 0 1269714576 5723984 6289120
14:31:02 9107 8192000000 10130048624 10532745216 1222955120 12.989 98 25 50 0 1234747568 5651744 6140704
14:32:13 71783 8192000000 10153419072 10560737280 1229184448 13.047 97 23 46 0 1239853104 5481936 5186720
14:34:28 5401 8192000000 10180327584 10585939968 1282918464 13.540 100 40 80 1 1298481664 5900192 9663008
14:35:36 7601 8192000000 10197982736 10608025600 1301404240 13.708 100 20 40 1 1312258416 6315712 4538464
14:36:45 4619 8192000000 10213879520 10629332992 1318198496 13.860 100 18 36 0 1327544800 5239728 4106576
14:37:50 17341 8192000000 10218245264 10633568256 1323879200 13.912 100 26 50 2 1336150976 5966096 6305680
14:39:18 28653 8192000000 10265281520 10673610752 1354372704 14.187 100 55 110 2 1375297120 8255088 12669328
14:40:53 36909 8192000000 10292874352 10709544960 1372529776 14.350 100 45 89 1 1393405472 10421600 10454096
14:42:33 28721 8192000000 10311480432 10732367872 1391749600 14.521 100 45 88 2 1419090896 16275824 11065472
14:44:09 18198 8192000000 10308445488 10732367872 1388906016 14.496 99 52 101 4 1420327872 19326384 12095472
14:45:40 9304 8192000000 10336325040 10762178560 1415246576 14.731 100 49 97 1 1449055936 22099264 11710096
14:46:56 13346 8192000000 10343034896 10769338368 1407127552 14.658 99 58 116 0 1445885808 24081552 14676704
14:48:13 2309 8192000000 10350397216 10774339584 1414260000 14.722 100 68 135 1 1464323776 33723296 16340480
14:49:19 6513 8192000000 10363286960 10787082240 1424235664 14.810 100 69 138 0 1475259216 33949728 17073824
14:50:31 4605 8192000000 10356516768 10776432640 1405378144 14.643 99 64 126 3 1455628912 33369440 16881328
14:51:46 2455 8192000000 10379698128 10790440960 1415433376 14.732 100 81 162 2 1480448512 44226880 20788256
14:52:57 3494 8192000000 10372841872 10783752192 1410156624 14.685 100 79 154 2 1474801552 44845760 19799168
14:54:08 4415 8192000000 10351936864 10765225984 1386507184 14.475 99 67 134 1 1456411216 52984192 16919840
14:55:20 3911 8192000000 10342449040 10750533632 1372361168 14.348 100 77 153 0 1441729072 49806544 19561360
14:56:27 10263 8192000000 10329168656 10749476864 1374461280 14.367 100 45 90 0 1435721760 49101392 12159088
14:57:53 6664 8192000000 10337473456 10754727936 1381176432 14.427 100 60 120 1 1442810672 46217376 15416864
14:59:10 3083 8192000000 10370713968 10777329664 1390520976 14.511 100 84 167 0 1466203984 54099152 21583856
15:00:15 13682 8192000000 10361284160 10787676160 1393222816 14.535 99 38 76 1 1454971872 51413136 10335920
15:01:33 13578 8192000000 10365716256 10798534656 1409900608 14.683 100 32 64 2 1470895792 51903152 9092032
15:02:53 15820 8192000000 10353054480 10792460288 1403866192 14.629 100 33 66 1 1462097120 48907472 9323456
15:04:41 54221 8192000000 10287513296 10739294208 1306368528 13.753 96 15 30 0 1353913248 42706864 4837856
15:06:37 67425 8192000000 10288552560 10727837696 1364300864 14.276 100 19 38 0 1394507200 24656336 5550000
15:08:45 59734 8192000000 10299106064 10749263872 1378694800 14.405 100 9 18 0 1397522880 17194896 1633184
15:10:47 22895 8192000000 10310881408 10738442240 1379979600 14.416 100 44 85 2 1401540800 11684992 9876208
15:12:11 70280 8192000000 10303503904 10756677632 1391700064 14.521 100 25 50 2 1410121744 12663168 5758512
15:14:46 36415 8192000000 10308561392 10759495680 1394591968 14.547 100 12 22 1 1411779792 14373088 2814736
15:16:35 106083 8192000000 10320327552 10775707648 1406125488 14.650 100 15 30 0 1431939456 22659664 3154304
15:19:22 29356 8192000000 10302001616 10759364608 1392502112 14.528 100 19 38 0 1411265392 14414032 4349248
15:21:02 44678 8192000000 10324797440 10779070464 1404526080 14.635 100 42 84 0 1434797440 20366160 9905200
15:23:01 49140 8192000000 10306496000 10763743232 1400953712 14.603 100 10 20 0 1415542384 12376496 2212176
15:24:50 27096 8192000000 10291013632 10758389760 1394655920 14.547 100 18 36 0 1405249424 6999296 3594208
15:26:19 70998 8192000000 10300726256 10773049344 1397210640 14.570 100 17 34 1 1412912608 11707264 3994704
15:28:52 82529 8192000000 10285610416 10750382080 1335786752 14.019 95 7 14 0 1352552976 15516320 1249904
15:31:17 29921 8192000000 10307110832 10774052864 1398283600 14.580 99 39 78 1 1419679072 12363024 9032448
15:32:48 47863 8192000000 10308692208 10781265920 1390809056 14.513 99 9 18 0 1405293232 12416976 2067200
15:35:09 33623 8192000000 10306599472 10759839744 1362897904 14.263 97 19 39 0 1375801808 8753760 4150144
15:36:44 74889 8192000000 10297066704 10752438272 1329140400 13.959 95 17 34 0 1346814624 13662096 4012128
15:39:05 42172 8192000000 10298359216 10747645952 1345126192 14.104 96 27 54 0 1360307280 9357280 5823808
15:40:48 62557 8192000000 10285425232 10737807360 1332273248 13.988 96 12 24 1 1342843024 8040144 2529632
15:42:52 45846 8192000000 10291341328 10735964160 1334606320 14.009 96 32 62 3 1353163872 11726912 6830640
15:44:38 45046 8192000000 10300637536 10757537792 1342551040 14.080 97 17 34 0 1365150336 19010288 3589008
15:46:23 98285 8192000000 10290237952 10736443392 1330536480 13.972 96 30 59 0 1346772928 9727904 6508544
15:49:08 60024 8192000000 10281789408 10733842432 1342666272 14.081 97 43 84 2 1363989488 11843168 9480048
15:52:24 45368 8192000000 10286015696 10757251072 1387034688 14.479 100 4 8 0 1402346336 14637952 673696
15:54:10 77545 8192000000 10259768624 10723160064 1333076800 13.995 97 8 16 0 1344153952 9097296 1979856
15:56:31 37601 8192000000 10276303488 10730401792 1361961280 14.255 99 18 37 0 1373707008 8133248 3612480
15:58:09 23333 8192000000 10256939056 10706108416 1319171024 13.869 96 38 76 1 1339995152 12255280 8568848
15:59:33 19619 8192000000 10268303872 10711654400 1341604352 14.072 99 27 53 0 1355219440 7949376 5665712
16:00:53 26105 8192000000 10290903328 10739466240 1372340368 14.348 100 27 55 2 1392626048 14251360 6034320
16:02:20 49853 8192000000 10278173136 10741002240 1357984048 14.219 99 12 24 1 1376132512 15394896 2753568
16:04:12 58028 8192000000 10287769216 10749214720 1365510800 14.287 99 21 42 2 1380698912 9910944 5277168
16:06:10 61916 8192000000 10282870592 10731954176 1319143680 13.869 95 17 32 0 1333005968 10057392 3804896
16:08:25 102953 8192000000 10281522224 10746691584 1360573152 14.243 99 11 22 0 1380953264 18170768 2209344
16:11:27 47092 8192000000 10285453680 10745511936 1382792992 14.442 99 26 52 0 1399933424 11551808 5588624
16:13:18 49863 8192000000 10298646480 10768248832 1393230224 14.535 100 11 22 0 1407255824 12013200 2012400
16:15:23 16782 8192000000 10297664208 10741481472 1360322656 14.240 98 33 65 1 1374287632 6696240 7268736
16:16:40 42497 8192000000 10289050240 10744328192 1379906336 14.416 100 20 40 1 1393676304 8750288 5019680
16:18:27 80396 8192000000 10273914288 10736947200 1325513712 13.927 96 15 30 0 1342675744 13593712 3568320
16:20:48 69061 8192000000 10259830992 10701570048 1259986768 13.330 92 21 42 0 1274869776 10199344 4683664
16:22:57 91719 8192000000 10280440416 10733240320 1339328224 14.051 98 12 24 0 1352848224 10353456 3166544
16:25:37 65302 8192000000 10287050544 10740588544 1318213008 13.861 96 20 40 0 1333706416 11719952 3773456
16:28:02 66075 8192000000 10262292304 10722242560 1357923680 14.219 100 12 24 0 1376516192 15855024 2737488
16:30:09 71418 8192000000 10264915536 10729324544 1352985072 14.174 99 11 22 0 1368461552 13059984 2416496
16:32:21 58052 8192000000 10262447024 10727841792 1353159392 14.176 99 10 20 0 1364727152 9554768 2012992
16:34:24 55128 8192000000 10269102224 10729562112 1337583344 14.036 98 16 32 0 1358393520 17215344 3594832
16:36:21 97949 8192000000 10246462080 10707337216 1298767888 13.684 95 14 28 0 1310450960 8614928 3068144

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-06-2022 16:40:25 to 21-06-2022 16:40:40.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 159 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-06-2022 16:40:41 to 21-06-2022 16:40:43.

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          252658
MON$OLDEST_ACTIVE               252659
MON$OLDEST_SNAPSHOT             252659
MON$NEXT_TRANSACTION            252659
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                       2034400
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33603 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33603 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33603 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33603 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.06.22 16:40:44. 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
2022-06-21 16:40:46 No severe PSQL-related problems occured
21.06.22 16:40:47. End of report.