Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.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 17413212 kB
MemAvailable 31550308 kB
Buffers 0 kB
Cached 14234224 kB
SwapCached 5440 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-15 22:26:57
DTS_END                         2020-10-15 22:26:57.5490
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> X_MAKE_QSTORNO_1000_1200(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(56:17)
IP                              ::1/38258
TRN_ID                          253836
ATT_ID                          691
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: 10002.94 18613 1200353
customer order: creation 186.63 345 22395
customer order: refuse 16.02 232 1923
order to supplier: creation 49.93 1121 5992
order to supplier: removal 3.97 69612 476
invoice (draft): creation 48.96 2583 5875
invoice (draft): removal 57.98 532 6957
invoice accept: apply 44.13 15425 5295
invoice accept: cancel 52.28 605 6273
customer reserve: creation 4088.99 153 490679
customer reserve: removal 5132.07 42 615848
realization accept: apply 91.78 149 11013
realization accept: cancel 90.46 97 10855
payment from customer: creation 63.21 83 7585
payment from customer: removal 6.18 52 742
payment to supplier: creation 53.89 113 6467
payment to supplier: removal 3.53 56 424
service: total inventory turnovers 5.19 862 623
service: total monetary turnovers 6.83 28 820
service: aggregate perf. data 0.89 17908 107
service: refresh index statistics 0.03 262266 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-10-2020 22:27:32 to 15-10-2020 22:27:34.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3892
TEST_TIME 2 3815
TEST_TIME 3 5159
TEST_TIME 4 5317
TEST_TIME 5 6156
TEST_TIME 6 6299
TEST_TIME 7 6526
TEST_TIME 8 7669
TEST_TIME 9 7465
TEST_TIME 10 7789
TEST_TIME 11 7983
TEST_TIME 12 8200
TEST_TIME 13 9001
TEST_TIME 14 9491
TEST_TIME 15 9726
TEST_TIME 16 9663
TEST_TIME 17 10095
TEST_TIME 18 10542
TEST_TIME 19 10860
TEST_TIME 20 11176
TEST_TIME 21 11765
TEST_TIME 22 11849
TEST_TIME 23 12308
TEST_TIME 24 11967
TEST_TIME 25 12050
TEST_TIME 26 11869
TEST_TIME 27 11586
TEST_TIME 28 11730
TEST_TIME 29 11550
TEST_TIME 30 11199
TEST_TIME 31 11532
TEST_TIME 32 11239
TEST_TIME 33 11113
TEST_TIME 34 11066
TEST_TIME 35 11203
TEST_TIME 36 11116
TEST_TIME 37 10977
TEST_TIME 38 10831
TEST_TIME 39 11015
TEST_TIME 40 10915
TEST_TIME 41 10813
TEST_TIME 42 10930
TEST_TIME 43 10909
TEST_TIME 44 10955
TEST_TIME 45 10944
TEST_TIME 46 10865
TEST_TIME 47 10923
TEST_TIME 48 10930
TEST_TIME 49 10927
TEST_TIME 50 11047
TEST_TIME 51 10915
TEST_TIME 52 10938
TEST_TIME 53 10788
TEST_TIME 54 10767
TEST_TIME 55 10719
TEST_TIME 56 10667
TEST_TIME 57 10574
TEST_TIME 58 10547
TEST_TIME 59 10508
TEST_TIME 60 10443
TEST_TIME 61 10409
TEST_TIME 62 10319
TEST_TIME 63 10297
TEST_TIME 64 10244
TEST_TIME 65 10260
TEST_TIME 66 10331
TEST_TIME 67 10319
TEST_TIME 68 10274
TEST_TIME 69 10256
TEST_TIME 70 10427
TEST_TIME 71 10453
TEST_TIME 72 10456
TEST_TIME 73 10472
TEST_TIME 74 10516
TEST_TIME 75 10571
TEST_TIME 76 10542
TEST_TIME 77 10499
TEST_TIME 78 10372
TEST_TIME 79 10281
TEST_TIME 80 10336
TEST_TIME 81 10319
TEST_TIME 82 10241
TEST_TIME 83 10187
TEST_TIME 84 10112
TEST_TIME 85 10071
TEST_TIME 86 10113
TEST_TIME 87 10100
TEST_TIME 88 10137
TEST_TIME 89 10160
TEST_TIME 90 10126
TEST_TIME 91 10127
TEST_TIME 92 10160
TEST_TIME 93 10149
TEST_TIME 94 10165
TEST_TIME 95 10178
TEST_TIME 96 10201
TEST_TIME 97 10200
TEST_TIME 98 10183
TEST_TIME 99 10215
TEST_TIME 100 10232
TEST_TIME 101 10224
TEST_TIME 102 10126
TEST_TIME 103 10103
TEST_TIME 104 10110
TEST_TIME 105 10087
TEST_TIME 106 10054
TEST_TIME 107 10008
TEST_TIME 108 9956
TEST_TIME 109 9951
TEST_TIME 110 9919
TEST_TIME 111 9926
TEST_TIME 112 9907
TEST_TIME 113 9871
TEST_TIME 114 9882
TEST_TIME 115 9877
TEST_TIME 116 9963
TEST_TIME 117 10007
TEST_TIME 118 9984
TEST_TIME 119 9992
TEST_TIME 120 10006

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 15-10-2020 22:27:36 to 15-10-2020 22:27:39.

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 1656598 1656598 0 0.00 0 12544 5 0 0
doc_list_biud 1656598 1656598 0 0.00 0 43688 20 0 0
sp_add_invoice_to_stock 5410 5295 115 2.12 17 166536 15425 0 115
sp_cancel_adding_invoice 6273 6273 0 0.00 5 52645 605 0 0
sp_cancel_client_order 1923 1923 0 0.00 4 11767 232 0 0
sp_cancel_customer_reserve 615848 615848 0 0.00 1 43689 43 0 0
sp_cancel_pay_from_customer 742 742 0 0.00 3 4254 53 0 0
sp_cancel_pay_to_supplier 424 424 0 0.00 3 1805 56 0 0
sp_cancel_supplier_invoice 6963 6957 6 0.08 1 16404 532 0 6
sp_cancel_supplier_order 476 476 0 0.00 4 732272 69612 0 0
sp_cancel_write_off 10855 10855 0 0.00 1 14015 98 0 0
sp_client_order 22395 22395 0 0.00 3 27332 345 0 0
sp_customer_reserve 490679 490679 0 0.00 0 67020 154 0 0
sp_fill_shopping_cart 34261 34261 0 0.00 1 13260 29 0 0
sp_fill_shopping_cart_clo_res 490672 490672 0 0.00 0 17712 13 0 0
sp_get_clo_for_invoice 5288 5288 0 0.00 0 39338 288 0 0
sp_kill_cost_storno 18294 18294 0 0.00 0 6814 39 0 0
sp_kill_qstorno_ret_qs2qd 1249984 1249984 0 0.00 0 43655 23 0 0
sp_kill_qty_storno 658633 658633 0 0.00 0 43687 50 0 0
sp_lock_selected_doc 22534 22534 0 0.00 0 6179 5 0 0
sp_make_cost_storno 30353 30353 0 0.00 0 11319 45 0 0
sp_multiply_rows_for_pdistr 30353 30353 0 0.00 0 8334 22 0 0
sp_multiply_rows_for_qdistr 22395 22395 0 0.00 0 27322 160 0 0
sp_pay_from_customer 7585 7585 0 0.00 4 13777 83 0 0
sp_pay_to_supplier 6467 6467 0 0.00 4 14080 113 0 0
sp_payment_common 14052 14052 0 0.00 1 13757 25 0 0
sp_qd_handle_on_cancel_clo 1923 1923 0 0.00 0 11104 210 0 0
sp_qd_handle_on_invoice_upd_sts 11561 11561 0 0.00 0 40619 263 0 0
sp_qd_handle_on_reserve_upd_sts 11013 11013 0 0.00 0 14316 55 0 0
sp_reserve_write_off 11013 11013 0 0.00 6 14329 150 0 0
sp_supplier_invoice 5875 5875 0 0.00 5 110698 2583 0 0
sp_supplier_order 5992 5992 0 0.00 5 30968 1121 0 0
srv_aggregate_perf_data 107 107 0 0.00 2942 291599 17909 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 2004 129358 50379 0 0
srv_find_qd_qs_mism 1150008 1150008 0 0.00 0 12543 4 0 0
srv_make_invnt_saldo 623 623 0 0.00 43 25822 862 0 0
srv_make_money_saldo 820 820 0 0.00 1 9320 29 0 0
srv_recalc_idx_stat 4 4 0 0.00 144538 378727 262266 0 0
t$perf-abend:sp_add_invoice_to_stock 115 115 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 5295 5295 0 0.00 0 11172 163 0 0
t$perf-norm:sp_cancel_adding_invoice 425 425 0 0.00 1 3634 57 0 0
t$perf-norm:sp_cancel_client_order 1923 1923 0 0.00 0 7054 23 0 0
t$perf-norm:sp_cancel_customer_reserve 764 764 0 0.00 0 445 6 0 0
t$perf-norm:sp_cancel_pay_from_customer 742 742 0 0.00 0 611 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 424 424 0 0.00 0 1163 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 455 455 0 0.00 0 1933 10 0 0
t$perf-norm:sp_cancel_supplier_order 476 476 0 0.00 0 19355 1054 0 0
t$perf-norm:sp_cancel_write_off 1028 1028 0 0.00 0 2280 5 0 0
t$perf-norm:sp_client_order 22395 22395 0 0.00 0 10456 17 0 0
t$perf-norm:sp_customer_reserve 1109 1109 0 0.00 0 7210 23 0 0
t$perf-norm:sp_pay_from_customer 7585 7585 0 0.00 0 9483 11 0 0
t$perf-norm:sp_pay_to_supplier 6467 6467 0 0.00 0 6899 12 0 0
t$perf-norm:sp_reserve_write_off 11013 11013 0 0.00 0 12157 18 0 0
t$perf-norm:sp_supplier_invoice 5875 5875 0 0.00 0 7245 19 0 0
t$perf-norm:sp_supplier_order 5992 5992 0 0.00 0 6784 15 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 9690 93 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 139 3 0 0
t$perf-norm:srv_make_invnt_saldo 623 623 0 0.00 0 308 7 0 0
t$perf-norm:srv_make_money_saldo 820 820 0 0.00 0 2810 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10798 10568 230 2.13 0 4384 17 0 230
v_all_customers 40574 40574 0 0.00 0 17 0 0 0
v_all_suppliers 33348 33348 0 0.00 0 37 1 0 0
v_all_wares 44788 44788 0 0.00 0 205 1 0 0
v_cancel_adding_invoice 850 850 0 0.00 0 489 5 0 0
v_cancel_client_order 3846 3846 0 0.00 0 2274 6 0 0
v_cancel_customer_prepayment 1484 1484 0 0.00 0 1912 4 0 0
v_cancel_customer_reserve 1528 1528 0 0.00 0 953 4 0 0
v_cancel_payment_to_supplier 848 848 0 0.00 0 1352 7 0 0
v_cancel_supplier_invoice 922 910 12 1.30 0 2566 19 0 12
v_cancel_supplier_order 952 952 0 0.00 0 3890 12 0 0
v_cancel_write_off 2056 2056 0 0.00 0 2912 15 0 0
v_max_id_clo_ord 5992 5992 0 0.00 0 547 1 0 0
v_max_id_clo_res 1109 1109 0 0.00 0 2236 10 0 0
v_max_id_ord_sup 5875 5875 0 0.00 0 1459 2 0 0
v_max_non_paid_invoice 1660 1660 0 0.00 0 6723 6 0 0
v_max_non_paid_realizn 4143 4143 0 0.00 0 3276 4 0 0
v_min_id_clo_ord 5992 5992 0 0.00 0 4476 5 0 0
v_min_id_clo_res 1109 1109 0 0.00 0 5269 15 0 0
v_min_id_ord_sup 5875 5875 0 0.00 0 4409 4 0 0
v_min_non_paid_invoice 6467 6467 0 0.00 0 5143 16 0 0
v_min_non_paid_realizn 7585 7585 0 0.00 0 6328 12 0 0
v_our_firm 11098 11098 0 0.00 0 23 1 0 0
v_reserve_write_off 22026 22026 0 0.00 0 2748 6 0 0
x_lock_depdocs_on_canc_invoice 12775 12775 0 0.00 0 14192 92 0 0
x_lock_depdocs_on_canc_sup_ord 476 476 0 0.00 0 25025 352 0 0
x_make_qstorno_1000_1200 5992 5992 0 0.00 1 30953 1058 0 0
x_make_qstorno_1200_2000 5875 5875 0 0.00 2 97433 2486 0 0
x_make_qstorno_2100_3300 490679 490679 0 0.00 0 67017 140 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-10-2020 22:27:40 to 15-10-2020 22:27:44.

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 1 8192000000 8798422528 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
19:58:02 23366 8192000000 10395627920 10720714752 1447719120 15.018 100 86 172 0 1482761376 6429168 28613088
19:59:29 17773 8192000000 10452931104 10787835904 1504531680 15.516 100 87 174 0 1541548144 8835792 28180672
20:00:52 14661 8192000000 10492646768 10838499328 1551000688 15.919 100 83 166 1 1586447360 9758544 25688128
20:02:08 13324 8192000000 10570766768 10926018560 1622076000 16.528 100 84 168 2 1658262176 11185024 25001152
20:03:25 6338 8192000000 10639651936 11008552960 1697152784 17.161 100 83 167 2 1734285456 12671504 24461168
20:04:34 3521 8192000000 10686492544 11061608448 1748023296 17.585 100 77 154 0 1782108464 12812736 21272432
20:05:38 4165 8192000000 10733460448 11119923200 1793145120 17.958 100 75 150 0 1828028688 14084000 20799568
20:06:44 2121 8192000000 10787319392 11172450304 1840234544 18.343 100 90 179 2 1880352560 15065168 25052848
20:07:47 1908 8192000000 10817047312 11204509696 1868700320 18.574 100 94 188 1 1907270320 12329696 26240304
20:08:49 11459 8192000000 10775369312 11203018752 1864518992 18.540 100 20 40 1 1876759456 6720992 5519472
20:10:05 8559 8192000000 10800499040 11226451968 1886437904 18.717 100 23 46 1 1903465520 10808496 6219120
20:11:19 11902 8192000000 10797047088 11234484224 1891652816 18.759 100 14 28 1 1901238592 5527424 4058352
20:12:32 20265 8192000000 10830965680 11249094656 1906796896 18.881 100 50 102 0 1927188704 5334496 15057312
20:13:54 7066 8192000000 10821904400 11256070144 1910235504 18.909 100 23 46 0 1922235904 5468352 6532048
20:15:04 14000 8192000000 10811352128 11244322816 1895988624 18.794 100 15 29 0 1905440240 5442416 4009200
20:16:19 8335 8192000000 10810160832 11241762816 1896019616 18.794 100 18 36 1 1906815680 5828752 4967312
20:17:30 15915 8192000000 10787793424 11191132160 1809395376 18.091 97 78 156 5 1838007792 5184432 23427984
20:18:46 4681 8192000000 10679643392 11090542592 1724348128 17.388 96 27 54 2 1738229888 5249568 8632192
20:19:54 6838 8192000000 10668086960 11080196096 1705050448 17.227 96 22 44 1 1717293168 5631792 6610928
20:21:03 43798 8192000000 10732508880 11143159808 1792516368 17.952 99 38 76 2 1809037328 5986000 10534960
20:23:07 7034 8192000000 10755398432 11176517632 1836647872 18.314 100 38 77 0 1855337584 7984496 10705216
20:24:15 7043 8192000000 10776697168 11197784064 1853405600 18.450 100 23 46 0 1866017088 5721296 6890192
20:25:22 10476 8192000000 10773635504 11208634368 1864889616 18.543 100 25 50 0 1878202624 5412880 7900128
20:26:33 42312 8192000000 10821563792 11246190592 1882443504 18.685 100 23 45 2 1893833456 5411808 5978144
20:28:27 8842 8192000000 10867423424 11282051072 1928020048 19.051 100 52 102 0 1952793184 10785568 13987568
20:29:45 16639 8192000000 10861882848 11295813632 1940769392 19.153 100 35 70 1 1958782480 8041488 9971600
20:31:13 16131 8192000000 10878913872 11314798592 1950423472 19.230 100 40 81 1 1971257296 8691296 12142528
20:32:31 12674 8192000000 10909288768 11349794816 1979222208 19.459 100 49 98 0 2010015824 15793840 14999776
20:33:47 16055 8192000000 10939424528 11373342720 1988035440 19.528 100 54 104 0 2024908496 21187680 15685376
20:35:10 19729 8192000000 10936545584 11370307584 1986289968 19.514 100 54 108 0 2022235136 20150576 15794592
20:36:33 3730 8192000000 10957148896 11370323968 1960232784 19.308 98 80 158 2 2014048416 30632544 23183088
20:37:39 3315 8192000000 10939884224 11355508736 1968404480 19.373 99 71 142 5 2021601840 32384944 20812416
20:38:45 2004 8192000000 10940939824 11344957440 1960073168 19.307 100 87 174 1 2019012048 33165168 25773712
20:39:51 3213 8192000000 10907859760 11324706816 1908197248 18.892 98 58 115 2 1961366192 35021120 18147824
20:40:59 12857 8192000000 10885784192 11318128640 1902837344 18.849 98 31 62 0 1947030400 34151776 10041280
20:42:17 10330 8192000000 10905074896 11344490496 1939595456 19.144 100 32 64 0 1989259568 38992240 10671872
20:43:33 11830 8192000000 10890198656 11332608000 1915683856 18.952 99 31 62 4 1963846128 37549232 10613040
20:44:48 15542 8192000000 10871486992 11311898624 1870399840 18.588 97 29 59 2 1918055168 37611152 10044176
20:46:09 28881 8192000000 10879040784 11332882432 1909406688 18.902 99 34 68 2 1959934272 39729520 10798064
20:47:42 60308 8192000000 10872454576 11338969088 1909421696 18.902 99 12 23 0 1944907936 31742464 3743776
20:49:43 52581 8192000000 10863849968 11300745216 1859724128 18.501 96 29 58 1 1876090832 7850672 8516032
20:51:36 62131 8192000000 10867385024 11311493120 1879290176 18.659 98 12 24 1 1891658464 9113568 3254720
20:53:42 21470 8192000000 10885519488 11318718464 1922345712 19.006 99 41 82 0 1951816448 17628608 11842128
20:55:12 127828 8192000000 10875208240 11314372608 1888290560 18.732 98 24 48 1 1913016064 17335280 7390224
20:58:25 31285 8192000000 10886270384 11345584128 1958635472 19.295 100 20 40 2 1977298272 13189200 5473600
20:59:58 49220 8192000000 10908955264 11371315200 1978336768 19.452 100 22 44 1 2003190608 17682752 7171088
21:01:51 24155 8192000000 10890959840 11382771712 1965341072 19.348 100 5 10 0 1985875136 19329472 1204592
21:03:16 28799 8192000000 10909317232 11373826048 1981475008 19.476 100 12 25 0 1994282480 9237280 3570192
21:04:48 71357 8192000000 10901477840 11398590464 1966452736 19.357 99 7 14 1 1984279488 15739872 2086880
21:07:00 84426 8192000000 10947444336 11423543296 2002504480 19.642 100 34 68 0 2025812672 13590624 9717568
21:09:57 62763 8192000000 10938549344 11407470592 2002524128 19.643 100 11 22 0 2020317744 14811904 2981712
21:12:00 49192 8192000000 10942111088 11402334208 1968133440 19.371 98 16 32 1 1987915136 15562656 4219040
21:13:51 69880 8192000000 10946307984 11415707648 2010281664 19.704 100 16 32 1 2025569872 10326000 4962208
21:16:26 67092 8192000000 10972163344 11427344384 1987485968 19.524 99 17 34 0 2012905696 20851536 4568192
21:18:35 94031 8192000000 10898475648 11357294592 1911534400 18.919 96 36 72 2 1935543376 13771808 10237168
21:21:16 42397 8192000000 10860498368 11329785856 1907561136 18.887 97 18 36 1 1925568976 12571232 5436608
21:23:03 48026 8192000000 10849957616 11326230528 1917618976 18.968 99 5 10 0 1926563472 7445472 1499024
21:24:57 61690 8192000000 10867967984 11336372224 1890277680 18.748 96 17 35 1 1902297376 7433040 4586656
21:27:00 53975 8192000000 10849724784 11333505024 1898446432 18.814 97 9 18 1 1909068208 8190928 2430848
21:28:56 73211 8192000000 10880996096 11361976320 1967087024 19.362 100 20 41 1 1980181008 7210704 5883280
21:31:37 52579 8192000000 10870268768 11350831104 1904330512 18.861 97 18 36 0 1921347232 12413088 4603632
21:33:30 52035 8192000000 10903887904 11354537984 1929339296 19.062 98 29 58 5 1948742256 10852624 8550336
21:35:26 69163 8192000000 10867676256 11351449600 1880603392 18.670 96 17 34 2 1903174256 17234608 5336256
21:37:35 56468 8192000000 10883775776 11332354048 1890516464 18.750 97 29 58 0 1910804416 11648128 8639824
21:39:38 27879 8192000000 10888492976 11339055104 1906600288 18.879 98 33 66 1 1924574128 8989136 8984704
21:41:14 129357 8192000000 10890850672 11352936448 1861001536 18.511 95 10 20 0 1882114336 18624512 2488288
21:44:29 123542 8192000000 10871303232 11349745664 1927217600 19.045 98 30 60 2 1948811184 13058624 8534960
21:47:38 73726 8192000000 10863078736 11343867904 1933192128 19.092 99 4 6 1 1942406496 8029168 1185200
21:49:52 67001 8192000000 10886818544 11362930688 1964197232 19.339 100 13 26 0 1980180048 11975616 4007200
21:52:00 60272 8192000000 10890811376 11371888640 1905230960 18.868 97 13 27 0 1928155152 18465648 4458544
21:54:10 66964 8192000000 10897197584 11363987456 1934961344 19.107 98 12 24 0 1946213136 7399616 3852176
21:56:22 50994 8192000000 10908512464 11372572672 1958354672 19.293 99 28 55 3 1981066832 15216928 7495232
21:58:19 38948 8192000000 10922562352 11388190720 1989327168 19.538 100 21 42 1 2002420416 7507504 5585744
22:00:01 72646 8192000000 10924803872 11391455232 1944597456 19.183 97 23 46 1 1960754432 10136048 6020928
22:02:14 63513 8192000000 10885251440 11349319680 1851838720 18.437 93 21 42 0 1866456256 9105824 5511712
22:04:17 51824 8192000000 10899658544 11364790272 1937805968 19.129 98 31 62 0 1957355680 10465472 9084240
22:06:10 73581 8192000000 10887322048 11370074112 1927000816 19.043 98 10 20 1 1941967840 12397936 2569088
22:08:43 84432 8192000000 10860383856 11324428288 1836496848 18.312 94 19 36 2 1851138688 9458864 5182976
22:11:12 33203 8192000000 10821774240 11301326848 1867164464 18.561 97 21 42 0 1885801472 12725200 5911808
22:12:47 68133 8192000000 10851149184 11324022784 1911915216 18.922 99 6 12 0 1920142688 6536736 1690736
22:15:00 43808 8192000000 10835819360 11299352576 1876610112 18.638 98 16 32 0 1894724912 13327872 4786928
22:17:53 45699 8192000000 10833295824 11305332736 1882181664 18.683 98 16 32 1 1895294080 8776576 4335840
22:19:49 95133 8192000000 10871406576 11338141696 1889446560 18.741 97 18 36 1 1909299328 14467104 5385664
22:22:29 111091 8192000000 10851166752 11307134976 1792248336 17.950 92 25 50 0 1817555232 18183040 7123856
22:25:22 51494 8192000000 10856415904 11311222784 1836795776 18.315 95 33 67 1 1858941568 12852464 9293328

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-10-2020 22:27:46 to 15-10-2020 22:28:01.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 115 v_add_invoice_to_stock 335544517
user_exc 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-10-2020 22:28:01 to 15-10-2020 22:28:03.

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          253028
MON$OLDEST_ACTIVE               253955
MON$OLDEST_SNAPSHOT             253955
MON$NEXT_TRANSACTION            253955
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                       2032224
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.33374 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.7.33374 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.7.33374 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.7.33374 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 ---
:204: 
:205: mscs-test-161.budzdorov.ru    Thu Oct 15 22:27:04 2020
:206:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:207: 
--- end of diff output ---

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

15.10.20 22:28:04. 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-15 22:28:07 No severe PSQL-related problems occured
15.10.20 22:28:08. End of report.