Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.08.2020 18: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 16685228 kB
MemAvailable 31025532 kB
Buffers 0 kB
Cached 15014728 kB
SwapCached 6212 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-12 18:51:57.4840
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(31:12) ==> SP_HALT_ON_ERROR(46:13)
IP                              ::1/33068
TRN_ID                          221480
ATT_ID                          184
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: 8727.78 29874 1047333
customer order: creation 159.38 393 19125
customer order: refuse 13.55 516 1626
order to supplier: creation 42.65 1543 5118
order to supplier: removal 3.12 168573 374
invoice (draft): creation 42.61 4831 5113
invoice (draft): removal 50.07 1361 6008
invoice accept: apply 38.47 24778 4616
invoice accept: cancel 45.21 1247 5425
customer reserve: creation 3632.83 235 435940
customer reserve: removal 4431.47 89 531776
realization accept: apply 76.52 261 9182
realization accept: cancel 70.63 168 8475
payment from customer: creation 55.30 187 6636
payment from customer: removal 4.76 125 571
payment to supplier: creation 45.23 192 5427
payment to supplier: removal 3.46 100 415
service: total inventory turnovers 4.84 1491 581
service: total monetary turnovers 6.98 96 837
service: aggregate perf. data 0.70 27954 84
service: refresh index statistics 0.03 363339 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-08-2020 18:53:38 to 12-08-2020 18:53:40.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 704
TEST_TIME 2 1589
TEST_TIME 3 2654
TEST_TIME 4 2731
TEST_TIME 5 2674
TEST_TIME 6 2934
TEST_TIME 7 3216
TEST_TIME 8 3202
TEST_TIME 9 3188
TEST_TIME 10 3290
TEST_TIME 11 3540
TEST_TIME 12 3973
TEST_TIME 13 4417
TEST_TIME 14 4431
TEST_TIME 15 4583
TEST_TIME 16 4723
TEST_TIME 17 4785
TEST_TIME 18 4903
TEST_TIME 19 5177
TEST_TIME 20 5654
TEST_TIME 21 5614
TEST_TIME 22 6070
TEST_TIME 23 6124
TEST_TIME 24 6349
TEST_TIME 25 6438
TEST_TIME 26 6515
TEST_TIME 27 6484
TEST_TIME 28 6522
TEST_TIME 29 6537
TEST_TIME 30 7157
TEST_TIME 31 7223
TEST_TIME 32 7292
TEST_TIME 33 7291
TEST_TIME 34 7631
TEST_TIME 35 7557
TEST_TIME 36 7745
TEST_TIME 37 7918
TEST_TIME 38 7915
TEST_TIME 39 8088
TEST_TIME 40 8509
TEST_TIME 41 8688
TEST_TIME 42 8913
TEST_TIME 43 9299
TEST_TIME 44 9376
TEST_TIME 45 9233
TEST_TIME 46 9137
TEST_TIME 47 9133
TEST_TIME 48 9077
TEST_TIME 49 8973
TEST_TIME 50 8875
TEST_TIME 51 8773
TEST_TIME 52 8822
TEST_TIME 53 8793
TEST_TIME 54 8717
TEST_TIME 55 8659
TEST_TIME 56 8690
TEST_TIME 57 8701
TEST_TIME 58 8654
TEST_TIME 59 8535
TEST_TIME 60 8487
TEST_TIME 61 8463
TEST_TIME 62 8425
TEST_TIME 63 8337
TEST_TIME 64 8570
TEST_TIME 65 8542
TEST_TIME 66 8752
TEST_TIME 67 8696
TEST_TIME 68 8604
TEST_TIME 69 8538
TEST_TIME 70 8480
TEST_TIME 71 8518
TEST_TIME 72 8548
TEST_TIME 73 8510
TEST_TIME 74 8493
TEST_TIME 75 8559
TEST_TIME 76 8603
TEST_TIME 77 8588
TEST_TIME 78 8585
TEST_TIME 79 8732
TEST_TIME 80 8693
TEST_TIME 81 8676
TEST_TIME 82 8720
TEST_TIME 83 8664
TEST_TIME 84 8852
TEST_TIME 85 8804
TEST_TIME 86 8808
TEST_TIME 87 8742
TEST_TIME 88 8702
TEST_TIME 89 8651
TEST_TIME 90 8629
TEST_TIME 91 8599
TEST_TIME 92 8657
TEST_TIME 93 8627
TEST_TIME 94 8591
TEST_TIME 95 8602
TEST_TIME 96 8612
TEST_TIME 97 8651
TEST_TIME 98 8608
TEST_TIME 99 8667
TEST_TIME 100 8666
TEST_TIME 101 8668
TEST_TIME 102 8757
TEST_TIME 103 8753
TEST_TIME 104 8736
TEST_TIME 105 8776
TEST_TIME 106 8749
TEST_TIME 107 8860
TEST_TIME 108 8929
TEST_TIME 109 8910
TEST_TIME 110 8884
TEST_TIME 111 8857
TEST_TIME 112 8842
TEST_TIME 113 8818
TEST_TIME 114 8814
TEST_TIME 115 8806
TEST_TIME 116 8785
TEST_TIME 117 8775
TEST_TIME 118 8748
TEST_TIME 119 8713
TEST_TIME 120 8729
TEST_TIME 121 8656

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-08-2020 18:53:42 to 12-08-2020 18: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 1449033 1449033 0 0.00 0 27955 9 0 0
doc_list_biud 1449033 1449033 0 0.00 0 76396 42 0 0
sp_add_invoice_to_stock 4766 4616 150 3.14 54 201660 24779 0 150
sp_cancel_adding_invoice 5425 5425 0 0.00 5 80395 1248 0 0
sp_cancel_client_order 1626 1626 0 0.00 4 12106 517 0 0
sp_cancel_customer_reserve 531776 531776 0 0.00 1 76400 89 0 0
sp_cancel_pay_from_customer 571 571 0 0.00 2 2913 126 0 0
sp_cancel_pay_to_supplier 415 415 0 0.00 3 1198 100 0 0
sp_cancel_supplier_invoice 6022 6008 14 0.23 2 30897 1362 0 14
sp_cancel_supplier_order 374 374 0 0.00 5 1377583 168573 0 0
sp_cancel_write_off 8475 8475 0 0.00 1 14298 169 0 0
sp_client_order 19125 19125 0 0.00 3 76482 393 0 0
sp_customer_reserve 435940 435940 0 0.00 0 141201 235 0 0
sp_fill_shopping_cart 29346 29346 0 0.00 1 26173 53 0 0
sp_fill_shopping_cart_clo_res 435938 435938 0 0.00 0 14832 19 0 0
sp_get_clo_for_invoice 4614 4614 0 0.00 0 27938 380 0 0
sp_kill_cost_storno 14886 14886 0 0.00 0 7587 103 0 0
sp_kill_qstorno_ret_qs2qd 1078409 1078409 0 0.00 0 76387 49 0 0
sp_kill_qty_storno 567479 567479 0 0.00 0 76395 106 0 0
sp_lock_selected_doc 19061 19061 0 0.00 0 10808 7 0 0
sp_make_cost_storno 25858 25858 0 0.00 0 27951 84 0 0
sp_multiply_rows_for_pdistr 25858 25858 0 0.00 0 11994 46 0 0
sp_multiply_rows_for_qdistr 19125 19125 0 0.00 0 76433 174 0 0
sp_pay_from_customer 6636 6636 0 0.00 4 36478 188 0 0
sp_pay_to_supplier 5427 5427 0 0.00 4 21259 192 0 0
sp_payment_common 12062 12062 0 0.00 1 36469 44 0 0
sp_qd_handle_on_cancel_clo 1626 1626 0 0.00 0 12098 477 0 0
sp_qd_handle_on_invoice_upd_sts 10038 10038 0 0.00 0 25733 567 0 0
sp_qd_handle_on_reserve_upd_sts 9182 9182 0 0.00 0 14417 78 0 0
sp_reserve_write_off 9182 9182 0 0.00 6 14463 261 0 0
sp_supplier_invoice 5113 5113 0 0.00 7 119988 4832 0 0
sp_supplier_order 5118 5118 0 0.00 6 24827 1543 0 0
srv_aggregate_perf_data 84 84 0 0.00 2951 389124 27954 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1347 173935 41890 0 0
srv_find_qd_qs_mism 1001737 1001737 0 0.00 0 22084 7 0 0
srv_make_invnt_saldo 581 581 0 0.00 40 132888 1491 0 0
srv_make_money_saldo 837 837 0 0.00 1 19495 96 0 0
srv_recalc_idx_stat 4 4 0 0.00 246252 478174 363339 0 0
t$perf-abend:sp_add_invoice_to_stock 150 150 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4616 4616 0 0.00 0 21798 339 0 0
t$perf-norm:sp_cancel_adding_invoice 378 378 0 0.00 0 3363 122 0 0
t$perf-norm:sp_cancel_client_order 1626 1626 0 0.00 0 8649 28 0 0
t$perf-norm:sp_cancel_customer_reserve 603 603 0 0.00 0 2292 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 571 571 0 0.00 0 2047 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 415 415 0 0.00 0 1746 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 368 368 0 0.00 0 3302 48 0 0
t$perf-norm:sp_cancel_supplier_order 374 374 0 0.00 0 40906 3183 0 0
t$perf-norm:sp_cancel_write_off 931 931 0 0.00 0 1533 10 0 0
t$perf-norm:sp_client_order 19125 19125 0 0.00 0 9417 32 0 0
t$perf-norm:sp_customer_reserve 920 920 0 0.00 0 4512 21 0 0
t$perf-norm:sp_pay_from_customer 6636 6636 0 0.00 0 6780 17 0 0
t$perf-norm:sp_pay_to_supplier 5427 5427 0 0.00 0 6289 17 0 0
t$perf-norm:sp_reserve_write_off 9182 9182 0 0.00 0 4442 16 0 0
t$perf-norm:sp_supplier_invoice 5113 5113 0 0.00 0 6544 34 0 0
t$perf-norm:sp_supplier_order 5118 5118 0 0.00 0 9204 32 0 0
t$perf-norm:srv_aggregate_perf_data 84 84 0 0.00 0 1029 25 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 845 14 0 0
t$perf-norm:srv_make_invnt_saldo 581 581 0 0.00 0 1820 58 0 0
t$perf-norm:srv_make_money_saldo 837 837 0 0.00 0 6069 42 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 2535 639 0 0
v_add_invoice_to_stock 9526 9226 300 3.14 0 10613 23 0 300
v_all_customers 35238 35238 0 0.00 0 16 1 0 0
v_all_suppliers 28372 28372 0 0.00 0 22 1 0 0
v_all_wares 38248 38248 0 0.00 0 24 1 0 0
v_cancel_adding_invoice 756 756 0 0.00 0 5680 19 0 0
v_cancel_client_order 3252 3252 0 0.00 0 4353 8 0 0
v_cancel_customer_prepayment 1142 1142 0 0.00 0 1407 10 0 0
v_cancel_customer_reserve 1206 1206 0 0.00 0 5666 14 0 0
v_cancel_payment_to_supplier 830 830 0 0.00 0 483 6 0 0
v_cancel_supplier_invoice 764 736 28 3.66 0 5006 38 0 28
v_cancel_supplier_order 748 748 0 0.00 0 3607 9 0 0
v_cancel_write_off 1862 1862 0 0.00 0 1697 17 0 0
v_max_id_clo_ord 5116 5116 0 0.00 0 6270 3 0 0
v_max_id_clo_res 920 920 0 0.00 0 4433 23 0 0
v_max_id_ord_sup 5106 5106 0 0.00 0 8108 4 0 0
v_max_non_paid_invoice 1463 1463 0 0.00 0 1077 4 0 0
v_max_non_paid_realizn 3350 3350 0 0.00 0 5664 7 0 0
v_min_id_clo_ord 5116 5116 0 0.00 0 8373 9 0 0
v_min_id_clo_res 920 920 0 0.00 0 3673 13 0 0
v_min_id_ord_sup 5106 5106 0 0.00 0 5156 7 0 0
v_min_non_paid_invoice 5427 5427 0 0.00 0 21139 30 0 0
v_min_non_paid_realizn 6635 6635 0 0.00 0 9444 27 0 0
v_our_firm 9580 9580 0 0.00 0 17 1 0 0
v_reserve_write_off 18364 18364 0 0.00 0 6061 9 0 0
x_lock_depdocs_on_canc_invoice 11065 11065 0 0.00 0 21928 178 0 0
x_lock_depdocs_on_canc_sup_ord 374 374 0 0.00 0 20306 658 0 0
x_make_qstorno_1000_1200 5118 5118 0 0.00 1 24813 1419 0 0
x_make_qstorno_1200_2000 5113 5113 0 0.00 4 104447 4640 0 0
x_make_qstorno_2100_3300 435940 435940 0 0.00 0 141199 211 0 0

