Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.08.2020 09:55
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 16650200 kB
MemAvailable 31025464 kB
Buffers 0 kB
Cached 15040120 kB
SwapCached 6324 kB
Command: fdisk -l

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.0
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=fbc, val=/opt/fb40/bin
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=report_compress_cmd, val=/usr/bin/7za
param=host, val=localhost
param=port, val=3400
param=usr, val=SYSDBA
param=pwd, val=masterkey
param=tmpdir, val=/var/tmp/logs.oltp40
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/oltp40-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 09:53:51.8090
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/59132
TRN_ID                          215477
ATT_ID                          510
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: 8399.21 22841 1007905
customer order: creation 158.95 428 19074
customer order: refuse 13.14 526 1577
order to supplier: creation 42.67 1451 5120
order to supplier: removal 3.31 134934 397
invoice (draft): creation 43.59 3947 5231
invoice (draft): removal 47.13 1065 5655
invoice accept: apply 38.41 21400 4609
invoice accept: cancel 42.63 1115 5116
customer reserve: creation 3578.23 208 429388
customer reserve: removal 4167.42 85 500090
realization accept: apply 76.64 199 9197
realization accept: cancel 68.69 162 8243
payment from customer: creation 54.26 121 6511
payment from customer: removal 5.54 116 665
payment to supplier: creation 45.62 126 5474
payment to supplier: removal 3.23 125 388
service: total inventory turnovers 3.41 5136 409
service: total monetary turnovers 5.41 907 649
service: aggregate perf. data 0.90 16194 108
service: refresh index statistics 0.03 268569 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-08-2020 09:55:30 to 11-08-2020 09:55:32.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2459
TEST_TIME 2 2574
TEST_TIME 3 3638
TEST_TIME 4 3833
TEST_TIME 5 3575
TEST_TIME 6 3864
TEST_TIME 7 4137
TEST_TIME 8 4187
TEST_TIME 9 4151
TEST_TIME 10 4368
TEST_TIME 11 4533
TEST_TIME 12 4808
TEST_TIME 13 4751
TEST_TIME 14 5039
TEST_TIME 15 5068
TEST_TIME 16 5212
TEST_TIME 17 5417
TEST_TIME 18 5603
TEST_TIME 19 5645
TEST_TIME 20 6127
TEST_TIME 21 6121
TEST_TIME 22 6350
TEST_TIME 23 6350
TEST_TIME 24 6691
TEST_TIME 25 7185
TEST_TIME 26 7041
TEST_TIME 27 7182
TEST_TIME 28 7025
TEST_TIME 29 7274
TEST_TIME 30 7544
TEST_TIME 31 8046
TEST_TIME 32 8825
TEST_TIME 33 9240
TEST_TIME 34 9142
TEST_TIME 35 8992
TEST_TIME 36 9241
TEST_TIME 37 9313
TEST_TIME 38 9168
TEST_TIME 39 9064
TEST_TIME 40 8994
TEST_TIME 41 8932
TEST_TIME 42 8906
TEST_TIME 43 9026
TEST_TIME 44 8991
TEST_TIME 45 9041
TEST_TIME 46 9034
TEST_TIME 47 9076
TEST_TIME 48 9086
TEST_TIME 49 9041
TEST_TIME 50 9111
TEST_TIME 51 9055
TEST_TIME 52 8888
TEST_TIME 53 8733
TEST_TIME 54 8670
TEST_TIME 55 8652
TEST_TIME 56 8602
TEST_TIME 57 8578
TEST_TIME 58 8584
TEST_TIME 59 8606
TEST_TIME 60 8606
TEST_TIME 61 8556
TEST_TIME 62 8520
TEST_TIME 63 8532
TEST_TIME 64 8564
TEST_TIME 65 8598
TEST_TIME 66 8611
TEST_TIME 67 8624
TEST_TIME 68 8666
TEST_TIME 69 8719
TEST_TIME 70 8738
TEST_TIME 71 8737
TEST_TIME 72 8816
TEST_TIME 73 8835
TEST_TIME 74 8847
TEST_TIME 75 8808
TEST_TIME 76 8812
TEST_TIME 77 8773
TEST_TIME 78 8672
TEST_TIME 79 8580
TEST_TIME 80 8601
TEST_TIME 81 8613
TEST_TIME 82 8548
TEST_TIME 83 8522
TEST_TIME 84 8497
TEST_TIME 85 8429
TEST_TIME 86 8435
TEST_TIME 87 8454
TEST_TIME 88 8487
TEST_TIME 89 8483
TEST_TIME 90 8458
TEST_TIME 91 8464
TEST_TIME 92 8541
TEST_TIME 93 8529
TEST_TIME 94 8566
TEST_TIME 95 8642
TEST_TIME 96 8614
TEST_TIME 97 8636
TEST_TIME 98 8696
TEST_TIME 99 8687
TEST_TIME 100 8647
TEST_TIME 101 8587
TEST_TIME 102 8547
TEST_TIME 103 8505
TEST_TIME 104 8483
TEST_TIME 105 8462
TEST_TIME 106 8452
TEST_TIME 107 8478
TEST_TIME 108 8441
TEST_TIME 109 8488
TEST_TIME 110 8469
TEST_TIME 111 8504
TEST_TIME 112 8485
TEST_TIME 113 8576
TEST_TIME 114 8557
TEST_TIME 115 8547
TEST_TIME 116 8545
TEST_TIME 117 8490
TEST_TIME 118 8464
TEST_TIME 119 8442
TEST_TIME 120 8403

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-08-2020 09:55:34 to 11-08-2020 09:55:37.

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 1403876 1403876 0 0.00 0 22403 8 0 0
doc_list_biud 1403872 1403872 0 0.00 0 29911 38 0 0
sp_add_invoice_to_stock 4708 4609 99 2.10 34 206422 21401 0 99
sp_cancel_adding_invoice 5116 5116 0 0.00 4 101001 1116 0 0
sp_cancel_client_order 1577 1577 0 0.00 4 8530 526 0 0
sp_cancel_customer_reserve 500090 500090 0 0.00 1 19822 85 0 0
sp_cancel_pay_from_customer 665 665 0 0.00 2 6520 117 0 0
sp_cancel_pay_to_supplier 388 388 0 0.00 3 4229 125 0 0
sp_cancel_supplier_invoice 5661 5655 6 0.10 1 13207 1066 0 6
sp_cancel_supplier_order 397 397 0 0.00 6 1319467 134935 0 0
sp_cancel_write_off 8243 8243 0 0.00 1 6712 162 0 0
sp_client_order 19074 19074 0 0.00 3 18731 429 0 0
sp_customer_reserve 429388 429388 0 0.00 0 51035 209 0 0
sp_fill_shopping_cart 29425 29425 0 0.00 1 16318 51 0 0
sp_fill_shopping_cart_clo_res 429380 429380 0 0.00 0 21933 20 0 0
sp_get_clo_for_invoice 4599 4599 0 0.00 0 28759 430 0 0
sp_kill_cost_storno 14412 14412 0 0.00 0 8181 96 0 0
sp_kill_qstorno_ret_qs2qd 1014475 1014475 0 0.00 0 19819 46 0 0
sp_kill_qty_storno 534874 534874 0 0.00 0 29911 98 0 0
sp_lock_selected_doc 19139 19139 0 0.00 0 10312 5 0 0
sp_make_cost_storno 25781 25781 0 0.00 0 22395 57 0 0
sp_multiply_rows_for_pdistr 25781 25781 0 0.00 0 6884 36 0 0
sp_multiply_rows_for_qdistr 19074 19074 0 0.00 0 13224 213 0 0
sp_pay_from_customer 6511 6511 0 0.00 4 15930 121 0 0
sp_pay_to_supplier 5474 5474 0 0.00 4 13144 127 0 0
sp_payment_common 11985 11985 0 0.00 1 5740 18 0 0
sp_qd_handle_on_cancel_clo 1577 1577 0 0.00 0 8521 486 0 0
sp_qd_handle_on_invoice_upd_sts 9715 9715 0 0.00 0 29911 437 0 0
sp_qd_handle_on_reserve_upd_sts 9197 9197 0 0.00 0 15113 61 0 0
sp_reserve_write_off 9197 9197 0 0.00 5 16703 200 0 0
sp_supplier_invoice 5231 5231 0 0.00 6 138891 3948 0 0
sp_supplier_order 5120 5120 0 0.00 4 46760 1451 0 0
srv_aggregate_perf_data 108 108 0 0.00 2711 252663 16195 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1447 187343 41758 0 0
srv_find_qd_qs_mism 962858 962858 0 0.00 0 14959 7 0 0
srv_make_invnt_saldo 409 409 0 0.00 35 144803 5136 0 0
srv_make_money_saldo 649 649 0 0.00 1 24531 907 0 0
srv_recalc_idx_stat 4 4 0 0.00 174391 412397 268570 0 0
t$perf-abend:sp_add_invoice_to_stock 99 99 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4610 4610 0 0.00 0 12851 244 0 0
t$perf-norm:sp_cancel_adding_invoice 410 410 0 0.00 0 15210 185 0 0
t$perf-norm:sp_cancel_client_order 1577 1577 0 0.00 0 2981 16 0 0
t$perf-norm:sp_cancel_customer_reserve 617 617 0 0.00 0 4069 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 665 665 0 0.00 0 2999 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 388 388 0 0.00 0 5787 31 0 0
t$perf-norm:sp_cancel_supplier_invoice 363 363 0 0.00 0 1711 20 0 0
t$perf-norm:sp_cancel_supplier_order 397 397 0 0.00 0 40653 2449 0 0
t$perf-norm:sp_cancel_write_off 926 926 0 0.00 0 2196 13 0 0
t$perf-norm:sp_client_order 19074 19074 0 0.00 0 6010 20 0 0
t$perf-norm:sp_customer_reserve 874 874 0 0.00 0 7520 16 0 0
t$perf-norm:sp_pay_from_customer 6511 6511 0 0.00 0 11837 13 0 0
t$perf-norm:sp_pay_to_supplier 5474 5474 0 0.00 0 4829 11 0 0
t$perf-norm:sp_reserve_write_off 9197 9197 0 0.00 0 5506 13 0 0
t$perf-norm:sp_supplier_invoice 5231 5231 0 0.00 0 6577 20 0 0
t$perf-norm:sp_supplier_order 5120 5120 0 0.00 0 5478 20 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 469 18 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 38 1 0 0
t$perf-norm:srv_make_invnt_saldo 409 409 0 0.00 0 1305 13 0 0
t$perf-norm:srv_make_money_saldo 649 649 0 0.00 0 754 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 376 98 0 0
v_add_invoice_to_stock 9396 9198 198 2.10 0 15754 25 0 198
v_all_customers 34488 34488 0 0.00 0 72 1 0 0
v_all_suppliers 28664 28664 0 0.00 0 68 1 0 0
v_all_wares 38148 38148 0 0.00 0 70 1 0 0
v_cancel_adding_invoice 820 820 0 0.00 0 2243 12 0 0
v_cancel_client_order 3154 3154 0 0.00 0 6700 11 0 0
v_cancel_customer_prepayment 1330 1330 0 0.00 0 4490 11 0 0
v_cancel_customer_reserve 1234 1234 0 0.00 0 1691 9 0 0
v_cancel_payment_to_supplier 776 776 0 0.00 0 2783 16 0 0
v_cancel_supplier_invoice 738 726 12 1.62 0 665 14 0 12
v_cancel_supplier_order 794 794 0 0.00 0 1600 5 0 0
v_cancel_write_off 1852 1852 0 0.00 0 1742 15 0 0
v_max_id_clo_ord 5120 5120 0 0.00 0 3611 3 0 0
v_max_id_clo_res 874 874 0 0.00 0 3074 14 0 0
v_max_id_ord_sup 5231 5231 0 0.00 0 7889 10 0 0
v_max_non_paid_invoice 1493 1493 0 0.00 0 813 3 0 0
v_max_non_paid_realizn 3558 3558 0 0.00 0 2257 4 0 0
v_min_id_clo_ord 5120 5120 0 0.00 0 3410 5 0 0
v_min_id_clo_res 874 874 0 0.00 0 6654 14 0 0
v_min_id_ord_sup 5231 5231 0 0.00 0 5577 5 0 0
v_min_non_paid_invoice 5474 5474 0 0.00 0 3606 10 0 0
v_min_non_paid_realizn 6511 6511 0 0.00 0 5737 13 0 0
v_our_firm 9566 9566 0 0.00 0 18 1 0 0
v_reserve_write_off 18394 18394 0 0.00 0 16590 7 0 0
x_lock_depdocs_on_canc_invoice 10408 10408 0 0.00 0 17016 151 0 0
x_lock_depdocs_on_canc_sup_ord 397 397 0 0.00 0 6986 390 0 0
x_make_qstorno_1000_1200 5120 5120 0 0.00 1 46751 1367 0 0
x_make_qstorno_1200_2000 5231 5231 0 0.00 3 131050 3739 0 0
x_make_qstorno_2100_3300 429388 429388 0 0.00 0 48866 185 0 0

