Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.10.2020 22: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 17412648 kB
MemAvailable 31506196 kB
Buffers 0 kB
Cached 14267168 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-28 22:27:02
DTS_END                         2020-10-28 22:27:02.4600
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/42480
TRN_ID                          245737
ATT_ID                          688
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 9174.66 19593 1100959
customer order: creation 181.28 350 21754
customer order: refuse 15.53 333 1864
order to supplier: creation 48.74 1255 5849
order to supplier: removal 3.81 89446 457
invoice (draft): creation 49.03 3505 5883
invoice (draft): removal 51.67 816 6200
invoice accept: apply 42.57 17633 5108
invoice accept: cancel 45.77 747 5492
customer reserve: creation 3945.58 175 473469
customer reserve: removal 4490.05 59 538806
realization accept: apply 87.57 171 10508
realization accept: cancel 76.08 112 9129
payment from customer: creation 62.88 104 7546
payment from customer: removal 5.63 97 676
payment to supplier: creation 51.55 131 6186
payment to supplier: removal 3.57 85 428
service: total inventory turnovers 5.32 959 638
service: total monetary turnovers 7.02 22 842
service: aggregate perf. data 1.00 16298 120
service: refresh index statistics 0.03 259570 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-10-2020 22:28:01 to 28-10-2020 22:28:03.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9937
TEST_TIME 2 6034
TEST_TIME 3 6581
TEST_TIME 4 6077
TEST_TIME 5 6467
TEST_TIME 6 6603
TEST_TIME 7 6156
TEST_TIME 8 6095
TEST_TIME 9 5849
TEST_TIME 10 5645
TEST_TIME 11 5896
TEST_TIME 12 6008
TEST_TIME 13 6303
TEST_TIME 14 6485
TEST_TIME 15 6496
TEST_TIME 16 6606
TEST_TIME 17 7005
TEST_TIME 18 7819
TEST_TIME 19 8064
TEST_TIME 20 8281
TEST_TIME 21 8397
TEST_TIME 22 8748
TEST_TIME 23 8621
TEST_TIME 24 8702
TEST_TIME 25 8739
TEST_TIME 26 8636
TEST_TIME 27 9007
TEST_TIME 28 9313
TEST_TIME 29 10615
TEST_TIME 30 10691
TEST_TIME 31 10436
TEST_TIME 32 10454
TEST_TIME 33 10548
TEST_TIME 34 10430
TEST_TIME 35 10358
TEST_TIME 36 10282
TEST_TIME 37 10066
TEST_TIME 38 10053
TEST_TIME 39 10019
TEST_TIME 40 9840
TEST_TIME 41 9844
TEST_TIME 42 9820
TEST_TIME 43 9832
TEST_TIME 44 9848
TEST_TIME 45 9785
TEST_TIME 46 9721
TEST_TIME 47 9712
TEST_TIME 48 9581
TEST_TIME 49 9501
TEST_TIME 50 9610
TEST_TIME 51 9563
TEST_TIME 52 9513
TEST_TIME 53 9554
TEST_TIME 54 9622
TEST_TIME 55 9609
TEST_TIME 56 9666
TEST_TIME 57 9651
TEST_TIME 58 9703
TEST_TIME 59 9660
TEST_TIME 60 9741
TEST_TIME 61 9707
TEST_TIME 62 9721
TEST_TIME 63 9813
TEST_TIME 64 9927
TEST_TIME 65 9816
TEST_TIME 66 9717
TEST_TIME 67 9619
TEST_TIME 68 9555
TEST_TIME 69 9573
TEST_TIME 70 9520
TEST_TIME 71 9479
TEST_TIME 72 9473
TEST_TIME 73 9383
TEST_TIME 74 9361
TEST_TIME 75 9309
TEST_TIME 76 9309
TEST_TIME 77 9364
TEST_TIME 78 9371
TEST_TIME 79 9341
TEST_TIME 80 9332
TEST_TIME 81 9401
TEST_TIME 82 9392
TEST_TIME 83 9381
TEST_TIME 84 9464
TEST_TIME 85 9492
TEST_TIME 86 9473
TEST_TIME 87 9514
TEST_TIME 88 9495
TEST_TIME 89 9498
TEST_TIME 90 9467
TEST_TIME 91 9417
TEST_TIME 92 9403
TEST_TIME 93 9419
TEST_TIME 94 9362
TEST_TIME 95 9339
TEST_TIME 96 9329
TEST_TIME 97 9332
TEST_TIME 98 9341
TEST_TIME 99 9312
TEST_TIME 100 9324
TEST_TIME 101 9364
TEST_TIME 102 9383
TEST_TIME 103 9419
TEST_TIME 104 9409
TEST_TIME 105 9479
TEST_TIME 106 9494
TEST_TIME 107 9461
TEST_TIME 108 9444
TEST_TIME 109 9374
TEST_TIME 110 9330
TEST_TIME 111 9321
TEST_TIME 112 9269
TEST_TIME 113 9254
TEST_TIME 114 9211
TEST_TIME 115 9207
TEST_TIME 116 9218
TEST_TIME 117 9207
TEST_TIME 118 9242
TEST_TIME 119 9252
TEST_TIME 120 9176

