Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.08.2020 15:53
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 369f65bbd7e5442bbb810a88d29f958d
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-957.5.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2019-11-01 09:39
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
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 1440.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.03
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 32644004 kB
MemFree 16661104 kB
MemAvailable 30960480 kB
Buffers 0 kB
Cached 14998788 kB
SwapCached 6180 kB
Command: fdisk -l

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=fbc, val=/opt/fb30/bin
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=report_compress_cmd, val=/usr/bin/7za
param=host, val=localhost
param=port, val=3333
param=usr, val=SYSDBA
param=pwd, val=masterkey
param=tmpdir, val=/var/tmp/logs-oltp30
param=remove_isql_logs, val=if_no_severe_errors
param=working_mode, val=small_03
param=actions_todo_before_reconnect, val=300
param=max_cps, val=25
param=sleep_max, val=0
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=no_auto_undo, val=1
param=recalc_idx_min_interval, val=30
param=used_in_replication, val=0
param=separate_workers, val=1
param=update_conflict_percent, val=0
param=unit_selection_method, val=random
param=detailed_info, val=0
param=mon_unit_perf, val=2
param=mon_unit_list, val=//
param=mon_query_interval, val=60
param=halt_test_on_errors, val=/CK/
param=qmism_verify_bitset, val=1
param=warm_time, val=30
param=test_time, val=120
param=test_intervals, val=60
param=create_with_fw, val=sync
param=create_with_sweep, val=0
param=wait_if_not_exists, val=0
param=wait_after_create, val=0
param=init_docs, val=0
param=expected_workers, val=100
param=init_buff, val=10000
param=wait_for_copy, val=1
param=create_with_debug_objects, val=1
param=create_with_split_heavy_tabs, val=1
param=create_with_separate_qdistr_idx, val=0
param=create_with_compound_columns_order, val=most_selective_first
param=replace_instance, val=1
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=make_html, val=1
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=file_name_with_test_params, val=regular
param=file_name_this_host_info, val=no_host_info
param=gather_hardware_info, val=1
param=use_mtee, val=0
param=is_embed, val=0
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED.  
DTS_END                         2020-08-15 15:52:53.3820
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(31:12) ==> SP_HALT_ON_ERROR(46:13)
IP                              ::1/47728
TRN_ID                          220005
ATT_ID                          504
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: 8739.16 28995 1048699
customer order: creation 155.99 384 18719
customer order: refuse 13.41 545 1609
order to supplier: creation 41.64 1599 4997
order to supplier: removal 3.12 163093 374
invoice (draft): creation 42.58 4306 5110
invoice (draft): removal 50.22 1232 6026
invoice accept: apply 37.88 24826 4545
invoice accept: cancel 45.56 1131 5467
customer reserve: creation 3556.70 237 426804
customer reserve: removal 4516.31 84 541957
realization accept: apply 75.88 225 9106
realization accept: cancel 80.83 175 9699
payment from customer: creation 53.54 144 6425
payment from customer: removal 5.39 135 647
payment to supplier: creation 44.67 173 5360
payment to supplier: removal 3.13 147 375
service: total inventory turnovers 4.41 1540 529
service: total monetary turnovers 7.06 117 847
service: aggregate perf. data 0.83 24281 99
service: refresh index statistics 0.03 355523 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 15-08-2020 15:53:57 to 15-08-2020 15:54:00.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2300
TEST_TIME 2 1875
TEST_TIME 3 2654
TEST_TIME 4 3288
TEST_TIME 5 3749
TEST_TIME 6 4188
TEST_TIME 7 3940
TEST_TIME 8 4238
TEST_TIME 9 4501
TEST_TIME 10 4612
TEST_TIME 11 4664
TEST_TIME 12 4985
TEST_TIME 13 4928
TEST_TIME 14 4961
TEST_TIME 15 5081
TEST_TIME 16 5169
TEST_TIME 17 5295
TEST_TIME 18 5598
TEST_TIME 19 5755
TEST_TIME 20 5929
TEST_TIME 21 5846
TEST_TIME 22 5908
TEST_TIME 23 5854
TEST_TIME 24 6031
TEST_TIME 25 6149
TEST_TIME 26 6360
TEST_TIME 27 6435
TEST_TIME 28 6446
TEST_TIME 29 6401
TEST_TIME 30 6554
TEST_TIME 31 6618
TEST_TIME 32 6639
TEST_TIME 33 6808
TEST_TIME 34 6856
TEST_TIME 35 7250
TEST_TIME 36 7530
TEST_TIME 37 7617
TEST_TIME 38 7884
TEST_TIME 39 8172
TEST_TIME 40 8856
TEST_TIME 41 9293
TEST_TIME 42 9274
TEST_TIME 43 9300
TEST_TIME 44 9173
TEST_TIME 45 8985
TEST_TIME 46 8851
TEST_TIME 47 8799
TEST_TIME 48 8692
TEST_TIME 49 8708
TEST_TIME 50 8733
TEST_TIME 51 8722
TEST_TIME 52 8674
TEST_TIME 53 8651
TEST_TIME 54 8616
TEST_TIME 55 8603
TEST_TIME 56 8547
TEST_TIME 57 8504
TEST_TIME 58 8493
TEST_TIME 59 8430
TEST_TIME 60 8423
TEST_TIME 61 8445
TEST_TIME 62 8371
TEST_TIME 63 8355
TEST_TIME 64 8391
TEST_TIME 65 8311
TEST_TIME 66 8337
TEST_TIME 67 8369
TEST_TIME 68 8419
TEST_TIME 69 8481
TEST_TIME 70 8453
TEST_TIME 71 8550
TEST_TIME 72 8592
TEST_TIME 73 8560
TEST_TIME 74 8522
TEST_TIME 75 8561
TEST_TIME 76 8680
TEST_TIME 77 8684
TEST_TIME 78 8761
TEST_TIME 79 8706
TEST_TIME 80 8653
TEST_TIME 81 8657
TEST_TIME 82 8621
TEST_TIME 83 8585
TEST_TIME 84 8612
TEST_TIME 85 8626
TEST_TIME 86 8590
TEST_TIME 87 8548
TEST_TIME 88 8477
TEST_TIME 89 8500
TEST_TIME 90 8557
TEST_TIME 91 8577
TEST_TIME 92 8619
TEST_TIME 93 8631
TEST_TIME 94 8653
TEST_TIME 95 8701
TEST_TIME 96 8663
TEST_TIME 97 8635
TEST_TIME 98 8726
TEST_TIME 99 8737
TEST_TIME 100 8759
TEST_TIME 101 8787
TEST_TIME 102 8781
TEST_TIME 103 8828
TEST_TIME 104 8799
TEST_TIME 105 8724
TEST_TIME 106 8774
TEST_TIME 107 8717
TEST_TIME 108 8699
TEST_TIME 109 8737
TEST_TIME 110 8704
TEST_TIME 111 8690
TEST_TIME 112 8636
TEST_TIME 113 8668
TEST_TIME 114 8651
TEST_TIME 115 8666
TEST_TIME 116 8729
TEST_TIME 117 8717
TEST_TIME 118 8703
TEST_TIME 119 8721
TEST_TIME 120 8742

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 15-08-2020 15:54:01 to 15-08-2020 15:54:04.

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 1443140 1443140 0 0.00 0 18828 8 0 0
doc_list_biud 1443135 1443135 0 0.00 0 25946 40 0 0
sp_add_invoice_to_stock 4679 4545 134 2.86 30 202014 24826 0 134
sp_cancel_adding_invoice 5467 5467 0 0.00 5 61522 1131 0 0
sp_cancel_client_order 1609 1609 0 0.00 4 15201 545 0 0
sp_cancel_customer_reserve 541957 541957 0 0.00 1 26006 84 0 0
sp_cancel_pay_from_customer 647 647 0 0.00 3 7873 135 0 0
sp_cancel_pay_to_supplier 375 375 0 0.00 3 5061 147 0 0
sp_cancel_supplier_invoice 6038 6026 12 0.19 2 22207 1232 0 12
sp_cancel_supplier_order 374 374 0 0.00 6 1751331 163094 0 0
sp_cancel_write_off 9699 9699 0 0.00 1 15380 175 0 0
sp_client_order 18719 18719 0 0.00 3 28718 384 0 0
sp_customer_reserve 426804 426804 0 0.00 0 78549 238 0 0
sp_fill_shopping_cart 28807 28807 0 0.00 1 17711 49 0 0
sp_fill_shopping_cart_clo_res 426789 426789 0 0.00 0 19540 15 0 0
sp_get_clo_for_invoice 4530 4530 0 0.00 0 17981 354 0 0
sp_kill_cost_storno 16188 16188 0 0.00 0 7679 95 0 0
sp_kill_qstorno_ret_qs2qd 1100013 1100013 0 0.00 0 25938 46 0 0
sp_kill_qty_storno 578766 578766 0 0.00 0 25946 100 0 0
sp_lock_selected_doc 18974 18974 0 0.00 0 8447 4 0 0
sp_make_cost_storno 25421 25421 0 0.00 0 18827 70 0 0
sp_multiply_rows_for_pdistr 25419 25419 0 0.00 0 7304 34 0 0
sp_multiply_rows_for_qdistr 18719 18719 0 0.00 0 11852 153 0 0
sp_pay_from_customer 6425 6425 0 0.00 4 45159 145 0 0
sp_pay_to_supplier 5360 5360 0 0.00 4 18833 173 0 0
sp_payment_common 11785 11785 0 0.00 1 45153 33 0 0
sp_qd_handle_on_cancel_clo 1609 1609 0 0.00 0 15188 503 0 0
sp_qd_handle_on_invoice_upd_sts 9996 9996 0 0.00 0 22063 525 0 0
sp_qd_handle_on_reserve_upd_sts 9105 9105 0 0.00 0 22495 80 0 0
sp_reserve_write_off 9106 9106 0 0.00 6 22516 225 0 0
sp_supplier_invoice 5110 5110 0 0.00 7 94886 4306 0 0
sp_supplier_order 4997 4997 0 0.00 5 37230 1600 0 0
srv_aggregate_perf_data 99 99 0 0.00 2261 320108 24282 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 868 125576 40638 0 0
srv_find_qd_qs_mism 1004555 1004555 0 0.00 0 17551 7 0 0
srv_make_invnt_saldo 529 529 0 0.00 43 116860 1541 0 0
srv_make_money_saldo 847 847 0 0.00 1 63449 117 0 0
srv_recalc_idx_stat 4 4 0 0.00 217055 596914 355524 0 0
t$perf-abend:sp_add_invoice_to_stock 134 134 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4547 4547 0 0.00 0 15931 320 0 0
t$perf-norm:sp_cancel_adding_invoice 361 361 0 0.00 1 10320 192 0 0
t$perf-norm:sp_cancel_client_order 1609 1609 0 0.00 0 5529 30 0 0
t$perf-norm:sp_cancel_customer_reserve 647 647 0 0.00 0 1116 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 647 647 0 0.00 0 1478 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 375 375 0 0.00 0 2353 18 0 0
t$perf-norm:sp_cancel_supplier_invoice 372 372 0 0.00 0 2272 38 0 0
t$perf-norm:sp_cancel_supplier_order 374 374 0 0.00 0 50725 2972 0 0
t$perf-norm:sp_cancel_write_off 955 955 0 0.00 0 2311 13 0 0
t$perf-norm:sp_client_order 18719 18719 0 0.00 0 15643 32 0 0
t$perf-norm:sp_customer_reserve 936 936 0 0.00 0 1385 9 0 0
t$perf-norm:sp_pay_from_customer 6426 6426 0 0.00 0 15955 17 0 0
t$perf-norm:sp_pay_to_supplier 5360 5360 0 0.00 0 13950 20 0 0
t$perf-norm:sp_reserve_write_off 9106 9106 0 0.00 0 11316 16 0 0
t$perf-norm:sp_supplier_invoice 5110 5110 0 0.00 0 5000 30 0 0
t$perf-norm:sp_supplier_order 4997 4997 0 0.00 0 15979 39 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 1035 52 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 1120 25 0 0
t$perf-norm:srv_make_invnt_saldo 530 530 0 0.00 0 11298 76 0 0
t$perf-norm:srv_make_money_saldo 847 847 0 0.00 0 1422 46 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 9326 9058 268 2.87 0 4087 23 0 268
v_all_customers 34326 34326 0 0.00 0 25 1 0 0
v_all_suppliers 27896 27896 0 0.00 0 22 1 0 0
v_all_wares 37438 37438 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 722 722 0 0.00 0 2243 13 0 0
v_cancel_client_order 3218 3218 0 0.00 0 2926 8 0 0
v_cancel_customer_prepayment 1294 1294 0 0.00 0 4087 12 0 0
v_cancel_customer_reserve 1294 1294 0 0.00 0 2987 11 0 0
v_cancel_payment_to_supplier 750 750 0 0.00 0 4959 24 0 0
v_cancel_supplier_invoice 768 744 24 3.12 0 3023 27 0 24
v_cancel_supplier_order 748 748 0 0.00 0 5177 18 0 0
v_cancel_write_off 1910 1910 0 0.00 0 3083 24 0 0
v_max_id_clo_ord 4991 4991 0 0.00 0 1382 2 0 0
v_max_id_clo_res 936 936 0 0.00 0 1961 11 0 0
v_max_id_ord_sup 5097 5097 0 0.00 0 17706 7 0 0
v_max_non_paid_invoice 1500 1500 0 0.00 0 8818 11 0 0
v_max_non_paid_realizn 3349 3349 0 0.00 0 6036 6 0 0
v_min_id_clo_ord 4991 4991 0 0.00 0 2999 9 0 0
v_min_id_clo_res 936 936 0 0.00 0 4679 9 0 0
v_min_id_ord_sup 5097 5097 0 0.00 0 4340 5 0 0
v_min_non_paid_invoice 5360 5360 0 0.00 0 6626 20 0 0
v_min_non_paid_realizn 6425 6425 0 0.00 0 7862 17 0 0
v_our_firm 9264 9264 0 0.00 0 14 1 0 0
v_reserve_write_off 18210 18210 0 0.00 0 3052 7 0 0
x_lock_depdocs_on_canc_invoice 11121 11121 0 0.00 0 14414 171 0 0
x_lock_depdocs_on_canc_sup_ord 374 374 0 0.00 0 3479 564 0 0
x_make_qstorno_1000_1200 4997 4997 0 0.00 2 37220 1478 0 0
x_make_qstorno_1200_2000 5110 5110 0 0.00 3 85448 4126 0 0
x_make_qstorno_2100_3300 426804 426804 0 0.00 0 78545 217 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-08-2020 15:54:05 to 15-08-2020 15:54:09.

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
13:22:56 1 8192000000 8798382336 8808857600 5057328 0.061 1 1 2 0 5175376 55488 62560
13:23:58 36333 8192000000 10392523264 10715222016 1434692576 14.903 100 90 180 2 1469771264 6205216 28873472
13:25:45 7101 8192000000 10443892816 10773622784 1500325824 15.479 100 92 185 0 1538892720 9259696 29307200
13:27:06 19647 8192000000 10488638032 10829799424 1538000192 15.806 100 84 166 3 1575236080 10660240 26575648
13:28:35 17363 8192000000 10560797008 10915209216 1612985360 16.450 100 89 178 0 1652304432 12389264 26929808
13:30:01 8801 8192000000 10620171472 10985971712 1676455168 16.988 100 77 154 1 1711191072 12481104 22254800
13:31:14 10670 8192000000 10654496512 11037544448 1728712256 17.425 100 65 129 1 1758895856 12411872 17771728
13:32:27 13484 8192000000 10694244288 11081539584 1767405120 17.746 100 71 142 1 1799190784 12108080 19677584
13:33:47 3967 8192000000 10747319904 11132182528 1810686352 18.102 100 78 157 0 1845327904 12722320 21919232
13:34:53 2650 8192000000 10775591008 11160707072 1836892320 18.316 100 84 168 0 1873233456 12261376 24079760
13:36:01 1651 8192000000 10797571584 11181641728 1854332016 18.457 100 87 175 0 1892935600 14223984 24379600
13:37:06 13976 8192000000 10805822160 11198111744 1868707008 18.574 100 78 155 1 1901994544 12125360 21162176
13:38:21 2676 8192000000 10790473312 11205443584 1870437104 18.588 100 43 86 2 1892576848 9481184 12658560
13:39:26 4936 8192000000 10772680512 11197030400 1870391600 18.587 100 23 46 1 1883050400 5838576 6820224
13:40:33 5634 8192000000 10797467392 11217969152 1883074976 18.690 100 33 66 0 1904074320 12212608 8786736
13:41:41 44058 8192000000 10831325952 11232374784 1898522288 18.814 100 65 130 4 1922871376 5481760 18867328
13:43:26 6226 8192000000 10815141456 11238531072 1898206752 18.812 100 24 48 1 1911266640 6422832 6637056
13:44:35 10348 8192000000 10797978368 11220951040 1884734672 18.703 100 20 40 1 1896661552 5702816 6224064
13:45:47 6499 8192000000 10758694016 11172524032 1815186544 18.138 98 21 42 1 1826741904 5701008 5854352
13:46:54 3493 8192000000 10693995088 11103854592 1752393008 17.621 98 27 52 2 1770369456 10175232 7801216
13:47:58 52291 8192000000 10671445344 11064401920 1687124208 17.077 95 32 64 2 1701995760 5356160 9515392
13:49:50 6239 8192000000 10708026832 11117572096 1754426176 17.638 97 29 58 3 1767852336 5734976 7691184
13:50:58 10244 8192000000 10728008832 11149131776 1803726848 18.044 99 16 32 2 1814097648 5894304 4476496
13:52:13 10774 8192000000 10768301680 11192352768 1858575040 18.492 100 19 37 1 1869175136 5207408 5392688
13:53:25 33608 8192000000 10807917680 11210055680 1876297952 18.635 100 48 96 0 1896368832 6387312 13683568
13:55:04 17887 8192000000 10820888096 11238600704 1896039040 18.794 100 56 111 2 1922710000 11455392 15215568
13:56:31 17202 8192000000 10849524384 11261202432 1917155552 18.964 100 51 103 2 1946022736 14386016 14481168
13:57:56 39320 8192000000 10861795088 11288748032 1928798464 19.057 100 39 78 0 1955787104 15039264 11949376
13:59:48 18558 8192000000 10886006672 11305291776 1947159488 19.204 100 53 103 1 1978107456 16733184 14214784
14:01:12 23791 8192000000 10898629952 11323809792 1959628976 19.303 100 45 89 2 1991816608 19057600 13130032
14:02:42 10302 8192000000 10918431568 11341791232 1974198688 19.419 100 66 129 2 2013290384 19335360 19756336
14:03:59 5013 8192000000 10920495184 11351912448 1981373216 19.476 100 53 106 0 2019812880 22339104 16100560
14:05:21 5617 8192000000 10940082464 11359453184 1984958288 19.504 100 61 122 0 2027700720 25044528 17697904
14:06:33 5455 8192000000 10946104064 11370598400 1987153568 19.521 100 58 116 0 2035039568 30562064 17323936
14:07:47 13864 8192000000 10968966560 11388510208 1995719216 19.589 100 66 132 2 2055773056 40607344 19446496
14:09:09 12851 8192000000 10953595120 11373346816 1971383568 19.396 99 63 124 1 2026145776 35596816 19165392
14:10:27 10569 8192000000 10943732368 11368136704 1979105760 19.458 100 54 108 3 2033921152 37907536 16907856
14:11:44 7735 8192000000 10950740784 11378614272 1967171344 19.363 99 58 116 0 2023315136 38119872 18023920
14:12:59 9500 8192000000 10958029344 11392475136 1998283968 19.609 100 55 110 2 2047534560 32447760 16802832
14:14:29 4244 8192000000 10969608528 11404787712 2004437472 19.658 100 52 102 1 2058307488 37564880 16305136
14:15:40 6268 8192000000 10965426336 11382312960 1967415312 19.365 99 73 144 2 2033565296 43233488 22916496
14:16:50 3901 8192000000 10995866928 11410415616 2005333392 19.665 100 84 167 1 2078373264 46791824 26248048
14:18:03 3537 8192000000 10992767776 11401216000 1982550336 19.485 99 93 186 1 2062672416 50152864 29969216
14:19:15 2542 8192000000 10989724208 11400814592 1992776736 19.566 100 82 164 1 2068874576 50773952 25323888
14:20:26 866 8192000000 11004566960 11412467712 1998913744 19.614 100 89 178 1 2085110864 57677424 28519696
14:21:35 3736 8192000000 11012089520 11419385856 1995027024 19.583 100 90 180 3 2086456480 62307280 29122176
14:22:50 1202 8192000000 11012428448 11415801856 1992647008 19.565 100 93 184 0 2091248288 69287296 29313984
14:23:58 1624 8192000000 11013750976 11414880256 1988609968 19.533 100 87 173 0 2086089248 70036496 27442784
14:25:07 2569 8192000000 11011911216 11418091520 1985844864 19.511 100 90 180 1 2091430032 76953600 28631568
14:26:18 3809 8192000000 11023480752 11428515840 1996205552 19.593 100 88 173 1 2101261712 77205200 27850960
14:27:31 11406 8192000000 10986284432 11414867968 1987501120 19.524 100 48 94 4 2068334048 64963280 15869648
14:28:48 19194 8192000000 10981774320 11426361344 1989279232 19.538 100 37 74 1 2062747248 59916128 13551888
14:30:13 15509 8192000000 10965761312 11414302720 1972881984 19.408 100 33 66 0 2053245056 68194208 12168864
14:31:37 33296 8192000000 10928497440 11380506624 1935600976 19.112 99 24 48 1 2012109200 66734336 9773888
14:33:15 38702 8192000000 10888073152 11343519744 1951670416 19.240 99 16 32 1 1970895728 14527936 4697376
14:34:55 87520 8192000000 10874173136 11342331904 1925472800 19.031 99 26 53 0 1948955856 15967296 7515760
14:37:30 70536 8192000000 10896873056 11332984832 1932057104 19.083 99 37 74 0 1951083584 7831360 11195120
14:39:43 65427 8192000000 10886922912 11345969152 1934986048 19.107 99 22 44 1 1953201712 11697680 6517984
14:41:54 56520 8192000000 10885670336 11337273344 1919153440 18.980 98 29 56 2 1937958256 11919072 6885744
14:43:53 97846 8192000000 10893153744 11356798976 1932990784 19.091 98 13 25 1 1944727984 8077824 3659376
14:46:41 33079 8192000000 10893507360 11357450240 1969253936 19.380 100 29 59 2 1987311648 9199680 8858032
14:48:26 33730 8192000000 10898601504 11360686080 1970933680 19.393 100 5 10 0 1980990848 8783488 1273680
14:50:01 94996 8192000000 10907188832 11370905600 1981908064 19.480 100 20 38 0 1997598000 9826704 5863232
14:52:42 79005 8192000000 10885546032 11353210880 1938242640 19.133 98 19 37 2 1950222624 7248384 4731600
14:55:31 51686 8192000000 10892842704 11364859904 1976041488 19.433 100 18 36 2 1989786736 9146272 4598976
14:57:28 48398 8192000000 10924000912 11384262656 1987820672 19.527 100 25 50 1 2005951504 10951824 7179008
14:59:17 59138 8192000000 10923885936 11387998208 1950401024 19.230 98 16 32 1 1977149552 22346800 4401728
15:01:31 30446 8192000000 10909393856 11357110272 1951021840 19.235 99 29 58 0 1968834256 10066192 7746224
15:03:04 35199 8192000000 10904387664 11372814336 1958763552 19.296 100 24 48 0 1998074400 32722080 6588768
15:04:40 40885 8192000000 10872344048 11325927424 1868525568 18.572 95 32 61 4 1892236352 15546768 8164016
15:06:22 37426 8192000000 10907296768 11365027840 1961999760 19.322 100 28 56 2 1984636832 14929968 7707104
15:08:02 85409 8192000000 10892989328 11337211904 1863225024 18.529 95 17 35 2 1889018192 20426096 5367072
15:10:33 116185 8192000000 10857871184 11308277760 1903043360 18.851 98 17 34 1 1920800496 12863216 4893920
15:13:32 56447 8192000000 10888508208 11339759616 1939608016 19.144 99 39 78 1 1967727184 16915680 11203488
15:16:09 81169 8192000000 10850480208 11314077696 1898576752 18.815 98 20 40 2 1917538592 13194256 5767584
15:18:32 68821 8192000000 10823419952 11285229568 1871063184 18.593 97 12 24 1 1890458320 16155632 3239504
15:20:42 64219 8192000000 10836934880 11296555008 1867710336 18.566 97 23 44 1 1894659872 20096016 6853520
15:22:49 67089 8192000000 10828548864 11287076864 1821817424 18.193 95 21 42 0 1839870032 12589568 5463040
15:24:57 48149 8192000000 10862544480 11321401344 1917370736 18.966 99 16 32 0 1931188048 9913584 3903728
15:26:54 94584 8192000000 10880642928 11334049792 1878234272 18.651 96 13 24 1 1892463936 10575856 3653808
15:29:29 24391 8192000000 10907365248 11354939392 1963156960 19.331 100 52 103 1 1994353248 17797728 13398560
15:30:54 64015 8192000000 10887223504 11344252928 1864201856 18.537 95 16 32 0 1884259248 15546256 4511136
15:33:09 59116 8192000000 10892935568 11346489344 1954290704 19.261 100 21 42 1 1974215568 14179936 5744928
15:35:11 125576 8192000000 10879703888 11347341312 1866964656 18.560 95 17 34 0 1886071776 14749472 4357648
15:38:49 67931 8192000000 10895917872 11359125504 1961207840 19.316 100 21 42 0 1976170144 9333520 5628784
15:40:58 26576 8192000000 10876615792 11349954560 1931272528 19.077 98 29 58 2 1954875808 15682224 7921056
15:42:26 51820 8192000000 10881080064 11347918848 1940689440 19.152 99 7 13 1 1953597712 10973632 1934640
15:44:19 66925 8192000000 10906679744 11375128576 1974591568 19.422 100 13 26 0 1995038992 16048528 4398896
15:46:26 89748 8192000000 10877171216 11360288768 1918715200 18.977 97 14 28 1 1938019584 14689424 4614960
15:48:58 53556 8192000000 10882961824 11356192768 1940440016 19.150 99 16 32 0 1956214304 11545888 4228400
15:50:52 105380 8192000000 10855481328 11328970752 1879858592 18.664 97 12 24 1 1895354704 12485488 3010624

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 15-08-2020 15:54:10 to 15-08-2020 15:54:24.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 134 v_add_invoice_to_stock 335544517
user_exc 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-08-2020 15:54:25 to 15-08-2020 15:54:27.

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          218647
MON$OLDEST_ACTIVE               220259
MON$OLDEST_SNAPSHOT             220259
MON$NEXT_TRANSACTION            220259
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                       2063008
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.33355 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.7.33355 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.7.33355 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.7.33355 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:6: 
:7: mscs-test-161.budzdorov.ru    Sat Aug 15 15:52:56 2020
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Sat Aug 15 15:53:02 2020
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:13: 
--- end of diff output ---

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

15.08.20 15:54:29. 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: 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-08-15 15:54:31 NO_SEVERE_ERRORS_FOUND
15.08.20 15:54:33. End of report.