Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.08.2020 00: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 16862828 kB
MemAvailable 30998824 kB
Buffers 0 kB
Cached 14848720 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-17 00:51:55.8330
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(131:22) ==> SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(31:12) ==> SP_HALT_ON_ERROR(46:13)
IP                              ::1/39610
TRN_ID                          219782
ATT_ID                          528
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: 8701.48 30442 1044177
customer order: creation 161.93 375 19432
customer order: refuse 13.97 546 1676
order to supplier: creation 44.15 1514 5298
order to supplier: removal 3.30 154751 396
invoice (draft): creation 43.80 4449 5256
invoice (draft): removal 47.53 1237 5704
invoice accept: apply 39.94 26089 4793
invoice accept: cancel 43.58 1265 5229
customer reserve: creation 3747.90 253 449748
customer reserve: removal 4274.28 91 512914
realization accept: apply 79.88 264 9585
realization accept: cancel 75.80 178 9096
payment from customer: creation 56.29 177 6755
payment from customer: removal 5.52 133 662
payment to supplier: creation 47.83 172 5740
payment to supplier: removal 3.13 128 376
service: total inventory turnovers 4.67 1346 560
service: total monetary turnovers 7.11 48 853
service: aggregate perf. data 0.83 21414 100
service: refresh index statistics 0.03 394407 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1728
TEST_TIME 2 2854
TEST_TIME 3 2535
TEST_TIME 4 3370
TEST_TIME 5 3670
TEST_TIME 6 4200
TEST_TIME 7 4189
TEST_TIME 8 4171
TEST_TIME 9 4256
TEST_TIME 10 4273
TEST_TIME 11 4705
TEST_TIME 12 4696
TEST_TIME 13 5095
TEST_TIME 14 5305
TEST_TIME 15 5183
TEST_TIME 16 5153
TEST_TIME 17 5157
TEST_TIME 18 5185
TEST_TIME 19 5247
TEST_TIME 20 5303
TEST_TIME 21 5357
TEST_TIME 22 5281
TEST_TIME 23 5292
TEST_TIME 24 5335
TEST_TIME 25 5507
TEST_TIME 26 5897
TEST_TIME 27 5851
TEST_TIME 28 6080
TEST_TIME 29 6157
TEST_TIME 30 6326
TEST_TIME 31 6566
TEST_TIME 32 6613
TEST_TIME 33 6649
TEST_TIME 34 6721
TEST_TIME 35 7362
TEST_TIME 36 7653
TEST_TIME 37 7661
TEST_TIME 38 7978
TEST_TIME 39 8012
TEST_TIME 40 8581
TEST_TIME 41 8548
TEST_TIME 42 8428
TEST_TIME 43 8557
TEST_TIME 44 8507
TEST_TIME 45 8474
TEST_TIME 46 8493
TEST_TIME 47 8607
TEST_TIME 48 8485
TEST_TIME 49 8403
TEST_TIME 50 8284
TEST_TIME 51 8434
TEST_TIME 52 8368
TEST_TIME 53 8376
TEST_TIME 54 8259
TEST_TIME 55 8248
TEST_TIME 56 8280
TEST_TIME 57 8241
TEST_TIME 58 8235
TEST_TIME 59 8215
TEST_TIME 60 8232
TEST_TIME 61 8326
TEST_TIME 62 8517
TEST_TIME 63 8489
TEST_TIME 64 8496
TEST_TIME 65 8455
TEST_TIME 66 8526
TEST_TIME 67 8539
TEST_TIME 68 8455
TEST_TIME 69 8534
TEST_TIME 70 8572
TEST_TIME 71 8612
TEST_TIME 72 8610
TEST_TIME 73 8624
TEST_TIME 74 8624
TEST_TIME 75 8599
TEST_TIME 76 8658
TEST_TIME 77 8768
TEST_TIME 78 8754
TEST_TIME 79 8830
TEST_TIME 80 8758
TEST_TIME 81 8699
TEST_TIME 82 8676
TEST_TIME 83 8686
TEST_TIME 84 8678
TEST_TIME 85 8620
TEST_TIME 86 8609
TEST_TIME 87 8617
TEST_TIME 88 8603
TEST_TIME 89 8583
TEST_TIME 90 8620
TEST_TIME 91 8591
TEST_TIME 92 8559
TEST_TIME 93 8540
TEST_TIME 94 8609
TEST_TIME 95 8573
TEST_TIME 96 8552
TEST_TIME 97 8619
TEST_TIME 98 8676
TEST_TIME 99 8688
TEST_TIME 100 8703
TEST_TIME 101 8735
TEST_TIME 102 8790
TEST_TIME 103 8756
TEST_TIME 104 8818
TEST_TIME 105 8802
TEST_TIME 106 8821
TEST_TIME 107 8846
TEST_TIME 108 8917
TEST_TIME 109 8931
TEST_TIME 110 8879
TEST_TIME 111 8856
TEST_TIME 112 8825
TEST_TIME 113 8814
TEST_TIME 114 8831
TEST_TIME 115 8819
TEST_TIME 116 8791
TEST_TIME 117 8772
TEST_TIME 118 8752
TEST_TIME 119 8751
TEST_TIME 120 8709