Performance per minute, chart


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

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 1539954 1539954 0 0.00 0 26000 6 0 0
doc_list_biud 1539953 1539953 0 0.00 0 46401 27 0 0
sp_add_invoice_to_stock 5187 5108 79 1.52 47 210531 17633 0 79
sp_cancel_adding_invoice 5492 5492 0 0.00 5 46891 748 0 0
sp_cancel_client_order 1864 1864 0 0.00 4 16619 334 0 0
sp_cancel_customer_reserve 538806 538806 0 0.00 1 40340 60 0 0
sp_cancel_pay_from_customer 676 676 0 0.00 3 9448 98 0 0
sp_cancel_pay_to_supplier 428 428 0 0.00 3 3667 86 0 0
sp_cancel_supplier_invoice 6205 6200 5 0.08 1 14902 816 0 5
sp_cancel_supplier_order 457 457 0 0.00 5 887676 89447 0 0
sp_cancel_write_off 9129 9129 0 0.00 1 10989 112 0 0
sp_client_order 21754 21754 0 0.00 3 29436 351 0 0
sp_customer_reserve 473469 473469 0 0.00 0 107942 175 0 0
sp_fill_shopping_cart 33477 33477 0 0.00 1 13354 36 0 0
sp_fill_shopping_cart_clo_res 473468 473468 0 0.00 0 14955 13 0 0
sp_get_clo_for_invoice 5105 5105 0 0.00 0 24078 282 0 0
sp_kill_cost_storno 15725 15725 0 0.00 0 14406 54 0 0
sp_kill_qstorno_ret_qs2qd 1093398 1093398 0 0.00 0 40336 33 0 0
sp_kill_qty_storno 577561 577561 0 0.00 0 46400 70 0 0
sp_lock_selected_doc 21643 21643 0 0.00 0 7438 5 0 0
sp_make_cost_storno 29344 29344 0 0.00 0 19696 51 0 0
sp_multiply_rows_for_pdistr 29344 29344 0 0.00 0 7025 26 0 0
sp_multiply_rows_for_qdistr 21754 21754 0 0.00 0 14008 153 0 0
sp_pay_from_customer 7546 7546 0 0.00 4 12113 105 0 0
sp_pay_to_supplier 6186 6186 0 0.00 5 13709 131 0 0
sp_payment_common 13731 13731 0 0.00 1 13668 31 0 0
sp_qd_handle_on_cancel_clo 1864 1864 0 0.00 0 16614 311 0 0
sp_qd_handle_on_invoice_upd_sts 10597 10597 0 0.00 0 46398 308 0 0
sp_qd_handle_on_reserve_upd_sts 10508 10508 0 0.00 0 28592 59 0 0
sp_reserve_write_off 10508 10508 0 0.00 6 28675 171 0 0
sp_supplier_invoice 5883 5883 0 0.00 6 69887 3505 0 0
sp_supplier_order 5849 5849 0 0.00 5 73408 1256 0 0
srv_aggregate_perf_data 120 120 0 0.00 2391 307331 16299 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 2307 129321 44681 0 0
srv_find_qd_qs_mism 1051342 1051342 0 0.00 0 26000 6 0 0
srv_make_invnt_saldo 638 638 0 0.00 52 62734 960 0 0
srv_make_money_saldo 842 842 0 0.00 1 1257 22 0 0
srv_recalc_idx_stat 4 4 0 0.00 166613 385823 259570 0 0
t$perf-abend:sp_add_invoice_to_stock 79 79 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 5108 5108 0 0.00 0 7777 163 0 0
t$perf-norm:sp_cancel_adding_invoice 417 417 0 0.00 1 13317 133 0 0
t$perf-norm:sp_cancel_client_order 1864 1864 0 0.00 0 4251 15 0 0
t$perf-norm:sp_cancel_customer_reserve 728 728 0 0.00 0 435 4 0 0
t$perf-norm:sp_cancel_pay_from_customer 676 676 0 0.00 0 1211 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 428 428 0 0.00 0 3989 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 408 408 0 0.00 0 4158 19 0 0
t$perf-norm:sp_cancel_supplier_order 457 457 0 0.00 0 21774 1275 0 0
t$perf-norm:sp_cancel_write_off 1054 1054 0 0.00 0 2787 6 0 0
t$perf-norm:sp_client_order 21755 21755 0 0.00 0 11280 16 0 0
t$perf-norm:sp_customer_reserve 1066 1066 0 0.00 0 2951 9 0 0
t$perf-norm:sp_pay_from_customer 7546 7546 0 0.00 0 5099 8 0 0
t$perf-norm:sp_pay_to_supplier 6186 6186 0 0.00 0 4679 12 0 0
t$perf-norm:sp_reserve_write_off 10508 10508 0 0.00 0 5221 10 0 0
t$perf-norm:sp_supplier_invoice 5883 5883 0 0.00 0 10617 20 0 0
t$perf-norm:sp_supplier_order 5849 5849 0 0.00 0 6176 17 0 0
t$perf-norm:srv_aggregate_perf_data 120 120 0 0.00 0 258 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 301 5 0 0
t$perf-norm:srv_make_invnt_saldo 638 638 0 0.00 0 984 21 0 0
t$perf-norm:srv_make_money_saldo 842 842 0 0.00 0 2003 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10366 10208 158 1.52 0 7631 18 0 158
v_all_customers 39830 39830 0 0.00 0 18 0 0 0
v_all_suppliers 32514 32514 0 0.00 0 22 1 0 0
v_all_wares 43508 43508 0 0.00 0 37 1 0 0
v_cancel_adding_invoice 834 834 0 0.00 0 2356 9 0 0
v_cancel_client_order 3728 3728 0 0.00 0 7636 6 0 0
v_cancel_customer_prepayment 1352 1352 0 0.00 0 3706 9 0 0
v_cancel_customer_reserve 1456 1456 0 0.00 0 1677 6 0 0
v_cancel_payment_to_supplier 856 856 0 0.00 0 3028 11 0 0
v_cancel_supplier_invoice 826 816 10 1.21 0 810 16 0 10
v_cancel_supplier_order 914 914 0 0.00 0 4363 10 0 0
v_cancel_write_off 2108 2108 0 0.00 0 7409 15 0 0
v_max_id_clo_ord 5844 5844 0 0.00 0 3373 3 0 0
v_max_id_clo_res 1066 1066 0 0.00 0 3468 20 0 0
v_max_id_ord_sup 5876 5876 0 0.00 0 3734 4 0 0
v_max_non_paid_invoice 1648 1648 0 0.00 0 7029 6 0 0
v_max_non_paid_realizn 4003 4003 0 0.00 0 4637 4 0 0
v_min_id_clo_ord 5844 5844 0 0.00 0 1982 6 0 0
v_min_id_clo_res 1066 1066 0 0.00 0 8084 16 0 0
v_min_id_ord_sup 5876 5876 0 0.00 0 4980 4 0 0
v_min_non_paid_invoice 6185 6185 0 0.00 0 13662 23 0 0
v_min_non_paid_realizn 7546 7546 0 0.00 0 12101 22 0 0
v_our_firm 10764 10764 0 0.00 0 17 1 0 0
v_reserve_write_off 21012 21012 0 0.00 0 7220 6 0 0
x_lock_depdocs_on_canc_invoice 11284 11284 0 0.00 0 15116 107 0 0
x_lock_depdocs_on_canc_sup_ord 457 457 0 0.00 0 3357 265 0 0
x_make_qstorno_1000_1200 5849 5849 0 0.00 2 73399 1177 0 0
x_make_qstorno_1200_2000 5883 5883 0 0.00 2 69881 3376 0 0
x_make_qstorno_2100_3300 473469 473469 0 0.00 0 107941 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-10-2020 22:28:09 to 28-10-2020 22:28:13.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
19:57:06 2 8192000000 8798422528 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
19:58:07 17198 8192000000 10368720416 10688573440 1429604720 14.858 100 90 180 0 1464287104 6072288 28610096
19:59:32 25943 8192000000 10423318624 10750808064 1471250144 15.225 100 89 178 0 1508384944 8663632 28471168
20:01:02 13804 8192000000 10468402224 10808823808 1526577104 15.707 100 81 162 1 1561251344 9754608 24919632
20:02:22 13084 8192000000 10546587792 10903621632 1599791664 16.338 100 73 146 1 1631688368 10507968 21388736
20:03:39 12464 8192000000 10622162096 10988896256 1681688688 17.032 100 74 149 1 1713686944 10977520 21020736
20:04:55 6029 8192000000 10678949696 11057229824 1746175696 17.570 100 78 154 0 1780484576 12629296 21679584
20:06:04 3679 8192000000 10734168256 11115839488 1791061760 17.941 100 85 170 1 1829298816 14129296 24107760
20:07:09 1853 8192000000 10780104320 11165458432 1837654240 18.322 100 89 176 1 1875827552 13446544 24726768
20:08:13 1382 8192000000 10798897744 11187339264 1858837552 18.494 100 96 190 2 1898460784 13155280 26467952
20:09:15 5233 8192000000 10812894496 11206889472 1878591040 18.654 100 85 174 1 1914062064 11795232 23675792
20:10:21 8175 8192000000 10779196752 11211915264 1875467104 18.628 100 23 44 3 1889011488 7570544 5973840
20:11:32 13963 8192000000 10785105568 11218272256 1880719200 18.671 100 17 34 3 1891583504 5838112 5026192
20:12:47 12069 8192000000 10803357376 11239088128 1894455984 18.782 100 21 42 3 1906809344 5774992 6578368
20:14:00 12977 8192000000 10853581584 11259781120 1908074144 18.891 100 58 116 0 1931216688 6977824 16164720
20:15:13 4345 8192000000 10821996240 11261263872 1910478384 18.910 100 27 54 1 1924245248 6108640 7658224
20:16:21 7090 8192000000 10812228016 11248164864 1887778112 18.728 99 31 60 3 1901590032 5309568 8502352
20:17:32 12636 8192000000 10794277280 11230810112 1856909264 18.478 98 10 20 0 1864861776 5271488 2681024
20:18:46 37765 8192000000 10701616544 11120902144 1662697440 16.872 90 16 32 1 1672797968 4990064 5110464
20:20:26 5686 8192000000 10728173936 11143974912 1796831648 17.988 99 32 64 0 1811781536 5697232 9252656
20:21:32 5944 8192000000 10692130768 11100622848 1733365984 17.464 97 34 68 1 1749957280 5992352 10598944
20:22:41 10384 8192000000 10743999712 11157159936 1788770400 17.922 98 32 65 2 1803920848 4726144 10424304
20:23:51 22897 8192000000 10740537824 11166208000 1824927904 18.218 100 18 35 3 1835375488 4812768 5634816
20:25:15 8391 8192000000 10771229504 11197575168 1854293632 18.457 100 24 48 1 1871754896 11002224 6459040
20:26:23 12516 8192000000 10782142992 11211403264 1865227808 18.546 100 22 44 0 1878220832 5461200 7531824
20:27:42 5308 8192000000 10856303072 11270303744 1914474832 18.943 100 58 114 3 1939069696 7683248 16911616
20:28:52 107549 8192000000 10865362096 11301875712 1917825024 18.969 99 38 74 0 1938819936 10181472 10813440
20:31:46 6373 8192000000 10884821952 11309776896 1952332336 19.245 100 53 103 2 1977299408 10235056 14732016
20:32:59 2304 8192000000 10916386032 11333591040 1970688160 19.391 100 80 159 2 2008980960 14629680 23663120
20:34:16 20195 8192000000 10912572080 11346329600 1976345648 19.436 100 42 84 1 2003697200 14969808 12381744
20:35:40 2853 8192000000 10944774064 11373375488 1996027904 19.591 100 56 112 0 2034888128 21978576 16881648
20:36:51 10980 8192000000 10948356400 11381264384 1998568320 19.611 100 54 107 5 2038677296 23502368 16606608
20:38:10 26746 8192000000 10957232224 11387813888 1984496224 19.500 99 44 88 3 2026047664 27102368 14449072
20:39:41 15723 8192000000 10958730304 11389698048 1999886048 19.622 100 51 102 1 2043822112 28406160 15529904
20:41:02 7703 8192000000 10960622112 11399176192 2005921936 19.669 100 61 123 2 2055729680 30948432 18859312
20:42:16 3881 8192000000 10971466208 11389001728 1992282400 19.562 100 82 161 1 2050657888 34638176 23737312
20:43:24 3102 8192000000 10947431552 11372773376 1974210672 19.419 100 69 137 0 2035842976 40379120 21253184
20:44:35 6179 8192000000 10949782928 11363426304 1957047504 19.283 100 79 158 2 2031398304 49006384 25344416
20:45:46 5228 8192000000 10926612080 11370229760 1967050320 19.362 100 45 90 1 2020139712 39143776 13945616
20:47:02 6564 8192000000 10922263680 11354685440 1952740176 19.248 100 50 100 2 2006646576 37244000 16662400
20:48:24 8600 8192000000 10890203232 11326435328 1930566608 19.071 100 38 76 0 1976542240 33335088 12640544
20:49:37 24265 8192000000 10901055216 11351453696 1950463888 19.230 100 32 64 1 1992220192 31847568 9908736
20:51:10 10218 8192000000 10914574272 11357040640 1948336608 19.213 99 45 90 0 1993232256 30393216 14502432
20:52:27 28250 8192000000 10931160784 11372052480 1957964736 19.290 100 30 60 2 2003625296 35109696 10550864
20:54:22 25716 8192000000 10926284048 11385106432 1955556320 19.271 100 27 54 0 2017256112 51550576 10149216
20:55:53 24171 8192000000 10911678304 11381309440 1941505872 19.159 100 21 42 1 2003829440 54889664 7433904
20:57:45 64525 8192000000 10867946720 11310231552 1836482672 18.312 94 22 43 0 1854371776 11657040 6232064
20:59:51 39563 8192000000 10871052864 11341119488 1907434112 18.886 98 8 16 0 1927159552 16704544 3020896
21:01:31 60825 8192000000 10905462656 11362238464 1962600912 19.327 100 21 42 2 1977702864 8560848 6541104
21:03:39 129321 8192000000 10893479104 11364040704 1937494016 19.127 98 29 57 1 1952822080 6784832 8543232
21:06:49 95775 8192000000 10902390112 11367084032 1949687024 19.224 99 16 32 0 1962660688 8499856 4473808
21:09:26 27359 8192000000 10906718944 11373465600 1982807424 19.487 100 38 74 3 2003432416 10083376 10541616
21:10:55 51565 8192000000 10898251824 11380142080 1959308224 19.301 99 14 28 2 1976453216 13145744 3999248
21:13:25 61120 8192000000 10880402384 11358371840 1926160736 19.036 98 12 25 0 1942916256 12496816 4258704
21:15:30 54427 8192000000 10867591584 11341815808 1931941568 19.082 98 9 18 1 1949117072 14235792 2939712
21:17:24 34109 8192000000 10880608320 11351781376 1951058464 19.235 99 42 85 2 1978149680 15044416 12046800
21:19:05 47663 8192000000 10880524784 11350278144 1937113888 19.124 98 20 40 0 1950280832 7304288 5862656
21:21:00 42074 8192000000 10889773408 11358896128 1920620224 18.992 97 18 36 0 1934226176 8867744 4738208
21:22:49 41492 8192000000 10903062032 11374460928 1938340944 19.134 97 24 48 2 1953669744 8577760 6751040
21:24:31 70638 8192000000 10913759856 11382861824 1949591616 19.223 98 16 32 0 1969274944 14986288 4697040
21:26:43 91660 8192000000 10872133024 11344822272 1811734752 18.110 92 17 34 0 1828233984 11838192 4661040
21:29:16 42733 8192000000 10887820640 11352498176 1950883392 19.234 100 17 34 0 1968525680 12887024 4755264
21:31:04 99657 8192000000 10876299104 11336450048 1850059424 18.423 94 15 30 1 1872731360 18150976 4520960
21:34:39 6767 8192000000 10877997232 11331104768 1916845200 18.962 98 26 52 1 1934299600 9956320 7498080
21:35:46 53010 8192000000 10846250512 11324522496 1873700128 18.614 96 14 28 0 1895903120 17683392 4519600
21:37:44 33157 8192000000 10862531536 11323080704 1919448448 18.982 99 8 16 0 1935527984 13831808 2247728
21:39:30 69704 8192000000 10840972336 11308961792 1881383488 18.676 97 17 34 0 1896551328 8834176 6333664
21:41:41 57019 8192000000 10815900640 11291553792 1873806336 18.615 97 17 34 1 1886598288 7820992 4970960
21:43:40 27520 8192000000 10858728976 11305893888 1877553088 18.645 97 35 69 2 1898629984 11048000 10028896
21:45:08 38212 8192000000 10849375536 11317542912 1911654064 18.920 99 24 48 2 1930004240 11562672 6787504
21:46:47 22499 8192000000 10840388000 11296993280 1872479424 18.604 97 34 68 2 1895954576 13180016 10295136
21:48:15 53557 8192000000 10865732416 11328872448 1914256224 18.941 99 20 40 0 1937162384 16896960 6009200
21:50:09 22104 8192000000 10898831728 11340091392 1933122384 19.092 99 51 102 2 1961109104 13776912 14209808
21:51:52 116466 8192000000 10885920464 11348336640 1916318464 18.957 98 19 38 0 1931422544 10011744 5092336
21:54:50 60839 8192000000 10911809232 11382620160 1945909728 19.194 99 13 26 0 1967551248 18046560 3594960
21:56:55 93349 8192000000 10892893360 11368402944 1933127680 19.092 98 28 56 1 1951548848 10509104 7912064
21:59:30 15067 8192000000 10914961040 11386966016 1987953040 19.528 100 45 87 2 2017991424 17272208 12766176
22:00:45 79111 8192000000 10879619328 11357618176 1884962896 18.705 95 8 16 0 1896334528 9431824 1939808
22:03:05 42330 8192000000 10891218208 11364098048 1934099680 19.100 98 23 45 1 1947528592 6160720 7268192
22:04:53 74815 8192000000 10926076896 11380441088 1932286128 19.085 97 30 59 1 1950958160 10448784 8223248
22:07:08 86618 8192000000 10920545008 11400863744 1941381728 19.158 97 21 42 1 1963996288 16002784 6611776
22:09:45 68457 8192000000 10913628560 11371499520 1934115024 19.100 98 19 38 0 1956316368 16670416 5530928
22:12:01 55698 8192000000 10911626016 11391213568 1942276832 19.165 98 13 26 1 1963416896 17622192 3517872
22:14:00 52490 8192000000 10938226640 11372544000 1887217616 18.723 95 38 74 0 1907213872 8963920 11032336
22:15:55 97045 8192000000 10851035200 11301371904 1855912928 18.470 95 37 74 0 1875229328 8991872 10324528
22:18:32 53843 8192000000 10853517728 11329458176 1926366112 19.038 99 18 36 0 1942517680 10813632 5337936
22:20:28 63790 8192000000 10878052640 11349815296 1935296688 19.109 99 12 20 1 1947710656 9530368 2883600
22:22:34 81821 8192000000 10867327616 11345498112 1880431184 18.669 96 9 18 0 1893881760 10761904 2688672
22:24:56 59477 8192000000 10852593152 11330260992 1910823680 18.913 98 17 34 2 1924124704 8094064 5206960
22:26:56 12957 8192000000 9036465616 9238196224 204213792 2.432 11 3 4 1 208190464 3345184 631488

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 79 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 28-10-2020 22:28:30 to 28-10-2020 22:28:32.

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          246166
MON$OLDEST_ACTIVE               246167
MON$OLDEST_SNAPSHOT             246167
MON$NEXT_TRANSACTION            246167
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                       2032352
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 ---
:239: 
:240: mscs-test-161.budzdorov.ru    Wed Oct 28 22:27:11 2020
:241:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:242: 
--- end of diff output ---

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

28.10.20 22:28:33. 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-28 22:28:36 No severe PSQL-related problems occured
28.10.20 22:28:37. End of report.