Completed "Performance in DETAILS". Done for 00:00:03, from 12-08-2020 18:53:46 to 12-08-2020 18:53:49.

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
16:22:00 2 8192000000 8798382336 8808857600 5057328 0.061 1 1 2 0 5175376 55488 62560
16:23:02 23608 8192000000 10366403200 10684178432 1415738464 14.735 100 86 172 0 1449359856 5547696 28073696
16:24:31 20093 8192000000 10434305904 10761773056 1486197712 15.356 100 92 184 0 1524241456 7886336 30157408
16:25:54 10762 8192000000 10475684800 10810552320 1534293488 15.774 100 92 184 0 1572116096 8978368 28844240
16:27:16 18398 8192000000 10531744176 10883244032 1594461168 16.292 100 89 178 2 1632181232 10669712 27050352
16:28:36 14951 8192000000 10598757552 10956046336 1655422288 16.810 100 83 166 2 1692100448 12272976 24405184
16:29:53 10411 8192000000 10648204656 11017658368 1707400928 17.247 100 85 168 2 1744870832 12911392 24558512
16:31:07 7540 8192000000 10688682576 11064512512 1757658224 17.665 100 79 157 1 1791851840 12309552 21884064
16:32:17 6448 8192000000 10724882272 11109904384 1794373472 17.968 100 67 134 0 1824931264 12162416 18395376
16:33:27 2823 8192000000 10768659792 11154579456 1828589632 18.248 100 77 152 2 1863096976 13361760 21145584
16:34:33 2601 8192000000 10793756048 11180515328 1858961872 18.495 100 81 162 1 1893338192 11486496 22889824
16:35:40 28673 8192000000 10773359376 11189784576 1860601520 18.508 100 37 74 1 1879190144 8243056 10345568
16:37:15 8199 8192000000 10794286816 11203444736 1874617328 18.622 100 32 62 0 1890474416 7109680 8747408
16:38:24 7845 8192000000 10780819152 11209359360 1881501792 18.677 100 25 50 5 1895269888 6721248 7046848
16:39:33 6712 8192000000 10793156976 11217506304 1886656896 18.719 100 30 60 2 1901586416 6174448 8755072
16:40:40 15113 8192000000 10822161952 11236048896 1897660256 18.807 100 57 113 5 1919973872 5719008 16594608
16:41:59 2948 8192000000 10827399424 11241029632 1897120624 18.803 100 45 88 2 1917657152 8470272 12066256
16:43:02 5904 8192000000 10809996608 11232333824 1890002384 18.746 100 23 47 1 1901881248 5532624 6346240
16:44:09 60160 8192000000 10775021840 11187515392 1749616880 17.598 93 32 63 3 1764175488 5871744 8686864
16:46:10 7027 8192000000 10708372784 11123634176 1768655216 17.756 98 21 42 3 1781257456 6264720 6337520
16:47:17 7775 8192000000 10671408624 11082616832 1711252032 17.279 96 26 52 0 1724656128 5761616 7642480
16:48:30 7142 8192000000 10722055872 11139264512 1802891088 18.038 100 17 34 3 1817477152 10375840 4210224
16:49:38 23090 8192000000 10714398736 11134402560 1775803424 17.815 98 22 44 1 1788213584 5316272 7093888
16:51:02 3219 8192000000 10757489600 11169914880 1842346704 18.360 100 49 98 3 1864140192 5676080 16117408
16:52:16 66130 8192000000 10788299200 11206705152 1865829136 18.551 100 20 40 0 1876385760 5558672 4997952
16:54:34 84869 8192000000 10838277472 11262373888 1913345424 18.933 100 48 96 0 1941250256 13655408 14249424
16:57:05 15630 8192000000 10861242416 11280568320 1921744640 19.001 100 44 88 1 1950214832 15059024 13411168
16:58:43 30926 8192000000 10860763984 11294466048 1935014320 19.107 100 29 58 1 1959822496 16022608 8785568
17:00:18 10953 8192000000 10887725168 11314192384 1949832272 19.225 100 42 83 0 1981129328 18422192 12874864
17:01:56 32586 8192000000 10913416832 11342303232 1963805152 19.336 100 42 80 1 1996897440 20979440 12112848
17:03:39 7820 8192000000 10938614160 11358339072 1984983456 19.504 100 63 124 1 2026951824 23727360 18241008
17:04:50 4450 8192000000 10940391024 11366584320 1973545664 19.414 99 60 118 1 2013418624 22999600 16873360
17:06:03 6674 8192000000 10948905136 11380645888 2000609040 19.628 100 55 109 3 2041611840 25137680 15865120
17:07:18 10968 8192000000 10962658320 11391471616 2005999200 19.670 100 73 146 4 2060739200 32980816 21759184
17:08:35 7491 8192000000 10997150080 11420045312 2021145216 19.789 100 70 136 2 2081198304 38501344 21551744
17:09:50 4601 8192000000 11013581952 11438825472 2027507088 19.839 100 76 150 1 2101637200 50235104 23895008
17:11:02 3139 8192000000 11005948192 11424776192 2018008224 19.765 100 84 167 0 2089664864 46892816 24763824
17:12:13 16380 8192000000 10984844192 11415740416 2014883680 19.740 100 66 130 3 2075029648 39793440 20352528
17:13:38 5920 8192000000 10974962336 11398217728 2004171952 19.656 100 67 134 1 2059680528 34830048 20678528
17:14:53 9445 8192000000 10969299056 11392716800 1994747600 19.581 100 72 142 2 2055232032 39046544 21437888
17:16:11 6187 8192000000 10977645632 11389095936 1988048016 19.528 100 82 164 0 2055656960 42027056 25581888
17:17:27 1347 8192000000 10991212304 11395493888 1989698528 19.541 100 96 190 3 2069936768 50315232 29923008
17:18:36 4271 8192000000 10968275376 11386941440 1964372160 19.341 99 80 158 1 2041540048 52450464 24717424
17:19:47 1827 8192000000 10979604128 11381522432 1967964720 19.369 100 84 166 1 2056229056 60799136 27465200
17:20:58 2550 8192000000 10989145264 11398918144 1974597056 19.422 100 91 183 2 2072506272 68462464 29446752
17:22:17 2308 8192000000 10986775872 11393163264 1977174864 19.442 100 94 185 1 2063230832 57480208 28575760
17:23:27 4821 8192000000 11011379504 11421347840 1999036560 19.615 100 83 167 1 2084863152 60103808 25722784
17:24:42 7730 8192000000 10960033744 11387084800 1962494880 19.326 100 52 104 0 2042869808 62219040 18155888
17:25:54 10054 8192000000 10947121776 11370635264 1955671856 19.272 100 51 100 2 2024812688 52671984 16468848
17:27:11 15320 8192000000 10938927424 11378737152 1948011536 19.211 99 48 94 2 2026749728 62885296 15852896
17:28:44 15764 8192000000 10942088112 11385868288 1953185056 19.252 100 35 70 0 2032024176 66347168 12491952
17:30:08 13988 8192000000 10990464176 11407966208 1949098976 19.219 99 66 132 1 2046865328 74995168 22771184
17:31:25 47449 8192000000 10940581248 11410309120 1969598192 19.382 100 48 97 1 2051035712 65487776 15949744
17:34:04 27844 8192000000 10903263120 11375194112 1950524816 19.231 100 19 38 1 1995330128 38497904 6307408
17:35:40 25255 8192000000 10885704592 11343581184 1949512112 19.223 100 22 44 0 1975710784 19820480 6378192
17:37:06 107238 8192000000 10888141008 11336007680 1921736112 19.001 98 23 46 0 1941821664 13551840 6533712
17:41:22 51934 8192000000 10894477488 11349020672 1956014320 19.274 100 23 47 3 1980123440 17798128 6310992
17:43:14 133719 8192000000 10877336240 11331522560 1920932800 18.994 98 21 42 0 1947292736 19937568 6422368
17:46:28 78944 8192000000 10901530080 11361492992 1966293712 19.356 100 40 81 0 1990627488 12451808 11881968
17:48:52 173934 8192000000 10905712528 11337355264 1891367104 18.757 96 35 65 2 1908504400 6868304 10268992
17:52:49 35801 8192000000 10923866656 11377291264 1985385200 19.507 100 24 49 0 2009604144 17193664 7025280
17:54:26 17434 8192000000 10963208080 11403710464 2010009376 19.702 100 61 121 0 2052191552 24389440 17792736
17:55:52 43353 8192000000 10914051552 11400015872 1964164560 19.339 99 15 30 1 1998681648 29821040 4696048
17:57:37 87217 8192000000 10887944288 11370426368 1909853552 18.905 97 7 14 1 1935636272 23423088 2359632
18:00:04 57903 8192000000 10885291888 11358052352 1953616304 19.255 99 19 37 0 1972478768 13234464 5628000
18:02:08 68708 8192000000 10876985312 11360096256 1943933088 19.178 99 7 14 1 1962062352 16390992 1738272
18:04:17 45997 8192000000 10915851312 11361906688 1960926464 19.313 99 36 73 3 1981284816 9896880 10461472
18:06:05 53711 8192000000 10874178048 11338993664 1939254416 19.141 99 33 66 2 1960630736 12054112 9322208
18:08:06 61587 8192000000 10889676672 11347582976 1920458560 18.991 97 10 20 0 1934849184 11365088 3025536
18:10:09 101461 8192000000 10869851152 11330895872 1807204016 18.073 92 14 28 1 1830643424 18708688 4730720
18:12:54 38867 8192000000 10832124848 11285045248 1824426368 18.214 95 17 34 0 1840141984 11049632 4665984
18:14:40 69219 8192000000 10846894016 11333992448 1915797552 18.953 100 11 22 0 1943347408 24445632 3104224
18:16:50 109569 8192000000 10891413072 11342848000 1947954304 19.210 100 35 70 0 1972280112 14412528 9913280
18:19:56 39581 8192000000 10856283792 11308863488 1925348816 19.030 100 37 74 3 1948086352 12133328 10604208
18:21:44 70986 8192000000 10859701328 11326443520 1936559616 19.119 100 15 30 0 1955247488 14911248 3776624
18:23:56 78183 8192000000 10869077152 11334324224 1919433376 18.982 98 15 30 0 1932310416 8960816 3916224
18:26:15 22895 8192000000 10863231264 11327492096 1931384464 19.078 99 14 28 1 1946225312 9979152 4861696
18:27:44 81045 8192000000 10873130240 11328647168 1840824304 18.348 94 17 34 1 1859366352 14083088 4458960
18:30:06 48409 8192000000 10866355856 11331215360 1943030896 19.171 99 29 57 0 1966287584 15161072 8095616
18:31:58 33220 8192000000 10893993216 11350872064 1928415760 19.054 98 17 34 0 1944073024 10943104 4714160
18:34:14 63484 8192000000 10905232880 11365998592 1928851024 19.058 98 28 54 0 1955692848 19800608 7041216
18:36:22 90471 8192000000 10921788336 11373871104 1938174480 19.132 98 22 45 1 1958513376 14249088 6089808
18:38:53 63232 8192000000 10899152880 11382018048 1969744560 19.383 100 5 10 0 1989721184 18862592 1114032
18:41:14 131533 8192000000 10911404544 11381628928 1980351776 19.467 100 38 76 1 2007654720 15177600 12125344
18:44:26 4501 8192000000 10911045136 11380482048 1982161632 19.482 100 30 57 4 2003447824 13052048 8234144
18:45:32 53142 8192000000 10861273360 11335135232 1895617152 18.791 96 29 58 1 1917672880 13680192 8375536
18:47:25 12770 8192000000 10885062256 11330777088 1945999616 19.195 100 42 79 0 1966193968 9017968 11176384
18:48:39 43331 8192000000 10852175280 11311165440 1925186096 19.028 99 21 41 0 1936847024 5978720 5682208
18:50:33 44652 8192000000 10872415680 11340668928 1933416688 19.094 99 16 32 1 1949900528 11636992 4846848

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 12-08-2020 18:53:51 to 12-08-2020 18:54:05.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 150 v_add_invoice_to_stock 335544517
user_exc 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-08-2020 18:54:06 to 12-08-2020 18: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          221776
MON$OLDEST_ACTIVE               221777
MON$OLDEST_SNAPSHOT             221777
MON$NEXT_TRANSACTION            221777
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                       2069600
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'

12.08.20 18:54:09. 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-12 18:54:12 NO_SEVERE_ERRORS_FOUND
12.08.20 18:54:14. End of report.