Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.01.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 16261920 kB
MemAvailable 31302796 kB
Buffers 0 kB
Cached 15437720 kB
SwapCached 2300 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.9
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-01-13 16:38:05
DTS_END                         2022-01-13 16:38:05.4070
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/34852
TRN_ID                          239230
ATT_ID                          716
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: 9527.52 24574 1143302
customer order: creation 167.31 392 20077
customer order: refuse 14.66 351 1759
order to supplier: creation 45.13 1295 5416
order to supplier: removal 3.53 106000 424
invoice (draft): creation 44.62 3522 5354
invoice (draft): removal 56.01 729 6721
invoice accept: apply 41.03 19057 4924
invoice accept: cancel 50.45 751 6054
customer reserve: creation 3832.09 187 459851
customer reserve: removal 4970.83 59 596500
realization accept: apply 82.74 196 9929
realization accept: cancel 90.46 107 10855
payment from customer: creation 58.49 112 7019
payment from customer: removal 5.82 98 698
payment to supplier: creation 48.46 137 5815
payment to supplier: removal 3.62 87 434
service: total inventory turnovers 4.55 1251 546
service: total monetary turnovers 6.91 34 829
service: aggregate perf. data 0.78 21015 93
service: refresh index statistics 0.03 336103 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-01-2022 16:40:18 to 13-01-2022 16:40:20.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6365
TEST_TIME 2 5053
TEST_TIME 3 5092
TEST_TIME 4 4790
TEST_TIME 5 5368
TEST_TIME 6 5191
TEST_TIME 7 5407
TEST_TIME 8 6103
TEST_TIME 9 6555
TEST_TIME 10 6578
TEST_TIME 11 6685
TEST_TIME 12 6820
TEST_TIME 13 6715
TEST_TIME 14 7003
TEST_TIME 15 7258
TEST_TIME 16 7321
TEST_TIME 17 7479
TEST_TIME 18 7779
TEST_TIME 19 8181
TEST_TIME 20 8248
TEST_TIME 21 8549
TEST_TIME 22 9111
TEST_TIME 23 9200
TEST_TIME 24 9348
TEST_TIME 25 9932
TEST_TIME 26 9754
TEST_TIME 27 10119
TEST_TIME 28 11373
TEST_TIME 29 11187
TEST_TIME 30 11304
TEST_TIME 31 11079
TEST_TIME 32 11035
TEST_TIME 33 11139
TEST_TIME 34 11052
TEST_TIME 35 10981
TEST_TIME 36 10700
TEST_TIME 37 10682
TEST_TIME 38 10583
TEST_TIME 39 10600
TEST_TIME 40 10437
TEST_TIME 41 10395
TEST_TIME 42 10297
TEST_TIME 43 10326
TEST_TIME 44 10225
TEST_TIME 45 10271
TEST_TIME 46 10381
TEST_TIME 47 10335
TEST_TIME 48 10332
TEST_TIME 49 10224
TEST_TIME 50 10214
TEST_TIME 51 10146
TEST_TIME 52 10084
TEST_TIME 53 9944
TEST_TIME 54 10009
TEST_TIME 55 9923
TEST_TIME 56 9897
TEST_TIME 57 9883
TEST_TIME 58 9928
TEST_TIME 59 9911
TEST_TIME 60 10059
TEST_TIME 61 10011
TEST_TIME 62 9968
TEST_TIME 63 10086
TEST_TIME 64 10129
TEST_TIME 65 10158
TEST_TIME 66 10162
TEST_TIME 67 10096
TEST_TIME 68 10235
TEST_TIME 69 10208
TEST_TIME 70 10143
TEST_TIME 71 10085
TEST_TIME 72 10066
TEST_TIME 73 9993
TEST_TIME 74 10007
TEST_TIME 75 10018
TEST_TIME 76 9993
TEST_TIME 77 9923
TEST_TIME 78 9952
TEST_TIME 79 9867
TEST_TIME 80 9845
TEST_TIME 81 9930
TEST_TIME 82 9908
TEST_TIME 83 9906
TEST_TIME 84 9872
TEST_TIME 85 9917
TEST_TIME 86 9966
TEST_TIME 87 10042
TEST_TIME 88 9987
TEST_TIME 89 10051
TEST_TIME 90 10118
TEST_TIME 91 10106
TEST_TIME 92 10126
TEST_TIME 93 10029
TEST_TIME 94 9973
TEST_TIME 95 9958
TEST_TIME 96 9897
TEST_TIME 97 9865
TEST_TIME 98 9835
TEST_TIME 99 9814
TEST_TIME 100 9887
TEST_TIME 101 9835
TEST_TIME 102 9836
TEST_TIME 103 9857
TEST_TIME 104 9903
TEST_TIME 105 9874
TEST_TIME 106 9883
TEST_TIME 107 9848
TEST_TIME 108 9931
TEST_TIME 109 9921
TEST_TIME 110 9876
TEST_TIME 111 9804
TEST_TIME 112 9764
TEST_TIME 113 9775
TEST_TIME 114 9802
TEST_TIME 115 9769
TEST_TIME 116 9730
TEST_TIME 117 9692
TEST_TIME 118 9661
TEST_TIME 119 9614
TEST_TIME 120 9534

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 13-01-2022 16:40:21 to 13-01-2022 16:40:25.

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 1569750 1569750 0 0.00 0 20167 6 0 0
doc_list_biud 1569750 1569750 0 0.00 0 38512 28 0 0
sp_add_invoice_to_stock 5044 4924 120 2.37 19 185025 19057 0 120
sp_cancel_adding_invoice 6054 6054 0 0.00 4 83811 752 0 0
sp_cancel_client_order 1759 1759 0 0.00 4 11308 351 0 0
sp_cancel_customer_reserve 596500 596500 0 0.00 1 38514 60 0 0
sp_cancel_pay_from_customer 698 698 0 0.00 2 5291 98 0 0
sp_cancel_pay_to_supplier 434 434 0 0.00 3 5556 87 0 0
sp_cancel_supplier_invoice 6727 6721 6 0.08 2 20216 729 0 6
sp_cancel_supplier_order 424 424 0 0.00 7 1199574 106000 0 0
sp_cancel_write_off 10855 10855 0 0.00 1 9405 107 0 0
sp_client_order 20077 20077 0 0.00 3 18657 393 0 0
sp_customer_reserve 459851 459851 0 0.00 0 155466 188 0 0
sp_fill_shopping_cart 30832 30832 0 0.00 1 18473 40 0 0
sp_fill_shopping_cart_clo_res 459845 459845 0 0.00 0 14942 14 0 0
sp_get_clo_for_invoice 4915 4915 0 0.00 0 26911 275 0 0
sp_kill_cost_storno 18041 18041 0 0.00 0 5638 56 0 0
sp_kill_qstorno_ret_qs2qd 1211000 1211000 0 0.00 0 38508 32 0 0
sp_kill_qty_storno 637157 637157 0 0.00 0 38511 69 0 0
sp_lock_selected_doc 20596 20596 0 0.00 0 6950 4 0 0
sp_make_cost_storno 27678 27678 0 0.00 0 19646 58 0 0
sp_multiply_rows_for_pdistr 27678 27678 0 0.00 0 14996 31 0 0
sp_multiply_rows_for_qdistr 20077 20077 0 0.00 0 18648 179 0 0
sp_pay_from_customer 7019 7019 0 0.00 4 28526 112 0 0
sp_pay_to_supplier 5815 5815 0 0.00 5 15007 137 0 0
sp_payment_common 12834 12834 0 0.00 2 28516 28 0 0
sp_qd_handle_on_cancel_clo 1759 1759 0 0.00 0 11300 314 0 0
sp_qd_handle_on_invoice_upd_sts 10969 10969 0 0.00 0 15655 337 0 0
sp_qd_handle_on_reserve_upd_sts 9929 9929 0 0.00 0 15264 65 0 0
sp_reserve_write_off 9929 9929 0 0.00 6 18654 197 0 0
sp_supplier_invoice 5354 5354 0 0.00 6 107268 3523 0 0
sp_supplier_order 5416 5416 0 0.00 5 29960 1295 0 0
srv_aggregate_perf_data 93 93 0 0.00 2970 183336 21015 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1899 189490 45551 0 0
srv_find_qd_qs_mism 1096480 1096480 0 0.00 0 10912 5 0 0
srv_make_invnt_saldo 546 546 0 0.00 46 75923 1252 0 0
srv_make_money_saldo 829 829 0 0.00 1 5845 35 0 0
srv_recalc_idx_stat 4 4 0 0.00 267468 456371 336103 0 0
t$perf-abend:sp_add_invoice_to_stock 120 120 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4924 4924 0 0.00 0 17696 220 0 0
t$perf-norm:sp_cancel_adding_invoice 393 393 0 0.00 0 6488 113 0 0
t$perf-norm:sp_cancel_client_order 1759 1759 0 0.00 0 4084 19 0 0
t$perf-norm:sp_cancel_customer_reserve 683 683 0 0.00 0 8869 25 0 0
t$perf-norm:sp_cancel_pay_from_customer 698 698 0 0.00 0 16164 32 0 0
t$perf-norm:sp_cancel_pay_to_supplier 434 434 0 0.00 0 3447 21 0 0
t$perf-norm:sp_cancel_supplier_invoice 385 385 0 0.00 0 2136 24 0 0
t$perf-norm:sp_cancel_supplier_order 424 424 0 0.00 0 31177 1748 0 0
t$perf-norm:sp_cancel_write_off 981 981 0 0.00 0 2935 18 0 0
t$perf-norm:sp_client_order 20077 20077 0 0.00 0 8967 21 0 0
t$perf-norm:sp_customer_reserve 945 945 0 0.00 0 3836 13 0 0
t$perf-norm:sp_pay_from_customer 7019 7019 0 0.00 0 7411 12 0 0
t$perf-norm:sp_pay_to_supplier 5815 5815 0 0.00 0 7299 16 0 0
t$perf-norm:sp_reserve_write_off 9929 9929 0 0.00 0 9359 18 0 0
t$perf-norm:sp_supplier_invoice 5354 5354 0 0.00 0 17012 30 0 0
t$perf-norm:sp_supplier_order 5416 5416 0 0.00 0 4222 20 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 212 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 956 17 0 0
t$perf-norm:srv_make_invnt_saldo 546 546 0 0.00 0 6392 30 0 0
t$perf-norm:srv_make_money_saldo 829 829 0 0.00 0 970 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10062 9822 240 2.38 0 5554 23 0 240
v_all_customers 36574 36574 0 0.00 0 21 1 0 0
v_all_suppliers 30076 30076 0 0.00 0 205 1 0 0
v_all_wares 40140 40140 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 786 786 0 0.00 0 558 7 0 0
v_cancel_client_order 3518 3518 0 0.00 0 8792 12 0 0
v_cancel_customer_prepayment 1396 1396 0 0.00 0 5276 13 0 0
v_cancel_customer_reserve 1366 1366 0 0.00 0 4346 8 0 0
v_cancel_payment_to_supplier 868 868 0 0.00 0 820 4 0 0
v_cancel_supplier_invoice 782 770 12 1.53 0 1521 16 0 12
v_cancel_supplier_order 848 848 0 0.00 0 2803 7 0 0
v_cancel_write_off 1962 1962 0 0.00 0 2237 15 0 0
v_max_id_clo_ord 5415 5415 0 0.00 0 380 1 0 0
v_max_id_clo_res 945 945 0 0.00 0 1856 12 0 0
v_max_id_ord_sup 5347 5347 0 0.00 0 897 2 0 0
v_max_non_paid_invoice 1539 1539 0 0.00 0 1559 5 0 0
v_max_non_paid_realizn 3744 3744 0 0.00 0 4194 5 0 0
v_min_id_clo_ord 5415 5415 0 0.00 0 9998 13 0 0
v_min_id_clo_res 945 945 0 0.00 0 4736 11 0 0
v_min_id_ord_sup 5347 5347 0 0.00 0 7213 8 0 0
v_min_non_paid_invoice 5815 5815 0 0.00 0 8524 18 0 0
v_min_non_paid_realizn 7019 7019 0 0.00 0 28510 23 0 0
v_our_firm 10116 10116 0 0.00 0 18 1 0 0
v_reserve_write_off 19856 19856 0 0.00 0 8792 12 0 0
x_lock_depdocs_on_canc_invoice 12390 12390 0 0.00 0 12452 117 0 0
x_lock_depdocs_on_canc_sup_ord 424 424 0 0.00 0 3384 323 0 0
x_make_qstorno_1000_1200 5416 5416 0 0.00 2 29953 1201 0 0
x_make_qstorno_1200_2000 5354 5354 0 0.00 2 107241 3389 0 0
x_make_qstorno_2100_3300 459851 459851 0 0.00 0 155464 171 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-01-2022 16:40:26 to 13-01-2022 16:40:30.

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:09 32 8192000000 8823393888 8842686464 24051136 0.292 6 6 7 0 24363520 108768 203616
14:09:10 23872 8192000000 9907185088 10218770432 983024192 10.714 100 85 170 0 1011313632 6180608 22108832
14:10:42 36193 8192000000 9961673552 10285772800 1037101248 11.237 100 91 182 0 1069295120 8792704 23401168
14:12:24 12961 8192000000 9997726160 10331324416 1071440768 11.566 100 85 168 1 1106504688 14542576 20521344
14:13:38 11785 8192000000 10047118848 10397540352 1132327440 12.143 100 84 168 1 1164035360 12077760 19630160
14:14:54 11027 8192000000 10089523552 10453364736 1176015104 12.553 100 81 162 0 1207668880 13096592 18557184
14:16:09 3480 8192000000 10129832640 10500952064 1214888352 12.914 100 82 164 0 1246188368 12978320 18321696
14:17:15 2076 8192000000 10168793104 10544037888 1254706464 13.281 100 86 172 1 1287531152 14244640 18580048
14:18:18 1343 8192000000 10206148688 10589593600 1289885664 13.603 100 93 186 0 1324018192 13260288 20872240
14:19:20 9930 8192000000 10194687856 10601426944 1300653136 13.701 100 36 72 0 1316514368 8061648 7799584
14:20:43 19084 8192000000 10198115920 10604077056 1305141568 13.742 100 18 36 0 1315044368 6018576 3884224
14:22:04 9703 8192000000 10200201360 10620280832 1317622256 13.855 100 21 43 0 1328098640 5780992 4695392
14:23:17 7331 8192000000 10212925360 10632245248 1328014032 13.949 100 23 46 0 1339029520 5694480 5321008
14:24:26 52956 8192000000 10241601504 10655997952 1335956320 14.021 100 26 52 1 1348291696 4994528 7340848
14:26:33 5918 8192000000 10231100736 10651705344 1336262192 14.024 100 29 56 0 1349343504 6588368 6492944
14:27:42 7641 8192000000 10225720016 10646966272 1333532336 13.999 100 23 46 2 1343990096 5155792 5301968
14:28:55 6024 8192000000 10179687328 10592395264 1279671104 13.510 99 12 24 1 1288220336 5541072 3008160
14:30:01 20468 8192000000 10146236816 10551701504 1184828016 12.635 92 23 46 0 1195803328 5920368 5054944
14:31:27 5053 8192000000 10139950368 10537017344 1239524608 13.142 99 33 65 1 1257007264 9461216 8021440
14:32:32 10145 8192000000 10144792256 10549760000 1255621296 13.290 100 23 45 1 1266810448 5563360 5625792
14:33:44 10723 8192000000 10174614800 10577350656 1268384544 13.407 99 26 52 2 1279654608 5406400 5863664
14:35:01 15789 8192000000 10173030832 10584690688 1283604096 13.546 100 33 66 0 1296520128 5005040 7910992
14:36:19 9496 8192000000 10188193984 10600890368 1297227792 13.670 100 25 50 1 1308980432 5948496 5804144
14:37:29 12835 8192000000 10205123920 10618417152 1310757792 13.793 100 26 51 0 1322620672 5533792 6329088
14:38:47 35426 8192000000 10248973680 10656751616 1337926736 14.039 100 44 85 1 1354742336 6808768 10006832
14:40:29 15808 8192000000 10270078368 10680913920 1357329312 14.213 100 49 98 0 1381952448 13261552 11361584
14:41:49 10187 8192000000 10278170880 10688008192 1349440624 14.142 99 70 136 3 1379145136 13229712 16474800
14:43:04 31700 8192000000 10304276752 10719059968 1383656064 14.449 100 59 118 1 1414290336 16857984 13776288
14:44:39 12418 8192000000 10315135536 10735161344 1396222224 14.561 100 49 95 0 1426481616 19168624 11090768
14:45:56 7550 8192000000 10322131984 10744508416 1400172416 14.597 100 51 102 0 1434824896 22135536 12516944
14:47:10 4471 8192000000 10327124224 10749308928 1407260432 14.660 100 60 119 0 1441405232 19934720 14210080
14:48:20 9136 8192000000 10330962880 10750222336 1393516816 14.537 99 59 118 2 1427708464 20505888 13685760
14:49:41 3616 8192000000 10336533424 10755444736 1391676880 14.521 99 64 128 0 1434219024 27273248 15268896
14:50:53 5617 8192000000 10333458832 10756096000 1400998288 14.604 100 49 98 3 1442894480 29680640 12215552
14:52:04 2033 8192000000 10362973104 10769743872 1407920784 14.665 100 85 170 1 1463582752 34980736 20681232
14:53:09 2901 8192000000 10344416032 10747404288 1380576496 14.422 99 83 164 0 1440661232 39367360 20717376
14:54:16 8304 8192000000 10335069776 10752516096 1381029920 14.426 99 53 106 0 1435512880 40173040 14309920
14:55:32 6996 8192000000 10337347520 10747117568 1379066880 14.408 100 73 145 1 1442833664 45144448 18622336
14:56:45 1898 8192000000 10357286064 10758414336 1384239888 14.454 99 88 176 1 1457406288 50423136 22743264
14:57:49 4716 8192000000 10358951520 10771349504 1394910928 14.550 100 69 138 0 1460583584 47545808 18126848
14:58:59 2579 8192000000 10388810784 10794999808 1404574832 14.636 100 87 172 2 1483829072 56297856 22956384
15:00:09 7218 8192000000 10378715520 10796601344 1402744992 14.619 99 68 136 1 1481986608 61915328 17326288
15:01:25 12618 8192000000 10354751520 10785341440 1404098608 14.631 100 41 82 1 1466066256 50618976 11348672
15:02:49 17836 8192000000 10351135376 10790223872 1382251280 14.437 99 32 64 0 1441401760 49766752 9383728
15:04:08 13586 8192000000 10343006736 10786779136 1396230160 14.561 100 27 54 2 1452956448 49305296 7420992
15:05:31 36354 8192000000 10326453792 10789744640 1381788592 14.433 100 16 31 1 1438792784 51392096 5612096
15:07:08 34848 8192000000 10283230944 10725134336 1350193536 14.149 98 15 30 1 1364575504 11074704 3307264
15:08:46 48754 8192000000 10288879920 10720681984 1347668928 14.126 97 37 72 1 1366780912 11928240 7183744
15:10:57 60491 8192000000 10298364464 10757853184 1366217712 14.293 99 7 14 0 1387594544 19693680 1683152
15:12:58 40973 8192000000 10316060608 10753896448 1384725952 14.459 100 47 93 1 1416392464 21273008 10393504
15:14:39 56912 8192000000 10299482736 10737491968 1357057792 14.211 98 41 82 1 1378688224 11407984 10222448
15:16:37 38666 8192000000 10275246496 10724155392 1330846816 13.975 96 31 62 3 1346067232 8176672 7043744
15:18:19 53935 8192000000 10310890624 10750029824 1386130992 14.471 100 19 36 0 1399458688 9348160 3979536
15:20:14 39893 8192000000 10320829744 10774220800 1398953216 14.586 100 20 40 0 1421839792 17899392 4987184
15:21:58 17077 8192000000 10352396304 10793521152 1421411808 14.785 100 32 64 2 1449628336 20068128 8148400
15:23:15 189490 8192000000 10310287312 10774032384 1363469616 14.268 96 20 40 1 1392327440 24399808 4458016
15:27:26 56666 8192000000 10300418928 10762211328 1398109264 14.578 100 13 26 0 1412353440 10993984 3250192
15:29:27 19953 8192000000 10296254816 10756337664 1393678480 14.539 100 9 18 0 1406438000 10848720 1910800
15:31:06 63143 8192000000 10302621760 10773901312 1409407088 14.679 100 12 24 1 1424976912 12445088 3124736
15:33:13 65713 8192000000 10298694272 10768699392 1372229392 14.347 98 12 24 1 1388944416 14158464 2556560
15:35:21 18451 8192000000 10307982976 10755993600 1392429312 14.528 100 39 78 0 1410624400 9860576 8334512
15:36:44 46566 8192000000 10277738688 10731065344 1332750320 13.992 96 15 30 0 1348340944 12312432 3278192
15:38:32 84780 8192000000 10311903232 10757500928 1352527728 14.170 97 19 38 0 1370980112 14067552 4384832
15:40:59 86331 8192000000 10279665840 10729750528 1342278992 14.078 97 16 32 0 1362108480 16052720 3776768
15:43:32 74802 8192000000 10287720864 10738380800 1308373120 13.771 94 12 24 0 1321158528 10340352 2445056
15:45:58 58374 8192000000 10302039200 10751864832 1377420752 14.393 99 18 37 2 1391260560 9762576 4077232
15:48:29 93541 8192000000 10261050240 10707976192 1320962128 13.885 96 32 64 0 1339380528 10610512 7807888
15:51:05 15885 8192000000 10284324320 10722258944 1344291312 14.096 98 46 92 0 1363281568 8905328 10084928
15:52:22 89344 8192000000 10264473968 10728652800 1331540192 13.981 97 26 52 0 1352002768 14508048 5954528
15:54:55 73396 8192000000 10270213584 10735841280 1360876032 14.245 99 10 20 1 1377523200 14080288 2566880
15:57:14 61731 8192000000 10271643472 10734170112 1339854752 14.056 97 20 39 2 1358351920 13863072 4634096
15:59:21 37986 8192000000 10255568832 10701783040 1325355920 13.925 97 12 24 0 1334928416 7027088 2545408
16:01:03 46349 8192000000 10282232784 10732371968 1375945648 14.380 100 34 68 0 1395345904 11485200 7915056
16:02:55 48078 8192000000 10285145232 10744307712 1365514272 14.287 99 13 26 1 1386223888 17660880 3048736
16:04:57 105480 8192000000 10291328336 10741469184 1350000336 14.147 98 17 34 0 1364635520 11224384 3410800
16:07:46 42767 8192000000 10301542384 10765647872 1379068464 14.408 100 8 16 0 1394411648 13682256 1660928
16:09:31 150636 8192000000 10308170736 10760478720 1365723248 14.289 97 18 32 4 1383643872 14591312 3329312
16:13:03 57036 8192000000 10285750256 10757111808 1388282592 14.491 100 16 30 1 1400211584 9037792 2891200
16:15:38 46869 8192000000 10306550512 10771804160 1399546848 14.591 100 13 26 4 1413101472 10709872 2844752
16:17:27 50922 8192000000 10307873168 10775773184 1391463024 14.519 99 20 41 1 1411728960 14966624 5299312
16:19:21 42673 8192000000 10323639552 10772242432 1379640368 14.413 98 30 60 2 1397563088 11201632 6721088
16:21:07 104377 8192000000 10290330704 10759524352 1351784496 14.164 97 5 10 0 1367724416 14630272 1309648
16:23:52 67591 8192000000 10268944064 10725961728 1334348560 14.006 97 21 42 1 1355300320 16503296 4448464
16:26:00 51736 8192000000 10287751488 10749272064 1360796640 14.245 99 17 34 1 1378817904 13939056 4082208
16:27:52 54154 8192000000 10300226112 10741489664 1350951936 14.156 98 28 56 0 1366506544 9194256 6360352
16:30:04 98753 8192000000 10269906464 10738909184 1362335984 14.258 100 22 44 3 1386761904 19013600 5412320
16:32:47 73968 8192000000 10260184656 10728431616 1357561040 14.215 99 13 26 1 1369836352 8206944 4068368
16:35:19 93051 8192000000 10239276848 10695962624 1279683600 13.510 94 18 36 1 1295019840 11020928 4315312

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 13-01-2022 16:40:32 to 13-01-2022 16:40:46.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 120 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 13-01-2022 16:40:47 to 13-01-2022 16:40:49.

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          236274
MON$OLDEST_ACTIVE               239342
MON$OLDEST_SNAPSHOT             239342
MON$NEXT_TRANSACTION            239342
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                       2052640
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33548 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33548 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33548 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33548 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 ---
:878: 
:879: mscs-test-161.budzdorov.ru    Thu Jan 13 16:38:27 2022
:880:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:881: 
--- end of diff output ---

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

13.01.22 16:40:50. 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-01-13 16:40:53 No severe PSQL-related problems occured
13.01.22 16:40:54. End of report.