Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.10.2020 01: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 17361484 kB
MemAvailable 31486240 kB
Buffers 0 kB
Cached 14296788 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 01:26:59
DTS_END                         2020-10-29 01:26:59.5690
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(56:17)
IP                              ::1/44280
TRN_ID                          245043
ATT_ID                          638
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: 9521.42 21061 1142571
customer order: creation 180.01 336 21601
customer order: refuse 14.98 362 1797
order to supplier: creation 47.20 1360 5664
order to supplier: removal 3.65 103144 438
invoice (draft): creation 47.74 3596 5729
invoice (draft): removal 55.23 779 6628
invoice accept: apply 42.42 17860 5090
invoice accept: cancel 49.81 803 5977
customer reserve: creation 3874.92 177 464990
customer reserve: removal 4886.32 61 586358
realization accept: apply 87.61 189 10513
realization accept: cancel 96.65 99 11598
payment from customer: creation 61.15 105 7338
payment from customer: removal 5.79 83 695
payment to supplier: creation 51.58 123 6189
payment to supplier: removal 3.71 131 445
service: total inventory turnovers 5.05 1026 606
service: total monetary turnovers 6.83 47 819
service: aggregate perf. data 0.77 26686 92
service: refresh index statistics 0.03 264248 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 29-10-2020 01:27:47 to 29-10-2020 01:27:49.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 577
TEST_TIME 2 1862
TEST_TIME 3 3589
TEST_TIME 4 4067
TEST_TIME 5 4733
TEST_TIME 6 4681
TEST_TIME 7 4565
TEST_TIME 8 4860
TEST_TIME 9 4884
TEST_TIME 10 5399
TEST_TIME 11 5502
TEST_TIME 12 6331
TEST_TIME 13 6398
TEST_TIME 14 6848
TEST_TIME 15 6974
TEST_TIME 16 7689
TEST_TIME 17 7749
TEST_TIME 18 7900
TEST_TIME 19 7869
TEST_TIME 20 8236
TEST_TIME 21 8624
TEST_TIME 22 8943
TEST_TIME 23 9225
TEST_TIME 24 9118
TEST_TIME 25 9174
TEST_TIME 26 9412
TEST_TIME 27 9622
TEST_TIME 28 9836
TEST_TIME 29 10411
TEST_TIME 30 11288
TEST_TIME 31 10977
TEST_TIME 32 10739
TEST_TIME 33 10529
TEST_TIME 34 10436
TEST_TIME 35 10445
TEST_TIME 36 10277
TEST_TIME 37 10186
TEST_TIME 38 10053
TEST_TIME 39 9994
TEST_TIME 40 9868
TEST_TIME 41 9812
TEST_TIME 42 9605
TEST_TIME 43 9501
TEST_TIME 44 9695
TEST_TIME 45 9601
TEST_TIME 46 9547
TEST_TIME 47 9468
TEST_TIME 48 9387
TEST_TIME 49 9553
TEST_TIME 50 9488
TEST_TIME 51 9683
TEST_TIME 52 9668
TEST_TIME 53 9781
TEST_TIME 54 9806
TEST_TIME 55 9878
TEST_TIME 56 9890
TEST_TIME 57 9883
TEST_TIME 58 9831
TEST_TIME 59 10023
TEST_TIME 60 9925
TEST_TIME 61 9781
TEST_TIME 62 9821
TEST_TIME 63 9720
TEST_TIME 64 9662
TEST_TIME 65 9625
TEST_TIME 66 9539
TEST_TIME 67 9520
TEST_TIME 68 9549
TEST_TIME 69 9613
TEST_TIME 70 9570
TEST_TIME 71 9733
TEST_TIME 72 9692
TEST_TIME 73 9725
TEST_TIME 74 9772
TEST_TIME 75 9714
TEST_TIME 76 9754
TEST_TIME 77 9780
TEST_TIME 78 9776
TEST_TIME 79 9754
TEST_TIME 80 9760
TEST_TIME 81 9651
TEST_TIME 82 9625
TEST_TIME 83 9565
TEST_TIME 84 9540
TEST_TIME 85 9537
TEST_TIME 86 9505
TEST_TIME 87 9470
TEST_TIME 88 9441
TEST_TIME 89 9445
TEST_TIME 90 9406
TEST_TIME 91 9485
TEST_TIME 92 9463
TEST_TIME 93 9506
TEST_TIME 94 9523
TEST_TIME 95 9558
TEST_TIME 96 9526
TEST_TIME 97 9526
TEST_TIME 98 9549
TEST_TIME 99 9520
TEST_TIME 100 9547
TEST_TIME 101 9563
TEST_TIME 102 9546
TEST_TIME 103 9600
TEST_TIME 104 9590
TEST_TIME 105 9506
TEST_TIME 106 9465
TEST_TIME 107 9424
TEST_TIME 108 9412
TEST_TIME 109 9393
TEST_TIME 110 9393
TEST_TIME 111 9382
TEST_TIME 112 9409
TEST_TIME 113 9361
TEST_TIME 114 9322
TEST_TIME 115 9407
TEST_TIME 116 9488
TEST_TIME 117 9496
TEST_TIME 118 9472
TEST_TIME 119 9524
TEST_TIME 120 9522

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 29-10-2020 01:27:50 to 29-10-2020 01:27:53.

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 1576206 1576206 0 0.00 0 28839 6 0 0
doc_list_biud 1576192 1576192 0 0.00 0 70808 29 0 0
sp_add_invoice_to_stock 5175 5090 85 1.64 29 204078 17860 0 85
sp_cancel_adding_invoice 5977 5977 0 0.00 5 88125 804 0 0
sp_cancel_client_order 1797 1797 0 0.00 4 11902 362 0 0
sp_cancel_customer_reserve 586358 586358 0 0.00 1 70810 62 0 0
sp_cancel_pay_from_customer 695 695 0 0.00 3 7422 84 0 0
sp_cancel_pay_to_supplier 445 445 0 0.00 2 6325 131 0 0
sp_cancel_supplier_invoice 6632 6628 4 0.06 2 12887 780 0 4
sp_cancel_supplier_order 438 438 0 0.00 11 1097859 103145 0 0
sp_cancel_write_off 11598 11598 0 0.00 1 7703 99 0 0
sp_client_order 21601 21601 0 0.00 4 20155 337 0 0
sp_customer_reserve 464990 464990 0 0.00 0 93874 178 0 0
sp_fill_shopping_cart 32977 32977 0 0.00 1 23678 42 0 0
sp_fill_shopping_cart_clo_res 464985 464985 0 0.00 0 22161 13 0 0
sp_get_clo_for_invoice 5085 5085 0 0.00 0 23314 309 0 0
sp_kill_cost_storno 18715 18715 0 0.00 0 6096 55 0 0
sp_kill_qstorno_ret_qs2qd 1191380 1191380 0 0.00 0 70797 33 0 0
sp_kill_qty_storno 628381 628381 0 0.00 0 70808 71 0 0
sp_lock_selected_doc 21621 21621 0 0.00 0 9780 8 0 0
sp_make_cost_storno 29125 29125 0 0.00 0 28830 58 0 0
sp_multiply_rows_for_pdistr 29111 29111 0 0.00 0 6867 29 0 0
sp_multiply_rows_for_qdistr 21601 21601 0 0.00 0 19888 156 0 0
sp_pay_from_customer 7338 7338 0 0.00 4 10396 105 0 0
sp_pay_to_supplier 6189 6189 0 0.00 5 8734 123 0 0
sp_payment_common 13526 13526 0 0.00 1 9972 27 0 0
sp_qd_handle_on_cancel_clo 1797 1797 0 0.00 0 11887 336 0 0
sp_qd_handle_on_invoice_upd_sts 11056 11056 0 0.00 0 34814 346 0 0
sp_qd_handle_on_reserve_upd_sts 10506 10506 0 0.00 0 15712 56 0 0
sp_reserve_write_off 10513 10513 0 0.00 7 15842 189 0 0
sp_supplier_invoice 5729 5729 0 0.00 7 90454 3597 0 0
sp_supplier_order 5664 5664 0 0.00 5 27178 1361 0 0
srv_aggregate_perf_data 92 92 0 0.00 2773 677703 26687 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 2138 161794 51121 0 0
srv_find_qd_qs_mism 1094960 1094960 0 0.00 0 18568 5 0 0
srv_make_invnt_saldo 606 606 0 0.00 33 77294 1026 0 0
srv_make_money_saldo 819 819 0 0.00 0 11932 47 0 0
srv_recalc_idx_stat 4 4 0 0.00 127022 438899 264248 0 0
t$perf-abend:sp_add_invoice_to_stock 85 85 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5091 5091 0 0.00 0 10634 172 0 0
t$perf-norm:sp_cancel_adding_invoice 437 437 0 0.00 0 4582 72 0 0
t$perf-norm:sp_cancel_client_order 1797 1797 0 0.00 0 4574 16 0 0
t$perf-norm:sp_cancel_customer_reserve 735 735 0 0.00 0 3384 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 695 695 0 0.00 0 1163 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 445 445 0 0.00 0 6592 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 421 421 0 0.00 0 730 11 0 0
t$perf-norm:sp_cancel_supplier_order 438 438 0 0.00 0 29295 1591 0 0
t$perf-norm:sp_cancel_write_off 1068 1068 0 0.00 0 2278 6 0 0
t$perf-norm:sp_client_order 21603 21603 0 0.00 0 6408 17 0 0
t$perf-norm:sp_customer_reserve 969 969 0 0.00 0 5688 12 0 0
t$perf-norm:sp_pay_from_customer 7339 7339 0 0.00 0 11904 9 0 0
t$perf-norm:sp_pay_to_supplier 6191 6191 0 0.00 0 15767 14 0 0
t$perf-norm:sp_reserve_write_off 10513 10513 0 0.00 0 21431 12 0 0
t$perf-norm:sp_supplier_invoice 5729 5729 0 0.00 0 18282 22 0 0
t$perf-norm:sp_supplier_order 5664 5664 0 0.00 0 8423 20 0 0
t$perf-norm:srv_aggregate_perf_data 92 92 0 0.00 0 799 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 108 3 0 0
t$perf-norm:srv_make_invnt_saldo 606 606 0 0.00 0 791 15 0 0
t$perf-norm:srv_make_money_saldo 819 819 0 0.00 0 482 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 3 0 0
v_add_invoice_to_stock 10328 10158 170 1.64 0 6828 20 0 170
v_all_customers 39158 39158 0 0.00 0 12 1 0 0
v_all_suppliers 31570 31570 0 0.00 0 21 1 0 0
v_all_wares 43202 43202 0 0.00 0 102 1 0 0
v_cancel_adding_invoice 874 874 0 0.00 0 2676 12 0 0
v_cancel_client_order 3594 3594 0 0.00 0 3922 8 0 0
v_cancel_customer_prepayment 1390 1390 0 0.00 0 1344 8 0 0
v_cancel_customer_reserve 1470 1470 0 0.00 0 2731 6 0 0
v_cancel_payment_to_supplier 890 890 0 0.00 0 3526 17 0 0
v_cancel_supplier_invoice 850 842 8 0.94 0 3989 22 0 8
v_cancel_supplier_order 876 876 0 0.00 0 2049 9 0 0
v_cancel_write_off 2136 2136 0 0.00 0 2748 14 0 0
v_max_id_clo_ord 5660 5660 0 0.00 0 4374 3 0 0
v_max_id_clo_res 968 968 0 0.00 0 4691 13 0 0
v_max_id_ord_sup 5716 5716 0 0.00 0 12153 9 0 0
v_max_non_paid_invoice 1780 1780 0 0.00 0 4668 7 0 0
v_max_non_paid_realizn 3883 3883 0 0.00 0 2848 7 0 0
v_min_id_clo_ord 5660 5660 0 0.00 0 1833 5 0 0
v_min_id_clo_res 968 968 0 0.00 0 973 4 0 0
v_min_id_ord_sup 5716 5716 0 0.00 0 14786 8 0 0
v_min_non_paid_invoice 6189 6189 0 0.00 0 4072 13 0 0
v_min_non_paid_realizn 7337 7337 0 0.00 0 4540 13 0 0
v_our_firm 10952 10952 0 0.00 0 14 1 0 0
v_reserve_write_off 21012 21012 0 0.00 0 4827 7 0 0
x_lock_depdocs_on_canc_invoice 12184 12184 0 0.00 0 15230 117 0 0
x_lock_depdocs_on_canc_sup_ord 438 438 0 0.00 0 21161 329 0 0
x_make_qstorno_1000_1200 5664 5664 0 0.00 2 27141 1272 0 0
x_make_qstorno_1200_2000 5729 5729 0 0.00 2 90431 3441 0 0
x_make_qstorno_2100_3300 464990 464990 0 0.00 0 93873 162 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 29-10-2020 01:27:54 to 29-10-2020 01:27:59.

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
22:57:03 1 8192000000 8798422528 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
22:58:04 14519 8192000000 10360357168 10675621888 1413986032 14.719 100 89 178 0 1449272960 5972976 29313952
22:59:25 17726 8192000000 10411984736 10738429952 1462745776 15.150 100 89 178 0 1500094160 8128272 29220112
23:00:50 22132 8192000000 10474865376 10812260352 1512978640 15.589 100 81 162 0 1548043360 9259200 25805520
23:02:17 11032 8192000000 10557202112 10915500032 1617199344 16.486 100 76 152 2 1651591280 11150112 23241824
23:03:32 8557 8192000000 10625547168 10995015680 1682427536 17.038 100 74 146 0 1715651184 12078032 21145616
23:04:42 6078 8192000000 10679118784 11053117440 1740522480 17.523 100 75 149 1 1773824752 12317664 20984608
23:05:53 6843 8192000000 10719490896 11101827072 1783672896 17.880 100 81 162 1 1820961888 14916432 22372560
23:07:01 1946 8192000000 10780130080 11161399296 1837972736 18.324 100 93 187 0 1877204432 13045824 26185872
23:08:05 1709 8192000000 10810213824 11202043904 1866918576 18.559 100 88 176 2 1905950368 13429536 25602256
23:09:08 7850 8192000000 10775901472 11199090688 1863070128 18.528 100 27 54 0 1877111360 6686624 7354608
23:10:18 14325 8192000000 10782181168 11214004224 1875147424 18.626 100 17 34 1 1885495648 5541072 4807152
23:11:35 79501 8192000000 10820584016 11231735808 1887612272 18.727 100 45 90 1 1905891488 5069216 13210000
23:13:55 8056 8192000000 10816658128 11236700160 1876912720 18.640 99 35 68 1 1893823072 6998832 9911520
23:15:18 6073 8192000000 10823324656 11250806784 1897060112 18.803 100 24 47 1 1909840576 6204384 6576080
23:16:26 9554 8192000000 10798895824 11235471360 1874154144 18.618 99 19 38 3 1885519648 5410624 5954880
23:17:39 51048 8192000000 10773420704 11195080704 1823760672 18.208 98 28 55 1 1837305360 5983184 7561504
23:19:31 6472 8192000000 10707380896 11114975232 1751128288 17.611 97 30 62 2 1764502720 4231776 9142656
23:20:41 8245 8192000000 10714305504 11123863552 1762942976 17.709 98 34 68 1 1778945984 5355872 10647136
23:21:50 68327 8192000000 10712920032 11140415488 1781007456 17.858 98 40 80 2 1797479280 4840528 11631296
23:23:58 4905 8192000000 10759313104 11175653376 1833333568 18.287 100 34 66 2 1848583040 5367776 9881696
23:25:07 4288 8192000000 10789790912 11204136960 1860150960 18.505 100 46 92 4 1879251056 5324288 13775808
23:26:12 9460 8192000000 10776881136 11213406208 1869691376 18.582 100 21 42 0 1881412992 5581872 6139744
23:27:22 92595 8192000000 10845213168 11269648384 1904403760 18.862 100 41 82 0 1925181504 8975264 11802480
23:29:58 14409 8192000000 10872043904 11296432128 1936843616 19.122 100 50 98 2 1964299888 13695936 13760336
23:31:15 41067 8192000000 10883813952 11331694592 1956719680 19.280 100 41 81 0 1988891600 20413824 11758096
23:33:03 16162 8192000000 10930073728 11352031232 1977980944 19.449 100 61 122 0 2016869696 21015264 17873488
23:34:28 7483 8192000000 10927893168 11357904896 1979238880 19.459 100 52 104 2 2015142976 21139760 14764336
23:35:44 8476 8192000000 10925994416 11353243648 1973600160 19.414 99 52 104 3 2012453280 22694816 16158304
23:36:56 3048 8192000000 10963762256 11388338176 2000859072 19.630 100 68 134 2 2045176224 24508400 19808752
23:38:02 2136 8192000000 10986570464 11411140608 2016399824 19.752 100 69 138 1 2069133584 31912256 20821504
23:39:10 2410 8192000000 10950613376 11374788608 1984106176 19.497 100 77 154 2 2039623200 31917664 23599360
23:40:21 5681 8192000000 10969133792 11388915712 1992955152 19.567 100 78 156 2 2057674624 40120160 24599312
23:41:31 4445 8192000000 10980945712 11395878912 1992953776 19.567 100 75 150 1 2058687712 42277680 23456256
23:42:38 9094 8192000000 10922499440 11351162880 1956650608 19.279 100 66 132 0 2020927056 43278256 20998192
23:43:57 3222 8192000000 10940342576 11357294592 1956172896 19.276 100 76 152 1 2020476368 40543120 23760352
23:45:07 13492 8192000000 10910189504 11335933952 1920190448 18.988 99 51 102 0 1983301440 46835504 16275488
23:46:48 5324 8192000000 10915725680 11332341760 1927108320 19.044 100 61 122 1 1995055152 48084064 19862768
23:48:05 11741 8192000000 10908417680 11338989568 1924856240 19.026 99 48 96 2 1985380608 44382528 16141840
23:49:20 11751 8192000000 10908084816 11352739840 1945249568 19.189 100 39 78 0 1999343984 41158640 12935776
23:50:36 12783 8192000000 10902094960 11330453504 1916866096 18.962 99 51 103 0 1979111680 46020752 16224832
23:51:58 5064 8192000000 10939220080 11347951616 1948209376 19.212 100 78 155 1 2017756320 44925472 24621472
23:53:08 13715 8192000000 10919679040 11360591872 1943219312 19.172 100 26 52 0 2002213440 49969712 9024416
23:54:31 18558 8192000000 10893653760 11347447808 1924774544 19.025 100 19 38 0 1981744384 49744544 7225296
23:55:51 52535 8192000000 10859597376 11311648768 1911452976 18.918 99 13 26 1 1950837872 34827008 4557888
23:57:50 84084 8192000000 10895352176 11326558208 1946585424 19.199 100 39 75 1 1964577280 7996432 9995424
00:00:19 39250 8192000000 10893991696 11353989120 1970861232 19.392 100 33 66 2 1990577280 10848512 8867536
00:02:01 101913 8192000000 10883068288 11354726400 1945934320 19.194 99 14 28 0 1956895312 6892464 4068528
00:04:51 20292 8192000000 10881663776 11341447168 1959227024 19.300 100 11 22 0 1969945392 7914064 2804304
00:06:12 88126 8192000000 10890607952 11361701888 1971342240 19.396 100 14 28 0 1986541856 11467584 3732032
00:08:41 94165 8192000000 10885119968 11366547456 1923190576 19.012 97 23 46 1 1944478240 14156480 7131184
00:11:59 36882 8192000000 10915790784 11374555136 1978976912 19.457 100 20 41 0 1994793936 9679120 6137904
00:13:37 61193 8192000000 10921541440 11395522560 1992918832 19.567 100 16 32 3 2011990384 14420672 4650880
00:15:39 57808 8192000000 10929837408 11400396800 1999238304 19.617 100 18 36 2 2025139600 20021632 5879664
00:17:37 66860 8192000000 10936232752 11407716352 1979334768 19.459 99 30 60 1 2009003232 20328784 9339680
00:19:48 76597 8192000000 10925948912 11394109440 1959302528 19.300 98 13 26 1 1977688592 14481376 3904688
00:22:12 60324 8192000000 10934840400 11389947904 1942662704 19.168 97 16 32 0 1958993824 11439824 4891296
00:24:27 55512 8192000000 10899198032 11362648064 1919043568 18.979 97 19 37 1 1936650720 12147600 5459552
00:26:25 120743 8192000000 10885681632 11348799488 1880921040 18.673 95 34 68 3 1900942560 9879712 10141808
00:29:26 13706 8192000000 10895029168 11368697856 1971235936 19.395 100 13 26 1 1985212688 10497648 3479104
00:30:45 101144 8192000000 10845497392 11321139200 1840250528 18.343 94 11 22 1 1857892800 14744608 2897664
00:33:26 47658 8192000000 10854081584 11332763648 1888806768 18.736 97 16 32 0 1910526352 16667888 5051696
00:36:12 115012 8192000000 10861281680 11341828096 1843800272 18.372 94 15 30 0 1871259072 22824848 4633952
00:39:07 33600 8192000000 10862992496 11334381568 1907423504 18.886 98 25 48 4 1924059760 10041488 6594768
00:40:56 101372 8192000000 10849154528 11316789248 1862648160 18.525 96 26 52 1 1880449328 9980096 7821072
00:43:47 96633 8192000000 10874359888 11336015872 1883392784 18.692 97 12 24 1 1897121296 10633968 3094544
00:46:25 102996 8192000000 10878753056 11329744896 1907319152 18.885 98 17 34 0 1920426144 8380096 4726896
00:49:09 62670 8192000000 10886949024 11341279232 1916581856 18.959 98 24 48 1 1934400464 10607968 7210640
00:51:13 58466 8192000000 10869717184 11347746816 1934190016 19.100 99 9 18 0 1948513616 11645184 2678416
00:53:11 51509 8192000000 10895573040 11361890304 1970473472 19.389 100 35 70 1 1992071888 11073088 10525328
00:55:07 47288 8192000000 10855515504 11337289728 1919150496 18.980 98 9 17 1 1932640320 10563904 2925920
00:56:56 53833 8192000000 10891169840 11369484288 1948424960 19.214 99 13 25 1 1962619248 10639968 3554320
00:58:50 73813 8192000000 10898720976 11364134912 1932737440 19.089 98 14 28 0 1953785408 17303648 3744320
01:01:04 44493 8192000000 10906135472 11381510144 1986396576 19.515 100 42 84 0 2010763840 12111616 12255648
01:02:55 42261 8192000000 10921496384 11396853760 1995804288 19.590 100 33 66 2 2018004368 12218656 9981424
01:04:43 56471 8192000000 10933260672 11380584448 1948510496 19.215 98 20 40 1 1965966560 11861248 5594816
01:06:39 44545 8192000000 10940802992 11396022272 1990940944 19.551 100 36 72 2 2014632736 13570256 10121536
01:08:27 161794 8192000000 10884104464 11357204480 1829252224 18.253 92 12 24 1 1845645936 13176160 3217552
01:12:31 70200 8192000000 10868028240 11325980672 1853900736 18.454 94 34 66 1 1870463424 7278560 9284128
01:14:41 61167 8192000000 10833319728 11315081216 1897850608 18.809 98 14 28 1 1918362528 16303632 4208288
01:16:43 84882 8192000000 10819299136 11281981440 1799697120 18.011 93 11 22 1 1816732368 13429744 3605504
01:19:33 75849 8192000000 10840477056 11305865216 1834185712 18.293 96 15 30 0 1855518128 17151552 4180864
01:21:50 110031 8192000000 10859900336 11324022784 1919492016 18.983 100 10 21 1 1935553744 13271920 2789808
01:25:00 87971 8192000000 10854638128 11328069632 1872112160 18.601 97 16 32 2 1887617568 11364544 4140864

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 01:28:00 to 29-10-2020 01:28:15.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 85 v_add_invoice_to_stock 335544517
user_exc 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 29-10-2020 01:28:16 to 29-10-2020 01:28:18.

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          244718
MON$OLDEST_ACTIVE               245185
MON$OLDEST_SNAPSHOT             245185
MON$NEXT_TRANSACTION            245185
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                       2035680
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 ---
:248: 
:249: mscs-test-161.budzdorov.ru    Thu Oct 29 01:27:08 2020
:250:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:251: 
--- end of diff output ---

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

29.10.20 01:28:19. 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 01:28:21 No severe PSQL-related problems occured
29.10.20 01:28:22. End of report.