Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.10.2020 22:27
Common Performance Final Results
  1. Hardware and OS info
  2. DB and 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, full
  3. Ratio "Versions / Records" for tables
  4. Database Validation Results
  5. New in firebird.log while test was run
  6. 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 e0e7b46250ba4f9795ceb55e0face73f
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 2020-09-25 13:42
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.00
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 17576176 kB
MemAvailable 31552628 kB
Buffers 0 kB
Cached 14086184 kB
SwapCached 5432 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.7
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 2020-10-17 22:26:57
DTS_END                         2020-10-17 22:26:57.6180
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(56:17)
IP                              ::1/42232
TRN_ID                          248292
ATT_ID                          714
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: 9447.69 18258 1133723
customer order: creation 179.03 375 21483
customer order: refuse 15.51 285 1861
order to supplier: creation 47.31 1205 5677
order to supplier: removal 3.49 71538 419
invoice (draft): creation 47.33 2986 5679
invoice (draft): removal 55.17 601 6620
invoice accept: apply 42.35 15670 5082
invoice accept: cancel 49.69 617 5963
customer reserve: creation 3864.35 158 463722
customer reserve: removal 4834.19 39 580103
realization accept: apply 84.78 161 10173
realization accept: cancel 90.75 97 10890
payment from customer: creation 61.59 118 7391
payment from customer: removal 6.06 50 727
payment to supplier: creation 50.40 129 6048
payment to supplier: removal 3.43 92 412
service: total inventory turnovers 5.03 1012 604
service: total monetary turnovers 6.42 21 770
service: aggregate perf. data 0.79 24847 95
service: refresh index statistics 0.03 245164 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-10-2020 22:28:11 to 17-10-2020 22:28:13.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3203
TEST_TIME 2 3656
TEST_TIME 3 4801
TEST_TIME 4 4021
TEST_TIME 5 4953
TEST_TIME 6 5314
TEST_TIME 7 5995
TEST_TIME 8 6012
TEST_TIME 9 6285
TEST_TIME 10 6748
TEST_TIME 11 7301
TEST_TIME 12 7432
TEST_TIME 13 7731
TEST_TIME 14 8342
TEST_TIME 15 8559
TEST_TIME 16 8935
TEST_TIME 17 9086
TEST_TIME 18 9334
TEST_TIME 19 10081
TEST_TIME 20 10724
TEST_TIME 21 10660
TEST_TIME 22 11392
TEST_TIME 23 11070
TEST_TIME 24 10748
TEST_TIME 25 10465
TEST_TIME 26 10113
TEST_TIME 27 10384
TEST_TIME 28 10252
TEST_TIME 29 10149
TEST_TIME 30 9970
TEST_TIME 31 9902
TEST_TIME 32 9706
TEST_TIME 33 9626
TEST_TIME 34 9524
TEST_TIME 35 9390
TEST_TIME 36 9385
TEST_TIME 37 9501
TEST_TIME 38 9530
TEST_TIME 39 9528
TEST_TIME 40 9708
TEST_TIME 41 9567
TEST_TIME 42 9530
TEST_TIME 43 9663
TEST_TIME 44 9713
TEST_TIME 45 9739
TEST_TIME 46 9768
TEST_TIME 47 9768
TEST_TIME 48 9789
TEST_TIME 49 9739
TEST_TIME 50 9599
TEST_TIME 51 9629
TEST_TIME 52 9586
TEST_TIME 53 9557
TEST_TIME 54 9523
TEST_TIME 55 9394
TEST_TIME 56 9483
TEST_TIME 57 9586
TEST_TIME 58 9508
TEST_TIME 59 9538
TEST_TIME 60 9496
TEST_TIME 61 9537
TEST_TIME 62 9614
TEST_TIME 63 9614
TEST_TIME 64 9680
TEST_TIME 65 9699
TEST_TIME 66 9785
TEST_TIME 67 9736
TEST_TIME 68 9855
TEST_TIME 69 9882
TEST_TIME 70 9871
TEST_TIME 71 9734
TEST_TIME 72 9679
TEST_TIME 73 9656
TEST_TIME 74 9699
TEST_TIME 75 9638
TEST_TIME 76 9574
TEST_TIME 77 9574
TEST_TIME 78 9541
TEST_TIME 79 9504
TEST_TIME 80 9510
TEST_TIME 81 9548
TEST_TIME 82 9541
TEST_TIME 83 9540
TEST_TIME 84 9629
TEST_TIME 85 9667
TEST_TIME 86 9646
TEST_TIME 87 9644
TEST_TIME 88 9777
TEST_TIME 89 9753
TEST_TIME 90 9803
TEST_TIME 91 9698
TEST_TIME 92 9670
TEST_TIME 93 9647
TEST_TIME 94 9637
TEST_TIME 95 9601
TEST_TIME 96 9591
TEST_TIME 97 9541
TEST_TIME 98 9648
TEST_TIME 99 9624
TEST_TIME 100 9582
TEST_TIME 101 9654
TEST_TIME 102 9632
TEST_TIME 103 9705
TEST_TIME 104 9688
TEST_TIME 105 9728
TEST_TIME 106 9736
TEST_TIME 107 9725
TEST_TIME 108 9689
TEST_TIME 109 9645
TEST_TIME 110 9667
TEST_TIME 111 9654
TEST_TIME 112 9596
TEST_TIME 113 9547
TEST_TIME 114 9533
TEST_TIME 115 9483
TEST_TIME 116 9484
TEST_TIME 117 9478
TEST_TIME 118 9477
TEST_TIME 119 9467
TEST_TIME 120 9448

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 17-10-2020 22:28:14 to 17-10-2020 22:28: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 1565106 1565106 0 0.00 0 25109 5 0 0
doc_list_biud 1565105 1565105 0 0.00 0 50506 19 0 0
sp_add_invoice_to_stock 5195 5082 113 2.17 22 174431 15671 0 113
sp_cancel_adding_invoice 5963 5963 0 0.00 5 76083 617 0 0
sp_cancel_client_order 1861 1861 0 0.00 4 8434 286 0 0
sp_cancel_customer_reserve 580103 580103 0 0.00 1 50507 40 0 0
sp_cancel_pay_from_customer 727 727 0 0.00 3 4433 51 0 0
sp_cancel_pay_to_supplier 412 412 0 0.00 3 6846 93 0 0
sp_cancel_supplier_invoice 6634 6620 14 0.21 2 13542 602 0 14
sp_cancel_supplier_order 419 419 0 0.00 7 745374 71538 0 0
sp_cancel_write_off 10890 10890 0 0.00 1 12397 97 0 0
sp_client_order 21483 21483 0 0.00 4 19702 375 0 0
sp_customer_reserve 463722 463722 0 0.00 0 52582 158 0 0
sp_fill_shopping_cart 32837 32837 0 0.00 1 16287 36 0 0
sp_fill_shopping_cart_clo_res 463720 463720 0 0.00 0 18904 14 0 0
sp_get_clo_for_invoice 5080 5080 0 0.00 0 26572 300 0 0
sp_kill_cost_storno 17992 17992 0 0.00 0 25090 40 0 0
sp_kill_qstorno_ret_qs2qd 1178135 1178135 0 0.00 0 50503 22 0 0
sp_kill_qty_storno 621109 621109 0 0.00 0 50506 48 0 0
sp_lock_selected_doc 21316 21316 0 0.00 0 7764 6 0 0
sp_make_cost_storno 28692 28692 0 0.00 0 11381 46 0 0
sp_multiply_rows_for_pdistr 28692 28692 0 0.00 0 8663 27 0 0
sp_multiply_rows_for_qdistr 21483 21483 0 0.00 0 13726 193 0 0
sp_pay_from_customer 7391 7391 0 0.00 4 13352 119 0 0
sp_pay_to_supplier 6048 6048 0 0.00 5 15370 129 0 0
sp_payment_common 13439 13439 0 0.00 1 12121 27 0 0
sp_qd_handle_on_cancel_clo 1861 1861 0 0.00 0 8425 254 0 0
sp_qd_handle_on_invoice_upd_sts 11043 11043 0 0.00 0 32091 258 0 0
sp_qd_handle_on_reserve_upd_sts 10173 10173 0 0.00 0 16261 53 0 0
sp_reserve_write_off 10173 10173 0 0.00 6 18779 162 0 0
sp_supplier_invoice 5679 5679 0 0.00 7 67203 2986 0 0
sp_supplier_order 5677 5677 0 0.00 4 23717 1205 0 0
srv_aggregate_perf_data 95 95 0 0.00 2873 497616 24848 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 1526 152831 51504 0 0
srv_find_qd_qs_mism 1085820 1085820 0 0.00 0 11266 5 0 0
srv_make_invnt_saldo 604 604 0 0.00 27 106946 1012 0 0
srv_make_money_saldo 770 770 0 0.00 0 1997 22 0 0
srv_recalc_idx_stat 4 4 0 0.00 183598 338032 245165 0 0
t$perf-abend:sp_add_invoice_to_stock 113 113 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5082 5082 0 0.00 0 22811 164 0 0
t$perf-norm:sp_cancel_adding_invoice 452 452 0 0.00 0 5718 76 0 0
t$perf-norm:sp_cancel_client_order 1861 1861 0 0.00 0 2049 10 0 0
t$perf-norm:sp_cancel_customer_reserve 700 700 0 0.00 0 2547 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 727 727 0 0.00 0 650 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 412 412 0 0.00 0 1226 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 404 404 0 0.00 0 868 11 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 28306 1558 0 0
t$perf-norm:sp_cancel_write_off 1088 1088 0 0.00 0 5159 11 0 0
t$perf-norm:sp_client_order 21483 21483 0 0.00 0 9390 14 0 0
t$perf-norm:sp_customer_reserve 1035 1035 0 0.00 0 1871 5 0 0
t$perf-norm:sp_pay_from_customer 7391 7391 0 0.00 0 8013 16 0 0
t$perf-norm:sp_pay_to_supplier 6048 6048 0 0.00 0 10647 13 0 0
t$perf-norm:sp_reserve_write_off 10173 10173 0 0.00 0 9708 11 0 0
t$perf-norm:sp_supplier_invoice 5679 5679 0 0.00 0 5616 13 0 0
t$perf-norm:sp_supplier_order 5677 5677 0 0.00 0 6598 13 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 342 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 10 1 0 0
t$perf-norm:srv_make_invnt_saldo 604 604 0 0.00 0 10411 38 0 0
t$perf-norm:srv_make_money_saldo 770 770 0 0.00 0 3903 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 3 0 0
v_add_invoice_to_stock 10384 10158 226 2.17 0 5818 22 0 226
v_all_customers 39188 39188 0 0.00 0 16 0 0 0
v_all_suppliers 31550 31550 0 0.00 0 72 1 0 0
v_all_wares 42966 42966 0 0.00 0 68 1 0 0
v_cancel_adding_invoice 904 904 0 0.00 0 3024 11 0 0
v_cancel_client_order 3722 3722 0 0.00 0 4238 10 0 0
v_cancel_customer_prepayment 1454 1454 0 0.00 0 1085 5 0 0
v_cancel_customer_reserve 1400 1400 0 0.00 0 3650 11 0 0
v_cancel_payment_to_supplier 824 824 0 0.00 0 3827 10 0 0
v_cancel_supplier_invoice 836 808 28 3.34 0 1047 16 0 28
v_cancel_supplier_order 838 838 0 0.00 0 4791 8 0 0
v_cancel_write_off 2176 2176 0 0.00 0 5254 24 0 0
v_max_id_clo_ord 5677 5677 0 0.00 0 1136 1 0 0
v_max_id_clo_res 1035 1035 0 0.00 0 3086 14 0 0
v_max_id_ord_sup 5677 5677 0 0.00 0 4703 3 0 0
v_max_non_paid_invoice 1627 1627 0 0.00 0 2880 6 0 0
v_max_non_paid_realizn 3871 3871 0 0.00 0 5701 6 0 0
v_min_id_clo_ord 5677 5677 0 0.00 0 5034 9 0 0
v_min_id_clo_res 1035 1035 0 0.00 0 965 3 0 0
v_min_id_ord_sup 5677 5677 0 0.00 0 4433 4 0 0
v_min_non_paid_invoice 6048 6048 0 0.00 0 8559 16 0 0
v_min_non_paid_realizn 7391 7391 0 0.00 0 6463 14 0 0
v_our_firm 10818 10818 0 0.00 0 11 1 0 0
v_reserve_write_off 20346 20346 0 0.00 0 8094 9 0 0
x_lock_depdocs_on_canc_invoice 12179 12179 0 0.00 0 9675 88 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 12437 249 0 0
x_make_qstorno_1000_1200 5677 5677 0 0.00 1 22785 1135 0 0
x_make_qstorno_1200_2000 5679 5679 0 0.00 4 67194 2859 0 0
x_make_qstorno_2100_3300 463722 463722 0 0.00 0 52287 142 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-10-2020 22:28:19 to 17-10-2020 22:28: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
19:57:01 2 8192000000 8798422528 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
19:58:02 27175 8192000000 10375672448 10702028800 1428612192 14.849 100 83 166 1 1460720240 5370896 26737152
19:59:42 18175 8192000000 10452104128 10787549184 1510487424 15.568 100 89 178 1 1547488800 8043200 28958176
20:01:05 19900 8192000000 10515501792 10865152000 1567716208 16.063 100 82 165 0 1602593280 9268992 25608080
20:02:29 12935 8192000000 10574067440 10937999360 1639514480 16.676 100 77 154 1 1673478064 10903552 23060032
20:03:44 5937 8192000000 10664439776 11040415744 1727454272 17.414 100 74 146 1 1760216640 11754048 21008320
20:04:53 3030 8192000000 10733417152 11114053632 1793010784 17.957 100 84 168 0 1829844656 13515904 23317968
20:05:58 2660 8192000000 10771383120 11157168128 1831887680 18.275 100 93 187 0 1872887808 14718688 26281440
20:07:02 1748 8192000000 10822000960 11210067968 1874216320 18.618 100 91 183 2 1912925712 12885536 25823856
20:08:04 9876 8192000000 10778583968 11196813312 1863207264 18.529 100 22 43 2 1875600592 6438352 5954976
20:09:14 8536 8192000000 10781438944 11211120640 1873824512 18.615 100 23 47 0 1886606480 5781072 7000896
20:10:23 7449 8192000000 10803425008 11231498240 1889440736 18.741 100 31 61 1 1905772640 7758384 8573520
20:11:31 63342 8192000000 10842325248 11248287744 1900855456 18.833 100 50 101 3 1920895120 5152320 14887344
20:13:34 10288 8192000000 10815081648 11257323520 1911034976 18.915 100 24 49 2 1923679408 5840224 6804208
20:14:45 15669 8192000000 10815860432 11253370880 1888423376 18.733 99 30 61 1 1907584864 10960176 8201312
20:16:19 6657 8192000000 10804417648 11227123712 1869907712 18.584 99 32 64 3 1885979712 7190848 8881152
20:17:27 9023 8192000000 10699342592 11123712000 1713429568 17.297 93 30 60 2 1727240080 4843360 8967152
20:18:36 10599 8192000000 10673237264 11096788992 1708929952 17.260 96 23 46 1 1721362640 5782272 6650416
20:19:52 14201 8192000000 10710828672 11136606208 1789001360 17.924 99 23 44 3 1800989360 4793664 7194336
20:21:07 6032 8192000000 10739182528 11162828800 1826917104 18.234 100 26 52 1 1840676320 6026288 7732928
20:22:13 7580 8192000000 10758387248 11180974080 1841235664 18.351 100 21 41 2 1852969280 5543952 6189664
20:23:23 10581 8192000000 10767311104 11200831488 1857338496 18.482 100 13 26 1 1866138480 5067824 3732160
20:24:33 10970 8192000000 10786922032 11214671872 1868252752 18.570 100 46 92 3 1888581552 6967936 13360864
20:25:50 4428 8192000000 10788281472 11219935232 1874461744 18.620 100 30 59 3 1889858864 5532624 9864496
20:26:55 18644 8192000000 10825369680 11248787456 1896091392 18.795 100 34 66 3 1910920752 5242032 9587328
20:28:16 44902 8192000000 10853901984 11275702272 1915854864 18.954 99 47 94 2 1939602304 9873760 13873680
20:30:02 54655 8192000000 10899213696 11332251648 1956724240 19.280 100 49 97 0 1982745648 11470832 14550576
20:32:49 6771 8192000000 10927839872 11355545600 1981151744 19.474 100 64 128 1 2019708368 20545952 18010672
20:33:59 1524 8192000000 10952358288 11366961152 1993809840 19.574 100 88 174 2 2039852608 19673360 26369408
20:35:13 7967 8192000000 10926311360 11345596416 1952757504 19.248 99 65 127 0 1999197616 27902480 18537632
20:36:24 6842 8192000000 10923589520 11351408640 1968356320 19.372 100 60 119 1 2016038320 29680192 18001808
20:37:33 5985 8192000000 10936625920 11362410496 1970340912 19.388 100 65 128 2 2023747920 33621008 19786000
20:38:44 6340 8192000000 10896517152 11326488576 1916292384 18.957 99 51 102 1 1970516736 37832288 16392064
20:40:00 5864 8192000000 10918591888 11331649536 1936513312 19.119 100 64 128 2 1998147408 41917776 19716320
20:41:09 2715 8192000000 10908078800 11322486784 1930635392 19.072 100 73 146 0 1993325552 39732240 22957920
20:42:14 6859 8192000000 10872180816 11293409280 1904539088 18.863 100 56 112 0 1960671904 38370176 17762640
20:43:36 16028 8192000000 10846257120 11280306176 1880089568 18.666 100 25 50 3 1924993392 36547344 8356480
20:45:03 14610 8192000000 10866125696 11312439296 1878482432 18.653 98 28 56 4 1936454832 48069584 9902816
20:46:20 36741 8192000000 10851844944 11301556224 1856856304 18.478 97 23 46 2 1897820000 33454896 7508800
20:47:57 49457 8192000000 10837293392 11284856832 1866964256 18.560 98 11 22 3 1890548832 20019744 3564832
20:49:47 33079 8192000000 10839831248 11294371840 1912738256 18.929 100 29 58 0 1931412928 10622576 8052096
20:51:36 62974 8192000000 10860278112 11314774016 1916585344 18.959 99 30 60 1 1941880496 16491376 8803776
20:54:35 60903 8192000000 10846786336 11311624192 1900239936 18.828 99 23 46 2 1917496288 11128544 6127808
20:57:02 60720 8192000000 10871576448 11332956160 1947373152 19.206 100 14 28 1 1960748048 9669088 3705808
20:59:59 32885 8192000000 10878511584 11336257536 1955240112 19.268 100 10 18 1 1964568672 7276224 2052336
21:01:33 19907 8192000000 10900078160 11361722368 1972808464 19.408 100 25 50 0 1992358080 12529376 7020240
21:02:55 94670 8192000000 10894702976 11375636480 1922608112 19.008 97 8 16 1 1941061104 16097472 2355520
21:05:46 92568 8192000000 10921591280 11378855936 1972099984 19.402 99 24 48 1 1991108096 11769744 7238368
21:08:21 22723 8192000000 10935490976 11396014080 1991311936 19.554 100 34 68 0 2016168752 15119296 9737520
21:09:52 79005 8192000000 10920604400 11388198912 1976387904 19.436 99 18 36 0 1995753472 14586480 4779088
21:12:12 61488 8192000000 10910540688 11363262464 1945861280 19.194 98 7 15 0 1956908880 8905424 2142176
21:14:17 60785 8192000000 10931189344 11390242816 1984291792 19.499 100 17 34 2 2014188176 24888400 5007984
21:16:25 64006 8192000000 10907567184 11379900416 1930772272 19.073 97 33 64 0 1953659584 12547872 10339440
21:19:28 76591 8192000000 10875321824 11353010176 1925018272 19.027 98 11 21 0 1942457168 14162864 3276032
21:21:45 59522 8192000000 10881138832 11365425152 1935470128 19.111 98 21 41 5 1958601248 16355472 6775648
21:24:08 33290 8192000000 10888070448 11348000768 1932249904 19.085 98 33 67 4 1947700304 6070928 9379472
21:25:42 70599 8192000000 10869224416 11336941568 1853215040 18.448 94 15 30 0 1867938336 10403568 4319728
21:27:52 56361 8192000000 10853868896 11314298880 1858929056 18.495 95 13 26 1 1872177584 9787248 3461280
21:29:50 79839 8192000000 10838036688 11307786240 1835021760 18.300 94 7 14 1 1848251792 11748064 1481968
21:32:12 71093 8192000000 10824102960 11279421440 1782697872 17.872 93 19 38 1 1799002608 11290224 5014512
21:34:24 43773 8192000000 10862571360 11329441792 1914179776 18.940 100 12 24 0 1934307024 16601424 3525824
21:36:13 37861 8192000000 10849574896 11303444480 1882063456 18.682 97 25 50 3 1900249856 10908352 7278048
21:38:00 72876 8192000000 10852092448 11307212800 1902956288 18.850 98 23 44 1 1917013008 7254992 6801728
21:40:16 89318 8192000000 10846191792 11331465216 1906942560 18.882 99 10 20 0 1933272800 23357360 2972880
21:42:45 55549 8192000000 10828816096 11301392384 1878507440 18.653 98 12 24 1 1910610256 28950400 3152416
21:44:47 40505 8192000000 10841760336 11299831808 1866463456 18.556 97 20 40 1 1881281984 9276736 5541792
21:46:28 68749 8192000000 10866741392 11327590400 1930823344 19.073 100 13 26 0 1947208192 12664800 3720048
21:48:37 69169 8192000000 10885854544 11349278720 1942913680 19.170 100 16 32 1 1960065008 12286976 4864352
21:50:56 43212 8192000000 10896198400 11361955840 1942980672 19.171 99 21 42 2 1959073360 10345312 5747376
21:52:51 64396 8192000000 10899827168 11359035392 1925782048 19.033 98 19 38 1 1947946512 16617280 5547184
21:54:59 49768 8192000000 10919954976 11370024960 1954392384 19.261 99 33 64 0 1973696288 9686752 9617152
21:56:49 100420 8192000000 10917516464 11376549888 1974520752 19.421 100 14 28 2 1988486992 10335152 3631088
21:59:47 47566 8192000000 10892870672 11374559232 1974644416 19.422 100 13 24 2 1988856768 11166608 3045744
22:01:40 61651 8192000000 10909634688 11389546496 1984642832 19.501 100 17 34 0 2003108048 12777536 5687680
22:03:41 64581 8192000000 10900806544 11376820224 1917621760 18.968 96 20 40 0 1936226704 12498736 6106208
22:05:59 51756 8192000000 10915253280 11362680832 1922582752 19.008 97 33 67 0 1942013904 9627792 9803360
22:07:56 115521 8192000000 10897633760 11372687360 1824013264 18.210 92 11 22 1 1847381632 20353520 3014848
22:10:54 152830 8192000000 10886153104 11354030080 1830296272 18.262 93 16 30 0 1853330736 18822496 4211968
22:14:27 20722 8192000000 10890252944 11331153920 1922755968 19.009 99 50 100 0 1954027344 17185968 14085408
22:15:53 83711 8192000000 10842538480 11292995584 1862302496 18.522 97 26 52 1 1887918128 17070736 8544896
22:18:16 59421 8192000000 10833461120 11305656320 1890513040 18.750 99 7 14 0 1903891792 11437056 1941696
22:20:16 89481 8192000000 10825792944 11293810688 1826708912 18.232 95 7 13 2 1835848208 7281744 1857552
22:22:46 63608 8192000000 10808306432 11272843264 1833905216 18.291 96 20 41 0 1847151600 7640944 5605440
22:25:05 64800 8192000000 10845343200 11313160192 1888374416 18.733 98 12 24 0 1904936480 13039344 3522720

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 17-10-2020 22:28:25 to 17-10-2020 22:28:39.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 113 v_add_invoice_to_stock 335544517
user_exc 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-10-2020 22:28:40 to 17-10-2020 22:28:42.

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          245254
MON$OLDEST_ACTIVE               248590
MON$OLDEST_SNAPSHOT             248590
MON$NEXT_TRANSACTION            248590
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                       2013952
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.7.33375 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.7.33375 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.7.33375 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.7.33375 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 ---
:32: 
:33: mscs-test-161.budzdorov.ru    Sat Oct 17 22:26:59 2020
:34:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:35: 
:36: 
:37: mscs-test-161.budzdorov.ru    Sat Oct 17 22:27:03 2020
:38:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:39: 
--- end of diff output ---

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

17.10.20 22:28:43. 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
2020-10-17 22:28:45 No severe PSQL-related problems occured
17.10.20 22:28:46. End of report.