Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.10.2020 16:32
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 17271228 kB
MemAvailable 31556000 kB
Buffers 0 kB
Cached 14451836 kB
SwapCached 3064 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-29 16:30:40
DTS_END                         2020-10-29 16:30:40.5530
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(56:17)
IP                              ::1/54194
TRN_ID                          225350
ATT_ID                          673
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: 8769.42 24162 1052331
customer order: creation 159.98 376 19198
customer order: refuse 13.33 397 1599
order to supplier: creation 42.19 1462 5063
order to supplier: removal 3.23 142669 388
invoice (draft): creation 42.16 4211 5059
invoice (draft): removal 50.47 1087 6056
invoice accept: apply 38.57 20876 4628
invoice accept: cancel 45.39 1138 5447
customer reserve: creation 3614.54 201 433745
customer reserve: removal 4494.57 79 539348
realization accept: apply 75.03 228 9004
realization accept: cancel 71.46 137 8575
payment from customer: creation 54.30 153 6516
payment from customer: removal 5.04 91 605
payment to supplier: creation 44.31 175 5317
payment to supplier: removal 3.08 111 370
service: total inventory turnovers 4.46 1288 535
service: total monetary turnovers 6.46 33 775
service: aggregate perf. data 0.83 21310 99
service: refresh index statistics 0.03 287212 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 29-10-2020 16:32:28 to 29-10-2020 16:32:30.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2657
TEST_TIME 2 3298
TEST_TIME 3 2757
TEST_TIME 4 2800
TEST_TIME 5 2943
TEST_TIME 6 3714
TEST_TIME 7 3969
TEST_TIME 8 4192
TEST_TIME 9 4501
TEST_TIME 10 4997
TEST_TIME 11 4987
TEST_TIME 12 5347
TEST_TIME 13 6088
TEST_TIME 14 6149
TEST_TIME 15 6101
TEST_TIME 16 6181
TEST_TIME 17 6323
TEST_TIME 18 6476
TEST_TIME 19 6643
TEST_TIME 20 6840
TEST_TIME 21 7031
TEST_TIME 22 7048
TEST_TIME 23 7222
TEST_TIME 24 7282
TEST_TIME 25 7415
TEST_TIME 26 7529
TEST_TIME 27 7611
TEST_TIME 28 7467
TEST_TIME 29 7765
TEST_TIME 30 7824
TEST_TIME 31 8090
TEST_TIME 32 8423
TEST_TIME 33 8975
TEST_TIME 34 9328
TEST_TIME 35 9731
TEST_TIME 36 9935
TEST_TIME 37 10236
TEST_TIME 38 10392
TEST_TIME 39 10229
TEST_TIME 40 10077
TEST_TIME 41 9892
TEST_TIME 42 9802
TEST_TIME 43 9673
TEST_TIME 44 9745
TEST_TIME 45 9778
TEST_TIME 46 9640
TEST_TIME 47 9583
TEST_TIME 48 9500
TEST_TIME 49 9422
TEST_TIME 50 9437
TEST_TIME 51 9367
TEST_TIME 52 9246
TEST_TIME 53 9214
TEST_TIME 54 9156
TEST_TIME 55 9242
TEST_TIME 56 9349
TEST_TIME 57 9220
TEST_TIME 58 9237
TEST_TIME 59 9153
TEST_TIME 60 9076
TEST_TIME 61 9111
TEST_TIME 62 9086
TEST_TIME 63 9015
TEST_TIME 64 8972
TEST_TIME 65 8953
TEST_TIME 66 8971
TEST_TIME 67 9012
TEST_TIME 68 8950
TEST_TIME 69 9027
TEST_TIME 70 8996
TEST_TIME 71 9053
TEST_TIME 72 9087
TEST_TIME 73 9088
TEST_TIME 74 9143
TEST_TIME 75 9092
TEST_TIME 76 9186
TEST_TIME 77 9150
TEST_TIME 78 9211
TEST_TIME 79 9211
TEST_TIME 80 9123
TEST_TIME 81 9138
TEST_TIME 82 9112
TEST_TIME 83 9047
TEST_TIME 84 9062
TEST_TIME 85 9022
TEST_TIME 86 8979
TEST_TIME 87 9080
TEST_TIME 88 9023
TEST_TIME 89 8963
TEST_TIME 90 8993
TEST_TIME 91 8935
TEST_TIME 92 8945
TEST_TIME 93 8896
TEST_TIME 94 8863
TEST_TIME 95 8864
TEST_TIME 96 8884
TEST_TIME 97 8902
TEST_TIME 98 8959
TEST_TIME 99 8975
TEST_TIME 100 8952
TEST_TIME 101 9001
TEST_TIME 102 8991
TEST_TIME 103 8981
TEST_TIME 104 8982
TEST_TIME 105 9023
TEST_TIME 106 9060
TEST_TIME 107 9118
TEST_TIME 108 9103
TEST_TIME 109 9038
TEST_TIME 110 8993
TEST_TIME 111 8967
TEST_TIME 112 8921
TEST_TIME 113 8908
TEST_TIME 114 8895
TEST_TIME 115 8857
TEST_TIME 116 8856
TEST_TIME 117 8849
TEST_TIME 118 8811
TEST_TIME 119 8797
TEST_TIME 120 8772

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 29-10-2020 16:32:31 to 29-10-2020 16:32:35.

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 1452447 1452447 0 0.00 0 17584 7 0 0
doc_list_biud 1452447 1452447 0 0.00 0 36014 37 0 0
sp_add_invoice_to_stock 4698 4628 70 1.48 62 232484 20876 0 70
sp_cancel_adding_invoice 5447 5447 0 0.00 4 93901 1139 0 0
sp_cancel_client_order 1599 1599 0 0.00 5 7278 398 0 0
sp_cancel_customer_reserve 539348 539348 0 0.00 1 25150 80 0 0
sp_cancel_pay_from_customer 605 605 0 0.00 3 5485 92 0 0
sp_cancel_pay_to_supplier 370 370 0 0.00 2 5026 112 0 0
sp_cancel_supplier_invoice 6061 6056 5 0.08 2 25610 1087 0 5
sp_cancel_supplier_order 388 388 0 0.00 9 1287947 142669 0 0
sp_cancel_write_off 8575 8575 0 0.00 1 14632 138 0 0
sp_client_order 19198 19198 0 0.00 4 42388 377 0 0
sp_customer_reserve 433745 433745 0 0.00 0 78979 201 0 0
sp_fill_shopping_cart 29317 29317 0 0.00 1 17706 46 0 0
sp_fill_shopping_cart_clo_res 433743 433743 0 0.00 0 23965 16 0 0
sp_get_clo_for_invoice 4625 4625 0 0.00 0 26289 390 0 0
sp_kill_cost_storno 14997 14997 0 0.00 0 12409 85 0 0
sp_kill_qstorno_ret_qs2qd 1093715 1093715 0 0.00 0 25603 43 0 0
sp_kill_qty_storno 575042 575042 0 0.00 0 36012 93 0 0
sp_lock_selected_doc 18860 18860 0 0.00 0 4652 4 0 0
sp_make_cost_storno 25462 25462 0 0.00 0 17198 71 0 0
sp_multiply_rows_for_pdistr 25462 25462 0 0.00 0 17151 43 0 0
sp_multiply_rows_for_qdistr 19198 19198 0 0.00 0 42365 159 0 0
sp_pay_from_customer 6516 6516 0 0.00 4 11365 154 0 0
sp_pay_to_supplier 5317 5317 0 0.00 5 17830 175 0 0
sp_payment_common 11833 11833 0 0.00 2 10665 36 0 0
sp_qd_handle_on_cancel_clo 1599 1599 0 0.00 0 7234 366 0 0
sp_qd_handle_on_invoice_upd_sts 10072 10072 0 0.00 0 36010 489 0 0
sp_qd_handle_on_reserve_upd_sts 9004 9004 0 0.00 0 14425 71 0 0
sp_reserve_write_off 9004 9004 0 0.00 6 16857 229 0 0
sp_supplier_invoice 5059 5059 0 0.00 7 101592 4211 0 0
sp_supplier_order 5063 5063 0 0.00 5 26189 1462 0 0
srv_aggregate_perf_data 99 99 0 0.00 2942 192303 21310 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1027 169106 45104 0 0
srv_find_qd_qs_mism 1007343 1007343 0 0.00 0 11921 5 0 0
srv_make_invnt_saldo 535 535 0 0.00 49 140611 1289 0 0
srv_make_money_saldo 775 775 0 0.00 0 973 34 0 0
srv_recalc_idx_stat 4 4 0 0.00 127214 385215 287212 0 0
t$perf-abend:sp_add_invoice_to_stock 70 70 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4628 4628 0 0.00 0 11706 223 0 0
t$perf-norm:sp_cancel_adding_invoice 365 365 0 0.00 0 4347 173 0 0
t$perf-norm:sp_cancel_client_order 1599 1599 0 0.00 0 2339 16 0 0
t$perf-norm:sp_cancel_customer_reserve 611 611 0 0.00 0 2417 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 605 605 0 0.00 0 984 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 370 370 0 0.00 0 133 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 379 379 0 0.00 0 1669 28 0 0
t$perf-norm:sp_cancel_supplier_order 388 388 0 0.00 0 41069 2431 0 0
t$perf-norm:sp_cancel_write_off 914 914 0 0.00 0 1530 8 0 0
t$perf-norm:sp_client_order 19198 19198 0 0.00 0 8614 24 0 0
t$perf-norm:sp_customer_reserve 964 964 0 0.00 0 4392 16 0 0
t$perf-norm:sp_pay_from_customer 6516 6516 0 0.00 0 10324 14 0 0
t$perf-norm:sp_pay_to_supplier 5317 5317 0 0.00 0 10469 15 0 0
t$perf-norm:sp_reserve_write_off 9004 9004 0 0.00 0 35358 19 0 0
t$perf-norm:sp_supplier_invoice 5059 5059 0 0.00 0 9649 36 0 0
t$perf-norm:sp_supplier_order 5063 5063 0 0.00 0 7343 26 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 770 26 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 179 4 0 0
t$perf-norm:srv_make_invnt_saldo 535 535 0 0.00 0 1582 40 0 0
t$perf-norm:srv_make_money_saldo 775 775 0 0.00 0 1340 25 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 4 0 0
v_add_invoice_to_stock 9390 9250 140 1.49 0 5725 19 0 140
v_all_customers 34764 34764 0 0.00 0 16 1 0 0
v_all_suppliers 27950 27950 0 0.00 0 24 1 0 0
v_all_wares 38396 38396 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 730 730 0 0.00 0 119 6 0 0
v_cancel_client_order 3198 3198 0 0.00 0 5644 8 0 0
v_cancel_customer_prepayment 1210 1210 0 0.00 0 3548 9 0 0
v_cancel_customer_reserve 1222 1222 0 0.00 0 2203 6 0 0
v_cancel_payment_to_supplier 740 740 0 0.00 0 2483 8 0 0
v_cancel_supplier_invoice 768 758 10 1.30 0 2187 15 0 10
v_cancel_supplier_order 776 776 0 0.00 0 2867 11 0 0
v_cancel_write_off 1828 1828 0 0.00 0 1598 15 0 0
v_max_id_clo_ord 5063 5063 0 0.00 0 1109 2 0 0
v_max_id_clo_res 964 964 0 0.00 0 4029 12 0 0
v_max_id_ord_sup 5056 5056 0 0.00 0 7580 4 0 0
v_max_non_paid_invoice 1461 1461 0 0.00 0 669 3 0 0
v_max_non_paid_realizn 3539 3539 0 0.00 0 1347 4 0 0
v_min_id_clo_ord 5063 5063 0 0.00 0 4369 7 0 0
v_min_id_clo_res 964 964 0 0.00 0 9157 23 0 0
v_min_id_ord_sup 5056 5056 0 0.00 0 4669 7 0 0
v_min_non_paid_invoice 5317 5317 0 0.00 0 9418 26 0 0
v_min_non_paid_realizn 6516 6516 0 0.00 0 9417 27 0 0
v_our_firm 9586 9586 0 0.00 0 17 1 0 0
v_reserve_write_off 18008 18008 0 0.00 0 2149 5 0 0
x_lock_depdocs_on_canc_invoice 11124 11124 0 0.00 0 14107 150 0 0
x_lock_depdocs_on_canc_sup_ord 388 388 0 0.00 0 5439 476 0 0
x_make_qstorno_1000_1200 5063 5063 0 0.00 2 26093 1365 0 0
x_make_qstorno_1200_2000 5059 5059 0 0.00 3 101550 4044 0 0
x_make_qstorno_2100_3300 433745 433745 0 0.00 0 78977 181 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-10-2020 16:32:36 to 29-10-2020 16:32:40.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
14:00:43 1 8192000000 8798422528 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
14:01:44 42704 8192000000 10400983904 10723196928 1427582480 14.840 100 81 162 0 1460688640 6434512 26671648
14:03:32 29402 8192000000 10449899376 10781364224 1495072624 15.433 100 88 176 0 1533431616 9785072 28573920
14:05:05 20887 8192000000 10497069712 10842578944 1546874528 15.883 100 87 174 0 1585787712 11366624 27546560
14:06:31 14243 8192000000 10563380720 10924838912 1619898016 16.509 100 73 144 0 1653505328 11382464 22224848
14:07:52 8991 8192000000 10635428192 11006664704 1702370000 17.205 100 83 164 0 1739439456 12645520 24423936
14:09:02 3831 8192000000 10707926192 11084877824 1772028416 17.784 100 89 178 1 1811290064 14199056 25062592
14:10:08 4167 8192000000 10745909792 11129884672 1808666656 18.085 100 86 172 1 1845694592 13058368 23969568
14:11:14 1844 8192000000 10780027760 11165347840 1836675136 18.314 100 90 178 1 1874530848 12717424 25138288
14:12:17 12854 8192000000 10802904048 11192782848 1862866800 18.527 100 77 153 1 1896438736 11978144 21593792
14:13:31 8177 8192000000 10787826464 11203264512 1867615936 18.565 100 32 63 1 1883254336 6512944 9125456
14:14:40 11884 8192000000 10797391280 11229376512 1886052656 18.714 100 16 32 0 1896833216 6350064 4430496
14:15:53 5806 8192000000 10811463664 11244756992 1901668096 18.840 100 21 42 1 1913199792 5260368 6271328
14:17:03 61392 8192000000 10852873104 11260866560 1912070592 18.923 100 51 102 1 1932323344 5590544 14662208
14:19:05 6121 8192000000 10824376784 11257708544 1893707488 18.776 99 36 72 2 1910960528 6547920 10705120
14:20:13 8651 8192000000 10824450784 11263152128 1908011856 18.891 100 31 60 1 1926775792 9743392 9020544
14:21:25 45124 8192000000 10775320192 11196354560 1835973616 18.308 98 48 94 1 1856301248 5636064 14691568
14:23:13 7755 8192000000 10672231248 11075358720 1696120896 17.153 93 30 59 0 1711047280 5737600 9188784
14:24:21 2968 8192000000 10704883616 11113168896 1746361312 17.571 97 20 38 1 1756809776 4937200 5511264
14:25:25 12959 8192000000 10743112656 11152900096 1799639280 18.011 99 17 35 2 1809911760 5359312 4913168
14:26:44 20950 8192000000 10708654544 11132432384 1750142800 17.603 96 15 30 1 1759864512 5478624 4243088
14:28:07 4795 8192000000 10753695296 11180421120 1839477584 18.337 100 29 58 0 1854483936 6297872 8708480
14:29:13 11260 8192000000 10766286992 11199598592 1857199344 18.481 100 27 53 1 1870591888 5429584 7962960
14:30:26 13597 8192000000 10797297840 11231690752 1888724656 18.736 100 16 32 0 1897508592 4522944 4260992
14:32:02 16316 8192000000 10842101792 11264249856 1913088432 18.931 100 33 66 1 1931143408 8763200 9291776
14:33:22 64117 8192000000 10857002576 11292225536 1933739840 19.097 100 34 68 2 1955014496 10854944 10419712
14:35:35 13007 8192000000 10891111600 11316203520 1948729696 19.216 100 50 100 1 1978498544 15529248 14239600
14:37:00 9496 8192000000 10902334736 11329519616 1964026672 19.338 100 50 100 0 1994250016 15059856 15163488
14:38:17 6829 8192000000 10921633216 11347087360 1975804640 19.431 100 59 118 3 2009358848 16453088 17101120
14:39:36 4300 8192000000 10921375968 11353808896 1983027984 19.489 100 59 118 2 2017347280 17208752 17110544
14:40:44 8032 8192000000 10927247712 11361902592 1979104032 19.458 100 45 87 1 2015733760 22154944 14474784
14:42:05 1218 8192000000 10968388976 11385491456 1987889296 19.527 99 84 170 1 2037020496 24340432 24790768
14:43:10 4284 8192000000 10969981552 11396112384 2002789744 19.645 100 63 124 0 2050213264 28378688 19044832
14:44:21 3026 8192000000 10950448048 11383558144 1981715984 19.478 99 57 114 0 2022027440 23253312 17058144
14:45:29 5244 8192000000 10957627824 11398488064 2002699664 19.644 100 54 108 0 2045384544 26507776 16177104
14:46:39 6161 8192000000 10976103488 11406651392 2010335472 19.704 100 68 136 1 2062581728 31159952 21086304
14:47:51 3856 8192000000 10980565008 11399090176 1995621888 19.588 100 72 144 0 2058647376 40314720 22710768
14:49:06 10319 8192000000 10974990192 11413176320 2005559584 19.667 100 62 124 0 2064502848 39749760 19193504
14:50:23 2637 8192000000 10979783440 11390959616 1970078192 19.386 99 78 155 0 2040387552 46633264 23676096
14:51:32 10831 8192000000 10965495408 11392552960 1981712912 19.478 100 65 129 0 2047067680 44706784 20647984
14:52:50 1026 8192000000 10993839232 11403587584 1992407056 19.563 100 90 178 1 2071735824 49676048 29652720
14:53:57 1982 8192000000 10987007936 11399667712 1981734864 19.478 99 88 176 2 2061356640 51694448 27927328
14:55:08 3819 8192000000 10997135344 11406344192 1985212128 19.506 100 87 174 1 2072884992 59925328 27747536
14:56:17 1708 8192000000 10992956096 11407237120 1977082112 19.442 99 78 154 0 2061663056 59883232 24697712
14:57:24 5646 8192000000 10976656544 11408531456 1986901536 19.519 100 53 105 1 2061880128 57293792 17684800
14:58:34 3534 8192000000 11020310608 11432374272 2003596736 19.651 100 84 170 1 2096675840 66097056 26982048
14:59:42 4330 8192000000 11037803968 11462189056 2013210384 19.727 100 74 148 2 2115268528 77164272 24893872
15:01:00 5736 8192000000 11041029104 11475922944 2023106688 19.805 100 65 130 0 2125942224 80872816 21962720
15:02:18 7974 8192000000 10997341168 11436601344 1984618192 19.501 99 52 105 1 2071112784 68641856 17852736
15:04:22 25507 8192000000 10927369200 11359805440 1897594032 18.807 97 38 75 3 1962622736 52891632 12137072
15:05:50 44235 8192000000 10900822624 11341099008 1904355488 18.861 99 36 72 2 1956818672 39930736 12532448
15:07:35 22109 8192000000 10849150256 11318292480 1896012288 18.794 99 19 38 1 1928248768 26313632 5922848
15:09:02 27307 8192000000 10865948992 11309129728 1929733936 19.065 100 48 94 2 1946930800 5342592 11854272
15:10:34 137018 8192000000 10889594704 11353124864 1925368752 19.030 99 20 40 2 1940800736 9615456 5816528
15:14:14 169104 8192000000 10898673056 11357155328 1942098272 19.163 99 34 68 3 1968113600 16271568 9743760
15:18:04 46705 8192000000 10878754544 11329540096 1917217104 18.965 98 19 39 0 1930796640 8412576 5166960
15:19:59 65819 8192000000 10877772864 11349786624 1926531360 19.039 98 29 58 2 1947927120 12553696 8842064
15:22:09 54243 8192000000 10867245104 11327410176 1940305808 19.149 100 15 30 2 1954898464 10414960 4177696
15:24:04 60609 8192000000 10914056704 11361619968 1954552032 19.263 99 40 77 2 1979842736 13834368 11456336
15:26:07 82458 8192000000 10880020656 11356794880 1942585888 19.167 99 7 14 1 1957671520 13168864 1916768
15:28:30 68785 8192000000 10899394656 11379445760 1976824160 19.440 100 11 22 0 1995552032 15243152 3484720
15:30:38 51119 8192000000 10922708496 11364171776 1958593456 19.295 99 45 89 1 1984890304 13985728 12311120
15:32:39 44132 8192000000 10894296816 11372339200 1970731808 19.391 99 11 21 2 1980206272 6838256 2636208
15:34:53 77102 8192000000 10917476592 11400286208 1992577328 19.564 100 10 20 0 2003991728 8984048 2430352
15:37:10 46073 8192000000 10895572928 11373645824 1934272304 19.101 97 26 52 1 1951410400 9845648 7292448
15:39:01 52563 8192000000 10905317344 11386155008 1955769472 19.272 98 22 42 1 1971341584 9523296 6048816
15:41:11 83909 8192000000 10894583104 11372797952 1921660384 19.000 97 14 28 1 1941375952 16226864 3488704
15:43:36 74339 8192000000 10866254112 11337863168 1881776816 18.679 96 18 36 0 1902226048 15325184 5124048
15:45:51 107335 8192000000 10895729328 11350970368 1902476288 18.846 97 13 26 3 1921754336 15916928 3361120
15:48:44 83288 8192000000 10847858160 11317485568 1782627408 17.871 91 20 40 0 1802627552 14276336 5723808
15:51:34 103036 8192000000 10848446912 11321495552 1877019248 18.641 97 17 32 4 1894505680 12823312 4663120
15:54:18 89128 8192000000 10850253200 11325534208 1893904240 18.777 98 21 40 3 1919732144 18802656 7025248
15:56:47 75846 8192000000 10860990496 11347595264 1925121552 19.028 99 11 22 0 1952519728 24148736 3249440
15:59:04 88983 8192000000 10845257648 11326472192 1866349632 18.555 96 19 37 3 1886904752 15724080 4831040
16:02:31 69966 8192000000 10860470256 11336577024 1942542000 19.167 100 20 40 1 1960420000 12400272 5477728
16:04:42 66952 8192000000 10850694512 11327279104 1868020976 18.568 96 15 29 0 1880122752 7846400 4255376
16:07:02 55063 8192000000 10834264672 11304595456 1870939680 18.592 97 13 26 0 1891663088 16866336 3857072
16:09:14 52541 8192000000 10860303552 11325419520 1933972496 19.099 100 9 18 0 1945151776 8635232 2544048
16:11:17 64610 8192000000 10876597280 11349307392 1923378800 19.014 99 14 27 2 1937824352 10465376 3980176
16:13:27 52172 8192000000 10887479488 11345842176 1915990608 18.955 98 29 58 1 1934468768 10611888 7866272
16:15:24 133174 8192000000 10884288592 11360976896 1888257280 18.732 97 13 26 2 1912137216 20393392 3486544
16:18:37 147173 8192000000 10883684688 11358076928 1958893520 19.297 100 15 29 0 1983059936 19769600 4396816
16:22:05 60818 8192000000 10846350944 11321851904 1881596096 18.678 96 8 16 1 1891879568 8143968 2139504
16:24:06 56603 8192000000 10875625456 11344269312 1951199840 19.236 100 13 25 0 1962425120 7307872 3917408
16:26:07 25337 8192000000 10858022960 11335323648 1940130592 19.148 100 17 34 0 1955056544 9812896 5113056
16:27:55 61918 8192000000 10871561168 11348008960 1950208960 19.228 100 18 36 0 1961995632 6453056 5333616
16:30:04 40394 8192000000 10118605296 10522906624 1220153152 12.963 62 4 8 0 1234015200 13087248 774800

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 29-10-2020 16:32:41 to 29-10-2020 16:32:56.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 70 v_add_invoice_to_stock 335544517
user_exc 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 29-10-2020 16:32:57 to 29-10-2020 16:32:59.

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          224687
MON$OLDEST_ACTIVE               225654
MON$OLDEST_SNAPSHOT             225654
MON$NEXT_TRANSACTION            225654
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                       2062048
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.33382 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.7.33382 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.7.33382 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.7.33382 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Thu Oct 29 16:30:59 2020
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:9: 
--- end of diff output ---

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

29.10.20 16:33:00. 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-29 16:33:03 No severe PSQL-related problems occured
29.10.20 16:33:04. End of report.