Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.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 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 16913792 kB
MemAvailable 30967952 kB
Buffers 0 kB
Cached 14785196 kB
SwapCached 5988 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-21 15:51:55.9930
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(31:12) ==> SP_HALT_ON_ERROR(46:13)
IP                              ::1/49220
TRN_ID                          218610
ATT_ID                          487
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: 8512.95 29096 1021554
customer order: creation 155.46 391 18655
customer order: refuse 13.09 525 1571
order to supplier: creation 41.61 1549 4993
order to supplier: removal 3.40 154947 408
invoice (draft): creation 41.00 4320 4920
invoice (draft): removal 49.90 1278 5988
invoice accept: apply 37.60 23348 4512
invoice accept: cancel 44.92 1161 5390
customer reserve: creation 3431.15 230 411738
customer reserve: removal 4416.18 90 529942
realization accept: apply 75.65 248 9078
realization accept: cancel 86.48 147 10378
payment from customer: creation 53.02 177 6362
payment from customer: removal 5.05 103 606
payment to supplier: creation 44.00 164 5280
payment to supplier: removal 2.98 123 357
service: total inventory turnovers 4.59 1291 551
service: total monetary turnovers 6.07 36 728
service: aggregate perf. data 0.78 22415 93
service: refresh index statistics 0.03 369383 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-08-2020 15:53:39 to 21-08-2020 15:53:41.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2243
TEST_TIME 2 2471
TEST_TIME 3 4151
TEST_TIME 4 4068
TEST_TIME 5 4599
TEST_TIME 6 4370
TEST_TIME 7 4349
TEST_TIME 8 4506
TEST_TIME 9 4427
TEST_TIME 10 4587
TEST_TIME 11 4747
TEST_TIME 12 4843
TEST_TIME 13 4812
TEST_TIME 14 4907
TEST_TIME 15 4981
TEST_TIME 16 5065
TEST_TIME 17 5207
TEST_TIME 18 5303
TEST_TIME 19 5324
TEST_TIME 20 5387
TEST_TIME 21 5439
TEST_TIME 22 5414
TEST_TIME 23 5391
TEST_TIME 24 5349
TEST_TIME 25 5505
TEST_TIME 26 5675
TEST_TIME 27 5736
TEST_TIME 28 5917
TEST_TIME 29 6313
TEST_TIME 30 6550
TEST_TIME 31 6503
TEST_TIME 32 6740
TEST_TIME 33 7018
TEST_TIME 34 7156
TEST_TIME 35 7370
TEST_TIME 36 7622
TEST_TIME 37 8069
TEST_TIME 38 8910
TEST_TIME 39 9112
TEST_TIME 40 9006
TEST_TIME 41 9094
TEST_TIME 42 9030
TEST_TIME 43 8901
TEST_TIME 44 8920
TEST_TIME 45 8792
TEST_TIME 46 8693
TEST_TIME 47 8660
TEST_TIME 48 8605
TEST_TIME 49 8615
TEST_TIME 50 8543
TEST_TIME 51 8550
TEST_TIME 52 8520
TEST_TIME 53 8427
TEST_TIME 54 8459
TEST_TIME 55 8397
TEST_TIME 56 8511
TEST_TIME 57 8487
TEST_TIME 58 8376
TEST_TIME 59 8354
TEST_TIME 60 8300
TEST_TIME 61 8294
TEST_TIME 62 8312
TEST_TIME 63 8387
TEST_TIME 64 8433
TEST_TIME 65 8327
TEST_TIME 66 8284
TEST_TIME 67 8311
TEST_TIME 68 8299
TEST_TIME 69 8249
TEST_TIME 70 8290
TEST_TIME 71 8297
TEST_TIME 72 8300
TEST_TIME 73 8332
TEST_TIME 74 8321
TEST_TIME 75 8320
TEST_TIME 76 8364
TEST_TIME 77 8361
TEST_TIME 78 8328
TEST_TIME 79 8429
TEST_TIME 80 8432
TEST_TIME 81 8507
TEST_TIME 82 8533
TEST_TIME 83 8478
TEST_TIME 84 8396
TEST_TIME 85 8407
TEST_TIME 86 8407
TEST_TIME 87 8401
TEST_TIME 88 8384
TEST_TIME 89 8356
TEST_TIME 90 8359
TEST_TIME 91 8307
TEST_TIME 92 8307
TEST_TIME 93 8318
TEST_TIME 94 8348
TEST_TIME 95 8424
TEST_TIME 96 8429
TEST_TIME 97 8400
TEST_TIME 98 8461
TEST_TIME 99 8508
TEST_TIME 100 8524
TEST_TIME 101 8500
TEST_TIME 102 8547
TEST_TIME 103 8537
TEST_TIME 104 8568
TEST_TIME 105 8604
TEST_TIME 106 8616
TEST_TIME 107 8664
TEST_TIME 108 8701
TEST_TIME 109 8695
TEST_TIME 110 8665
TEST_TIME 111 8619
TEST_TIME 112 8614
TEST_TIME 113 8559
TEST_TIME 114 8590
TEST_TIME 115 8551
TEST_TIME 116 8519
TEST_TIME 117 8546
TEST_TIME 118 8529
TEST_TIME 119 8529
TEST_TIME 120 8516

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 21-08-2020 15:53:42 to 21-08-2020 15:53: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 1403071 1403071 0 0.00 0 22479 8 0 0
doc_list_biud 1403068 1403068 0 0.00 0 91531 43 0 0
sp_add_invoice_to_stock 4627 4512 115 2.48 34 355744 23348 0 115
sp_cancel_adding_invoice 5390 5390 0 0.00 5 69881 1161 0 0
sp_cancel_client_order 1571 1571 0 0.00 4 14462 525 0 0
sp_cancel_customer_reserve 529942 529942 0 0.00 1 28205 91 0 0
sp_cancel_pay_from_customer 606 606 0 0.00 3 3433 104 0 0
sp_cancel_pay_to_supplier 357 357 0 0.00 3 4216 124 0 0
sp_cancel_supplier_invoice 6004 5988 16 0.26 2 91540 1279 0 16
sp_cancel_supplier_order 408 408 0 0.00 13 1561475 154947 0 0
sp_cancel_write_off 10378 10378 0 0.00 1 10594 148 0 0
sp_client_order 18655 18655 0 0.00 3 35480 391 0 0
sp_customer_reserve 411738 411738 0 0.00 0 118434 231 0 0
sp_fill_shopping_cart 28556 28556 0 0.00 1 21929 49 0 0
sp_fill_shopping_cart_clo_res 411732 411732 0 0.00 0 12125 16 0 0
sp_get_clo_for_invoice 4505 4505 0 0.00 0 23046 369 0 0
sp_kill_cost_storno 16731 16731 0 0.00 0 9752 91 0 0
sp_kill_qstorno_ret_qs2qd 1076658 1076658 0 0.00 0 91528 49 0 0
sp_kill_qty_storno 567260 567260 0 0.00 0 91530 105 0 0
sp_lock_selected_doc 18715 18715 0 0.00 0 11277 5 0 0
sp_make_cost_storno 25225 25225 0 0.00 0 22457 83 0 0
sp_multiply_rows_for_pdistr 25225 25225 0 0.00 0 8998 41 0 0
sp_multiply_rows_for_qdistr 18655 18655 0 0.00 0 24489 141 0 0
sp_pay_from_customer 6362 6362 0 0.00 4 23411 177 0 0
sp_pay_to_supplier 5280 5280 0 0.00 5 14768 165 0 0
sp_payment_common 11642 11642 0 0.00 1 23406 45 0 0
sp_qd_handle_on_cancel_clo 1571 1571 0 0.00 0 14455 490 0 0
sp_qd_handle_on_invoice_upd_sts 9895 9895 0 0.00 0 14717 486 0 0
sp_qd_handle_on_reserve_upd_sts 9078 9078 0 0.00 0 9318 69 0 0
sp_reserve_write_off 9078 9078 0 0.00 6 14323 249 0 0
sp_supplier_invoice 4920 4920 0 0.00 7 107456 4320 0 0
sp_supplier_order 4993 4993 0 0.00 4 44295 1549 0 0
srv_aggregate_perf_data 93 93 0 0.00 3080 314386 22416 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 891 154693 39841 0 0
srv_find_qd_qs_mism 978869 978869 0 0.00 0 18344 6 0 0
srv_make_invnt_saldo 551 551 0 0.00 39 137934 1291 0 0
srv_make_money_saldo 728 728 0 0.00 1 757 36 0 0
srv_recalc_idx_stat 4 4 0 0.00 81973 625316 369384 0 0
t$perf-abend:sp_add_invoice_to_stock 115 115 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4512 4512 0 0.00 0 14516 283 0 0
t$perf-norm:sp_cancel_adding_invoice 382 382 0 0.00 1 26455 259 0 0
t$perf-norm:sp_cancel_client_order 1571 1571 0 0.00 0 15647 32 0 0
t$perf-norm:sp_cancel_customer_reserve 601 601 0 0.00 0 7588 33 0 0
t$perf-norm:sp_cancel_pay_from_customer 606 606 0 0.00 0 5096 23 0 0
t$perf-norm:sp_cancel_pay_to_supplier 357 357 0 0.00 0 834 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 347 347 0 0.00 0 1213 22 0 0
t$perf-norm:sp_cancel_supplier_order 408 408 0 0.00 0 49265 2888 0 0
t$perf-norm:sp_cancel_write_off 861 861 0 0.00 0 9505 23 0 0
t$perf-norm:sp_client_order 18656 18656 0 0.00 0 21794 27 0 0
t$perf-norm:sp_customer_reserve 880 880 0 0.00 0 1098 10 0 0
t$perf-norm:sp_pay_from_customer 6362 6362 0 0.00 0 14525 18 0 0
t$perf-norm:sp_pay_to_supplier 5280 5280 0 0.00 0 6932 18 0 0
t$perf-norm:sp_reserve_write_off 9078 9078 0 0.00 0 10210 17 0 0
t$perf-norm:sp_supplier_invoice 4920 4920 0 0.00 0 18266 34 0 0
t$perf-norm:sp_supplier_order 4993 4993 0 0.00 0 4256 28 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 1998 47 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 4 1 0 0
t$perf-norm:srv_make_invnt_saldo 551 551 0 0.00 0 2184 59 0 0
t$perf-norm:srv_make_money_saldo 728 728 0 0.00 0 26821 82 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 3 0 0
v_add_invoice_to_stock 9238 9008 230 2.48 0 8792 23 0 230
v_all_customers 34022 34022 0 0.00 0 161 1 0 0
v_all_suppliers 27584 27584 0 0.00 0 23 1 0 0
v_all_wares 37310 37310 0 0.00 0 72 1 0 0
v_cancel_adding_invoice 764 764 0 0.00 0 1248 10 0 0
v_cancel_client_order 3142 3142 0 0.00 0 2618 6 0 0
v_cancel_customer_prepayment 1212 1212 0 0.00 0 2565 7 0 0
v_cancel_customer_reserve 1202 1202 0 0.00 0 1178 7 0 0
v_cancel_payment_to_supplier 714 714 0 0.00 0 3252 11 0 0
v_cancel_supplier_invoice 726 694 32 4.40 0 5508 33 0 32
v_cancel_supplier_order 816 816 0 0.00 0 684 6 0 0
v_cancel_write_off 1722 1722 0 0.00 0 3628 19 0 0
v_max_id_clo_ord 4992 4992 0 0.00 0 552 2 0 0
v_max_id_clo_res 880 880 0 0.00 0 1716 10 0 0
v_max_id_ord_sup 4909 4909 0 0.00 0 4533 4 0 0
v_max_non_paid_invoice 1389 1389 0 0.00 0 1007 4 0 0
v_max_non_paid_realizn 3336 3336 0 0.00 0 10979 10 0 0
v_min_id_clo_ord 4992 4992 0 0.00 0 7227 13 0 0
v_min_id_clo_res 880 880 0 0.00 0 6756 21 0 0
v_min_id_ord_sup 4909 4909 0 0.00 0 6864 9 0 0
v_min_non_paid_invoice 5280 5280 0 0.00 0 10212 23 0 0
v_min_non_paid_realizn 6362 6362 0 0.00 0 23402 34 0 0
v_our_firm 9340 9340 0 0.00 0 19 1 0 0
v_reserve_write_off 18156 18156 0 0.00 0 7675 8 0 0
x_lock_depdocs_on_canc_invoice 11031 11031 0 0.00 0 30750 188 0 0
x_lock_depdocs_on_canc_sup_ord 408 408 0 0.00 0 9409 560 0 0
x_make_qstorno_1000_1200 4993 4993 0 0.00 2 44283 1433 0 0
x_make_qstorno_1200_2000 4920 4920 0 0.00 3 92583 4144 0 0
x_make_qstorno_2100_3300 411738 411738 0 0.00 0 118432 210 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-08-2020 15:53:46 to 21-08-2020 15:53: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
13:21:59 2 8192000000 8798382528 8808857600 5057520 0.061 1 1 2 0 5175568 55488 62560
13:22:59 29630 8192000000 10358671360 10670735360 1395256416 14.553 100 91 182 0 1431858784 5811664 30790704
13:24:37 22060 8192000000 10431117232 10757435392 1479340784 15.296 100 89 178 0 1516340384 8563680 28435920
13:26:03 28258 8192000000 10467299056 10805878784 1520173424 15.652 100 82 164 2 1556439760 10367552 25898784
13:27:42 12632 8192000000 10531866960 10883452928 1595399776 16.300 100 81 162 1 1631505792 11435856 24670160
13:28:56 12991 8192000000 10601448064 10960216064 1655960800 16.815 100 82 163 2 1692645120 13079344 23604976
13:30:13 9500 8192000000 10652595728 11017875456 1710212528 17.271 100 82 162 0 1746152096 13045984 22893584
13:31:26 3720 8192000000 10685900576 11061342208 1750020048 17.602 100 78 154 1 1783560896 11546368 21994480
13:32:32 3248 8192000000 10739939120 11118845952 1796898560 17.988 100 88 175 0 1834533680 13082032 24553088
13:33:39 1839 8192000000 10778704464 11160510464 1836376832 18.311 100 85 171 0 1873651520 13318736 23955952
13:34:45 2164 8192000000 10790610624 11176427520 1854801904 18.461 100 86 172 0 1891599728 12460064 24337760
13:35:49 7562 8192000000 10759984912 11178954752 1856891136 18.478 100 47 92 1 1877508256 7263312 13353808
13:36:58 5371 8192000000 10775095264 11196891136 1868141984 18.569 100 31 62 1 1887932928 11222080 8568864
13:38:09 9365 8192000000 10776458832 11204640768 1874069328 18.617 100 22 43 2 1886557712 6043904 6444480
13:39:19 14513 8192000000 10809300096 11225632768 1887417392 18.725 100 27 53 0 1902327392 6032368 8877632
13:40:34 17845 8192000000 10797459872 11232051200 1889361152 18.741 100 17 34 1 1899936928 5673984 4901792
13:41:53 3824 8192000000 10830936560 11245461504 1902576560 18.847 100 47 94 2 1923918192 7383232 13958400
13:43:02 6738 8192000000 10792618144 11222114304 1869180848 18.578 99 28 54 0 1883262432 6304576 7777008
13:44:10 46941 8192000000 10733752144 11146563584 1730324736 17.438 93 37 74 1 1747505472 5625696 11555040
13:46:01 5936 8192000000 10726623360 11133632512 1782744336 17.872 98 29 58 1 1796754560 5473280 8536944
13:47:09 4797 8192000000 10679689840 11083608064 1732293968 17.455 97 30 61 0 1746991216 6256848 8440400
13:48:16 56033 8192000000 10737791056 11142389760 1758906048 17.675 97 34 68 2 1773808288 5612160 9290080
13:50:20 7066 8192000000 10749827328 11158884352 1824368064 18.213 100 26 49 3 1837341760 6081152 6892544
13:51:27 15822 8192000000 10778054928 11199422464 1863828592 18.534 100 27 54 3 1876411104 5006288 7576224
13:52:50 36531 8192000000 10809261184 11227983872 1891247008 18.756 100 44 89 1 1911351904 7402304 12702592
13:54:30 25802 8192000000 10843482976 11258286080 1906272928 18.877 100 41 82 0 1929143056 10999648 11870480
13:56:02 22219 8192000000 10859077152 11278753792 1921364448 18.998 100 54 108 1 1951684224 14522336 15797440
13:57:29 11941 8192000000 10886137136 11301220352 1937434320 19.126 100 52 104 1 1968665968 16490448 14741200
13:58:46 19422 8192000000 10892823648 11314958336 1946440736 19.198 100 45 90 1 1980751680 21176400 13134544
14:00:27 34572 8192000000 10897058496 11326369792 1953961216 19.258 100 40 78 0 1988009792 22018720 12029856
14:02:22 28177 8192000000 10915203968 11339501568 1965584240 19.350 100 52 101 2 2004131248 23288592 15258416
14:04:02 7030 8192000000 10933004592 11353812992 1974488608 19.421 100 52 105 3 2015354656 25691552 15174496
14:05:19 3768 8192000000 10953653536 11375538176 1990986992 19.552 100 63 125 1 2041891520 32733328 18171200
14:06:26 4900 8192000000 10974202752 11395624960 1998433008 19.610 100 69 138 0 2056165600 36612320 21120272
14:07:43 4416 8192000000 10980968528 11405070336 2010047248 19.702 100 65 130 0 2070679440 41126544 19505648
14:08:59 1363 8192000000 10995613056 11407638528 2006680880 19.675 100 85 168 2 2077309488 46632208 23996400
14:10:05 5156 8192000000 10973389776 11391565824 1993187040 19.569 100 73 144 0 2065364368 50470128 21707200
14:11:23 2525 8192000000 10987392192 11393236992 1978865936 19.456 99 82 161 3 2054386368 50200672 25319760
14:12:33 4966 8192000000 10993084448 11407241216 2000324304 19.625 100 72 142 1 2073382688 50488080 22570304
14:13:44 5523 8192000000 10995310064 11412639744 1997215024 19.601 100 77 153 2 2078384208 57164832 24004352
14:15:00 889 8192000000 11001658560 11409317888 1989768352 19.542 100 86 172 0 2083047808 65215792 28063664
14:16:06 4372 8192000000 11000931760 11407073280 1982686352 19.486 100 86 170 1 2080053376 70167472 27199552
14:17:22 4385 8192000000 10999113856 11412123648 1994352208 19.578 100 87 175 2 2087770064 65206768 28211088
14:18:37 1552 8192000000 10999032064 11414396928 1984674672 19.502 100 81 159 1 2083852496 73205344 25972480
14:19:49 2008 8192000000 11030150064 11443769344 2012823232 19.724 100 86 171 0 2110044720 70177056 27044432
14:20:59 2276 8192000000 11028370944 11437146112 2010383408 19.705 100 92 183 3 2108455648 69160112 28912128
14:22:09 11477 8192000000 11009515296 11441324032 1997199104 19.601 99 49 96 3 2082950096 69177344 16573648
14:23:40 6769 8192000000 10993328640 11420790784 1972455088 19.405 99 53 105 0 2060954656 70783648 17715920
14:24:54 18050 8192000000 10971011248 11412975616 1971073728 19.394 100 36 72 4 2054338352 71036912 12227712
14:26:31 16819 8192000000 10936203968 11383504896 1946613680 19.199 100 28 56 1 2026405504 69356464 10435360
14:28:00 36332 8192000000 10944985184 11377971200 1908062816 18.891 98 31 62 3 1988306640 69981184 10262640
14:29:40 29055 8192000000 10900272560 11353178112 1923308192 19.013 99 23 46 0 1965938240 35553872 7076176
14:31:15 14950 8192000000 10913497920 11347394560 1952942720 19.250 100 48 93 2 1974362512 8530448 12889344
14:32:32 18576 8192000000 10877174064 11342905344 1935879184 19.114 99 27 54 0 1960315136 16518400 7917552
14:34:12 129591 8192000000 10871453248 11341897728 1932795408 19.089 99 15 30 0 1949505744 12542704 4167632
14:37:28 48346 8192000000 10883830096 11335106560 1937958144 19.130 99 41 81 1 1964656496 13583328 13115024
14:39:24 61427 8192000000 10905161968 11362635776 1973589152 19.414 100 36 72 0 1999616384 14828800 11198432
14:41:37 64472 8192000000 10875571904 11347693568 1921324640 18.997 98 20 40 0 1948169184 20560912 6283632
14:43:42 72231 8192000000 10889681456 11344617472 1956878528 19.281 99 36 72 1 1975816064 7835200 11102336
14:46:02 52597 8192000000 10895355952 11353837568 1947130752 19.204 99 37 69 3 1980561136 22542320 10888064
14:48:01 118323 8192000000 10890048112 11334844416 1878182432 18.650 95 44 88 0 1901502832 11358512 11961888
14:51:03 22040 8192000000 10868497440 11323916288 1905225584 18.868 97 26 52 1 1925084848 12746192 7113072
14:52:31 16954 8192000000 10938831728 11367280640 1978706608 19.454 100 66 128 3 2013292592 15671200 18914784
14:53:56 86941 8192000000 10860322144 11335090176 1920583536 18.992 97 14 28 0 1938659744 13757120 4319088
14:56:25 76174 8192000000 10854441872 11321864192 1906540816 18.879 98 17 34 0 1921624496 9859344 5224336
14:58:41 24063 8192000000 10856867216 11341135872 1943332336 19.173 100 4 8 0 1956720208 12466608 921264
15:00:13 52426 8192000000 10880870992 11346567168 1932043808 19.083 99 9 18 1 1951956528 17780688 2132032
15:02:06 61107 8192000000 10876587056 11338997760 1925192736 19.028 98 20 40 1 1939862464 9365504 5304224
15:04:15 83060 8192000000 10867428224 11324850176 1879961760 18.665 96 14 25 1 1891634512 7960768 3711984
15:06:47 30624 8192000000 10879456528 11342467072 1937556624 19.127 99 25 50 0 1958576048 14314224 6705200
15:08:19 90678 8192000000 10861621600 11319746560 1873725488 18.614 96 22 42 0 1894683712 15023296 5934928
15:11:01 67642 8192000000 10872532800 11337011200 1910859232 18.914 98 36 68 1 1932106272 11785328 9461712
15:13:09 154693 8192000000 10898019008 11349667840 1939942336 19.146 99 20 39 1 1967001056 21562016 5496704
15:16:54 49828 8192000000 10869785424 11339255808 1908229056 18.892 98 18 36 2 1929697808 16752192 4716560
15:18:46 34871 8192000000 10852708240 11312095232 1897818736 18.809 98 27 52 1 1917289824 11658496 7812592
15:20:25 49651 8192000000 10835402400 11294146560 1860597520 18.508 96 10 20 0 1872644384 8884256 3162608
15:22:47 75605 8192000000 10855236336 11317354496 1904953424 18.866 98 19 38 0 1922537424 11835824 5748176
15:25:09 45990 8192000000 10841711344 11308040192 1911177984 18.916 99 11 22 0 1923859664 9837264 2844416
15:26:55 82830 8192000000 10844536000 11302776832 1839268320 18.335 95 16 31 1 1855117408 12076272 3772816
15:29:24 20769 8192000000 10856793600 11304071168 1916379680 18.958 99 48 96 0 1941426160 11330832 13715648
15:30:50 71854 8192000000 10861481520 11325587456 1905841248 18.873 98 14 28 1 1924561760 14901744 3818768
15:33:08 46265 8192000000 10856726624 11319754752 1889769296 18.744 97 19 38 3 1910427888 15414800 5243792
15:34:54 26811 8192000000 10885861536 11325710336 1940469712 19.151 100 38 74 3 1961878736 10622608 10786416
15:36:24 51597 8192000000 10854146976 11319214080 1916451088 18.958 99 19 38 0 1930806464 9121008 5234368
15:38:21 21344 8192000000 10876753840 11326459904 1910135296 18.908 98 35 70 2 1931800080 11525072 10139712
15:40:17 101291 8192000000 10884122016 11339902976 1915569888 18.951 98 25 50 3 1938325008 15729360 7025760
15:43:19 87318 8192000000 10907671568 11351883776 1950311504 19.229 99 38 75 2 1969221200 8644576 10265120
15:45:49 47127 8192000000 10843419536 11312693248 1897513680 18.806 98 14 28 2 1912630112 10356832 4759600
15:47:42 70046 8192000000 10862585968 11337162752 1914197808 18.940 98 28 56 1 1938216080 15216688 8801584
15:50:22 46932 8192000000 10857477744 11334111232 1940381744 19.150 100 14 28 0 1953439408 8505872 4551792

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:13, from 21-08-2020 15:53:52 to 21-08-2020 15:54:05.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 115 v_add_invoice_to_stock 335544517
user_exc 16 v_cancel_supplier_invoice 335544517

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

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          218609
MON$OLDEST_ACTIVE               219628
MON$OLDEST_SNAPSHOT             219628
MON$NEXT_TRANSACTION            219628
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                       2033824
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.33357 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.7.33357 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.7.33357 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.7.33357 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 ---
:122: 
:123: mscs-test-161.budzdorov.ru    Fri Aug 21 15:52:07 2020
:124:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:125: 
--- end of diff output ---

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

21.08.20 15:54: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: 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-21 15:54:12 NO_SEVERE_ERRORS_FOUND
21.08.20 15:54:15. End of report.