Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.08.2021 16:38
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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 16118204 kB
MemAvailable 30967276 kB
Buffers 0 kB
Cached 15525060 kB
SwapCached 2580 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.8
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 2021-08-15 16:37:48
DTS_END                         2021-08-15 16:37:48.8590
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/59758
TRN_ID                          244168
ATT_ID                          665
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: 9682.76 19188 1161931
customer order: creation 179.58 396 21549
customer order: refuse 15.27 367 1832
order to supplier: creation 47.21 1275 5665
order to supplier: removal 3.54 103445 425
invoice (draft): creation 46.16 3573 5539
invoice (draft): removal 55.36 809 6643
invoice accept: apply 43.01 17922 5161
invoice accept: cancel 50.02 830 6002
customer reserve: creation 4054.00 173 486480
customer reserve: removal 4876.93 60 585232
realization accept: apply 86.78 187 10413
realization accept: cancel 88.66 106 10639
payment from customer: creation 62.08 97 7450
payment from customer: removal 6.07 75 728
payment to supplier: creation 51.58 109 6189
payment to supplier: removal 3.63 59 436
service: total inventory turnovers 4.90 1180 588
service: total monetary turnovers 6.98 25 837
service: aggregate perf. data 0.99 16724 119
service: refresh index statistics 0.03 236341 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-08-2021 16:38:58 to 15-08-2021 16:39:00.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2265
TEST_TIME 2 2996
TEST_TIME 3 3629
TEST_TIME 4 4145
TEST_TIME 5 5066
TEST_TIME 6 4914
TEST_TIME 7 4811
TEST_TIME 8 5176
TEST_TIME 9 5309
TEST_TIME 10 5551
TEST_TIME 11 5606
TEST_TIME 12 5878
TEST_TIME 13 6008
TEST_TIME 14 6248
TEST_TIME 15 6886
TEST_TIME 16 7399
TEST_TIME 17 7569
TEST_TIME 18 7603
TEST_TIME 19 8277
TEST_TIME 20 8792
TEST_TIME 21 9247
TEST_TIME 22 9299
TEST_TIME 23 9352
TEST_TIME 24 9447
TEST_TIME 25 9289
TEST_TIME 26 9413
TEST_TIME 27 9793
TEST_TIME 28 10831
TEST_TIME 29 11232
TEST_TIME 30 11098
TEST_TIME 31 11064
TEST_TIME 32 10933
TEST_TIME 33 10762
TEST_TIME 34 10849
TEST_TIME 35 10819
TEST_TIME 36 10787
TEST_TIME 37 10597
TEST_TIME 38 10406
TEST_TIME 39 10394
TEST_TIME 40 10364
TEST_TIME 41 10365
TEST_TIME 42 10209
TEST_TIME 43 10203
TEST_TIME 44 10131
TEST_TIME 45 10147
TEST_TIME 46 10119
TEST_TIME 47 10021
TEST_TIME 48 9891
TEST_TIME 49 9831
TEST_TIME 50 9863
TEST_TIME 51 9854
TEST_TIME 52 10142
TEST_TIME 53 10104
TEST_TIME 54 10007
TEST_TIME 55 10154
TEST_TIME 56 10187
TEST_TIME 57 10253
TEST_TIME 58 10313
TEST_TIME 59 10334
TEST_TIME 60 10329
TEST_TIME 61 10372
TEST_TIME 62 10297
TEST_TIME 63 10292
TEST_TIME 64 10173
TEST_TIME 65 10127
TEST_TIME 66 10037
TEST_TIME 67 9974
TEST_TIME 68 10042
TEST_TIME 69 10022
TEST_TIME 70 9940
TEST_TIME 71 9970
TEST_TIME 72 9941
TEST_TIME 73 9866
TEST_TIME 74 9905
TEST_TIME 75 9896
TEST_TIME 76 9857
TEST_TIME 77 9846
TEST_TIME 78 9877
TEST_TIME 79 9852
TEST_TIME 80 9891
TEST_TIME 81 9915
TEST_TIME 82 9992
TEST_TIME 83 9974
TEST_TIME 84 9934
TEST_TIME 85 10029
TEST_TIME 86 9971
TEST_TIME 87 9945
TEST_TIME 88 9895
TEST_TIME 89 9902
TEST_TIME 90 9861
TEST_TIME 91 9836
TEST_TIME 92 9863
TEST_TIME 93 9859
TEST_TIME 94 9815
TEST_TIME 95 9779
TEST_TIME 96 9797
TEST_TIME 97 9785
TEST_TIME 98 9826
TEST_TIME 99 9829
TEST_TIME 100 9839
TEST_TIME 101 9819
TEST_TIME 102 9840
TEST_TIME 103 9866
TEST_TIME 104 9864
TEST_TIME 105 9916
TEST_TIME 106 9902
TEST_TIME 107 9903
TEST_TIME 108 9934
TEST_TIME 109 9867
TEST_TIME 110 9827
TEST_TIME 111 9791
TEST_TIME 112 9758
TEST_TIME 113 9729
TEST_TIME 114 9733
TEST_TIME 115 9697
TEST_TIME 116 9663
TEST_TIME 117 9680
TEST_TIME 118 9668
TEST_TIME 119 9646
TEST_TIME 120 9688

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 15-08-2021 16:39:02 to 15-08-2021 16:39:05.

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 1611135 1611135 0 0.00 0 26844 6 0 0
doc_list_biud 1611135 1611135 0 0.00 0 30524 28 0 0
sp_add_invoice_to_stock 5289 5161 128 2.42 40 169179 17922 0 128
sp_cancel_adding_invoice 6002 6002 0 0.00 5 71658 830 0 0
sp_cancel_client_order 1832 1832 0 0.00 5 12734 367 0 0
sp_cancel_customer_reserve 585232 585232 0 0.00 1 30527 60 0 0
sp_cancel_pay_from_customer 728 728 0 0.00 2 5806 76 0 0
sp_cancel_pay_to_supplier 436 436 0 0.00 2 1306 60 0 0
sp_cancel_supplier_invoice 6651 6643 8 0.12 2 12696 809 0 8
sp_cancel_supplier_order 425 425 0 0.00 6 945616 103446 0 0
sp_cancel_write_off 10639 10639 0 0.00 1 11671 106 0 0
sp_client_order 21549 21549 0 0.00 4 28072 396 0 0
sp_customer_reserve 486480 486480 0 0.00 0 76887 174 0 0
sp_fill_shopping_cart 32730 32730 0 0.00 1 9714 35 0 0
sp_fill_shopping_cart_clo_res 486465 486465 0 0.00 0 13149 13 0 0
sp_get_clo_for_invoice 5146 5146 0 0.00 0 21483 272 0 0
sp_kill_cost_storno 17805 17805 0 0.00 0 6561 53 0 0
sp_kill_qstorno_ret_qs2qd 1188171 1188171 0 0.00 0 30378 33 0 0
sp_kill_qty_storno 626332 626332 0 0.00 0 30524 70 0 0
sp_lock_selected_doc 21620 21620 0 0.00 0 9417 6 0 0
sp_make_cost_storno 29198 29198 0 0.00 0 14030 50 0 0
sp_multiply_rows_for_pdistr 29198 29198 0 0.00 0 6419 24 0 0
sp_multiply_rows_for_qdistr 21549 21549 0 0.00 0 15530 187 0 0
sp_pay_from_customer 7450 7450 0 0.00 4 13053 98 0 0
sp_pay_to_supplier 6189 6189 0 0.00 5 14043 110 0 0
sp_payment_common 13639 13639 0 0.00 1 13047 24 0 0
sp_qd_handle_on_cancel_clo 1832 1832 0 0.00 0 12727 336 0 0
sp_qd_handle_on_invoice_upd_sts 11148 11148 0 0.00 0 12356 308 0 0
sp_qd_handle_on_reserve_upd_sts 10413 10413 0 0.00 0 23302 73 0 0
sp_reserve_write_off 10413 10413 0 0.00 6 23412 188 0 0
sp_supplier_invoice 5539 5539 0 0.00 6 92377 3573 0 0
sp_supplier_order 5665 5665 0 0.00 4 51368 1276 0 0
srv_aggregate_perf_data 119 119 0 0.00 2209 142452 16724 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1697 153018 47461 0 0
srv_find_qd_qs_mism 1111344 1111344 0 0.00 0 26843 6 0 0
srv_make_invnt_saldo 588 588 0 0.00 45 65567 1181 0 0
srv_make_money_saldo 837 837 0 0.00 1 2565 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 145256 307605 236341 0 0
t$perf-abend:sp_add_invoice_to_stock 128 128 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5161 5161 0 0.00 0 16144 185 0 0
t$perf-norm:sp_cancel_adding_invoice 422 422 0 0.00 0 5102 137 0 0
t$perf-norm:sp_cancel_client_order 1832 1832 0 0.00 0 4416 13 0 0
t$perf-norm:sp_cancel_customer_reserve 718 718 0 0.00 0 5760 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 728 728 0 0.00 0 2815 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 436 436 0 0.00 0 1697 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 440 440 0 0.00 0 878 12 0 0
t$perf-norm:sp_cancel_supplier_order 425 425 0 0.00 0 26299 1841 0 0
t$perf-norm:sp_cancel_write_off 1062 1062 0 0.00 0 2282 11 0 0
t$perf-norm:sp_client_order 21549 21549 0 0.00 0 12817 20 0 0
t$perf-norm:sp_customer_reserve 1016 1016 0 0.00 0 1772 9 0 0
t$perf-norm:sp_pay_from_customer 7450 7450 0 0.00 0 4723 9 0 0
t$perf-norm:sp_pay_to_supplier 6189 6189 0 0.00 0 8171 13 0 0
t$perf-norm:sp_reserve_write_off 10413 10413 0 0.00 0 24244 17 0 0
t$perf-norm:sp_supplier_invoice 5539 5539 0 0.00 0 9929 23 0 0
t$perf-norm:sp_supplier_order 5665 5665 0 0.00 0 20768 21 0 0
t$perf-norm:srv_aggregate_perf_data 119 119 0 0.00 0 558 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 124 3 0 0
t$perf-norm:srv_make_invnt_saldo 588 588 0 0.00 0 6549 29 0 0
t$perf-norm:srv_make_money_saldo 837 837 0 0.00 0 7505 28 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10544 10288 256 2.42 0 5061 19 0 256
v_all_customers 39432 39432 0 0.00 0 18 1 0 0
v_all_suppliers 31688 31688 0 0.00 0 54 1 0 0
v_all_wares 43098 43098 0 0.00 0 94 1 0 0
v_cancel_adding_invoice 844 844 0 0.00 0 3934 14 0 0
v_cancel_client_order 3664 3664 0 0.00 0 1983 4 0 0
v_cancel_customer_prepayment 1456 1456 0 0.00 0 1444 6 0 0
v_cancel_customer_reserve 1436 1436 0 0.00 0 2265 6 0 0
v_cancel_payment_to_supplier 872 872 0 0.00 0 1213 6 0 0
v_cancel_supplier_invoice 896 880 16 1.78 0 299 14 0 16
v_cancel_supplier_order 850 850 0 0.00 0 2080 10 0 0
v_cancel_write_off 2124 2124 0 0.00 0 5845 16 0 0
v_max_id_clo_ord 5665 5665 0 0.00 0 640 1 0 0
v_max_id_clo_res 1016 1016 0 0.00 0 28994 48 0 0
v_max_id_ord_sup 5516 5516 0 0.00 0 8645 7 0 0
v_max_non_paid_invoice 1526 1526 0 0.00 0 1509 4 0 0
v_max_non_paid_realizn 3915 3915 0 0.00 0 4137 5 0 0
v_min_id_clo_ord 5665 5665 0 0.00 0 3392 6 0 0
v_min_id_clo_res 1016 1016 0 0.00 0 13167 31 0 0
v_min_id_ord_sup 5516 5516 0 0.00 0 4419 6 0 0
v_min_non_paid_invoice 6189 6189 0 0.00 0 5662 12 0 0
v_min_non_paid_realizn 7450 7450 0 0.00 0 13042 16 0 0
v_our_firm 10736 10736 0 0.00 0 14 1 0 0
v_reserve_write_off 20826 20826 0 0.00 0 5642 7 0 0
x_lock_depdocs_on_canc_invoice 12205 12205 0 0.00 0 16124 120 0 0
x_lock_depdocs_on_canc_sup_ord 425 425 0 0.00 0 13259 358 0 0
x_make_qstorno_1000_1200 5665 5665 0 0.00 1 51349 1196 0 0
x_make_qstorno_1200_2000 5539 5539 0 0.00 3 88471 3443 0 0
x_make_qstorno_2100_3300 486480 486480 0 0.00 0 76885 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-08-2021 16:39:06 to 15-08-2021 16:39:10.

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:07:52 1 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
14:08:53 18612 8192000000 10342963056 10653790208 1400265408 14.597 100 86 172 0 1435004032 5662640 29075984
14:10:14 14671 8192000000 10417258896 10742173696 1468467632 15.200 100 87 174 0 1504249536 7557088 28224816
14:11:33 12767 8192000000 10452854384 10790612992 1515423040 15.610 100 88 176 0 1552529264 9066224 28040000
14:12:59 16061 8192000000 10536671488 10891747328 1593058128 16.280 100 77 152 0 1626804752 9505872 24240752
14:14:19 8472 8192000000 10632785376 11001118720 1695702000 17.149 100 81 162 0 1730997120 11448192 23846928
14:15:29 4549 8192000000 10695131136 11068325888 1751626336 17.615 100 79 158 1 1787322512 13037840 22658336
14:16:36 3629 8192000000 10733868112 11115020288 1798565584 18.002 100 82 160 2 1835111696 14092784 22453328
14:17:42 2440 8192000000 10787862368 11167932416 1835987536 18.308 100 85 172 1 1873783616 13390752 24405328
14:18:46 5863 8192000000 10794682640 11186126848 1859088272 18.496 100 72 143 1 1889745296 10091344 20565680
14:19:52 6466 8192000000 10774971328 11195408384 1862883376 18.527 100 30 57 0 1878142720 7768160 7491184
14:21:03 5304 8192000000 10790298272 11209281536 1872832336 18.607 100 29 56 0 1886623072 6428544 7362192
14:22:08 9820 8192000000 10791948480 11222896640 1887847872 18.728 100 20 39 0 1898660688 4865072 5947744
14:23:22 50391 8192000000 10827237120 11252527104 1901863840 18.841 100 26 52 0 1915226336 5855136 7507360
14:25:13 9778 8192000000 10823926256 11255791616 1907467008 18.886 100 19 37 2 1918503856 5601632 5435216
14:26:23 7204 8192000000 10812305872 11249512448 1904821024 18.865 100 14 28 0 1914160992 5386528 3953440
14:27:30 75434 8192000000 10761200752 11174322176 1727343184 17.413 91 31 61 1 1741215008 4543424 9328400
14:29:48 10190 8192000000 10730285008 11147665408 1748356912 17.588 95 27 54 0 1765458768 9362448 7739408
14:31:01 9623 8192000000 10681102016 11099480064 1731970288 17.452 97 29 58 0 1745991888 5931024 8090576
14:32:16 67676 8192000000 10717948928 11134349312 1777430416 17.828 98 27 51 1 1792035968 5124560 9480992
14:34:27 7329 8192000000 10768059168 11191803904 1852435968 18.442 100 26 52 2 1865700000 5319536 7944496
14:35:34 7009 8192000000 10781845296 11213004800 1870400016 18.588 100 28 57 0 1885216256 6225488 8590752
14:36:42 14012 8192000000 10822517440 11234189312 1881178864 18.675 100 21 42 0 1894254832 5528000 7547968
14:37:59 19104 8192000000 10830200864 11252703232 1907749264 18.889 100 46 89 0 1926523344 6069296 12704784
14:39:24 12646 8192000000 10857760320 11283865600 1929056224 19.059 100 49 96 3 1952765312 10146032 13563056
14:40:42 23148 8192000000 10875191472 11301617664 1942249472 19.165 100 44 88 0 1964664032 9984560 12430000
14:42:11 21435 8192000000 10900757888 11326312448 1964475328 19.342 100 56 109 1 1996748208 16426864 15846016
14:43:39 7307 8192000000 10925576320 11350835200 1981648032 19.478 100 63 124 0 2014106000 14534848 17923120
14:44:51 15624 8192000000 10936831344 11369775104 1993688832 19.573 100 40 81 0 2024637360 18314272 12634256
14:46:11 5244 8192000000 10963539952 11392483328 2011627984 19.714 100 51 100 0 2048114432 21383952 15102496
14:47:21 10122 8192000000 10972463280 11403427840 2019594944 19.777 100 64 128 0 2065027520 26299840 19132736
14:48:38 6234 8192000000 10967919024 11398070272 1992622656 19.565 99 55 110 1 2036438320 26015360 17800304
14:49:49 3845 8192000000 10987017184 11412176896 2023869312 19.811 100 69 134 3 2072665200 27938960 20856928
14:50:54 3465 8192000000 10995545344 11420622848 2025331040 19.822 100 76 149 3 2081390800 33040688 23019072
14:52:07 3062 8192000000 10991989456 11408355328 2006289104 19.672 100 81 160 2 2074513232 42631536 25592592
14:53:15 1696 8192000000 10967618240 11394703360 1993374128 19.570 100 77 151 4 2057488912 40565440 23549344
14:54:20 4551 8192000000 10940678816 11367145472 1949799168 19.225 99 58 116 0 2009283360 41245648 18238544
14:55:37 5064 8192000000 10960419568 11378270208 1963201248 19.331 100 80 160 0 2037491568 49098192 25192128
14:56:46 9730 8192000000 10929196320 11366739968 1945106560 19.187 100 45 89 1 2014045168 53975424 14963184
14:57:58 5119 8192000000 10931264192 11366359040 1952765920 19.248 100 51 100 2 2013262416 43358128 17138368
14:59:06 14784 8192000000 10924789424 11378290688 1959688480 19.304 100 25 50 0 2010659344 42513008 8457856
15:00:26 15425 8192000000 10933900576 11383259136 1967329600 19.364 100 28 57 0 2015988432 39434576 9224256
15:01:43 26705 8192000000 10935868864 11391143936 1959092832 19.299 99 22 44 0 2010778768 44271488 7414448
15:03:12 12808 8192000000 10937301312 11380068352 1955600672 19.271 99 38 75 1 2019258432 50931920 12725840
15:04:47 18447 8192000000 10910760032 11361873920 1905432608 18.870 98 24 48 3 1967048464 52021136 9594720
15:06:07 41022 8192000000 10867110496 11295703040 1873755856 18.615 97 19 39 2 1902089920 22638320 5695744
15:07:48 112094 8192000000 10886443584 11341516800 1881260672 18.675 96 12 24 0 1897275840 13294720 2720448
15:10:42 70020 8192000000 10887086928 11354128384 1944324656 19.181 99 18 37 1 1957915152 8679728 4910768
15:12:52 49354 8192000000 10926704896 11365007360 1959118160 19.299 99 16 32 2 1971568624 8222944 4227520
15:14:52 63434 8192000000 10942207392 11383504896 1983986512 19.496 100 52 101 0 2007469664 8331552 15151600
15:16:56 36280 8192000000 10931230288 11386310656 1991502560 19.556 100 30 60 2 2011120048 11120048 8497440
15:18:33 77967 8192000000 10940937808 11402223616 1995188768 19.585 100 21 42 3 2014304528 12949888 6165872
15:20:54 27410 8192000000 10934717408 11398131712 2003202528 19.648 100 21 41 2 2020239232 10637376 6399328
15:22:22 43210 8192000000 10937059936 11404488704 1976179312 19.434 98 37 74 0 2001425408 14202000 11044096
15:24:06 73991 8192000000 10953396176 11425792000 1999442112 19.618 99 11 22 0 2019675120 16358032 3874976
15:26:20 43304 8192000000 10950447536 11427344384 1996620288 19.596 99 13 26 0 2022695792 21668640 4406864
15:28:21 48873 8192000000 10953299136 11436339200 1985577392 19.509 99 18 36 1 2014368176 23749360 5041424
15:30:13 70309 8192000000 10949185312 11412738048 1966260576 19.356 98 25 50 3 1986929344 13407920 7260848
15:32:24 56383 8192000000 10936876208 11410432000 1960385696 19.309 98 13 27 0 1974031136 10008736 3636704
15:34:30 34908 8192000000 10914252352 11388682240 1952690240 19.248 98 43 86 2 1979439680 12838176 13911264
15:36:10 62029 8192000000 10946275792 11409793024 1963186512 19.331 98 18 36 0 1985862640 17421744 5254384
15:38:23 61977 8192000000 10865850624 11317669888 1750335712 17.604 89 18 36 2 1764884144 10020096 4528336
15:40:26 111108 8192000000 10888834896 11347730432 1904030336 18.859 97 34 68 1 1925645568 11690128 9925104
15:43:18 37748 8192000000 10881916688 11343859712 1924925920 19.026 98 30 60 1 1947944240 14624464 8393856
15:44:59 70968 8192000000 10833064464 11321233408 1865292224 18.546 96 11 22 0 1883739616 14993152 3454240
15:47:11 43658 8192000000 10867648176 11327356928 1906387312 18.878 99 24 48 2 1927365552 14142192 6836048
15:48:55 77865 8192000000 10867419568 11355148288 1932648944 19.088 99 9 18 0 1947726864 12465136 2612784
15:51:40 99362 8192000000 10855536224 11336876032 1830704416 18.265 94 5 10 0 1843846784 11498832 1643536
15:54:20 86418 8192000000 10862499376 11350556672 1903408560 18.854 98 10 19 2 1923496896 17094448 2993888
15:56:47 38433 8192000000 10909250128 11380985856 1971485968 19.397 100 31 62 0 1993201568 12887840 8827760
15:58:34 153017 8192000000 10895685136 11361488896 1873760048 18.615 95 3 6 0 1885909456 11545296 604112
16:02:13 56350 8192000000 10898639360 11377176576 1935337584 19.110 98 11 22 2 1950386240 11811264 3237392
16:04:11 117390 8192000000 10918386640 11402006528 1967464272 19.365 99 17 34 0 1988578288 16294176 4819840
16:07:09 57652 8192000000 10903449584 11386728448 1959939920 19.306 98 34 68 1 1983156112 13657312 9558880
16:09:07 68292 8192000000 10919794928 11415744512 1992700720 19.565 100 9 18 0 2010999296 15797872 2500704
16:11:58 28362 8192000000 10925708800 11390025728 1965271344 19.348 98 38 73 2 1983907120 7850048 10785728
16:13:30 56762 8192000000 10957414624 11425222656 2011106512 19.710 100 15 30 1 2029655968 13753168 4796288
16:15:31 88291 8192000000 10922530368 11410296832 1964976240 19.346 98 19 38 1 1983619040 12624784 6018016
16:18:01 39313 8192000000 10929343392 11405344768 1981206560 19.474 99 25 50 1 2000137552 11529296 7401696
16:20:09 64431 8192000000 10929280800 11405352960 1961768256 19.320 98 23 46 0 1979230128 11537776 5924096
16:22:16 78660 8192000000 10913016240 11388071936 1873723968 18.614 94 14 29 1 1888373088 11104592 3544528
16:24:38 102438 8192000000 10832186816 11314479104 1754452560 17.638 90 19 38 0 1774126688 13574256 6099872
16:27:21 103491 8192000000 10825167376 11288010752 1766328736 17.737 92 25 49 1 1780433968 6799280 7305952
16:30:09 81893 8192000000 10785786464 11263234048 1770747920 17.773 92 14 28 0 1782701616 7946704 4006992
16:32:35 50368 8192000000 10821074976 11304079360 1884678160 18.703 99 12 24 1 1895871536 7679344 3514032
16:34:29 56628 8192000000 10838728416 11331055616 1907968288 18.890 100 15 30 1 1923391568 10884512 4538768
16:36:26 50003 8192000000 10863367136 11329441792 1874210560 18.618 97 18 36 2 1891086096 11521392 5354144

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 15-08-2021 16:39:12 to 15-08-2021 16:39:27.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 128 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 15-08-2021 16:39:28 to 15-08-2021 16:39:29.

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          242631
MON$OLDEST_ACTIVE               244303
MON$OLDEST_SNAPSHOT             244303
MON$NEXT_TRANSACTION            244303
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                       2043424
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33488 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33488 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33488 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33488 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'

15.08.21 16:39:30. 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
2021-08-15 16:39:33 No severe PSQL-related problems occured
15.08.21 16:39:34. End of report.