Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.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 17700344 kB
MemAvailable 31533764 kB
Buffers 0 kB
Cached 13980372 kB
SwapCached 3048 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-24 19:26:57
DTS_END                         2020-10-24 19:26:57.1950
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(56:17)
IP                              ::1/40040
TRN_ID                          244793
ATT_ID                          707
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: 9265.29 17002 1111835
customer order: creation 176.09 345 21131
customer order: refuse 15.18 303 1821
order to supplier: creation 45.00 1173 5400
order to supplier: removal 3.41 73036 409
invoice (draft): creation 46.91 3234 5629
invoice (draft): removal 52.09 649 6251
invoice accept: apply 42.91 16808 5149
invoice accept: cancel 46.68 663 5602
customer reserve: creation 3892.46 171 467095
customer reserve: removal 4650.13 41 558015
realization accept: apply 83.08 172 9969
realization accept: cancel 83.85 92 10062
payment from customer: creation 58.20 115 6984
payment from customer: removal 5.69 55 683
payment to supplier: creation 48.88 132 5865
payment to supplier: removal 3.50 58 420
service: total inventory turnovers 4.36 2200 523
service: total monetary turnovers 6.08 20 729
service: aggregate perf. data 0.78 21100 94
service: refresh index statistics 0.03 219669 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2542
TEST_TIME 2 4100
TEST_TIME 3 5781
TEST_TIME 4 5769
TEST_TIME 5 5460
TEST_TIME 6 6453
TEST_TIME 7 6626
TEST_TIME 8 6829
TEST_TIME 9 6670
TEST_TIME 10 7370
TEST_TIME 11 7808
TEST_TIME 12 8264
TEST_TIME 13 8110
TEST_TIME 14 8443
TEST_TIME 15 8638
TEST_TIME 16 8986
TEST_TIME 17 10403
TEST_TIME 18 10487
TEST_TIME 19 10072
TEST_TIME 20 10171
TEST_TIME 21 10297
TEST_TIME 22 9996
TEST_TIME 23 9956
TEST_TIME 24 10162
TEST_TIME 25 9969
TEST_TIME 26 10114
TEST_TIME 27 9932
TEST_TIME 28 9861
TEST_TIME 29 9690
TEST_TIME 30 9722
TEST_TIME 31 9511
TEST_TIME 32 9615
TEST_TIME 33 9532
TEST_TIME 34 9308
TEST_TIME 35 9718
TEST_TIME 36 9650
TEST_TIME 37 9705
TEST_TIME 38 9713
TEST_TIME 39 9725
TEST_TIME 40 9661
TEST_TIME 41 9772
TEST_TIME 42 9876
TEST_TIME 43 9841
TEST_TIME 44 9847
TEST_TIME 45 9789
TEST_TIME 46 9734
TEST_TIME 47 9709
TEST_TIME 48 9858
TEST_TIME 49 9721
TEST_TIME 50 9691
TEST_TIME 51 9655
TEST_TIME 52 9571
TEST_TIME 53 9602
TEST_TIME 54 9623
TEST_TIME 55 9541
TEST_TIME 56 9506
TEST_TIME 57 9454
TEST_TIME 58 9328
TEST_TIME 59 9287
TEST_TIME 60 9251
TEST_TIME 61 9290
TEST_TIME 62 9312
TEST_TIME 63 9321
TEST_TIME 64 9350
TEST_TIME 65 9303
TEST_TIME 66 9399
TEST_TIME 67 9463
TEST_TIME 68 9460
TEST_TIME 69 9521
TEST_TIME 70 9476
TEST_TIME 71 9515
TEST_TIME 72 9525
TEST_TIME 73 9547
TEST_TIME 74 9566
TEST_TIME 75 9560
TEST_TIME 76 9613
TEST_TIME 77 9507
TEST_TIME 78 9465
TEST_TIME 79 9385
TEST_TIME 80 9352
TEST_TIME 81 9315
TEST_TIME 82 9248
TEST_TIME 83 9200
TEST_TIME 84 9166
TEST_TIME 85 9272
TEST_TIME 86 9288
TEST_TIME 87 9278
TEST_TIME 88 9287
TEST_TIME 89 9298
TEST_TIME 90 9260
TEST_TIME 91 9323
TEST_TIME 92 9311
TEST_TIME 93 9332
TEST_TIME 94 9295
TEST_TIME 95 9366
TEST_TIME 96 9344
TEST_TIME 97 9386
TEST_TIME 98 9468
TEST_TIME 99 9468
TEST_TIME 100 9390
TEST_TIME 101 9353
TEST_TIME 102 9336
TEST_TIME 103 9295
TEST_TIME 104 9248
TEST_TIME 105 9240
TEST_TIME 106 9215
TEST_TIME 107 9154
TEST_TIME 108 9184
TEST_TIME 109 9174
TEST_TIME 110 9188
TEST_TIME 111 9169
TEST_TIME 112 9194
TEST_TIME 113 9205
TEST_TIME 114 9276
TEST_TIME 115 9312
TEST_TIME 116 9314
TEST_TIME 117 9283
TEST_TIME 118 9292
TEST_TIME 119 9300
TEST_TIME 120 9268

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 24-10-2020 19:27:38 to 24-10-2020 19:27:42.

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 1544237 1544237 0 0.00 0 30617 6 0 0
doc_list_biud 1544237 1544237 0 0.00 0 39421 20 0 0
sp_add_invoice_to_stock 5210 5149 61 1.17 28 234155 16809 0 61
sp_cancel_adding_invoice 5602 5602 0 0.00 3 63798 664 0 0
sp_cancel_client_order 1821 1821 0 0.00 4 17309 303 0 0
sp_cancel_customer_reserve 558015 558015 0 0.00 1 39423 41 0 0
sp_cancel_pay_from_customer 683 683 0 0.00 3 7578 56 0 0
sp_cancel_pay_to_supplier 420 420 0 0.00 3 3922 58 0 0
sp_cancel_supplier_invoice 6259 6251 8 0.12 2 34981 649 0 8
sp_cancel_supplier_order 409 409 0 0.00 10 661695 73036 0 0
sp_cancel_write_off 10062 10062 0 0.00 1 7649 93 0 0
sp_client_order 21131 21131 0 0.00 3 39306 346 0 0
sp_customer_reserve 467095 467095 0 0.00 0 94694 172 0 0
sp_fill_shopping_cart 32160 32160 0 0.00 1 14512 35 0 0
sp_fill_shopping_cart_clo_res 467093 467093 0 0.00 0 17703 13 0 0
sp_get_clo_for_invoice 5143 5143 0 0.00 0 30956 299 0 0
sp_kill_cost_storno 16767 16767 0 0.00 0 30607 44 0 0
sp_kill_qstorno_ret_qs2qd 1132752 1132752 0 0.00 0 39418 23 0 0
sp_kill_qty_storno 597272 597272 0 0.00 0 39421 50 0 0
sp_lock_selected_doc 21012 21012 0 0.00 0 6734 4 0 0
sp_make_cost_storno 27961 27961 0 0.00 0 13284 48 0 0
sp_multiply_rows_for_pdistr 27961 27961 0 0.00 0 5801 25 0 0
sp_multiply_rows_for_qdistr 21131 21131 0 0.00 0 39299 131 0 0
sp_pay_from_customer 6984 6984 0 0.00 4 13856 116 0 0
sp_pay_to_supplier 5865 5865 0 0.00 5 14983 132 0 0
sp_payment_common 12849 12849 0 0.00 1 8941 28 0 0
sp_qd_handle_on_cancel_clo 1821 1821 0 0.00 0 17299 271 0 0
sp_qd_handle_on_invoice_upd_sts 10745 10745 0 0.00 0 21328 265 0 0
sp_qd_handle_on_reserve_upd_sts 9969 9969 0 0.00 0 17398 63 0 0
sp_reserve_write_off 9969 9969 0 0.00 6 17418 172 0 0
sp_supplier_invoice 5629 5629 0 0.00 8 95209 3235 0 0
sp_supplier_order 5400 5400 0 0.00 5 49018 1174 0 0
srv_aggregate_perf_data 94 94 0 0.00 2849 432152 21100 0 0
srv_fill_mon_cache_memory 59 59 0 0.00 6561 153486 55238 0 0
srv_find_qd_qs_mism 1063779 1063779 0 0.00 0 11710 6 0 0
srv_make_invnt_saldo 523 523 0 0.00 43 641602 2201 0 0
srv_make_money_saldo 729 729 0 0.00 1 3834 21 0 0
srv_recalc_idx_stat 4 4 0 0.00 127998 334264 219669 0 0
t$perf-abend:sp_add_invoice_to_stock 61 61 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5149 5149 0 0.00 0 17648 127 0 0
t$perf-norm:sp_cancel_adding_invoice 435 435 0 0.00 1 3932 72 0 0
t$perf-norm:sp_cancel_client_order 1821 1821 0 0.00 0 2824 11 0 0
t$perf-norm:sp_cancel_customer_reserve 678 678 0 0.00 0 5660 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 683 683 0 0.00 0 1177 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 420 420 0 0.00 0 4820 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 429 429 0 0.00 0 324 6 0 0
t$perf-norm:sp_cancel_supplier_order 409 409 0 0.00 0 28484 1364 0 0
t$perf-norm:sp_cancel_write_off 1025 1025 0 0.00 0 2766 5 0 0
t$perf-norm:sp_client_order 21131 21131 0 0.00 0 8384 15 0 0
t$perf-norm:sp_customer_reserve 1017 1017 0 0.00 0 6011 10 0 0
t$perf-norm:sp_pay_from_customer 6984 6984 0 0.00 0 9616 9 0 0
t$perf-norm:sp_pay_to_supplier 5865 5865 0 0.00 0 11340 13 0 0
t$perf-norm:sp_reserve_write_off 9969 9969 0 0.00 0 7300 9 0 0
t$perf-norm:sp_supplier_invoice 5629 5629 0 0.00 0 4807 9 0 0
t$perf-norm:sp_supplier_order 5400 5400 0 0.00 0 2960 10 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 382 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 59 59 0 0.00 0 398 7 0 0
t$perf-norm:srv_make_invnt_saldo 523 523 0 0.00 0 255 4 0 0
t$perf-norm:srv_make_money_saldo 729 729 0 0.00 0 1330 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10408 10286 122 1.17 0 6845 17 0 122
v_all_customers 38162 38162 0 0.00 0 14 0 0 0
v_all_suppliers 30246 30246 0 0.00 0 15 0 0 0
v_all_wares 42262 42262 0 0.00 0 41 1 0 0
v_cancel_adding_invoice 870 870 0 0.00 0 5207 27 0 0
v_cancel_client_order 3642 3642 0 0.00 0 5028 9 0 0
v_cancel_customer_prepayment 1366 1366 0 0.00 0 1570 5 0 0
v_cancel_customer_reserve 1356 1356 0 0.00 0 2877 8 0 0
v_cancel_payment_to_supplier 840 840 0 0.00 0 3866 11 0 0
v_cancel_supplier_invoice 874 858 16 1.83 0 1056 12 0 16
v_cancel_supplier_order 818 818 0 0.00 0 3032 6 0 0
v_cancel_write_off 2050 2050 0 0.00 0 2987 17 0 0
v_max_id_clo_ord 5400 5400 0 0.00 0 155 1 0 0
v_max_id_clo_res 1017 1017 0 0.00 0 1534 7 0 0
v_max_id_ord_sup 5629 5629 0 0.00 0 5886 4 0 0
v_max_non_paid_invoice 1771 1771 0 0.00 0 1163 3 0 0
v_max_non_paid_realizn 3691 3691 0 0.00 0 3248 4 0 0
v_min_id_clo_ord 5400 5400 0 0.00 0 14475 7 0 0
v_min_id_clo_res 1017 1017 0 0.00 0 2308 11 0 0
v_min_id_ord_sup 5629 5629 0 0.00 0 5273 8 0 0
v_min_non_paid_invoice 5865 5865 0 0.00 0 5612 19 0 0
v_min_non_paid_realizn 6984 6984 0 0.00 0 6344 17 0 0
v_our_firm 10686 10686 0 0.00 0 56 0 0 0
v_reserve_write_off 19938 19938 0 0.00 0 6762 9 0 0
x_lock_depdocs_on_canc_invoice 11424 11424 0 0.00 0 23222 97 0 0
x_lock_depdocs_on_canc_sup_ord 409 409 0 0.00 0 11645 300 0 0
x_make_qstorno_1000_1200 5400 5400 0 0.00 1 49005 1115 0 0
x_make_qstorno_1200_2000 5629 5629 0 0.00 4 83477 3099 0 0
x_make_qstorno_2100_3300 467095 467095 0 0.00 0 94693 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-10-2020 19:27:43 to 24-10-2020 19:27:47.

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:57:00 1 8192000000 8798422528 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
16:58:01 21369 8192000000 10342549968 10665857024 1397869376 14.576 100 86 173 0 1431631312 5749344 28012592
16:59:32 18975 8192000000 10435743664 10769522688 1494743184 15.430 100 92 184 0 1533388592 8775616 29869792
17:00:57 12967 8192000000 10483251360 10829619200 1543707680 15.856 100 87 174 0 1581237312 9935824 27593808
17:02:19 9217 8192000000 10576108608 10934775808 1633713696 16.626 100 87 172 1 1670135728 11025808 25396224
17:03:32 6295 8192000000 10653030464 11027759104 1713591904 17.299 100 78 154 2 1748112464 12380560 22140000
17:04:43 3578 8192000000 10708656192 11090288640 1770486064 17.771 100 83 161 0 1804295280 10697456 23111760
17:05:48 7265 8192000000 10746728416 11128143872 1806818144 18.070 100 80 160 1 1843121696 14617984 21685568
17:06:57 1214 8192000000 10806714880 11195592704 1857991104 18.487 100 93 184 1 1898503056 14715840 25796112
17:07:59 3923 8192000000 10786351840 11204390912 1871038112 18.593 100 43 86 1 1892248304 8786128 12424064
17:09:06 69276 8192000000 10780586032 11214737408 1876309888 18.635 100 35 70 0 1893015888 5799680 10906320
17:11:17 10471 8192000000 10793658480 11230822400 1890641664 18.751 100 20 40 2 1902079648 6061792 5376192
17:12:28 7184 8192000000 10817375776 11247558656 1904042320 18.859 100 35 73 1 1920012096 6489104 9480672
17:13:35 43686 8192000000 10839650608 11251380224 1904391008 18.862 100 50 102 2 1925863360 6564704 14907648
17:15:20 8202 8192000000 10801780928 11235864576 1892818208 18.768 100 32 63 3 1909528912 6770576 9940128
17:16:30 5393 8192000000 10771429632 11199938560 1859689152 18.501 100 23 46 2 1872793824 6361904 6742768
17:17:39 15419 8192000000 10732646464 11151704064 1740098128 17.519 95 33 66 1 1754821632 4999952 9723552
17:18:54 20828 8192000000 10688623344 11109208064 1736384592 17.489 96 32 64 2 1751858176 6194768 9278816
17:20:20 6174 8192000000 10706880736 11116851200 1771381456 17.778 99 29 58 5 1785599936 5143872 9074608
17:21:27 9134 8192000000 10741016288 11159207936 1821795120 18.192 100 31 62 0 1836654448 5368672 9490656
17:22:37 7807 8192000000 10757569488 11188051968 1848706720 18.412 100 17 34 1 1859659120 5767072 5185328
17:23:46 41154 8192000000 10764256512 11195785216 1854745088 18.461 100 26 52 1 1867466816 5400208 7321520
17:25:31 8326 8192000000 10790532400 11221090304 1875551520 18.629 100 27 52 2 1888358784 5738000 7069264
17:26:41 7974 8192000000 10791588928 11226701824 1878243280 18.651 100 11 22 0 1886492816 5381632 2867904
17:27:55 77364 8192000000 10880424064 11299643392 1902712896 18.848 98 45 89 2 1924874064 9248128 12913040
17:30:14 72702 8192000000 10912557456 11343302656 1967978016 19.369 100 41 81 3 1992259312 12739424 11541872
17:32:30 32148 8192000000 10924946560 11357519872 1964719392 19.344 99 37 71 2 1987500384 12662560 10118432
17:34:06 74102 8192000000 10922184832 11355553792 1908917280 18.898 96 34 68 2 1940793536 21793312 10082944
17:36:24 6561 8192000000 10919386400 11341598720 1960208800 19.308 100 60 120 6 2005622784 26966784 18447200
17:37:38 8656 8192000000 10937177664 11349282816 1943865456 19.178 98 65 130 1 1986912896 23990352 19057088
17:39:01 24806 8192000000 10897423696 11324628992 1926796816 19.041 99 44 87 1 1963452928 23462608 13193504
17:40:30 19331 8192000000 10841232592 11280678912 1851453216 18.434 96 24 48 2 1881687360 22206848 8027296
17:41:53 22215 8192000000 10854823728 11300380672 1895230240 18.788 99 42 84 2 1941382384 32500112 13652032
17:43:20 28571 8192000000 10836501136 11281141760 1845241248 18.383 97 27 52 1 1888975296 34502656 9231392
17:44:49 22650 8192000000 10812733552 11249299456 1840550112 18.345 97 18 36 1 1863976128 17335328 6090688
17:46:19 19871 8192000000 10826103856 11262697472 1866157952 18.553 98 25 50 1 1893780000 20338880 7283168
17:48:05 48767 8192000000 10862087536 11289518080 1896134288 18.795 99 44 82 4 1927684304 17605888 13944128
17:49:54 68266 8192000000 10821695088 11275849728 1854428736 18.458 97 20 40 2 1884286384 23694208 6163440
17:52:40 40227 8192000000 10830880480 11292680192 1904784000 18.865 100 17 34 1 1924313344 14666144 4863200
17:54:21 41243 8192000000 10854457600 11296722944 1899523680 18.822 99 23 46 1 1919166464 12597216 7045568
17:56:07 66006 8192000000 10841766800 11300323328 1902103888 18.843 99 22 44 0 1923898576 15309728 6484960
17:58:21 31209 8192000000 10864899648 11328606208 1940463216 19.150 100 19 38 2 1968971872 22864336 5644320
17:59:53 61387 8192000000 10883015184 11328749568 1947108880 19.203 100 21 42 2 1970272368 17264832 5898656
18:02:23 52488 8192000000 10918222160 11354923008 1970133568 19.387 100 26 52 0 1988143216 10970016 7039632
18:04:30 70065 8192000000 10909011936 11365621760 1971007632 19.393 100 16 32 0 1983634144 8549216 4077296
18:07:06 40579 8192000000 10937706928 11392749568 1986138848 19.513 100 23 46 1 2017139552 24527920 6472784
18:08:59 60539 8192000000 10931433360 11378909184 1993488160 19.571 100 22 44 3 2009598336 10111264 5998912
18:11:01 33000 8192000000 10945136576 11372761088 1958190944 19.292 98 31 60 1 1976041008 9261408 8588656
18:12:36 17412 8192000000 10962657328 11402747904 2006339504 19.673 100 49 98 1 2034422560 14564528 13518528
18:13:56 44903 8192000000 10906217408 11361214464 1919084960 18.980 96 10 20 0 1940919120 18019616 3814544
18:15:45 65791 8192000000 10929335920 11386949632 1995102080 19.584 100 40 80 1 2016094256 9290736 11701440
18:17:56 56089 8192000000 10915497488 11379720192 1980707344 19.470 99 21 42 0 1996918976 9984368 6227264
18:19:53 153486 8192000000 10858047632 11322265600 1801926576 18.030 91 13 26 2 1813014032 7558832 3528624
18:23:34 96244 8192000000 10877422496 11341701120 1931319952 19.077 98 23 46 1 1945051344 6976432 6754960
18:26:12 43045 8192000000 10890359408 11369496576 1973455952 19.413 100 11 22 1 1987272096 10721040 3095104
18:27:57 26288 8192000000 10864712064 11331100672 1905324336 18.869 97 14 28 2 1915722976 6592832 3805808
18:29:30 47883 8192000000 10840907200 11298693120 1834785744 18.298 94 21 42 1 1850962608 10104752 6072112
18:31:23 38984 8192000000 10843490048 11287994368 1835285744 18.302 95 49 96 3 1860203104 10980128 13937232
18:33:02 84998 8192000000 10853488544 11315032064 1829327312 18.254 95 16 32 1 1845399424 11610176 4461936
18:35:27 48922 8192000000 10865752608 11313893376 1882603184 18.686 97 27 54 2 1905834192 16185712 7045296
18:37:19 83174 8192000000 10871542208 11344408576 1887565728 18.726 97 37 74 1 1915936144 18140448 10229968
18:39:43 88163 8192000000 10867914304 11337256960 1879129984 18.658 97 12 23 1 1897067360 15182288 2755088
18:42:12 65087 8192000000 10844123888 11317354496 1895114144 18.787 98 22 44 0 1921431264 19614960 6702160
18:44:17 79786 8192000000 10880461392 11324731392 1920284368 18.989 99 32 64 0 1935659872 6766464 8609040
18:46:57 59588 8192000000 10871385728 11345010688 1943568368 19.175 99 16 32 0 1964934144 15651728 5714048
18:48:57 35606 8192000000 10877734640 11333738496 1922502288 19.007 98 27 54 3 1950043904 19745632 7795984
18:50:37 60038 8192000000 10880704496 11366166528 1938370256 19.134 99 17 34 1 1972363200 28256976 5735968
18:52:59 34870 8192000000 10883237376 11351207936 1961985984 19.322 100 18 36 0 1977417456 10357824 5073648
18:54:40 91618 8192000000 10879731584 11341058048 1904694576 18.864 97 17 34 1 1918683008 8946496 5041936
18:57:14 92923 8192000000 10882114528 11352834048 1917695824 18.968 98 5 10 0 1930012368 10965248 1351296
18:59:55 28825 8192000000 10915621712 11364704256 1961762016 19.320 99 31 60 4 1977678496 7648256 8268224
19:01:25 58950 8192000000 10909849552 11387916288 1924972672 19.027 97 16 32 0 1945520160 16094224 4453264
19:03:24 58989 8192000000 10925641616 11396501504 1989256880 19.538 100 21 42 1 2013840560 18660592 5923088
19:06:05 116289 8192000000 10884738304 11354890240 1919323456 18.981 97 20 40 2 1935783744 10614608 5845680
19:09:05 57745 8192000000 10870421376 11347189760 1942869472 19.170 99 13 25 3 1956510000 9676784 3963744
19:11:03 73108 8192000000 10849975904 11313942528 1872845680 18.607 95 13 26 0 1890100656 13494816 3760160
19:13:19 74000 8192000000 10862182960 11340967936 1906636224 18.880 98 15 30 1 1922670128 11679920 4353984
19:15:42 49937 8192000000 10873106272 11338805248 1903048800 18.851 97 16 32 1 1916310496 8448352 4813344
19:17:34 48850 8192000000 10858336048 11321192448 1899396272 18.821 98 22 44 1 1922683712 16698912 6588528
19:19:23 60573 8192000000 10851046320 11327823872 1890650544 18.751 98 16 32 0 1914556880 18848752 5057584
19:21:56 88682 8192000000 10825477040 11276124160 1760092112 17.685 91 12 24 0 1773557024 10314016 3150896
19:24:25 87284 8192000000 10841010272 11310669824 1858589184 18.492 97 13 27 0 1874995888 13581600 2825104
19:26:52 18078 8192000000 9155030896 9377902592 313982368 3.691 17 2 4 0 316723648 2396784 344496

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 24-10-2020 19:27:48 to 24-10-2020 19:28:03.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 61 v_add_invoice_to_stock 335544517
user_exc 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 24-10-2020 19:28:04 to 24-10-2020 19:28:05.

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          246780
MON$OLDEST_ACTIVE               246781
MON$OLDEST_SNAPSHOT             246781
MON$NEXT_TRANSACTION            246781
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                       2003776
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 ---
--- end of diff output ---

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

24.10.20 19:28:07. 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-24 19:28:09 No severe PSQL-related problems occured
24.10.20 19:28:10. End of report.