Completed "Performance in DETAILS". Done for 00:00:03, from 11-08-2020 09:55:38 to 11-08-2020 09:55:41.

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
07:23:55 2 8192000000 8796611856 8807043072 3144672 0.038 1 1 2 0 3241968 61344 35952
07:24:56 42793 8192000000 9736033520 10030325760 806426496 8.961 100 87 175 1 835928768 8220208 21282064
07:26:43 20818 8192000000 9767423056 10069991424 839060480 9.290 100 91 181 1 872832576 12127888 21644208
07:28:09 18320 8192000000 9792551664 10102583296 860066608 9.501 100 89 178 1 898918352 18513376 20338368
07:29:43 14040 8192000000 9838365264 10160222208 896549776 9.864 100 79 158 1 951333904 37811088 16973040
07:31:05 14261 8192000000 9900727888 10236694528 937308064 10.267 100 77 154 0 1009978736 56570704 16099968
07:32:26 8908 8192000000 9942149040 10285211648 966300448 10.551 100 83 164 2 1049239552 65375216 17563888
07:33:40 5801 8192000000 9972524144 10324099072 1001589040 10.894 100 86 171 0 1085617232 66149744 17878448
07:34:48 2585 8192000000 9993810560 10357362688 1029516864 11.164 100 78 155 1 1105678416 60492608 15668944
07:35:55 1774 8192000000 10010905072 10374893568 1047236048 11.334 100 84 167 3 1121688720 57212256 17240416
07:37:01 2658 8192000000 10021061776 10389381120 1058238496 11.440 100 88 176 1 1129573952 52540752 18794704
07:38:07 45182 8192000000 9980382416 10362867712 1061004880 11.466 100 42 84 1 1119002768 48099776 9898112
07:40:02 4082 8192000000 10014352944 10394603520 1069334320 11.546 100 57 114 3 1114583920 32821248 12428352
07:41:10 8212 8192000000 10007174848 10414628864 1077879904 11.627 100 46 92 3 1135037024 47361584 9795536
07:42:18 9487 8192000000 9991453024 10383233024 1086254976 11.707 100 25 50 1 1125465264 33769136 5441152
07:43:29 47961 8192000000 9995642128 10407301120 1086893776 11.713 100 25 50 1 1108170496 14798096 6478624
07:45:19 6850 8192000000 10011077312 10404417536 1067605472 11.529 99 40 80 2 1114539552 37516400 9417680
07:46:29 7100 8192000000 10006433200 10397609984 1075041776 11.600 100 47 93 3 1121223280 35275936 10905568
07:47:37 4975 8192000000 9930807952 10304569344 991037264 10.792 95 31 60 4 1009811056 11930720 6843072
07:48:43 16070 8192000000 9951285824 10319126528 1021146816 11.083 99 71 145 2 1052333904 13683264 17503824
07:50:00 4750 8192000000 9946625856 10319450112 1030084176 11.169 100 50 99 4 1059889536 18261216 11544144
07:51:05 8717 8192000000 9923537648 10309226496 989361520 10.775 97 32 64 2 1026572144 29682560 7528064
07:52:16 56488 8192000000 9954466032 10341216256 1042073824 11.285 100 24 48 1 1080918352 33857936 4986592
07:54:18 33787 8192000000 10003998656 10390286336 1075786656 11.607 100 36 69 2 1112166560 29343536 7036368
07:56:01 19793 8192000000 10076224368 10461655040 1099458720 11.833 100 60 119 2 1176568512 64869488 12240304
07:57:30 31382 8192000000 10079981232 10475655168 1116560912 11.994 100 48 95 2 1189798960 61688848 11549200
07:59:17 9431 8192000000 10151244128 10545426432 1132529184 12.145 100 53 106 2 1253096928 109203280 11364464
08:00:37 22837 8192000000 10158859424 10583076864 1143885184 12.252 100 46 91 3 1281512512 127839152 9788176
08:02:10 9277 8192000000 10175381616 10577911808 1154871408 12.355 100 49 98 0 1286618800 120601376 11146016
08:03:24 20491 8192000000 10224936704 10626785280 1154897072 12.355 100 51 101 1 1336779312 170005328 11876912
08:04:50 31426 8192000000 10243060928 10666594304 1168988512 12.487 100 48 94 3 1349904816 169569280 11347024
08:06:27 4198 8192000000 10284461664 10689499136 1181770720 12.607 100 66 129 3 1386911232 190309712 14830800
08:07:38 5372 8192000000 10320688064 10734292992 1189127904 12.675 100 75 147 3 1425233728 220312976 15792848
08:08:47 18681 8192000000 10361155312 10786942976 1202115472 12.796 100 63 124 0 1465659088 249129936 14413680
08:10:11 3435 8192000000 10381067264 10789146624 1204357344 12.817 100 74 146 1 1484577024 262319200 17900480
08:11:18 1446 8192000000 10413027568 10821074944 1209441952 12.864 100 75 148 1 1516458240 289245200 17771088
08:12:27 5084 8192000000 10475190000 10902163456 1208217744 12.853 100 72 143 2 1571972608 346685776 17069088
08:13:35 4293 8192000000 10438310288 10888560640 1210360080 12.872 100 72 145 0 1541973040 314597808 17015152
08:14:46 1671 8192000000 10471948672 10886377472 1214509600 12.911 100 86 168 1 1572033968 337792000 19732368
08:15:59 2775 8192000000 10496785104 10907721728 1220614208 12.967 100 85 169 1 1589542624 349219936 19708480
08:17:07 2140 8192000000 10485851264 10929725440 1202353840 12.798 100 77 154 0 1583463280 362936944 18172496
08:18:15 8786 8192000000 10488852480 10940526592 1195878560 12.738 99 50 100 0 1585371664 376927376 12565728
08:19:37 7214 8192000000 10456435344 10887323648 1177386352 12.566 99 43 86 1 1541842704 354262912 10193440
08:20:54 11467 8192000000 10456614992 10888859648 1162163056 12.424 99 39 76 2 1541084688 369092656 9828976
08:22:18 9156 8192000000 10494546416 10936115200 1164232400 12.443 100 34 68 1 1588651952 415689760 8729792
08:23:52 9650 8192000000 10367120992 10845290496 1125967344 12.083 98 32 64 0 1454279856 320122800 8189712
08:25:08 6432 8192000000 10258663584 10730795008 1155796416 12.364 100 49 98 0 1346578496 180171232 10610848
08:26:18 25304 8192000000 10118167936 10551341056 1138476576 12.201 100 28 56 3 1216301440 71449504 6375360
08:27:45 47063 8192000000 10109959200 10548535296 1118131968 12.009 98 24 46 0 1209223648 86061904 5029776
08:29:40 28637 8192000000 10098850864 10604429312 1139608272 12.212 100 16 32 0 1216487472 73124336 3754864
08:31:59 70191 8192000000 10073031328 10489602048 1091186928 11.754 97 43 80 1 1128329360 28084112 9058320
08:34:13 32254 8192000000 10085625280 10534948864 1127279200 12.096 100 25 50 0 1187584672 54135984 6169488
08:36:03 113839 8192000000 10055289680 10502963200 1073616592 11.587 95 13 26 0 1108512736 31996576 2899568
08:38:57 76822 8192000000 10065193488 10509676544 1086333200 11.708 97 14 28 1 1141931552 52640928 2957424
08:41:22 75424 8192000000 10081148912 10536718336 1086104192 11.706 97 5 10 1 1125556784 38192048 1260544
08:44:03 187342 8192000000 10047515600 10476617728 1088098832 11.725 97 18 36 0 1123401520 31179536 4123152
08:49:30 35707 8192000000 10120037072 10583498752 1116842864 11.997 99 39 78 2 1215967808 90106032 9018912
08:51:11 33729 8192000000 10124346464 10581495808 1124428608 12.069 99 16 32 2 1213015168 84905760 3680800
08:53:01 88243 8192000000 10121421312 10553847808 1130880848 12.130 99 22 44 0 1227088688 90898336 5309504
08:55:36 79444 8192000000 10114976176 10609766400 1113099712 11.962 97 18 36 1 1189202384 71902432 4200240
08:58:06 41267 8192000000 10081433760 10522218496 1136256336 12.180 100 14 26 1 1185604496 46684192 2663968
08:59:54 48940 8192000000 10114704560 10559447040 1122573392 12.051 98 32 63 0 1177327424 48808304 5945728
09:01:51 32635 8192000000 10128536576 10556235776 1141531824 12.230 100 40 80 2 1203498864 53239728 8727312
09:03:31 101421 8192000000 10135405888 10585223168 1140546464 12.221 100 15 30 0 1215628960 71638704 3443792
09:06:16 62127 8192000000 10087607056 10530988032 1107557504 11.909 97 12 24 0 1155904720 46458976 1888240
09:08:23 32549 8192000000 10075350960 10511298560 1100770080 11.845 98 21 42 1 1143544464 39020048 3754336
09:10:05 28127 8192000000 10069162976 10496143360 1092826608 11.770 97 30 61 2 1129450784 30750864 5873312
09:12:57 53315 8192000000 10065428288 10517458944 1096167152 11.801 98 42 84 4 1173513296 68697488 8648656
09:14:51 70576 8192000000 10062464752 10522320896 1102491632 11.861 98 9 18 0 1140385824 35773456 2120736
09:17:02 57071 8192000000 10094406096 10554318848 1115729520 11.987 99 18 36 1 1191739408 72120736 3889152
09:19:00 79104 8192000000 10077090128 10551676928 1101416384 11.851 98 11 22 1 1167738368 63752128 2569856
09:21:21 75938 8192000000 10065652016 10519019520 1125738272 12.081 100 13 26 0 1180487104 51946240 2802592
09:23:40 37997 8192000000 10103538976 10529701888 1107451728 11.908 98 31 62 1 1182552848 69032224 6068896
09:25:18 75140 8192000000 10087671264 10518433792 1067344928 11.527 94 25 50 1 1121024704 48474304 5205472
09:27:39 71603 8192000000 10061201344 10573447168 1080658144 11.654 96 23 46 1 1180004544 94695024 4651376
09:29:56 59850 8192000000 10087483072 10543665152 1068775824 11.540 96 26 51 1 1142494480 68737840 4980816
09:31:57 13856 8192000000 10050927872 10451443712 1096209872 11.802 99 46 92 0 1117274864 10842368 10222624
09:33:11 98000 8192000000 10046865680 10507956224 1116742176 11.996 100 25 50 1 1155701984 34018800 4941008
09:35:50 36734 8192000000 10040319504 10502520832 1113129808 11.962 99 12 24 0 1150520272 34990400 2400064
09:37:33 67216 8192000000 10028070320 10497515520 1092060832 11.762 98 6 12 0 1125605888 32010448 1534608
09:39:40 73443 8192000000 10080143056 10572337152 1084633440 11.692 97 17 34 1 1149277536 60437120 4206976
09:42:09 36845 8192000000 10048399024 10475692032 1118715104 12.015 100 14 28 1 1156009216 34627120 2666992
09:43:46 63784 8192000000 10121930704 10569543680 1114027472 11.971 98 21 41 1 1194843152 76454272 4361408
09:45:56 56759 8192000000 10069407824 10571714560 1113995696 11.970 99 20 39 1 1208328288 89835120 4497472
09:47:53 33269 8192000000 10082865984 10496049152 1114736112 11.977 99 42 82 3 1157591056 34343936 8511008
09:49:33 43571 8192000000 10080765120 10523828224 1122373264 12.049 100 53 106 1 1190151776 57149344 10629168
09:52:13 47037 8192000000 10056898640 10522185728 1121904464 12.045 99 4 8 0 1141983280 19449504 629312

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 09:55:43 to 11-08-2020 09:55:57.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 99 v_add_invoice_to_stock 335544517
user_exc 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-08-2020 09:55:58 to 11-08-2020 09:56:00.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          214987
MON$OLDEST_ACTIVE               215608
MON$OLDEST_SNAPSHOT             215608
MON$NEXT_TRANSACTION            215608
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-30 07:30:08.6030 GMT
MON$PAGES                       2074912
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000003c06020000000000
MON$NEXT_ATTACHMENT             675
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2143 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2143 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2143 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2143 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
on disk structure version 13.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.oltp40 according to config parameter 'remove_isql_logs'

11.08.20 09:56:02. There are 3 logs in /var/tmp/logs.oltp40 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 09:56:04 NO_SEVERE_ERRORS_FOUND
11.08.20 09:56:06. End of report.