Performance per minute, chart


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

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 1459230 1459230 0 0.00 0 18440 9 0 0
doc_list_biud 1459230 1459230 0 0.00 0 65839 41 0 0
sp_add_invoice_to_stock 4892 4793 99 2.02 34 231575 26090 0 99
sp_cancel_adding_invoice 5229 5229 0 0.00 6 75120 1265 0 0
sp_cancel_client_order 1676 1676 0 0.00 5 11787 546 0 0
sp_cancel_customer_reserve 512914 512914 0 0.00 1 46344 92 0 0
sp_cancel_pay_from_customer 662 662 0 0.00 3 4316 133 0 0
sp_cancel_pay_to_supplier 376 376 0 0.00 3 3744 129 0 0
sp_cancel_supplier_invoice 5717 5704 13 0.22 2 65843 1237 0 13
sp_cancel_supplier_order 396 396 0 0.00 10 1384785 154751 0 0
sp_cancel_write_off 9096 9096 0 0.00 1 56689 178 0 0
sp_client_order 19432 19432 0 0.00 3 20055 376 0 0
sp_customer_reserve 449748 449748 0 0.00 0 73523 254 0 0
sp_fill_shopping_cart 29983 29983 0 0.00 1 15547 46 0 0
sp_fill_shopping_cart_clo_res 449735 449735 0 0.00 0 12627 16 0 0
sp_get_clo_for_invoice 4778 4778 0 0.00 0 27008 351 0 0
sp_kill_cost_storno 15363 15363 0 0.00 0 9044 94 0 0
sp_kill_qstorno_ret_qs2qd 1041024 1041024 0 0.00 0 65837 49 0 0
sp_kill_qty_storno 549378 549378 0 0.00 0 65838 107 0 0
sp_lock_selected_doc 19864 19864 0 0.00 0 8404 5 0 0
sp_make_cost_storno 26858 26858 0 0.00 0 18066 79 0 0
sp_multiply_rows_for_pdistr 26858 26858 0 0.00 0 10119 42 0 0
sp_multiply_rows_for_qdistr 19432 19432 0 0.00 0 20043 164 0 0
sp_pay_from_customer 6755 6755 0 0.00 4 15970 178 0 0
sp_pay_to_supplier 5740 5740 0 0.00 5 19799 173 0 0
sp_payment_common 12495 12495 0 0.00 1 19727 41 0 0
sp_qd_handle_on_cancel_clo 1676 1676 0 0.00 0 11778 509 0 0
sp_qd_handle_on_invoice_upd_sts 10007 10007 0 0.00 0 38398 521 0 0
sp_qd_handle_on_reserve_upd_sts 9585 9585 0 0.00 0 33725 94 0 0
sp_reserve_write_off 9585 9585 0 0.00 6 33738 264 0 0
sp_supplier_invoice 5256 5256 0 0.00 8 128646 4449 0 0
sp_supplier_order 5298 5298 0 0.00 5 26254 1515 0 0
srv_aggregate_perf_data 100 100 0 0.00 2365 156625 21415 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 1675 117001 36759 0 0
srv_find_qd_qs_mism 997539 997539 0 0.00 0 12243 8 0 0
srv_make_invnt_saldo 560 560 0 0.00 46 71979 1346 0 0
srv_make_money_saldo 853 853 0 0.00 1 1663 49 0 0
srv_recalc_idx_stat 4 4 0 0.00 136995 557710 394407 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 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4793 4793 0 0.00 0 30545 294 0 0
t$perf-norm:sp_cancel_adding_invoice 424 424 0 0.00 1 2138 129 0 0
t$perf-norm:sp_cancel_client_order 1676 1676 0 0.00 0 2180 19 0 0
t$perf-norm:sp_cancel_customer_reserve 650 650 0 0.00 0 9496 50 0 0
t$perf-norm:sp_cancel_pay_from_customer 662 662 0 0.00 0 2315 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 376 376 0 0.00 0 9487 31 0 0
t$perf-norm:sp_cancel_supplier_invoice 375 375 0 0.00 0 3105 36 0 0
t$perf-norm:sp_cancel_supplier_order 396 396 0 0.00 0 35542 2796 0 0
t$perf-norm:sp_cancel_write_off 951 951 0 0.00 0 1383 8 0 0
t$perf-norm:sp_client_order 19432 19432 0 0.00 0 10829 30 0 0
t$perf-norm:sp_customer_reserve 980 980 0 0.00 0 9524 16 0 0
t$perf-norm:sp_pay_from_customer 6755 6755 0 0.00 0 8217 13 0 0
t$perf-norm:sp_pay_to_supplier 5740 5740 0 0.00 0 4731 12 0 0
t$perf-norm:sp_reserve_write_off 9585 9585 0 0.00 0 17006 18 0 0
t$perf-norm:sp_supplier_invoice 5256 5256 0 0.00 0 6409 28 0 0
t$perf-norm:sp_supplier_order 5298 5298 0 0.00 0 8694 29 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 1789 61 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 338 7 0 0
t$perf-norm:srv_make_invnt_saldo 560 560 0 0.00 0 3117 54 0 0
t$perf-norm:srv_make_money_saldo 853 853 0 0.00 0 1283 38 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 3 0 0
v_add_invoice_to_stock 9740 9542 198 2.03 0 4536 22 0 198
v_all_customers 35578 35578 0 0.00 0 21 1 0 0
v_all_suppliers 29630 29630 0 0.00 0 70 1 0 0
v_all_wares 38864 38864 0 0.00 0 97 1 0 0
v_cancel_adding_invoice 848 848 0 0.00 0 3645 13 0 0
v_cancel_client_order 3352 3352 0 0.00 0 4549 10 0 0
v_cancel_customer_prepayment 1324 1324 0 0.00 0 1481 8 0 0
v_cancel_customer_reserve 1300 1300 0 0.00 0 4542 8 0 0
v_cancel_payment_to_supplier 752 752 0 0.00 0 1913 10 0 0
v_cancel_supplier_invoice 776 750 26 3.35 0 1930 26 0 26
v_cancel_supplier_order 792 792 0 0.00 0 4356 17 0 0
v_cancel_write_off 1902 1902 0 0.00 0 7165 24 0 0
v_max_id_clo_ord 5298 5298 0 0.00 0 252 1 0 0
v_max_id_clo_res 980 980 0 0.00 0 1324 12 0 0
v_max_id_ord_sup 5253 5253 0 0.00 0 1346 2 0 0
v_max_non_paid_invoice 1476 1476 0 0.00 0 837 3 0 0
v_max_non_paid_realizn 3581 3581 0 0.00 0 4320 5 0 0
v_min_id_clo_ord 5298 5298 0 0.00 0 3407 8 0 0
v_min_id_clo_res 980 980 0 0.00 0 3840 7 0 0
v_min_id_ord_sup 5253 5253 0 0.00 0 4787 6 0 0
v_min_non_paid_invoice 5740 5740 0 0.00 0 19722 24 0 0
v_min_non_paid_realizn 6755 6755 0 0.00 0 15946 32 0 0
v_our_firm 9634 9634 0 0.00 0 23 1 0 0
v_reserve_write_off 19166 19166 0 0.00 0 5303 8 0 0
x_lock_depdocs_on_canc_invoice 10558 10558 0 0.00 0 15107 189 0 0
x_lock_depdocs_on_canc_sup_ord 396 396 0 0.00 0 9733 545 0 0
x_make_qstorno_1000_1200 5298 5298 0 0.00 1 26211 1398 0 0
x_make_qstorno_1200_2000 5256 5256 0 0.00 4 128638 4280 0 0
x_make_qstorno_2100_3300 449748 449748 0 0.00 0 73457 232 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-08-2020 00:54:16 to 17-08-2020 00:54:20.

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
22:21:59 1 8192000000 8798382336 8808857600 5057328 0.061 1 1 2 0 5175376 55488 62560
22:23:00 20944 8192000000 10361698752 10678886400 1408651136 14.672 100 85 170 0 1441940064 5660480 27628448
22:24:28 24391 8192000000 10413658320 10739183616 1469528944 15.210 100 87 175 0 1505910320 8081536 28299840
22:25:55 17166 8192000000 10442266960 10776457216 1501734688 15.491 100 88 176 0 1538207728 8510560 27962480
22:27:19 16506 8192000000 10517893520 10867449856 1576648928 16.139 100 73 146 0 1607522784 9004576 21869280
22:28:37 11681 8192000000 10591966368 10954829824 1655614992 16.812 100 77 154 0 1689018896 10488704 22915200
22:29:50 9937 8192000000 10646754512 11015348224 1709075440 17.261 100 77 150 0 1741565424 11628432 20861552
22:31:05 6675 8192000000 10693112976 11064782848 1754617488 17.640 100 85 170 2 1792883056 14442432 23823136
22:32:14 3843 8192000000 10729272512 11107926016 1790830112 17.939 100 83 164 1 1828339488 14856832 22652544
22:33:20 3418 8192000000 10772518352 11151867904 1827451456 18.239 100 88 172 1 1864377888 13010224 23916208
22:34:26 1618 8192000000 10788720976 11175538688 1852944672 18.446 100 90 180 1 1891240944 13547360 24748912
22:35:30 27923 8192000000 10782615888 11181391872 1852978176 18.446 100 61 121 0 1879681888 9593840 17109872
22:37:18 2967 8192000000 10770540528 11189260288 1861897328 18.519 100 36 72 0 1880745712 8732464 10115920
22:38:27 5422 8192000000 10778950784 11208523776 1868144096 18.569 100 20 40 0 1884519792 11410096 4965600
22:39:43 10876 8192000000 10792006256 11218944000 1883182560 18.691 100 23 46 1 1896680000 6634176 6863264
22:40:57 35909 8192000000 10811229920 11231154176 1886543152 18.718 100 35 70 3 1902758832 5749904 10465776
22:42:37 8598 8192000000 10802827200 11227082752 1870713792 18.590 99 25 48 2 1884807872 6634032 7460048
22:43:48 7009 8192000000 10783490320 11208327168 1841078784 18.350 98 22 45 1 1852524368 4856096 6589488
22:44:56 14077 8192000000 10730064192 11144646656 1756269424 17.654 95 20 41 1 1766863584 5465760 5128400
22:46:10 32756 8192000000 10719144944 11139665920 1728323200 17.422 94 23 46 1 1740208480 5605136 6280144
22:47:46 4965 8192000000 10698273408 11102232576 1747641392 17.582 98 35 70 1 1764990992 7655472 9694128
22:48:54 6616 8192000000 10711108048 11133030400 1807609376 18.076 100 31 62 1 1823109664 5680544 9819744
22:50:04 16809 8192000000 10736487776 11154563072 1824489408 18.214 100 32 65 3 1839206544 5248288 9468848
22:51:22 25666 8192000000 10757004928 11183976448 1846809680 18.396 100 17 33 0 1860283952 9095376 4378896
22:52:54 28433 8192000000 10805173680 11213811712 1874882992 18.624 100 49 97 0 1901246592 12633872 13729728
22:54:30 47897 8192000000 10831106880 11246383104 1897930928 18.810 100 53 105 5 1926983632 12454864 16597840
22:56:23 15547 8192000000 10844348192 11265708032 1908949680 18.898 100 39 78 4 1930559312 10220400 11389232
22:57:42 40804 8192000000 10850023376 11276992512 1916008144 18.955 100 36 72 2 1940783328 14559072 10216112
22:59:33 41413 8192000000 10858956592 11288625152 1927845632 19.050 100 42 84 0 1953882848 13835664 12201552
23:01:21 7120 8192000000 10899498592 11311558656 1946755472 19.201 100 64 128 0 1985952928 20745552 18451904
23:02:34 12354 8192000000 10892369904 11321102336 1953025664 19.251 100 51 99 2 1983197776 15432784 14739328
23:03:51 9089 8192000000 10910727232 11333296128 1967828144 19.368 100 58 114 0 2005535392 20712176 16995072
23:05:04 5064 8192000000 10923764240 11348275200 1975617088 19.430 100 58 114 2 2009984912 18183104 16184720
23:06:17 3970 8192000000 10929924576 11344478208 1974414320 19.420 100 71 142 1 2013864432 18502480 20947632
23:07:31 16973 8192000000 10936562224 11362418688 1987660144 19.525 100 58 115 0 2025214160 19804128 17749888
23:08:58 2708 8192000000 10970063616 11385864192 2005087008 19.663 100 71 144 1 2054456736 27616720 21753008
23:10:08 4339 8192000000 10981143808 11398639616 2012009840 19.717 100 78 152 1 2066634688 31212880 23411968
23:11:24 2648 8192000000 10991042224 11405705216 2021598624 19.793 100 74 147 0 2076617648 32731120 22287904
23:12:33 2718 8192000000 11010398304 11418312704 2022744864 19.802 100 84 167 1 2082063040 33678704 25639472
23:13:44 11496 8192000000 10986981760 11407765504 2005130912 19.663 100 71 142 1 2071953072 43889488 22932672
23:15:07 6561 8192000000 10998823344 11416760320 2011624832 19.714 100 79 157 3 2083201888 45823456 25753600
23:16:26 7267 8192000000 11005233968 11428409344 2022208800 19.797 100 75 150 1 2092028704 44770448 25049456
23:17:47 10394 8192000000 11000789584 11421835264 2006354608 19.673 99 78 154 2 2069507184 38119504 25033072
23:19:05 2667 8192000000 10992927104 11406561280 1988307184 19.530 99 87 173 2 2061757664 45791504 27658976
23:20:16 3863 8192000000 10991337600 11404660736 1989104576 19.537 99 80 160 1 2063002704 48338688 25559440
23:21:37 5539 8192000000 10987679488 11408617472 1996786800 19.597 100 68 136 0 2063614512 45307120 21520592
23:22:51 19727 8192000000 10919763072 11338022912 1934313920 19.101 98 49 98 0 1991744704 40625504 16805280
23:24:20 1674 8192000000 10980315616 11403014144 1987561312 19.525 100 67 134 0 2056975008 48041696 21372000
23:25:27 4994 8192000000 10997089328 11412193280 1994617648 19.580 100 82 164 2 2080531440 59814176 26099616
23:26:39 8346 8192000000 10935633056 11373395968 1961653056 19.319 100 39 78 2 2022408896 47010128 13745712
23:27:52 13403 8192000000 10929797152 11367301120 1963520016 19.334 100 40 77 1 2007513440 31703136 12290288
23:29:10 8496 8192000000 10937358800 11380498432 1973251696 19.411 100 51 100 1 2026419200 37051344 16116160
23:30:26 37477 8192000000 10908383696 11369934848 1953512848 19.254 100 20 40 0 1991208608 30962304 6733456
23:32:10 29148 8192000000 10903050816 11357732864 1962875712 19.329 100 26 53 2 1984665328 14330080 7459536
23:33:42 78782 8192000000 10838310128 11294507008 1831106992 18.268 94 14 30 2 1852020672 16625520 4288160
23:36:13 68598 8192000000 10864464352 11323502592 1903763888 18.857 98 17 34 1 1920096864 11183824 5149152
23:38:49 87705 8192000000 10877642256 11321217024 1893695424 18.776 98 20 39 1 1911616960 12334336 5587200
23:41:54 70411 8192000000 10876472144 11325526016 1919771120 18.985 99 44 88 1 1950453392 17053440 13628832
23:44:06 26515 8192000000 10893231120 11357466624 1967158576 19.363 100 43 86 2 1994174144 14851616 12163952
23:46:02 19511 8192000000 10891064224 11333509120 1934416624 19.102 99 35 69 1 1957541872 12793280 10331968
23:47:24 19862 8192000000 10900379936 11355201536 1964333040 19.340 100 24 47 2 1982181568 11213200 6635328
23:48:48 27889 8192000000 10912322784 11357110272 1967374480 19.365 100 31 61 0 1991114032 14248032 9491520
23:50:29 24484 8192000000 10919012880 11364597760 1973502896 19.413 100 40 79 0 2005416512 18738544 13175072
23:51:57 61097 8192000000 10898173936 11365019648 1956196864 19.276 99 16 33 0 1987373152 25689856 5486432
23:54:59 74216 8192000000 10882191136 11345448960 1925314784 19.029 98 13 26 0 1942289568 12992400 3982384
23:57:25 33651 8192000000 10882086384 11361955840 1948894272 19.218 99 6 12 0 1965917024 14226480 2796272
23:58:59 23844 8192000000 10900104944 11348328448 1930705216 19.073 98 30 60 1 1947136752 8086192 8345344
00:00:28 62337 8192000000 10891675216 11356999680 1912744816 18.929 97 19 38 1 1931118336 12971008 5402512
00:02:30 56042 8192000000 10857268080 11325132800 1897494496 18.806 97 25 48 3 1915869056 10310320 8064240
00:04:31 32054 8192000000 10882021824 11332276224 1943283040 19.173 100 28 54 3 1959508384 8981984 7243360
00:06:09 18209 8192000000 10893076896 11331280896 1942066832 19.163 100 36 72 1 1972062720 20208736 9787152
00:07:29 92923 8192000000 10855702928 11325075456 1896251456 18.796 98 9 18 0 1913805520 15105792 2448272
00:10:02 72267 8192000000 10885143040 11333300224 1911097344 18.915 98 9 16 0 1929069024 14943664 3028016
00:12:16 117001 8192000000 10837776704 11298775040 1870456176 18.588 97 21 42 0 1888700624 11764704 6479744
00:15:20 80551 8192000000 10872908752 11328376832 1935212864 19.109 100 23 46 1 1952129760 10120592 6796304
00:17:44 6665 8192000000 10882951728 11311677440 1923329968 19.014 99 65 126 4 1953577984 12329488 17918528
00:19:01 19161 8192000000 10868419696 11309281280 1929228176 19.061 100 41 82 0 1951616128 10635120 11752832
00:20:22 87734 8192000000 10871350224 11344924672 1931878368 19.082 99 12 23 2 1949645680 13960656 3806656
00:22:50 42041 8192000000 10858254960 11317972992 1899083904 18.819 97 15 30 0 1914179712 10933280 4162528
00:24:48 74732 8192000000 10855500288 11339960320 1866982272 18.560 96 16 32 1 1888330064 17512608 3835184
00:27:03 102711 8192000000 10876783424 11327107072 1865488352 18.548 95 23 46 1 1889887936 17884672 6514912
00:29:47 92800 8192000000 10878752048 11329564672 1892233136 18.764 97 17 34 0 1913930320 16558928 5138256
00:32:40 27263 8192000000 10889298560 11334889472 1935479072 19.111 99 35 69 3 1954659328 9476224 9704032
00:34:08 43274 8192000000 10899625376 11346272256 1951992080 19.242 100 33 66 2 1972129776 10956336 9181360
00:35:51 66477 8192000000 10892031824 11334193152 1902155968 18.844 97 36 70 2 1923870208 12266064 9448176
00:38:01 53041 8192000000 10883335824 11342700544 1906483344 18.878 98 28 55 1 1933049616 19206976 7359296
00:39:55 84972 8192000000 10866421584 11335983104 1904393120 18.862 98 4 8 2 1936322352 30832208 1097024
00:42:28 41492 8192000000 10903923856 11341811712 1951572624 19.239 100 39 77 2 1971639680 8930784 11136272
00:44:12 12551 8192000000 10894640464 11341418496 1954352592 19.261 100 39 78 0 1976418160 11168912 10896656
00:45:26 39798 8192000000 10871112912 11321032704 1896494928 18.798 97 19 38 0 1908251488 6502064 5254496
00:47:06 64399 8192000000 10861814896 11324317696 1865910848 18.551 95 18 36 1 1880269520 8980864 5377808
00:49:12 80351 8192000000 10850947328 11321851904 1900520944 18.830 97 15 30 1 1917408224 12632016 4255264

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 17-08-2020 00:54:22 to 17-08-2020 00:54:36.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 17-08-2020 00:54:37 to 17-08-2020 00:54:38.

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          219781
MON$OLDEST_ACTIVE               224856
MON$OLDEST_SNAPSHOT             224856
MON$NEXT_TRANSACTION            224856
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                       2042464
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.33356 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.7.33356 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.7.33356 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.7.33356 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 ---
:41: 
:42: mscs-test-161.budzdorov.ru    Mon Aug 17 00:52:24 2020
:43:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:44: 
:45: 
:46: mscs-test-161.budzdorov.ru    Mon Aug 17 00:52:24 2020
:47:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:48: 
:49: 
:50: mscs-test-161.budzdorov.ru    Mon Aug 17 00:52:24 2020
:51:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:52: 
--- end of diff output ---

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

17.08.20 00:54:40. 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-17 00:54:43 NO_SEVERE_ERRORS_FOUND
17.08.20 00:54:45. End of report.