Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.10.2020 22:28
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 17395648 kB
MemAvailable 31567516 kB
Buffers 0 kB
Cached 14333352 kB
SwapCached 3056 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-27 22:27:00
DTS_END                         2020-10-27 22:27:00.5560
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(113:22) ==> SP_CANCEL_WRITE_OFF(41:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(56:17)
IP                              ::1/55064
TRN_ID                          240145
ATT_ID                          670
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: 9499.20 22594 1139904
customer order: creation 174.53 364 20944
customer order: refuse 14.95 327 1794
order to supplier: creation 45.47 1242 5456
order to supplier: removal 3.67 93094 440
invoice (draft): creation 46.37 3396 5564
invoice (draft): removal 55.90 747 6708
invoice accept: apply 41.29 18462 4955
invoice accept: cancel 50.29 806 6035
customer reserve: creation 3827.28 183 459274
customer reserve: removal 4939.96 54 592795
realization accept: apply 84.40 197 10128
realization accept: cancel 85.38 96 10245
payment from customer: creation 59.88 112 7186
payment from customer: removal 5.48 86 657
payment to supplier: creation 48.92 138 5870
payment to supplier: removal 3.26 75 391
service: total inventory turnovers 4.33 1257 519
service: total monetary turnovers 6.93 34 832
service: aggregate perf. data 0.89 18258 107
service: refresh index statistics 0.03 312957 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-10-2020 22:28:38 to 27-10-2020 22:28:40.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7751
TEST_TIME 2 6347
TEST_TIME 3 5706
TEST_TIME 4 5087
TEST_TIME 5 5118
TEST_TIME 6 4960
TEST_TIME 7 6451
TEST_TIME 8 6801
TEST_TIME 9 7356
TEST_TIME 10 7295
TEST_TIME 11 7632
TEST_TIME 12 7780
TEST_TIME 13 7691
TEST_TIME 14 7929
TEST_TIME 15 8074
TEST_TIME 16 8482
TEST_TIME 17 8674
TEST_TIME 18 8868
TEST_TIME 19 9076
TEST_TIME 20 8933
TEST_TIME 21 9378
TEST_TIME 22 9389
TEST_TIME 23 9765
TEST_TIME 24 9932
TEST_TIME 25 10012
TEST_TIME 26 11566
TEST_TIME 27 11596
TEST_TIME 28 11418
TEST_TIME 29 11327
TEST_TIME 30 11359
TEST_TIME 31 11187
TEST_TIME 32 11264
TEST_TIME 33 11270
TEST_TIME 34 11259
TEST_TIME 35 11146
TEST_TIME 36 10884
TEST_TIME 37 10621
TEST_TIME 38 10784
TEST_TIME 39 10838
TEST_TIME 40 10626
TEST_TIME 41 10588
TEST_TIME 42 10469
TEST_TIME 43 10472
TEST_TIME 44 10538
TEST_TIME 45 10436
TEST_TIME 46 10414
TEST_TIME 47 10308
TEST_TIME 48 10234
TEST_TIME 49 10215
TEST_TIME 50 10253
TEST_TIME 51 10195
TEST_TIME 52 10158
TEST_TIME 53 10087
TEST_TIME 54 10058
TEST_TIME 55 10265
TEST_TIME 56 10261
TEST_TIME 57 10207
TEST_TIME 58 10338
TEST_TIME 59 10302
TEST_TIME 60 10340
TEST_TIME 61 10405
TEST_TIME 62 10428
TEST_TIME 63 10485
TEST_TIME 64 10343
TEST_TIME 65 10228
TEST_TIME 66 10164
TEST_TIME 67 10145
TEST_TIME 68 10068
TEST_TIME 69 9979
TEST_TIME 70 9948
TEST_TIME 71 9957
TEST_TIME 72 9898
TEST_TIME 73 9912
TEST_TIME 74 9828
TEST_TIME 75 9725
TEST_TIME 76 9749
TEST_TIME 77 9743
TEST_TIME 78 9712
TEST_TIME 79 9721
TEST_TIME 80 9668
TEST_TIME 81 9740
TEST_TIME 82 9775
TEST_TIME 83 9780
TEST_TIME 84 9830
TEST_TIME 85 9783
TEST_TIME 86 9756
TEST_TIME 87 9859
TEST_TIME 88 9852
TEST_TIME 89 9872
TEST_TIME 90 9792
TEST_TIME 91 9773
TEST_TIME 92 9736
TEST_TIME 93 9715
TEST_TIME 94 9684
TEST_TIME 95 9670
TEST_TIME 96 9669
TEST_TIME 97 9661
TEST_TIME 98 9661
TEST_TIME 99 9635
TEST_TIME 100 9758
TEST_TIME 101 9765
TEST_TIME 102 9741
TEST_TIME 103 9796
TEST_TIME 104 9803
TEST_TIME 105 9780
TEST_TIME 106 9835
TEST_TIME 107 9823
TEST_TIME 108 9837
TEST_TIME 109 9777
TEST_TIME 110 9765
TEST_TIME 111 9744
TEST_TIME 112 9738
TEST_TIME 113 9705
TEST_TIME 114 9660
TEST_TIME 115 9630
TEST_TIME 116 9597
TEST_TIME 117 9596
TEST_TIME 118 9553
TEST_TIME 119 9580
TEST_TIME 120 9500

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 27-10-2020 22:28:42 to 27-10-2020 22:28:45.

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 1566632 1566632 0 0.00 0 19781 6 0 0
doc_list_biud 1566630 1566630 0 0.00 0 49255 26 0 0
sp_add_invoice_to_stock 5040 4955 85 1.68 37 204666 18463 0 85
sp_cancel_adding_invoice 6035 6035 0 0.00 5 65020 806 0 0
sp_cancel_client_order 1794 1794 0 0.00 4 11315 328 0 0
sp_cancel_customer_reserve 592795 592795 0 0.00 1 29342 54 0 0
sp_cancel_pay_from_customer 657 657 0 0.00 3 7615 86 0 0
sp_cancel_pay_to_supplier 391 391 0 0.00 3 3605 76 0 0
sp_cancel_supplier_invoice 6719 6708 11 0.16 2 49260 747 0 11
sp_cancel_supplier_order 440 440 0 0.00 7 1079377 93095 0 0
sp_cancel_write_off 10245 10245 0 0.00 1 12227 96 0 0
sp_client_order 20944 20944 0 0.00 3 23943 365 0 0
sp_customer_reserve 459274 459274 0 0.00 0 70586 183 0 0
sp_fill_shopping_cart 31958 31958 0 0.00 1 13747 40 0 0
sp_fill_shopping_cart_clo_res 459268 459268 0 0.00 0 13880 13 0 0
sp_get_clo_for_invoice 4949 4949 0 0.00 0 14672 284 0 0
sp_kill_cost_storno 17328 17328 0 0.00 0 11420 50 0 0
sp_kill_qstorno_ret_qs2qd 1202983 1202983 0 0.00 0 49252 29 0 0
sp_kill_qty_storno 633094 633094 0 0.00 0 49254 64 0 0
sp_lock_selected_doc 20850 20850 0 0.00 0 10661 6 0 0
sp_make_cost_storno 28133 28133 0 0.00 0 14923 62 0 0
sp_multiply_rows_for_pdistr 28133 28133 0 0.00 0 13398 32 0 0
sp_multiply_rows_for_qdistr 20944 20944 0 0.00 0 17274 164 0 0
sp_pay_from_customer 7186 7186 0 0.00 4 15005 113 0 0
sp_pay_to_supplier 5870 5870 0 0.00 5 13538 138 0 0
sp_payment_common 13056 13056 0 0.00 1 14729 32 0 0
sp_qd_handle_on_cancel_clo 1794 1794 0 0.00 0 11285 300 0 0
sp_qd_handle_on_invoice_upd_sts 10984 10984 0 0.00 0 30623 328 0 0
sp_qd_handle_on_reserve_upd_sts 10128 10128 0 0.00 0 22718 64 0 0
sp_reserve_write_off 10128 10128 0 0.00 5 22783 197 0 0
sp_supplier_invoice 5564 5564 0 0.00 6 80155 3397 0 0
sp_supplier_order 5456 5456 0 0.00 5 64578 1243 0 0
srv_aggregate_perf_data 107 107 0 0.00 2222 114655 18259 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1288 171207 46697 0 0
srv_find_qd_qs_mism 1092816 1092816 0 0.00 0 19779 5 0 0
srv_make_invnt_saldo 519 519 0 0.00 42 65971 1257 0 0
srv_make_money_saldo 832 832 0 0.00 0 10539 35 0 0
srv_recalc_idx_stat 4 4 0 0.00 272720 379259 312958 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 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4955 4955 0 0.00 0 12234 184 0 0
t$perf-norm:sp_cancel_adding_invoice 431 431 0 0.00 0 9219 120 0 0
t$perf-norm:sp_cancel_client_order 1794 1794 0 0.00 0 7391 17 0 0
t$perf-norm:sp_cancel_customer_reserve 671 671 0 0.00 0 3846 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 657 657 0 0.00 0 4329 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 391 391 0 0.00 0 4222 23 0 0
t$perf-norm:sp_cancel_supplier_invoice 403 403 0 0.00 0 556 8 0 0
t$perf-norm:sp_cancel_supplier_order 440 440 0 0.00 0 31954 1452 0 0
t$perf-norm:sp_cancel_write_off 986 986 0 0.00 0 3722 6 0 0
t$perf-norm:sp_client_order 20944 20944 0 0.00 0 22031 19 0 0
t$perf-norm:sp_customer_reserve 992 992 0 0.00 0 7983 18 0 0
t$perf-norm:sp_pay_from_customer 7186 7186 0 0.00 0 10250 15 0 0
t$perf-norm:sp_pay_to_supplier 5870 5870 0 0.00 0 15551 23 0 0
t$perf-norm:sp_reserve_write_off 10128 10128 0 0.00 0 9029 14 0 0
t$perf-norm:sp_supplier_invoice 5564 5564 0 0.00 0 9215 25 0 0
t$perf-norm:sp_supplier_order 5456 5456 0 0.00 0 4893 16 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 406 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 5 0 0 0
t$perf-norm:srv_make_invnt_saldo 519 519 0 0.00 0 995 19 0 0
t$perf-norm:srv_make_money_saldo 832 832 0 0.00 0 3928 24 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 5 4 0 0
v_add_invoice_to_stock 10068 9898 170 1.68 0 12147 20 0 170
v_all_customers 37824 37824 0 0.00 0 92 1 0 0
v_all_suppliers 30776 30776 0 0.00 0 32 1 0 0
v_all_wares 41888 41888 0 0.00 0 14 1 0 0
v_cancel_adding_invoice 862 862 0 0.00 0 3485 12 0 0
v_cancel_client_order 3588 3588 0 0.00 0 3264 5 0 0
v_cancel_customer_prepayment 1314 1314 0 0.00 0 3429 7 0 0
v_cancel_customer_reserve 1342 1342 0 0.00 0 177 3 0 0
v_cancel_payment_to_supplier 782 782 0 0.00 0 3587 12 0 0
v_cancel_supplier_invoice 828 806 22 2.65 0 1790 18 0 22
v_cancel_supplier_order 880 880 0 0.00 0 939 4 0 0
v_cancel_write_off 1972 1972 0 0.00 0 9983 16 0 0
v_max_id_clo_ord 5456 5456 0 0.00 0 244 1 0 0
v_max_id_clo_res 992 992 0 0.00 0 1981 16 0 0
v_max_id_ord_sup 5558 5558 0 0.00 0 5608 6 0 0
v_max_non_paid_invoice 1496 1496 0 0.00 0 1834 4 0 0
v_max_non_paid_realizn 3834 3834 0 0.00 0 8620 8 0 0
v_min_id_clo_ord 5456 5456 0 0.00 0 8592 10 0 0
v_min_id_clo_res 992 992 0 0.00 0 2848 7 0 0
v_min_id_ord_sup 5558 5558 0 0.00 0 10914 15 0 0
v_min_non_paid_invoice 5870 5870 0 0.00 0 9761 24 0 0
v_min_non_paid_realizn 7186 7186 0 0.00 0 10234 21 0 0
v_our_firm 10768 10768 0 0.00 0 11 1 0 0
v_reserve_write_off 20256 20256 0 0.00 0 6028 6 0 0
x_lock_depdocs_on_canc_invoice 12340 12340 0 0.00 0 11474 109 0 0
x_lock_depdocs_on_canc_sup_ord 440 440 0 0.00 0 14384 294 0 0
x_make_qstorno_1000_1200 5456 5456 0 0.00 2 64568 1156 0 0
x_make_qstorno_1200_2000 5564 5564 0 0.00 3 78490 3256 0 0
x_make_qstorno_2100_3300 459274 459274 0 0.00 0 70585 167 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-10-2020 22:28:46 to 27-10-2020 22:28:50.

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:04 1 8192000000 8798422528 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
19:58:05 21572 8192000000 10345160336 10662764544 1396412256 14.563 100 80 160 0 1428796624 5691040 26693328
19:59:32 26992 8192000000 10444381472 10779312128 1502608176 15.499 100 90 180 1 1539835024 8783536 28443312
20:01:11 18903 8192000000 10506476416 10850635776 1561057920 16.005 100 83 166 0 1597542208 10304080 26180208
20:02:33 13326 8192000000 10562680976 10921697280 1617758640 16.491 100 83 167 0 1654266224 11773952 24733632
20:03:48 10122 8192000000 10612941872 10977951744 1672810224 16.957 100 85 170 3 1710511344 12798016 24903104
20:05:00 3112 8192000000 10675436464 11050242048 1743538432 17.548 100 74 148 1 1776328640 12148640 20641568
20:06:05 5397 8192000000 10719928528 11102629888 1783790512 17.881 100 78 156 0 1817675824 11966672 21918640
20:07:13 2354 8192000000 10769756032 11150929920 1828322400 18.246 100 83 166 1 1865598800 14234768 23041632
20:08:17 2184 8192000000 10795288160 11180494848 1853440224 18.450 100 87 175 4 1890926464 13084784 24401456
20:09:20 28109 8192000000 10788569440 11188965376 1854261952 18.457 100 41 82 0 1872782144 6438384 12081808
20:10:49 5358 8192000000 10772104400 11202285568 1869546560 18.581 100 21 42 0 1881586032 6157888 5881584
20:11:55 8455 8192000000 10793071904 11218423808 1882396864 18.684 100 27 53 2 1896376192 6517952 7461376
20:13:04 11730 8192000000 10803393072 11231432704 1892451488 18.766 100 20 40 1 1904139296 6131232 5556576
20:14:20 67681 8192000000 10829009568 11251273728 1901691968 18.840 100 33 66 0 1916418592 5037456 9689168
20:16:28 7295 8192000000 10822300144 11252121600 1905792512 18.873 100 28 56 1 1920125680 6418656 7914512
20:17:36 6618 8192000000 10791572448 11211743232 1853047968 18.447 99 23 45 3 1865021248 4946528 7026752
20:18:45 50745 8192000000 10706697184 11127246848 1690943488 17.109 92 32 63 1 1706294000 5055216 10295296
20:20:37 5278 8192000000 10686254816 11102470144 1752753840 17.624 98 39 79 2 1770465600 6051424 11660336
20:21:43 6131 8192000000 10730253936 11134894080 1776957616 17.824 98 27 54 1 1789911344 5369312 7584416
20:22:53 54952 8192000000 10755902816 11176964096 1834921744 18.299 100 27 54 1 1847557088 5036064 7599280
20:24:56 6196 8192000000 10784917072 11204100096 1859548752 18.500 100 27 53 1 1872529824 5688960 7292112
20:26:03 6413 8192000000 10792204848 11220299776 1876981744 18.641 100 20 38 1 1887822384 5386896 5453744
20:27:18 10512 8192000000 10844850832 11253612544 1905496288 18.870 100 43 82 3 1922939504 5415584 12027632
20:28:32 21571 8192000000 10848637600 11275059200 1929642336 19.064 100 45 90 1 1952635424 9213760 13779328
20:29:56 65726 8192000000 10887688496 11312615424 1947940240 19.210 100 50 100 1 1972805728 10497664 14367824
20:32:08 17039 8192000000 10893732496 11329966080 1965215744 19.347 100 43 86 0 1994066384 15289008 13561632
20:33:42 11851 8192000000 10932051312 11349827584 1974768224 19.423 100 51 103 0 2008620064 18952784 14899056
20:34:56 1285 8192000000 10944279360 11360600064 1986782768 19.518 100 74 145 1 2030305056 21469856 22052432
20:36:00 2686 8192000000 10935437600 11362775040 1990625952 19.549 100 71 140 0 2030881280 20283376 19971952
20:37:05 2268 8192000000 10928841776 11344703488 1958960256 19.298 99 86 170 1 2003030000 19182768 24886976
20:38:12 2003 8192000000 10945071248 11358396416 1975480160 19.429 100 80 155 0 2022256208 23972928 22803120
20:39:18 4401 8192000000 10925191520 11336237056 1958811568 19.297 99 78 156 0 2007409648 25323088 23274992
20:40:26 8216 8192000000 10925705568 11343785984 1952555424 19.247 98 64 128 0 1999852656 27772032 19525200
20:41:39 5807 8192000000 10934565520 11361820672 1962575696 19.327 100 61 121 0 2018926272 37439328 18911248
20:42:47 3854 8192000000 10927230464 11339866112 1949527376 19.223 100 81 162 0 2015340480 41380032 24433072
20:43:55 2068 8192000000 10954479680 11355381760 1961635616 19.319 99 81 161 3 2028134560 41293312 25205632
20:45:07 4232 8192000000 10944450400 11363569664 1968098480 19.370 100 72 142 2 2032519296 42302736 22118080
20:46:14 12415 8192000000 10930065104 11360006144 1959037008 19.298 100 43 86 3 2015499728 41496816 14965904
20:47:31 10524 8192000000 10944246336 11378135040 1969270288 19.380 100 40 80 0 2027692128 44524880 13896960
20:48:45 14212 8192000000 10941375472 11383136256 1958164032 19.291 99 40 80 2 2016056544 43933232 13959280
20:50:02 12036 8192000000 10942824016 11381903360 1955377008 19.269 100 35 71 0 2019679312 51900304 12402000
20:51:18 28586 8192000000 10929715376 11390885888 1960961040 19.314 100 24 48 1 2015596592 45808608 8826944
20:52:48 36868 8192000000 10924212528 11396165632 1928838448 19.058 99 22 46 0 1992637344 55926256 7872640
20:54:37 59858 8192000000 10896684672 11350323200 1929614608 19.064 99 15 29 0 1947808464 14662192 3531664
20:57:11 136828 8192000000 10899699184 11345035264 1871994000 18.600 95 23 44 1 1893047488 14293168 6760320
21:00:28 135741 8192000000 10897210800 11362369536 1874128720 18.618 95 6 12 1 1888408272 12428288 1851264
21:03:56 120883 8192000000 10900588144 11367723008 1936122384 19.116 98 44 88 1 1961745088 12510544 13112160
21:07:10 18337 8192000000 10900355440 11373096960 1964699088 19.343 100 20 40 0 1983892688 13823232 5370368
21:08:31 73730 8192000000 10916556720 11377561600 1950729840 19.232 98 24 48 3 1969471648 11750368 6991440
21:10:50 64970 8192000000 10906812496 11398017024 1976385920 19.436 100 3 6 0 1989960720 12898560 676240
21:13:23 40457 8192000000 10918308288 11400765440 1991807904 19.558 100 12 24 0 2013447056 18300880 3338272
21:15:05 55109 8192000000 10915373728 11396128768 1977560512 19.445 99 14 28 0 1996880032 15321552 3997968
21:17:59 44570 8192000000 10916625808 11382435840 1950915584 19.234 98 14 27 1 1968256256 13223200 4117472
21:19:46 85330 8192000000 10950904448 11419578368 2002874048 19.645 100 22 44 2 2025329952 16281808 6174096
21:22:15 55159 8192000000 10901851744 11381379072 1941228752 19.157 98 9 18 0 1961803328 17691472 2883104
21:24:15 28369 8192000000 10875857712 11337875456 1901156240 18.836 97 19 38 0 1917359232 10822496 5380496
21:25:46 47593 8192000000 10866508272 11313659904 1831627952 18.273 93 24 48 1 1848663328 10620432 6414944
21:27:42 44225 8192000000 10902978560 11363168256 1926415328 19.038 98 35 70 0 1950943472 14792720 9735424
21:29:42 63507 8192000000 10864061568 11331821568 1866086608 18.553 96 11 22 0 1883315728 14249104 2980016
21:33:03 29682 8192000000 10883538592 11337142272 1923812672 19.017 99 38 75 2 1942745616 8517616 10415328
21:34:35 73249 8192000000 10852983968 11335643136 1924382320 19.022 99 7 14 0 1936596752 10022368 2192064
21:36:55 68450 8192000000 10838984144 11316469760 1895594576 18.791 98 20 41 0 1914376848 12853456 5928816
21:39:07 63849 8192000000 10853438896 11321081856 1898263920 18.812 98 39 77 2 1919331296 10054128 11013248
21:41:14 58009 8192000000 10833699936 11303170048 1869549776 18.581 97 14 26 1 1880609616 6565936 4493904
21:43:12 48084 8192000000 10811102464 11293224960 1843255472 18.367 96 20 40 2 1863454832 13892912 6306448
21:45:04 58673 8192000000 10849439376 11320500224 1897674832 18.808 99 12 24 2 1917165808 16481552 3009424
21:47:07 29193 8192000000 10884009248 11343265792 1931295840 19.077 99 33 67 4 1953902128 13348992 9257296
21:48:45 60016 8192000000 10836204288 11305484288 1842962176 18.365 95 22 44 2 1863376736 14348528 6066032
21:50:52 74801 8192000000 10854898576 11327934464 1916640800 18.960 99 25 49 1 1935864736 12003776 7220160
21:53:07 32110 8192000000 10872502208 11333640192 1926140352 19.036 99 25 50 2 1947297936 13744384 7413200
21:54:46 60781 8192000000 10887471664 11352838144 1948858320 19.217 100 37 74 1 1970700000 10775824 11065856
21:56:48 49181 8192000000 10901341680 11354361856 1912662496 18.928 97 52 103 2 1937461552 10248704 14550352
21:59:01 48951 8192000000 10911959984 11377598464 1986153488 19.513 100 27 54 0 2004692496 10845632 7693376
22:00:52 76899 8192000000 10887902304 11373936640 1931178416 19.076 97 10 19 2 1942356640 8443536 2734688
22:03:09 88640 8192000000 10925681616 11397038080 1987282192 19.522 100 12 25 0 1999854176 9189728 3382256
22:05:40 49548 8192000000 10922851392 11392278528 1957726160 19.288 98 13 26 1 1975862128 13948992 4186976
22:07:32 96458 8192000000 10911840304 11389702144 1928406320 19.054 97 8 16 0 1948833312 18092736 2334256
22:10:39 50710 8192000000 10900663984 11380252672 1953091712 19.251 99 9 18 0 1969156368 13526432 2538224
22:12:29 17948 8192000000 10938637056 11377115136 1961423568 19.317 99 36 72 0 1979514784 7798000 10293216
22:13:50 48431 8192000000 10884787312 11352928256 1889803040 18.744 96 22 44 0 1909658608 13329232 6526336
22:15:42 171207 8192000000 10872948992 11337158656 1828727040 18.249 93 23 46 2 1845614752 10233120 6654592
22:19:54 32195 8192000000 10858464176 11338301440 1943937232 19.178 100 15 30 0 1954975088 6920032 4117824
22:21:26 94187 8192000000 10861425216 11334479872 1896880656 18.801 97 10 20 1 1907634768 8049856 2704256
22:24:02 74181 8192000000 10854408048 11327795200 1905065360 18.867 98 16 32 0 1920725328 10808208 4851760
22:26:33 33516 8192000000 9479769264 9789186048 631979376 7.162 33 4 8 0 638860592 5692400 1188816

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 27-10-2020 22:29:08 to 27-10-2020 22:29:09.

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          241787
MON$OLDEST_ACTIVE               241788
MON$OLDEST_SNAPSHOT             241788
MON$NEXT_TRANSACTION            241788
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                       2039968
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 ---
:195: 
:196: mscs-test-161.budzdorov.ru    Tue Oct 27 22:27:08 2020
:197:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:198: 
:199: 
:200: mscs-test-161.budzdorov.ru    Tue Oct 27 22:27:10 2020
:201:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:202: 
--- end of diff output ---

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

27.10.20 22:29:10. 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-27 22:29:13 No severe PSQL-related problems occured
27.10.20 22:29:14. End of report.