Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.08.2020 21: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 1200.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 16802520 kB
MemAvailable 31030808 kB
Buffers 0 kB
Cached 14906400 kB
SwapCached 6280 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-11 21:51:56.6630
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/50276
TRN_ID                          225626
ATT_ID                          585
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: 8531.17 30452 1023741
customer order: creation 158.16 397 18979
customer order: refuse 13.66 538 1639
order to supplier: creation 43.55 1510 5226
order to supplier: removal 3.33 144792 400
invoice (draft): creation 43.38 4221 5206
invoice (draft): removal 46.59 1219 5591
invoice accept: apply 39.67 25193 4760
invoice accept: cancel 42.77 1304 5132
customer reserve: creation 3655.89 245 438707
customer reserve: removal 4204.13 88 504496
realization accept: apply 77.83 244 9339
realization accept: cancel 78.54 156 9425
payment from customer: creation 55.95 187 6714
payment from customer: removal 5.36 106 643
payment to supplier: creation 46.64 190 5597
payment to supplier: removal 3.32 122 398
service: total inventory turnovers 4.48 1404 538
service: total monetary turnovers 7.06 60 847
service: aggregate perf. data 0.83 25409 100
service: refresh index statistics 0.03 401655 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-08-2020 21:54:15 to 11-08-2020 21:54:17.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3043
TEST_TIME 2 2761
TEST_TIME 3 4000
TEST_TIME 4 3803
TEST_TIME 5 4029
TEST_TIME 6 4048
TEST_TIME 7 3988
TEST_TIME 8 3999
TEST_TIME 9 3908
TEST_TIME 10 4092
TEST_TIME 11 4449
TEST_TIME 12 4457
TEST_TIME 13 4606
TEST_TIME 14 4579
TEST_TIME 15 4865
TEST_TIME 16 4887
TEST_TIME 17 4837
TEST_TIME 18 5171
TEST_TIME 19 5083
TEST_TIME 20 5451
TEST_TIME 21 5531
TEST_TIME 22 5622
TEST_TIME 23 5794
TEST_TIME 24 5921
TEST_TIME 25 6291
TEST_TIME 26 6266
TEST_TIME 27 6249
TEST_TIME 28 6647
TEST_TIME 29 6873
TEST_TIME 30 7050
TEST_TIME 31 7241
TEST_TIME 32 7110
TEST_TIME 33 7179
TEST_TIME 34 7266
TEST_TIME 35 7500
TEST_TIME 36 7891
TEST_TIME 37 8170
TEST_TIME 38 8494
TEST_TIME 39 8580
TEST_TIME 40 8422
TEST_TIME 41 8555
TEST_TIME 42 8546
TEST_TIME 43 8839
TEST_TIME 44 9041
TEST_TIME 45 8892
TEST_TIME 46 8948
TEST_TIME 47 8911
TEST_TIME 48 8832
TEST_TIME 49 8668
TEST_TIME 50 8629
TEST_TIME 51 8579
TEST_TIME 52 8562
TEST_TIME 53 8508
TEST_TIME 54 8585
TEST_TIME 55 8603
TEST_TIME 56 8575
TEST_TIME 57 8496
TEST_TIME 58 8443
TEST_TIME 59 8367
TEST_TIME 60 8468
TEST_TIME 61 8567
TEST_TIME 62 8641
TEST_TIME 63 8574
TEST_TIME 64 8603
TEST_TIME 65 8552
TEST_TIME 66 8485
TEST_TIME 67 8433
TEST_TIME 68 8426
TEST_TIME 69 8466
TEST_TIME 70 8447
TEST_TIME 71 8482
TEST_TIME 72 8479
TEST_TIME 73 8502
TEST_TIME 74 8467
TEST_TIME 75 8523
TEST_TIME 76 8496
TEST_TIME 77 8497
TEST_TIME 78 8508
TEST_TIME 79 8554
TEST_TIME 80 8595
TEST_TIME 81 8607
TEST_TIME 82 8568
TEST_TIME 83 8632
TEST_TIME 84 8579
TEST_TIME 85 8545
TEST_TIME 86 8599
TEST_TIME 87 8536
TEST_TIME 88 8505
TEST_TIME 89 8499
TEST_TIME 90 8490
TEST_TIME 91 8515
TEST_TIME 92 8477
TEST_TIME 93 8439
TEST_TIME 94 8395
TEST_TIME 95 8357
TEST_TIME 96 8460
TEST_TIME 97 8483
TEST_TIME 98 8481
TEST_TIME 99 8494
TEST_TIME 100 8470
TEST_TIME 101 8496
TEST_TIME 102 8570
TEST_TIME 103 8546
TEST_TIME 104 8539
TEST_TIME 105 8640
TEST_TIME 106 8639
TEST_TIME 107 8742
TEST_TIME 108 8726
TEST_TIME 109 8773
TEST_TIME 110 8751
TEST_TIME 111 8699
TEST_TIME 112 8702
TEST_TIME 113 8651
TEST_TIME 114 8620
TEST_TIME 115 8639
TEST_TIME 116 8599
TEST_TIME 117 8614
TEST_TIME 118 8598
TEST_TIME 119 8576
TEST_TIME 120 8535

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-08-2020 21:54:18 to 11-08-2020 21:54:21.

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 1428566 1428566 0 0.00 0 28133 9 0 0
doc_list_biud 1428566 1428566 0 0.00 0 27677 40 0 0
sp_add_invoice_to_stock 4874 4760 114 2.33 52 268776 25193 0 114
sp_cancel_adding_invoice 5132 5132 0 0.00 6 83512 1304 0 0
sp_cancel_client_order 1639 1639 0 0.00 4 10193 538 0 0
sp_cancel_customer_reserve 504496 504496 0 0.00 1 24568 89 0 0
sp_cancel_pay_from_customer 643 643 0 0.00 3 2371 107 0 0
sp_cancel_pay_to_supplier 398 398 0 0.00 3 9393 122 0 0
sp_cancel_supplier_invoice 5600 5591 9 0.16 2 23802 1219 0 9
sp_cancel_supplier_order 400 400 0 0.00 8 1350098 144793 0 0
sp_cancel_write_off 9425 9425 0 0.00 1 9945 157 0 0
sp_client_order 18979 18979 0 0.00 4 16740 398 0 0
sp_customer_reserve 438707 438707 0 0.00 0 142292 246 0 0
sp_fill_shopping_cart 29404 29404 0 0.00 1 35833 55 0 0
sp_fill_shopping_cart_clo_res 438699 438699 0 0.00 0 18931 18 0 0
sp_get_clo_for_invoice 4752 4752 0 0.00 0 51010 390 0 0
sp_kill_cost_storno 15598 15598 0 0.00 0 12035 95 0 0
sp_kill_qstorno_ret_qs2qd 1024408 1024408 0 0.00 0 24304 48 0 0
sp_kill_qty_storno 540774 540774 0 0.00 0 27676 104 0 0
sp_lock_selected_doc 19528 19528 0 0.00 0 10439 4 0 0
sp_make_cost_storno 26402 26402 0 0.00 0 17157 80 0 0
sp_multiply_rows_for_pdistr 26402 26402 0 0.00 0 10379 41 0 0
sp_multiply_rows_for_qdistr 18979 18979 0 0.00 0 16678 157 0 0
sp_pay_from_customer 6714 6714 0 0.00 4 16984 188 0 0
sp_pay_to_supplier 5597 5597 0 0.00 5 13044 190 0 0
sp_payment_common 12311 12311 0 0.00 2 16937 55 0 0
sp_qd_handle_on_cancel_clo 1639 1639 0 0.00 0 10184 500 0 0
sp_qd_handle_on_invoice_upd_sts 9884 9884 0 0.00 0 27675 551 0 0
sp_qd_handle_on_reserve_upd_sts 9339 9339 0 0.00 0 18430 80 0 0
sp_reserve_write_off 9339 9339 0 0.00 7 18465 244 0 0
sp_supplier_invoice 5206 5206 0 0.00 7 95102 4221 0 0
sp_supplier_order 5226 5226 0 0.00 5 48168 1511 0 0
srv_aggregate_perf_data 100 100 0 0.00 2707 409014 25410 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1060 205605 41842 0 0
srv_find_qd_qs_mism 978000 978000 0 0.00 0 28132 7 0 0
srv_make_invnt_saldo 538 538 0 0.00 33 81735 1405 0 0
srv_make_money_saldo 847 847 0 0.00 1 11900 61 0 0
srv_recalc_idx_stat 4 4 0 0.00 232064 485927 401656 0 0
t$perf-abend:sp_add_invoice_to_stock 114 114 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4760 4760 0 0.00 0 18676 290 0 0
t$perf-norm:sp_cancel_adding_invoice 401 401 0 0.00 1 6658 139 0 0
t$perf-norm:sp_cancel_client_order 1639 1639 0 0.00 0 1518 17 0 0
t$perf-norm:sp_cancel_customer_reserve 655 655 0 0.00 0 1508 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 643 643 0 0.00 0 4208 21 0 0
t$perf-norm:sp_cancel_pay_to_supplier 398 398 0 0.00 0 645 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 346 346 0 0.00 0 1417 19 0 0
t$perf-norm:sp_cancel_supplier_order 400 400 0 0.00 0 36739 2639 0 0
t$perf-norm:sp_cancel_write_off 955 955 0 0.00 0 1140 7 0 0
t$perf-norm:sp_client_order 18979 18979 0 0.00 0 11794 27 0 0
t$perf-norm:sp_customer_reserve 998 998 0 0.00 0 4033 14 0 0
t$perf-norm:sp_pay_from_customer 6714 6714 0 0.00 0 4613 13 0 0
t$perf-norm:sp_pay_to_supplier 5597 5597 0 0.00 0 9266 15 0 0
t$perf-norm:sp_reserve_write_off 9339 9339 0 0.00 0 11675 15 0 0
t$perf-norm:sp_supplier_invoice 5206 5206 0 0.00 0 4410 28 0 0
t$perf-norm:sp_supplier_order 5226 5226 0 0.00 0 10036 27 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 1114 29 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 765 16 0 0
t$perf-norm:srv_make_invnt_saldo 538 538 0 0.00 0 1643 49 0 0
t$perf-norm:srv_make_money_saldo 847 847 0 0.00 0 4919 34 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 27 11 0 0
v_add_invoice_to_stock 9732 9504 228 2.34 0 8607 25 0 228
v_all_customers 35016 35016 0 0.00 0 23 1 0 0
v_all_suppliers 28774 28774 0 0.00 0 25 1 0 0
v_all_wares 37958 37958 0 0.00 0 30 1 0 0
v_cancel_adding_invoice 802 802 0 0.00 0 903 9 0 0
v_cancel_client_order 3278 3278 0 0.00 0 2279 8 0 0
v_cancel_customer_prepayment 1286 1286 0 0.00 0 1716 8 0 0
v_cancel_customer_reserve 1310 1310 0 0.00 0 2082 8 0 0
v_cancel_payment_to_supplier 796 796 0 0.00 0 1620 6 0 0
v_cancel_supplier_invoice 710 692 18 2.53 0 4420 25 0 18
v_cancel_supplier_order 800 800 0 0.00 0 668 7 0 0
v_cancel_write_off 1910 1910 0 0.00 0 9361 23 0 0
v_max_id_clo_ord 5225 5225 0 0.00 0 684 2 0 0
v_max_id_clo_res 998 998 0 0.00 0 2307 17 0 0
v_max_id_ord_sup 5199 5199 0 0.00 0 8539 6 0 0
v_max_non_paid_invoice 1634 1634 0 0.00 0 4816 7 0 0
v_max_non_paid_realizn 3497 3497 0 0.00 0 4437 5 0 0
v_min_id_clo_ord 5225 5225 0 0.00 0 10392 10 0 0
v_min_id_clo_res 998 998 0 0.00 0 3893 8 0 0
v_min_id_ord_sup 5199 5199 0 0.00 0 4464 6 0 0
v_min_non_paid_invoice 5597 5597 0 0.00 0 11002 35 0 0
v_min_non_paid_realizn 6714 6714 0 0.00 0 16932 40 0 0
v_our_firm 9376 9376 0 0.00 0 20 1 0 0
v_reserve_write_off 18678 18678 0 0.00 0 9265 9 0 0
x_lock_depdocs_on_canc_invoice 10377 10377 0 0.00 0 17720 181 0 0
x_lock_depdocs_on_canc_sup_ord 400 400 0 0.00 0 3057 453 0 0
x_make_qstorno_1000_1200 5226 5226 0 0.00 2 47765 1391 0 0
x_make_qstorno_1200_2000 5206 5206 0 0.00 3 95024 4020 0 0
x_make_qstorno_2100_3300 438707 438707 0 0.00 0 142290 223 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-08-2020 21:54:22 to 11-08-2020 21:54:26.

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:21:59 2 8192000000 8798382528 8808857600 5057520 0.061 1 1 2 0 5175568 55488 62560
19:23:00 37764 8192000000 10366467968 10685181952 1405582592 14.645 100 90 179 0 1440837536 5473664 29781280
19:24:43 26379 8192000000 10437065824 10763534336 1483951280 15.336 100 90 180 0 1520226096 7744288 28530528
19:26:17 15574 8192000000 10465764976 10800103424 1519056992 15.642 100 92 184 0 1557857408 9451360 29349056
19:27:40 9579 8192000000 10516657168 10867617792 1578745056 16.157 100 70 140 0 1609001072 9362976 20893040
19:28:53 12043 8192000000 10602088672 10963791872 1668128464 16.917 100 80 158 2 1702699552 11800992 22770096
19:30:10 9240 8192000000 10668807728 11040739328 1726725040 17.408 100 73 146 0 1760207968 13074816 20408112
19:31:22 5722 8192000000 10712007168 11091730432 1772257536 17.786 100 69 128 4 1803043392 12648672 18137184
19:32:29 4716 8192000000 10760141328 11144269824 1818243424 18.163 100 85 170 2 1856457200 14463008 23750768
19:33:36 1285 8192000000 10774843568 11157078016 1842898944 18.364 100 89 178 2 1880963840 13352816 24712080
19:34:39 14206 8192000000 10791405808 11178778624 1857817840 18.486 100 84 168 1 1893859680 13085760 22956080
19:35:54 9638 8192000000 10780666784 11181142016 1858235264 18.489 100 40 80 3 1877304832 5972272 13097296
19:37:05 6660 8192000000 10779315568 11195576320 1867978912 18.568 100 39 78 0 1887049168 8101840 10968416
19:38:15 8205 8192000000 10785954736 11207487488 1875608240 18.630 100 40 80 4 1899211312 10984336 12618736
19:39:27 13946 8192000000 10796152864 11220082688 1883621936 18.694 100 22 40 2 1894854160 5373936 5858288
19:40:42 37216 8192000000 10827510560 11239518208 1897595360 18.807 100 50 101 3 1918649376 5685728 15368288
19:42:19 6250 8192000000 10809991056 11239006208 1900457728 18.830 100 34 67 1 1916228128 6221616 9548784
19:43:27 5115 8192000000 10785491088 11212709888 1863655872 18.533 99 27 55 2 1877625024 5503568 8465584
19:44:33 54582 8192000000 10731233104 11137585152 1716306160 17.321 93 17 34 0 1725814096 4741088 4766848
19:46:29 5697 8192000000 10706899408 11112919040 1755646992 17.648 97 33 66 0 1772353488 7376576 9329920
19:47:35 7109 8192000000 10666771904 11061465088 1666364064 16.903 94 15 30 2 1676415760 4745568 5306128
19:48:43 14945 8192000000 10725070512 11137593344 1810556016 18.100 100 22 44 2 1825579104 9229840 5793248
19:50:00 20835 8192000000 10738646832 11159711744 1825646160 18.224 100 17 34 0 1836098992 5187264 5265568
19:51:23 11249 8192000000 10768803056 11172212736 1841557456 18.353 100 24 45 1 1853966816 5079488 7329872
19:52:47 9016 8192000000 10812922656 11213307904 1878927280 18.656 100 57 113 1 1902884224 8329104 15627840
19:53:59 35006 8192000000 10828783104 11244113920 1896686240 18.800 100 55 110 3 1922489584 10811920 14991424
19:55:39 70016 8192000000 10851115040 11277799424 1921894480 19.002 100 50 100 1 1951266480 15150784 14221216
19:57:58 38451 8192000000 10849082832 11283759104 1930297968 19.069 100 27 53 3 1950197472 11989552 7909952
19:59:46 20272 8192000000 10887706720 11309473792 1950004496 19.227 100 51 101 1 1979143456 14750224 14388736
20:01:19 10223 8192000000 10908850368 11326308352 1962978576 19.330 100 68 136 5 2002533328 20336800 19217952
20:02:32 2173 8192000000 10930224272 11346006016 1976683456 19.438 100 74 147 1 2020317568 22028112 21606000
20:03:40 5274 8192000000 10941746880 11358261248 1985875824 19.511 100 60 118 0 2026310832 23023552 17411456
20:04:52 10367 8192000000 10943914528 11366178816 1994424832 19.579 100 65 130 0 2038639408 24731008 19483568
20:06:11 13531 8192000000 10951170176 11376963584 1998009712 19.607 100 54 109 2 2043255840 28538208 16707920
20:07:29 11434 8192000000 10969651408 11394105344 2016414352 19.752 100 59 114 1 2061827456 28741600 16671504
20:08:47 4147 8192000000 10975255120 11384565760 1984283936 19.499 99 72 143 1 2038964032 32931712 21748384
20:09:58 14449 8192000000 10960360928 11393007616 2001910496 19.638 100 54 108 2 2051812224 32393488 17508240
20:11:21 9444 8192000000 10989782896 11420651520 2001935456 19.638 99 58 115 0 2060071008 39478624 18656928
20:12:37 2765 8192000000 10998594032 11416715264 2012770176 19.723 100 85 167 2 2080918928 40860064 27288688
20:13:48 11950 8192000000 10994926784 11411091456 2015937104 19.748 100 79 153 1 2078458048 39344320 23176624
20:15:06 2754 8192000000 10979923568 11392774144 1993906400 19.575 100 86 169 1 2061003968 41075728 26021840
20:16:17 2685 8192000000 10966625840 11382362112 1974012480 19.417 99 78 156 0 2034639248 36786272 23840496
20:17:26 2238 8192000000 10961346016 11369308160 1976520112 19.437 100 85 168 1 2040837232 38063024 26254096
20:18:38 2994 8192000000 10972627856 11380678656 1979989680 19.465 100 80 161 1 2049117136 44380144 24747312
20:19:52 5088 8192000000 10926660064 11364372480 1949701680 19.224 99 51 102 1 2010010160 44181632 16126848
20:21:20 3319 8192000000 10919876192 11332853760 1922575920 19.007 99 81 161 2 1994902992 46789312 25537760
20:22:32 2344 8192000000 10958970192 11365085184 1969348800 19.380 100 92 186 3 2040017184 43194896 27473488
20:23:42 4775 8192000000 10956094800 11362308096 1967013808 19.362 100 84 168 2 2034284336 41164912 26105616
20:24:55 4492 8192000000 10994014352 11403841536 1994121648 19.576 100 79 157 0 2069328032 50081728 25124656
20:26:11 1059 8192000000 11004937264 11417456640 2000538080 19.627 100 88 175 1 2085662960 57137728 27987152
20:27:17 30411 8192000000 10958605360 11405983744 1971838736 19.400 99 31 62 2 2028650160 46323840 10487584
20:28:57 17810 8192000000 10953962944 11395092480 1958424208 19.294 98 40 80 0 2011679328 40822144 12432976
20:30:21 13144 8192000000 10959968544 11404218368 1997989024 19.607 100 43 86 5 2044021312 32683552 13348736
20:32:25 24890 8192000000 10933262016 11381800960 1987615312 19.525 100 47 97 0 2025671072 23684240 14371520
20:33:55 22243 8192000000 10924508144 11381944320 1965170992 19.347 100 20 40 2 2009758304 38001648 6585664
20:35:27 24425 8192000000 10892165856 11363246080 1943023152 19.171 99 24 49 0 1976958560 26559328 7376080
20:37:06 162030 8192000000 10893744864 11365257216 1956432624 19.278 99 26 52 0 1976996672 12500784 8063264
20:40:59 64571 8192000000 10908928896 11346620416 1918571344 18.975 97 40 80 1 1939255840 9576096 11108400
20:43:10 57468 8192000000 10888671872 11351613440 1923690720 19.016 98 14 28 1 1946184144 17870240 4623184
20:45:18 205604 8192000000 10902261824 11345137664 1886148192 18.715 96 20 39 0 1913376304 21391792 5836320
20:49:46 39404 8192000000 10909293280 11363700736 1970104480 19.386 100 21 42 1 1991558912 14871024 6583408
20:51:38 29235 8192000000 10891992528 11366666240 1958195632 19.292 99 32 64 2 1986972704 18897568 9879504
20:53:21 119769 8192000000 10889278720 11349262336 1933222368 19.093 98 14 28 0 1951535728 14364672 3948688
20:56:36 93026 8192000000 10843967024 11305345024 1885744944 18.711 96 19 39 0 1897024928 6572896 4707088
20:59:12 56507 8192000000 10874009712 11344121856 1929683280 19.064 98 4 8 1 1940609632 10104384 821968
21:01:15 40065 8192000000 10871477216 11309797376 1907564560 18.887 97 27 53 2 1924021568 9431056 7025952
21:03:05 37156 8192000000 10879545888 11333799936 1910446784 18.910 98 15 30 0 1929018704 14519696 4052224
21:04:46 85592 8192000000 10878164976 11337342976 1885690720 18.711 96 27 54 0 1905889200 11700512 8497968
21:07:19 27181 8192000000 10884424992 11328163840 1934068336 19.099 99 22 44 1 1947062336 7302064 5691936
21:08:47 66675 8192000000 10871092704 11323584512 1886798832 18.720 97 26 50 2 1904331392 10405344 7127216
21:10:54 93606 8192000000 10862534960 11314692096 1840649248 18.346 95 6 12 0 1853569680 11028992 1891440
21:13:34 52472 8192000000 10859457120 11313573888 1901809168 18.841 98 35 69 0 1934971008 23375648 9786192
21:15:27 92220 8192000000 10877271280 11329212416 1906520272 18.879 98 25 50 2 1924104976 10168384 7416320
21:18:01 72102 8192000000 10853030656 11316928512 1897978768 18.810 98 14 28 0 1912535152 10266688 4289696
21:20:14 42485 8192000000 10872961552 11321966592 1915496640 18.951 98 50 100 1 1942046864 12641232 13908992
21:22:02 57580 8192000000 10859265696 11321942016 1902778928 18.849 98 8 16 2 1914443344 9668192 1996224
21:24:02 78818 8192000000 10873840336 11341631488 1939026704 19.139 99 9 18 0 1951988128 10349072 2612352
21:27:11 54608 8192000000 10890106112 11356954624 1963088496 19.331 100 20 40 0 1982558448 13596640 5873312
21:29:07 37669 8192000000 10914030080 11365834752 1962879120 19.329 99 26 52 1 1978962272 8874736 7208416
21:30:50 31250 8192000000 10910067104 11347521536 1937960752 19.130 98 39 78 3 1956198768 7628480 10609536
21:32:27 29648 8192000000 10912981792 11367272448 1981567888 19.477 100 30 58 1 2001930784 11708560 8654336
21:34:23 53658 8192000000 10880389664 11341762560 1910055200 18.907 97 18 36 2 1926152480 10853440 5243840
21:36:22 84361 8192000000 10898984432 11351646208 1883202880 18.691 96 14 28 0 1897888096 10987536 3697680
21:39:04 86996 8192000000 10869971696 11332636672 1858305264 18.490 95 13 27 0 1871271344 9401408 3564672
21:41:37 102585 8192000000 10863305664 11318722560 1898188096 18.812 98 19 38 0 1915046400 11266560 5591744
21:44:20 50253 8192000000 10886918864 11336138752 1952261744 19.244 100 23 47 0 1966252912 7287280 6703888
21:46:12 72725 8192000000 10853757776 11323990016 1884522368 18.702 97 12 24 2 1899334112 11279488 3532256
21:48:25 53896 8192000000 10851527680 11325440000 1876282560 18.635 96 13 26 0 1893394880 13490592 3621728
21:50:26 55241 8192000000 10855847904 11320328192 1938294864 19.133 100 14 28 0 1949422160 7646064 3481232

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 11-08-2020 21:54:27 to 11-08-2020 21:54:41.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 114 v_add_invoice_to_stock 335544517
user_exc 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-08-2020 21:54:42 to 11-08-2020 21:54:44.

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

Database statistics

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

Database validation

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

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

--- start of diff output ---
--- end of diff output ---

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

11.08.20 21:54:46. 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-11 21:54:48 NO_SEVERE_ERRORS_FOUND
11.08.20 21:54:50. End of report.