Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.10.2020 22:28
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 17256924 kB
MemAvailable 31646528 kB
Buffers 0 kB
Cached 14455340 kB
SwapCached 5608 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-11 22:26:58
DTS_END                         2020-10-11 22:26:58.3780
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(56:17)
IP                              ::1/58154
TRN_ID                          254555
ATT_ID                          688
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.10 18638 1200252
customer order: creation 188.03 288 22563
customer order: refuse 15.33 247 1839
order to supplier: creation 49.83 1125 5980
order to supplier: removal 3.81 78257 457
invoice (draft): creation 49.60 2728 5952
invoice (draft): removal 57.18 643 6862
invoice accept: apply 44.62 14870 5354
invoice accept: cancel 51.04 696 6125
customer reserve: creation 4149.73 147 497967
customer reserve: removal 5069.73 46 608367
realization accept: apply 90.46 165 10855
realization accept: cancel 92.29 82 11075
payment from customer: creation 63.43 118 7611
payment from customer: removal 6.16 72 739
payment to supplier: creation 53.63 121 6435
payment to supplier: removal 3.79 78 455
service: total inventory turnovers 5.13 967 616
service: total monetary turnovers 7.31 24 877
service: aggregate perf. data 0.99 13943 119
service: refresh index statistics 0.03 258138 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1389
TEST_TIME 2 2855
TEST_TIME 3 3695
TEST_TIME 4 3875
TEST_TIME 5 5245
TEST_TIME 6 5552
TEST_TIME 7 5257
TEST_TIME 8 5464
TEST_TIME 9 6031
TEST_TIME 10 6748
TEST_TIME 11 7161
TEST_TIME 12 8714
TEST_TIME 13 9303
TEST_TIME 14 9479
TEST_TIME 15 9734
TEST_TIME 16 9824
TEST_TIME 17 9816
TEST_TIME 18 9707
TEST_TIME 19 9835
TEST_TIME 20 9906
TEST_TIME 21 10101
TEST_TIME 22 10362
TEST_TIME 23 10698
TEST_TIME 24 10299
TEST_TIME 25 11894
TEST_TIME 26 12112
TEST_TIME 27 11800
TEST_TIME 28 11625
TEST_TIME 29 11567
TEST_TIME 30 11379
TEST_TIME 31 11219
TEST_TIME 32 11103
TEST_TIME 33 11181
TEST_TIME 34 11114
TEST_TIME 35 11229
TEST_TIME 36 11073
TEST_TIME 37 10991
TEST_TIME 38 11117
TEST_TIME 39 11088
TEST_TIME 40 11041
TEST_TIME 41 11092
TEST_TIME 42 11041
TEST_TIME 43 10871
TEST_TIME 44 10705
TEST_TIME 45 10619
TEST_TIME 46 10647
TEST_TIME 47 10638
TEST_TIME 48 10530
TEST_TIME 49 10597
TEST_TIME 50 10589
TEST_TIME 51 10637
TEST_TIME 52 10542
TEST_TIME 53 10455
TEST_TIME 54 10351
TEST_TIME 55 10395
TEST_TIME 56 10376
TEST_TIME 57 10317
TEST_TIME 58 10315
TEST_TIME 59 10385
TEST_TIME 60 10407
TEST_TIME 61 10433
TEST_TIME 62 10513
TEST_TIME 63 10465
TEST_TIME 64 10527
TEST_TIME 65 10609
TEST_TIME 66 10602
TEST_TIME 67 10712
TEST_TIME 68 10682
TEST_TIME 69 10646
TEST_TIME 70 10638
TEST_TIME 71 10519
TEST_TIME 72 10506
TEST_TIME 73 10449
TEST_TIME 74 10420
TEST_TIME 75 10387
TEST_TIME 76 10341
TEST_TIME 77 10279
TEST_TIME 78 10287
TEST_TIME 79 10210
TEST_TIME 80 10249
TEST_TIME 81 10287
TEST_TIME 82 10303
TEST_TIME 83 10244
TEST_TIME 84 10241
TEST_TIME 85 10239
TEST_TIME 86 10300
TEST_TIME 87 10307
TEST_TIME 88 10345
TEST_TIME 89 10344
TEST_TIME 90 10319
TEST_TIME 91 10434
TEST_TIME 92 10388
TEST_TIME 93 10332
TEST_TIME 94 10244
TEST_TIME 95 10249
TEST_TIME 96 10224
TEST_TIME 97 10175
TEST_TIME 98 10142
TEST_TIME 99 10083
TEST_TIME 100 10141
TEST_TIME 101 10119
TEST_TIME 102 10088
TEST_TIME 103 10066
TEST_TIME 104 9991
TEST_TIME 105 9996
TEST_TIME 106 9984
TEST_TIME 107 9954
TEST_TIME 108 9972
TEST_TIME 109 9949
TEST_TIME 110 9984
TEST_TIME 111 9994
TEST_TIME 112 10000
TEST_TIME 113 9990
TEST_TIME 114 10003
TEST_TIME 115 9995
TEST_TIME 116 10125
TEST_TIME 117 10132
TEST_TIME 118 10139
TEST_TIME 119 10067
TEST_TIME 120 10005

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 11-10-2020 22:28:39 to 11-10-2020 22:28:43.

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 1661413 1661413 0 0.00 0 16541 5 0 0
doc_list_biud 1661408 1661408 0 0.00 0 56944 22 0 0
sp_add_invoice_to_stock 5506 5354 152 2.76 33 203893 14870 0 152
sp_cancel_adding_invoice 6125 6125 0 0.00 6 59930 696 0 0
sp_cancel_client_order 1839 1839 0 0.00 4 8201 248 0 0
sp_cancel_customer_reserve 608367 608367 0 0.00 1 35438 46 0 0
sp_cancel_pay_from_customer 739 739 0 0.00 3 11510 72 0 0
sp_cancel_pay_to_supplier 455 455 0 0.00 3 8050 78 0 0
sp_cancel_supplier_invoice 6873 6862 11 0.16 2 57009 644 0 11
sp_cancel_supplier_order 457 457 0 0.00 6 738062 78258 0 0
sp_cancel_write_off 11075 11075 0 0.00 1 12960 83 0 0
sp_client_order 22563 22563 0 0.00 3 17703 288 0 0
sp_customer_reserve 497967 497967 0 0.00 0 91569 147 0 0
sp_fill_shopping_cart 34486 34486 0 0.00 1 13737 33 0 0
sp_fill_shopping_cart_clo_res 497959 497959 0 0.00 0 15812 12 0 0
sp_get_clo_for_invoice 5343 5343 0 0.00 0 22647 287 0 0
sp_kill_cost_storno 18394 18394 0 0.00 0 12958 41 0 0
sp_kill_qstorno_ret_qs2qd 1235128 1235128 0 0.00 0 56944 25 0 0
sp_kill_qty_storno 650921 650921 0 0.00 0 56944 55 0 0
sp_lock_selected_doc 22374 22374 0 0.00 0 7902 6 0 0
sp_make_cost_storno 30244 30244 0 0.00 0 14520 48 0 0
sp_multiply_rows_for_pdistr 30242 30242 0 0.00 0 6730 23 0 0
sp_multiply_rows_for_qdistr 22563 22563 0 0.00 0 12162 121 0 0
sp_pay_from_customer 7611 7611 0 0.00 4 14681 118 0 0
sp_pay_to_supplier 6435 6435 0 0.00 4 17874 122 0 0
sp_payment_common 14044 14044 0 0.00 1 11075 34 0 0
sp_qd_handle_on_cancel_clo 1839 1839 0 0.00 0 6207 213 0 0
sp_qd_handle_on_invoice_upd_sts 11468 11468 0 0.00 0 24400 282 0 0
sp_qd_handle_on_reserve_upd_sts 10853 10853 0 0.00 0 15744 55 0 0
sp_reserve_write_off 10855 10855 0 0.00 6 17810 165 0 0
sp_supplier_invoice 5952 5952 0 0.00 6 76888 2729 0 0
sp_supplier_order 5980 5980 0 0.00 5 34111 1125 0 0
srv_aggregate_perf_data 119 119 0 0.00 2849 99182 13944 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 1917 146067 53302 0 0
srv_find_qd_qs_mism 1148554 1148554 0 0.00 0 16540 5 0 0
srv_make_invnt_saldo 616 616 0 0.00 44 43987 968 0 0
srv_make_money_saldo 877 877 0 0.00 1 4830 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 234353 290240 258138 0 0
t$perf-abend:sp_add_invoice_to_stock 152 152 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5354 5354 0 0.00 0 15983 127 0 0
t$perf-norm:sp_cancel_adding_invoice 416 416 0 0.00 0 4991 120 0 0
t$perf-norm:sp_cancel_client_order 1839 1839 0 0.00 0 2531 10 0 0
t$perf-norm:sp_cancel_customer_reserve 745 745 0 0.00 0 2105 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 739 739 0 0.00 0 3074 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 455 455 0 0.00 0 1890 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 445 445 0 0.00 0 572 5 0 0
t$perf-norm:sp_cancel_supplier_order 457 457 0 0.00 0 23024 1286 0 0
t$perf-norm:sp_cancel_write_off 1084 1084 0 0.00 0 3910 11 0 0
t$perf-norm:sp_client_order 22563 22563 0 0.00 0 14345 16 0 0
t$perf-norm:sp_customer_reserve 1123 1123 0 0.00 0 3467 10 0 0
t$perf-norm:sp_pay_from_customer 7611 7611 0 0.00 0 15566 15 0 0
t$perf-norm:sp_pay_to_supplier 6435 6435 0 0.00 0 8527 12 0 0
t$perf-norm:sp_reserve_write_off 10856 10856 0 0.00 0 7264 11 0 0
t$perf-norm:sp_supplier_invoice 5952 5952 0 0.00 0 7987 13 0 0
t$perf-norm:sp_supplier_order 5980 5980 0 0.00 0 5428 13 0 0
t$perf-norm:srv_aggregate_perf_data 119 119 0 0.00 0 166 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 14 1 0 0
t$perf-norm:srv_make_invnt_saldo 616 616 0 0.00 0 350 7 0 0
t$perf-norm:srv_make_money_saldo 877 877 0 0.00 0 4398 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 2 2 0 0
v_add_invoice_to_stock 10986 10682 304 2.76 0 11491 20 0 304
v_all_customers 40628 40628 0 0.00 0 111 1 0 0
v_all_suppliers 33448 33448 0 0.00 0 24 1 0 0
v_all_wares 45126 45126 0 0.00 0 116 1 0 0
v_cancel_adding_invoice 832 832 0 0.00 0 1378 9 0 0
v_cancel_client_order 3678 3678 0 0.00 0 2602 8 0 0
v_cancel_customer_prepayment 1478 1478 0 0.00 0 11488 13 0 0
v_cancel_customer_reserve 1490 1490 0 0.00 0 5312 8 0 0
v_cancel_payment_to_supplier 910 910 0 0.00 0 3461 6 0 0
v_cancel_supplier_invoice 912 890 22 2.41 0 1992 23 0 22
v_cancel_supplier_order 914 914 0 0.00 0 2458 10 0 0
v_cancel_write_off 2168 2168 0 0.00 0 4198 17 0 0
v_max_id_clo_ord 5977 5977 0 0.00 0 2069 2 0 0
v_max_id_clo_res 1122 1122 0 0.00 0 5835 21 0 0
v_max_id_ord_sup 5946 5946 0 0.00 0 1865 2 0 0
v_max_non_paid_invoice 1632 1632 0 0.00 0 4786 7 0 0
v_max_non_paid_realizn 4188 4188 0 0.00 0 5468 4 0 0
v_min_id_clo_ord 5977 5977 0 0.00 0 890 3 0 0
v_min_id_clo_res 1122 1122 0 0.00 0 4520 20 0 0
v_min_id_ord_sup 5946 5946 0 0.00 0 8033 5 0 0
v_min_non_paid_invoice 6433 6433 0 0.00 0 8602 23 0 0
v_min_non_paid_realizn 7611 7611 0 0.00 0 11069 23 0 0
v_our_firm 11344 11344 0 0.00 0 15 1 0 0
v_reserve_write_off 21706 21706 0 0.00 0 11504 10 0 0
x_lock_depdocs_on_canc_invoice 12542 12542 0 0.00 0 44613 104 0 0
x_lock_depdocs_on_canc_sup_ord 457 457 0 0.00 0 7784 219 0 0
x_make_qstorno_1000_1200 5980 5980 0 0.00 1 34008 1060 0 0
x_make_qstorno_1200_2000 5952 5952 0 0.00 2 76883 2611 0 0
x_make_qstorno_2100_3300 497967 497967 0 0.00 0 91567 134 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 11-10-2020 22:28:43 to 11-10-2020 22:28:48.

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:02 1 8192000000 8798422528 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
19:58:02 14830 8192000000 10352622832 10669846528 1403518272 14.626 100 91 181 1 1439262928 5871392 29873264
19:59:25 16435 8192000000 10434308880 10768687104 1493322832 15.418 100 85 170 0 1528427200 8158064 26946304
20:00:44 16681 8192000000 10484789584 10824101888 1532918832 15.762 100 82 165 1 1567913520 9684416 25310272
20:02:04 7525 8192000000 10532755984 10889760768 1598281536 16.325 100 70 140 1 1629746688 10017072 21448080
20:03:14 9292 8192000000 10645591600 11011203072 1693570832 17.131 100 78 155 1 1728426080 12392688 22462560
20:04:26 5925 8192000000 10709370096 11086548992 1764610720 17.723 100 83 165 4 1802521520 15352880 22557920
20:05:33 6839 8192000000 10733184064 11118243840 1799364640 18.009 100 88 173 4 1837101200 13811552 23925008
20:06:41 1593 8192000000 10794333792 11180171264 1852918032 18.446 100 91 182 2 1891945520 14271456 24756032
20:07:44 34385 8192000000 10778365440 11197440000 1856999520 18.479 100 35 70 0 1873124992 5317024 10808448
20:09:19 7765 8192000000 10777511824 11199901696 1864853936 18.543 100 24 48 0 1877945024 6357504 6733584
20:10:27 9091 8192000000 10775350304 11208876032 1869269744 18.578 100 11 22 1 1877768208 5839008 2659456
20:11:39 57718 8192000000 10799032576 11231141888 1881580752 18.678 100 34 69 1 1897211200 5658672 9971776
20:13:38 5857 8192000000 10800860480 11235201024 1887762064 18.728 100 34 70 3 1904076768 5907248 10407456
20:14:46 10800 8192000000 10801878592 11236003840 1890014736 18.746 100 19 38 1 1901493696 5801872 5677088
20:15:59 59610 8192000000 10783087136 11216756736 1825689536 18.224 97 35 70 1 1841341600 6018144 9633920
20:17:59 7378 8192000000 10711067216 11133120512 1759839744 17.683 97 18 36 1 1770352720 5096080 5416896
20:19:09 6057 8192000000 10724104336 11135201280 1787747728 17.913 99 28 56 1 1801202000 5256800 8197472
20:20:15 11310 8192000000 10683074912 11096367104 1720061664 17.353 96 17 34 3 1730875552 5303888 5510000
20:21:30 24669 8192000000 10714644992 11135864832 1774343984 17.803 98 17 34 2 1784637088 4678688 5614416
20:22:55 12214 8192000000 10755225584 11179769856 1841228272 18.351 100 25 51 0 1854063392 5688160 7146960
20:24:08 11162 8192000000 10768792384 11196121088 1853370688 18.449 100 13 26 3 1862912144 5475040 4066416
20:25:20 32011 8192000000 10782381936 11208511488 1866588096 18.557 100 21 42 1 1878496848 5402720 6506032
20:26:54 29722 8192000000 10795134736 11215601664 1866476832 18.556 100 26 52 4 1879311024 5112624 7721568
20:28:49 18527 8192000000 10841366096 11263352832 1913434384 18.934 100 54 108 2 1938624912 9947760 15242768
20:30:11 9753 8192000000 10866173088 11287748608 1932588080 19.088 100 47 94 0 1957015072 10638928 13788064
20:31:25 23462 8192000000 10867823552 11308453888 1945008160 19.187 100 38 76 1 1972467104 15422832 12036112
20:33:13 22871 8192000000 10870930048 11302486016 1925763952 19.033 98 45 89 2 1951199728 12049392 13386384
20:34:40 11591 8192000000 10913251424 11332837376 1961208976 19.316 100 57 113 2 1996413920 18179824 17025120
20:35:54 1916 8192000000 10895613424 11317686272 1946313616 19.197 99 60 118 0 1982575520 17788624 18473280
20:37:00 2346 8192000000 10928690336 11340615680 1965655440 19.351 100 78 156 1 2010651152 21869936 23125776
20:38:05 4058 8192000000 10891434128 11302182912 1908111824 18.891 99 65 129 1 1958616608 29926064 20578720
20:39:13 20185 8192000000 10852071520 11291607040 1871766880 18.599 97 31 60 1 1899280720 17528384 9985456
20:40:38 4115 8192000000 10926907968 11340451840 1952772368 19.249 100 76 152 2 2004823184 28303568 23747248
20:41:44 15930 8192000000 10901510672 11342430208 1946421808 19.198 100 26 52 0 1982047024 27094000 8531216
20:43:12 3913 8192000000 10909955936 11348131840 1940487680 19.151 99 37 74 0 1985231856 32655376 12088800
20:44:25 9532 8192000000 10916623168 11347963904 1933363648 19.094 99 41 80 3 1983700032 36501072 13835312
20:45:48 18323 8192000000 10928653664 11364605952 1943397152 19.174 99 35 69 2 1999069824 44872240 10800432
20:47:12 13705 8192000000 10943031760 11388600320 1966498864 19.358 100 32 64 0 2029747728 52407472 10841392
20:48:30 11898 8192000000 10912535648 11362549760 1918374864 18.974 99 27 54 1 1978972944 51424112 9173968
20:49:43 55220 8192000000 10904648848 11341148160 1905665552 18.872 99 38 73 3 1963795008 45678752 12450704
20:51:40 30457 8192000000 10859339472 11351089152 1926421904 19.038 100 10 21 1 1967363040 37466336 3474800
20:53:14 47878 8192000000 10854760704 11295207424 1905465456 18.870 99 34 71 1 1925063648 8946160 10652032
20:55:27 129213 8192000000 10875141984 11336577024 1906239520 18.876 98 21 42 2 1921289184 9583968 5465696
20:58:43 75333 8192000000 10897729296 11368562688 1951215568 19.236 99 15 30 0 1971108384 15847184 4045632
21:00:59 111930 8192000000 10901928992 11366817792 1952268656 19.245 99 13 26 3 1968365520 12496608 3600256
21:03:52 79422 8192000000 10923888800 11405361152 1994158000 19.577 100 15 29 1 2012256640 14055744 4042896
21:06:12 77546 8192000000 10934095984 11398782976 1980477776 19.468 99 11 21 1 2002649872 18843648 3328448
21:08:30 146066 8192000000 10934818656 11400253440 1987573152 19.525 99 32 62 0 2006257168 10866864 7817152
21:12:01 79860 8192000000 10945315840 11417677824 1989800560 19.542 99 39 78 1 2015443504 14567984 11074960
21:14:21 30308 8192000000 10938683424 11399389184 1975662208 19.430 98 42 80 1 1999894560 12278736 11953616
21:16:05 31529 8192000000 10916372512 11393572864 1989915808 19.543 100 18 36 1 2007946320 12605088 5425424
21:17:53 127847 8192000000 10882600256 11363176448 1924075760 19.019 97 11 22 0 1934262416 7094464 3092192
21:21:03 82134 8192000000 10893424896 11387727872 1923731168 19.017 97 10 19 0 1945843088 19757952 2353968
21:23:44 31720 8192000000 10880553120 11350388736 1923553440 19.015 98 24 48 1 1939236448 9105360 6577648
21:25:19 58039 8192000000 10840427136 11312848896 1825402464 18.222 94 20 40 0 1840094864 9066000 5626400
21:27:20 47280 8192000000 10870337696 11339288576 1914053072 18.939 99 17 34 0 1931104368 12701968 4349328
21:29:08 85559 8192000000 10857488464 11324329984 1853960864 18.454 95 25 49 2 1876052480 15049488 7042128
21:31:33 64784 8192000000 10837923712 11308904448 1852273872 18.441 96 14 28 0 1869636944 13716672 3646400
21:33:38 46429 8192000000 10866487312 11308871680 1855828224 18.469 96 23 45 2 1869409184 7040960 6540000
21:35:26 18699 8192000000 10856678176 11293847552 1876209200 18.634 98 45 90 5 1899073616 10411120 12453296
21:36:48 111903 8192000000 10844016480 11313942528 1906848688 18.881 100 23 46 1 1921681872 8855680 5977504
21:39:41 14226 8192000000 10870253776 11324481536 1927305472 19.045 100 20 39 0 1942287632 9467360 5514800
21:40:56 62238 8192000000 10856167536 11331428352 1917895840 18.970 99 17 34 0 1934561136 12123248 4542048
21:42:59 47741 8192000000 10865573360 11329105920 1919304976 18.981 99 23 46 4 1939932992 13698464 6929552
21:44:48 58452 8192000000 10885886480 11361046528 1947009584 19.203 100 17 33 1 1968375312 16636672 4729056
21:46:54 76500 8192000000 10869095024 11356205056 1915769456 18.953 98 9 18 1 1931755648 13514960 2471232
21:49:11 32791 8192000000 10907250768 11356377088 1958259184 19.292 100 45 89 1 1978362160 8198896 11904080
21:50:48 54862 8192000000 10913561648 11384201216 1976423216 19.436 100 33 66 1 1999464720 14021264 9020240
21:52:43 134800 8192000000 10906130256 11381399552 1939430880 19.142 98 14 28 0 1958548720 15490864 3626976
21:55:59 53910 8192000000 10915379488 11379625984 1947418144 19.206 98 31 62 0 1970522320 14272960 8831216
21:57:57 141011 8192000000 10914970608 11390119936 1933726400 19.097 97 12 24 0 1950799728 14271040 2802288
22:01:19 63065 8192000000 10897416832 11383230464 1944193904 19.180 98 27 55 0 1970966464 18746224 8026336
22:03:29 79883 8192000000 10872074288 11354005504 1869022144 18.576 95 12 24 0 1882117824 9764288 3331392
22:05:49 67743 8192000000 10858986128 11342700544 1901733344 18.840 97 19 38 1 1922417504 14687312 5996848
22:07:59 27043 8192000000 10870007440 11340144640 1931446048 19.078 99 17 32 1 1942825664 6655248 4724368
22:09:33 66590 8192000000 10866796784 11345608704 1945178816 19.188 100 12 23 1 1957123792 8410912 3534064
22:13:57 73453 8192000000 10885360240 11360264192 1941178880 19.156 99 24 48 0 1956511008 8652304 6679824
22:16:10 51931 8192000000 10874900160 11348099072 1933374832 19.094 99 21 41 1 1949331936 9564144 6392960
22:18:03 76693 8192000000 10832799120 11291443200 1730227296 17.437 89 23 46 1 1751517968 14817680 6472992
22:20:25 46193 8192000000 10844828832 11299139584 1867452256 18.564 98 28 56 0 1886507776 11550912 7504608
22:22:11 68650 8192000000 10866101200 11364225024 1910447120 18.910 99 14 28 0 1940564944 26099808 4018016
22:24:20 109214 8192000000 10870450592 11335200768 1923244608 19.013 99 27 54 0 1946981584 15765872 7971104

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 11-10-2020 22:28:49 to 11-10-2020 22:29:04.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 152 v_add_invoice_to_stock 335544517
user_exc 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-10-2020 22:29:05 to 11-10-2020 22:29:07.

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          253049
MON$OLDEST_ACTIVE               254667
MON$OLDEST_SNAPSHOT             254667
MON$NEXT_TRANSACTION            254667
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                       2065248
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.33372 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.7.33372 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.7.33372 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.7.33372 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 ---
:88: 
:89: mscs-test-161.budzdorov.ru    Sun Oct 11 22:27:07 2020
:90:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:91: 
:92: 
:93: mscs-test-161.budzdorov.ru    Sun Oct 11 22:27:07 2020
:94:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:95: 
--- end of diff output ---

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

11.10.20 22:29:08. 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-11 22:29:10 No severe PSQL-related problems occured
11.10.20 22:29:11. End of report.