Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.10.2020 19: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 17411072 kB
MemAvailable 31523932 kB
Buffers 0 kB
Cached 14268344 kB
SwapCached 3044 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-25 19:26:55
DTS_END                         2020-10-25 19:26:55.9210
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/55964
TRN_ID                          254746
ATT_ID                          648
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: 9862.76 20715 1183531
customer order: creation 181.76 335 21811
customer order: refuse 15.34 253 1841
order to supplier: creation 49.34 1179 5921
order to supplier: removal 3.66 81278 439
invoice (draft): creation 49.05 2782 5886
invoice (draft): removal 56.36 681 6763
invoice accept: apply 45.16 16511 5419
invoice accept: cancel 50.91 619 6109
customer reserve: creation 4135.65 166 496278
customer reserve: removal 4962.08 47 595450
realization accept: apply 88.62 167 10634
realization accept: cancel 87.27 94 10472
payment from customer: creation 63.17 101 7580
payment from customer: removal 6.19 64 743
payment to supplier: creation 52.35 114 6282
payment to supplier: removal 3.51 61 421
service: total inventory turnovers 4.98 1019 598
service: total monetary turnovers 6.52 34 782
service: aggregate perf. data 0.83 20205 99
service: refresh index statistics 0.03 288585 3

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 25-10-2020 19:27:37 to 25-10-2020 19:27:39.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3267
TEST_TIME 2 5074
TEST_TIME 3 5611
TEST_TIME 4 6182
TEST_TIME 5 6292
TEST_TIME 6 6768
TEST_TIME 7 7214
TEST_TIME 8 7079
TEST_TIME 9 7003
TEST_TIME 10 7202
TEST_TIME 11 7377
TEST_TIME 12 7954
TEST_TIME 13 7966
TEST_TIME 14 8638
TEST_TIME 15 8708
TEST_TIME 16 8789
TEST_TIME 17 9215
TEST_TIME 18 9502
TEST_TIME 19 9556
TEST_TIME 20 9611
TEST_TIME 21 9842
TEST_TIME 22 10423
TEST_TIME 23 11581
TEST_TIME 24 11730
TEST_TIME 25 11538
TEST_TIME 26 11330
TEST_TIME 27 11057
TEST_TIME 28 10739
TEST_TIME 29 10845
TEST_TIME 30 10763
TEST_TIME 31 10702
TEST_TIME 32 10704
TEST_TIME 33 10588
TEST_TIME 34 10496
TEST_TIME 35 10461
TEST_TIME 36 10423
TEST_TIME 37 10236
TEST_TIME 38 10109
TEST_TIME 39 10327
TEST_TIME 40 10209
TEST_TIME 41 10175
TEST_TIME 42 10174
TEST_TIME 43 10183
TEST_TIME 44 10182
TEST_TIME 45 10147
TEST_TIME 46 10176
TEST_TIME 47 10132
TEST_TIME 48 10254
TEST_TIME 49 10273
TEST_TIME 50 10482
TEST_TIME 51 10375
TEST_TIME 52 10455
TEST_TIME 53 10474
TEST_TIME 54 10439
TEST_TIME 55 10514
TEST_TIME 56 10432
TEST_TIME 57 10381
TEST_TIME 58 10307
TEST_TIME 59 10272
TEST_TIME 60 10181
TEST_TIME 61 10105
TEST_TIME 62 10015
TEST_TIME 63 9969
TEST_TIME 64 10049
TEST_TIME 65 10008
TEST_TIME 66 10105
TEST_TIME 67 10043
TEST_TIME 68 10051
TEST_TIME 69 10021
TEST_TIME 70 10122
TEST_TIME 71 10107
TEST_TIME 72 10161
TEST_TIME 73 10177
TEST_TIME 74 10257
TEST_TIME 75 10306
TEST_TIME 76 10285
TEST_TIME 77 10272
TEST_TIME 78 10154
TEST_TIME 79 10078
TEST_TIME 80 10107
TEST_TIME 81 10040
TEST_TIME 82 10048
TEST_TIME 83 10005
TEST_TIME 84 9994
TEST_TIME 85 9933
TEST_TIME 86 9923
TEST_TIME 87 9881
TEST_TIME 88 9892
TEST_TIME 89 9890
TEST_TIME 90 9895
TEST_TIME 91 9868
TEST_TIME 92 9922
TEST_TIME 93 10003
TEST_TIME 94 9959
TEST_TIME 95 10012
TEST_TIME 96 10009
TEST_TIME 97 9994
TEST_TIME 98 10026
TEST_TIME 99 10092
TEST_TIME 100 10042
TEST_TIME 101 10086
TEST_TIME 102 10005
TEST_TIME 103 9939
TEST_TIME 104 9952
TEST_TIME 105 9922
TEST_TIME 106 9891
TEST_TIME 107 9841
TEST_TIME 108 9830
TEST_TIME 109 9805
TEST_TIME 110 9800
TEST_TIME 111 9760
TEST_TIME 112 9810
TEST_TIME 113 9799
TEST_TIME 114 9801
TEST_TIME 115 9824
TEST_TIME 116 9823
TEST_TIME 117 9836
TEST_TIME 118 9850
TEST_TIME 119 9839
TEST_TIME 120 9870

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 25-10-2020 19:27:41 to 25-10-2020 19:27:44.

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 1642753 1642753 0 0.00 0 20364 6 0 0
doc_list_biud 1642752 1642752 0 0.00 0 23859 22 0 0
sp_add_invoice_to_stock 5564 5419 145 2.60 34 170079 16512 0 145
sp_cancel_adding_invoice 6109 6109 0 0.00 5 46708 619 0 0
sp_cancel_client_order 1841 1841 0 0.00 4 6910 254 0 0
sp_cancel_customer_reserve 595450 595450 0 0.00 1 16394 47 0 0
sp_cancel_pay_from_customer 743 743 0 0.00 2 2397 65 0 0
sp_cancel_pay_to_supplier 421 421 0 0.00 3 4167 61 0 0
sp_cancel_supplier_invoice 6775 6763 12 0.17 2 23937 682 0 12
sp_cancel_supplier_order 439 439 0 0.00 6 910338 81278 0 0
sp_cancel_write_off 10472 10472 0 0.00 1 10909 94 0 0
sp_client_order 21811 21811 0 0.00 4 24140 336 0 0
sp_customer_reserve 496278 496278 0 0.00 0 55589 167 0 0
sp_fill_shopping_cart 33614 33614 0 0.00 1 12987 35 0 0
sp_fill_shopping_cart_clo_res 496251 496251 0 0.00 0 21006 12 0 0
sp_get_clo_for_invoice 5389 5389 0 0.00 0 28512 288 0 0
sp_kill_cost_storno 17745 17745 0 0.00 0 7852 41 0 0
sp_kill_qstorno_ret_qs2qd 1208574 1208574 0 0.00 0 23858 26 0 0
sp_kill_qty_storno 637097 637097 0 0.00 0 23859 56 0 0
sp_lock_selected_doc 22130 22130 0 0.00 0 8034 5 0 0
sp_make_cost_storno 29885 29885 0 0.00 0 18231 47 0 0
sp_multiply_rows_for_pdistr 29885 29885 0 0.00 0 7391 23 0 0
sp_multiply_rows_for_qdistr 21811 21811 0 0.00 0 11569 156 0 0
sp_pay_from_customer 7580 7580 0 0.00 4 15970 102 0 0
sp_pay_to_supplier 6282 6282 0 0.00 4 18459 114 0 0
sp_payment_common 13862 13862 0 0.00 1 9006 28 0 0
sp_qd_handle_on_cancel_clo 1841 1841 0 0.00 0 6902 225 0 0
sp_qd_handle_on_invoice_upd_sts 11498 11498 0 0.00 0 21283 266 0 0
sp_qd_handle_on_reserve_upd_sts 10634 10634 0 0.00 0 17027 61 0 0
sp_reserve_write_off 10634 10634 0 0.00 6 17788 167 0 0
sp_supplier_invoice 5886 5886 0 0.00 5 75929 2782 0 0
sp_supplier_order 5921 5921 0 0.00 5 60197 1180 0 0
srv_aggregate_perf_data 99 99 0 0.00 2830 321924 20206 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 2237 111234 49415 0 0
srv_find_qd_qs_mism 1132425 1132425 0 0.00 0 20361 5 0 0
srv_make_invnt_saldo 598 598 0 0.00 30 32308 1020 0 0
srv_make_money_saldo 782 782 0 0.00 1 8110 34 0 0
srv_recalc_idx_stat 3 3 0 0.00 197045 400037 288586 0 0
t$perf-abend:sp_add_invoice_to_stock 145 145 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5419 5419 0 0.00 0 14046 135 0 0
t$perf-norm:sp_cancel_adding_invoice 414 414 0 0.00 0 3211 80 0 0
t$perf-norm:sp_cancel_client_order 1841 1841 0 0.00 0 1925 10 0 0
t$perf-norm:sp_cancel_customer_reserve 733 733 0 0.00 0 1396 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 743 743 0 0.00 0 2855 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 421 421 0 0.00 0 419 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 417 417 0 0.00 0 4947 36 0 0
t$perf-norm:sp_cancel_supplier_order 439 439 0 0.00 0 14566 1182 0 0
t$perf-norm:sp_cancel_write_off 1104 1104 0 0.00 0 857 2 0 0
t$perf-norm:sp_client_order 21811 21811 0 0.00 0 5006 12 0 0
t$perf-norm:sp_customer_reserve 1032 1032 0 0.00 0 3760 10 0 0
t$perf-norm:sp_pay_from_customer 7580 7580 0 0.00 0 4284 7 0 0
t$perf-norm:sp_pay_to_supplier 6282 6282 0 0.00 0 4406 8 0 0
t$perf-norm:sp_reserve_write_off 10634 10634 0 0.00 0 5628 10 0 0
t$perf-norm:sp_supplier_invoice 5886 5886 0 0.00 0 5413 17 0 0
t$perf-norm:sp_supplier_order 5921 5921 0 0.00 0 7713 12 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 306 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 2115 36 0 0
t$perf-norm:srv_make_invnt_saldo 598 598 0 0.00 0 2002 14 0 0
t$perf-norm:srv_make_money_saldo 782 782 0 0.00 0 892 9 0 0
t$perf-norm:srv_recalc_idx_stat 3 3 0 0.00 1 1728 577 0 0
v_add_invoice_to_stock 11058 10768 290 2.62 0 7583 18 0 290
v_all_customers 39626 39626 0 0.00 0 74 0 0 0
v_all_suppliers 32378 32378 0 0.00 0 96 1 0 0
v_all_wares 43622 43622 0 0.00 0 115 1 0 0
v_cancel_adding_invoice 828 828 0 0.00 0 3278 13 0 0
v_cancel_client_order 3682 3682 0 0.00 0 3872 8 0 0
v_cancel_customer_prepayment 1486 1486 0 0.00 0 2373 10 0 0
v_cancel_customer_reserve 1466 1466 0 0.00 0 1939 6 0 0
v_cancel_payment_to_supplier 842 842 0 0.00 0 2865 11 0 0
v_cancel_supplier_invoice 858 834 24 2.79 0 2724 31 0 24
v_cancel_supplier_order 878 878 0 0.00 0 2470 7 0 0
v_cancel_write_off 2208 2208 0 0.00 0 9238 18 0 0
v_max_id_clo_ord 5919 5919 0 0.00 0 8017 2 0 0
v_max_id_clo_res 1032 1032 0 0.00 0 2571 13 0 0
v_max_id_ord_sup 5884 5884 0 0.00 0 4614 3 0 0
v_max_non_paid_invoice 1896 1896 0 0.00 0 1877 3 0 0
v_max_non_paid_realizn 4103 4103 0 0.00 0 3201 4 0 0
v_min_id_clo_ord 5919 5919 0 0.00 0 2153 5 0 0
v_min_id_clo_res 1032 1032 0 0.00 0 1036 3 0 0
v_min_id_ord_sup 5884 5884 0 0.00 0 5703 5 0 0
v_min_non_paid_invoice 6282 6282 0 0.00 0 7127 18 0 0
v_min_non_paid_realizn 7580 7580 0 0.00 0 9001 16 0 0
v_our_firm 10950 10950 0 0.00 0 126 1 0 0
v_reserve_write_off 21267 21267 0 0.00 0 7274 8 0 0
x_lock_depdocs_on_canc_invoice 12455 12455 0 0.00 0 10605 79 0 0
x_lock_depdocs_on_canc_sup_ord 439 439 0 0.00 0 3731 232 0 0
x_make_qstorno_1000_1200 5921 5921 0 0.00 1 60179 1108 0 0
x_make_qstorno_1200_2000 5886 5886 0 0.00 3 75922 2656 0 0
x_make_qstorno_2100_3300 496278 496278 0 0.00 0 55588 153 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-10-2020 19:27:45 to 25-10-2020 19:27:49.

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
16:56:59 1 8192000000 8798422528 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
16:58:00 21900 8192000000 10378080064 10699190272 1425245808 14.819 100 83 166 1 1457360864 5823312 26291744
16:59:33 16016 8192000000 10462655216 10796589056 1503771776 15.509 100 89 177 0 1540353136 8465248 28116112
17:00:52 20878 8192000000 10513955824 10864197632 1570350944 16.085 100 84 169 1 1606734896 10677792 25706160
17:02:18 7520 8192000000 10584689392 10949926912 1647267872 16.741 100 80 160 2 1683640800 12534304 23838624
17:03:30 7116 8192000000 10645488000 11016597504 1704955808 17.227 100 74 148 1 1738891392 12620720 21314864
17:04:42 8952 8192000000 10693644208 11080749056 1763827760 17.716 100 70 138 2 1795248256 12245936 19174560
17:05:52 2188 8192000000 10777618944 11161460736 1832876224 18.283 100 91 180 1 1871554704 13219984 25458496
17:06:56 1947 8192000000 10812411744 11199885312 1867850912 18.567 100 88 174 3 1904300864 11979424 24470528
17:07:58 3030 8192000000 10797738624 11212730368 1873473136 18.612 100 52 106 1 1896903952 8657984 14772832
17:09:03 8600 8192000000 10790374720 11220541440 1883198512 18.691 100 22 44 4 1895580096 6397856 5983728
17:10:12 18318 8192000000 10811737920 11240456192 1904998256 18.866 100 27 55 1 1916810480 5319376 6492848
17:11:34 18789 8192000000 10813596096 11251367936 1903498048 18.854 100 18 34 1 1914034864 5644624 4892192
17:12:53 11178 8192000000 10827587520 11262574592 1911040784 18.915 100 18 36 3 1922508448 6564528 4903136
17:14:06 6764 8192000000 10832308032 11267428352 1914438976 18.942 100 22 44 1 1926329344 5527088 6363280
17:15:16 67608 8192000000 10763444016 11199746048 1773780304 17.798 93 31 60 0 1787224304 4511440 8932560
17:17:24 3871 8192000000 10766271792 11185111040 1841919088 18.356 100 27 54 3 1860027296 10492000 7616208
17:18:28 7409 8192000000 10729646032 11146805248 1794946352 17.972 99 24 48 3 1808381360 6185696 7249312
17:19:38 8930 8192000000 10691412832 11102859264 1751080768 17.611 98 37 74 1 1767934752 5509472 11344512
17:20:49 21839 8192000000 10716325088 11132788736 1741408336 17.530 96 38 77 0 1759008096 7420896 10178864
17:22:11 4154 8192000000 10752714992 11175006208 1825850288 18.225 99 47 96 1 1846834928 7234192 13750448
17:23:15 6119 8192000000 10758486576 11186847744 1846047376 18.390 100 29 58 3 1859909328 5545664 8316288
17:24:26 12525 8192000000 10777605520 11208224768 1860161760 18.505 100 17 34 3 1874432512 9738816 4531936
17:25:38 29977 8192000000 10830057936 11226214400 1878973536 18.657 100 65 135 3 1905078768 4764224 21341008
17:27:11 22937 8192000000 10842508016 11258441728 1909954256 18.906 100 57 114 1 1935065536 9099696 16011584
17:28:38 14101 8192000000 10851799040 11277230080 1925286336 19.029 100 53 105 0 1950563648 9999456 15277856
17:29:55 8665 8192000000 10889644832 11310354432 1947459248 19.206 100 53 106 1 1974670544 10967984 16243312
17:31:06 30905 8192000000 10889567456 11317284864 1948704640 19.216 100 56 109 0 1977208032 13019744 15483648
17:32:44 4402 8192000000 10874205488 11298316288 1931712224 19.081 99 61 122 1 1966278688 16079408 18487056
17:33:56 3288 8192000000 10899109200 11317338112 1949004928 19.219 100 69 139 1 1984901936 16458640 19438368
17:35:02 6169 8192000000 10909458400 11337531392 1960938448 19.313 100 55 110 0 1996543456 19400784 16204224
17:36:10 15072 8192000000 10928709792 11358466048 1959530352 19.302 99 59 116 1 2001667712 24498448 17638912
17:37:33 2722 8192000000 10944170784 11368280064 1975932432 19.432 100 60 118 0 2026464560 32530016 18002112
17:38:40 2236 8192000000 10922994112 11331964928 1931241840 19.077 98 83 160 4 1988084000 31763712 25078448
17:39:45 3162 8192000000 10903292144 11322957824 1929826448 19.065 99 64 129 1 1987476304 37714944 19934912
17:40:52 5782 8192000000 10899874576 11324563456 1928820624 19.057 99 41 81 3 1972336496 30712368 12803504
17:42:00 5973 8192000000 10937438032 11357265920 1954475376 19.262 100 54 106 2 2013505168 40719088 18310704
17:43:12 17556 8192000000 10907470416 11340828672 1937840144 19.130 100 30 60 0 1989489184 41390928 10258112
17:44:38 32844 8192000000 10913536144 11350573056 1936020144 19.115 99 38 76 0 1988230560 40152240 12058176
17:46:13 15417 8192000000 10911702064 11353571328 1922019472 19.003 99 31 59 1 1986140880 53549328 10572080
17:47:47 37936 8192000000 10898548432 11353874432 1930996736 19.075 100 22 44 2 1993701776 54422848 8282192
17:49:33 46967 8192000000 10852995312 11314278400 1859839440 18.502 96 11 22 1 1901876144 38116800 3919904
17:51:21 45927 8192000000 10870100320 11316097024 1912355072 18.926 99 31 63 2 1929484256 8354656 8774528
17:53:14 74785 8192000000 10878345200 11331391488 1931638208 19.080 99 34 67 0 1956624800 15272864 9713728
17:55:35 75250 8192000000 10894808608 11347783680 1955732032 19.272 100 25 50 1 1970065456 7496848 6836576
17:57:50 32324 8192000000 10926177408 11356487680 1958557568 19.295 99 45 88 1 1979600464 8517072 12525824
17:59:24 51906 8192000000 10912062160 11381813248 1966459856 19.357 99 30 60 1 1994287024 18710256 9116912
18:01:41 74508 8192000000 10901131280 11375284224 1945947232 19.194 98 13 26 1 1970139264 20393728 3798304
18:03:58 24757 8192000000 10908070112 11374370816 1963158960 19.331 99 24 46 3 1988812912 18918512 6735440
18:05:23 29629 8192000000 10896449152 11362856960 1971301920 19.396 100 38 75 0 1993219808 10732400 11185488
18:06:56 97889 8192000000 10886509600 11360399360 1928705632 19.057 97 32 63 2 1951151632 13101472 9344528
18:09:36 57068 8192000000 10905087728 11383934976 1988832512 19.535 100 18 36 0 2007711552 14193376 4685664
18:11:35 77261 8192000000 10918943920 11393785856 1953562256 19.255 98 18 36 0 1973282304 14694192 5025856
18:13:57 60110 8192000000 10934024544 11410534400 1975876224 19.432 99 16 32 1 2005235712 24366320 4993168
18:16:04 54413 8192000000 10884153824 11365896192 1929948048 19.066 98 10 20 0 1949566336 16924304 2693984
18:17:58 58550 8192000000 10902133232 11349610496 1913784768 18.937 97 50 101 1 1937438912 8944496 14709648
18:19:59 63321 8192000000 10848166304 11304382464 1806716128 18.069 93 24 48 2 1827658384 13826944 7115312
18:22:03 107997 8192000000 10828954496 11292028928 1863244608 18.530 97 28 56 0 1888128880 16090816 8793456
18:24:51 20709 8192000000 10838059504 11294199808 1908986688 18.899 99 42 84 1 1930892448 9921376 11984384
18:26:14 60861 8192000000 10852211728 11321290752 1896192256 18.796 97 11 22 1 1909145968 9586064 3367648
18:28:15 65378 8192000000 10855028672 11323117568 1904986512 18.866 98 14 28 0 1919439808 10450768 4002528
18:31:15 74335 8192000000 10844777472 11317506048 1886190144 18.715 98 13 27 0 1903782528 13623088 3969296
18:33:34 39390 8192000000 10844917216 11304386560 1891509056 18.758 97 21 42 0 1907924624 10398720 6016848
18:35:16 49608 8192000000 10855758112 11325214720 1893957568 18.778 98 15 30 0 1913608528 15255072 4395888
18:37:41 66871 8192000000 10863699584 11326390272 1915130960 18.948 99 21 40 1 1937292288 15565376 6595952
18:39:53 104840 8192000000 10902103248 11371524096 1909421472 18.902 98 17 34 2 1934543744 20119088 5003184
18:42:45 106549 8192000000 10873923248 11346767872 1903290848 18.853 97 17 34 1 1925170176 16963648 4915680
18:45:44 74242 8192000000 10875704992 11359952896 1942300624 19.165 99 20 40 0 1959512576 11829248 5382704
18:48:00 86471 8192000000 10875797600 11362889728 1938858176 19.138 99 6 12 0 1950544896 9937280 1749440
18:50:27 36422 8192000000 10870639328 11335757824 1915152032 18.948 98 13 26 2 1928002304 9011136 3839136
18:52:06 57954 8192000000 10870042400 11358326784 1953166880 19.252 100 9 18 0 1967007248 11299680 2540688
18:54:04 37600 8192000000 10894915712 11364704256 1954875584 19.265 99 40 79 0 1974786544 8643776 11267184
18:55:42 50544 8192000000 10891516832 11364036608 1937835488 19.129 98 13 26 2 1948701328 7210928 3654912
18:57:34 67480 8192000000 10891150272 11367477248 1933741808 19.097 98 18 36 2 1952835584 14263376 4830400
18:59:43 59520 8192000000 10895955360 11349442560 1874952160 18.624 95 20 40 0 1888553008 8268256 5332592
19:01:51 57203 8192000000 10904602336 11366690816 1947370432 19.206 99 22 44 0 1965699552 12530208 5798912
19:03:53 43490 8192000000 10901505792 11366404096 1938683456 19.136 98 35 70 2 1960099632 11482752 9933424
19:05:45 82864 8192000000 10897281456 11354673152 1891798544 18.760 96 23 46 3 1917318128 19644336 5875248
19:09:00 85225 8192000000 10886711936 11356733440 1940151712 19.148 98 22 45 1 1954555888 7798352 6605824
19:11:31 72864 8192000000 10868682048 11343917056 1901891936 18.842 97 13 26 0 1914151680 8786896 3472848
19:13:45 111234 8192000000 10865980592 11348733952 1933137072 19.092 99 17 35 1 1949038832 10994544 4907216
19:16:46 50958 8192000000 10879583024 11344297984 1901134992 18.835 97 16 32 0 1915082384 9880672 4066720
19:19:02 77830 8192000000 10870155584 11319185408 1870903216 18.592 96 20 40 0 1886774368 10225008 5646144
19:21:21 57628 8192000000 10868090336 11331031040 1911074144 18.915 98 26 52 0 1928739264 10824816 6840304
19:23:22 57763 8192000000 10858036304 11317694464 1913369088 18.934 99 23 46 1 1931096416 11678608 6048720
19:25:20 45999 8192000000 10832574368 11290071040 1836106848 18.309 95 18 36 1 1851048256 9927504 5013904

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 25-10-2020 19:27:51 to 25-10-2020 19:28:06.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 145 v_add_invoice_to_stock 335544517
user_exc 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 25-10-2020 19:28:06 to 25-10-2020 19:28:08.

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          253148
MON$OLDEST_ACTIVE               254867
MON$OLDEST_SNAPSHOT             254867
MON$NEXT_TRANSACTION            254867
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                       2034272
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.33380 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.7.33380 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.7.33380 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.7.33380 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 ---
:67: 
:68: mscs-test-161.budzdorov.ru    Sun Oct 25 19:26:58 2020
:69:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:70: 
--- end of diff output ---

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

25.10.20 19:28:09. 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-25 19:28:12 No severe PSQL-related problems occured
25.10.20 19:28:13. End of report.