Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.08.2020 12:52
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 16477800 kB
MemAvailable 30933976 kB
Buffers 0 kB
Cached 15158896 kB
SwapCached 5992 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-18 12:51:50.6160
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/49582
TRN_ID                          211816
ATT_ID                          477
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: 8818.60 26640 1058232
customer order: creation 154.97 446 18596
customer order: refuse 13.14 483 1577
order to supplier: creation 42.58 1649 5110
order to supplier: removal 3.40 137175 408
invoice (draft): creation 42.21 4285 5065
invoice (draft): removal 52.94 1017 6353
invoice accept: apply 38.33 21103 4599
invoice accept: cancel 47.18 1042 5662
customer reserve: creation 3512.04 206 421445
customer reserve: removal 4635.48 79 556257
realization accept: apply 74.09 216 8891
realization accept: cancel 85.21 139 10225
payment from customer: creation 54.19 128 6503
payment from customer: removal 4.97 103 597
payment to supplier: creation 44.77 140 5372
payment to supplier: removal 3.41 120 409
service: total inventory turnovers 3.36 5212 403
service: total monetary turnovers 5.58 926 670
service: aggregate perf. data 0.72 26857 86
service: refresh index statistics 0.03 331477 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-08-2020 12:52:31 to 18-08-2020 12:52:33.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2503
TEST_TIME 2 3332
TEST_TIME 3 3476
TEST_TIME 4 4069
TEST_TIME 5 4371
TEST_TIME 6 4534
TEST_TIME 7 4449
TEST_TIME 8 4624
TEST_TIME 9 4762
TEST_TIME 10 4499
TEST_TIME 11 4608
TEST_TIME 12 4856
TEST_TIME 13 4961
TEST_TIME 14 5170
TEST_TIME 15 5171
TEST_TIME 16 5344
TEST_TIME 17 5235
TEST_TIME 18 5317
TEST_TIME 19 5520
TEST_TIME 20 5973
TEST_TIME 21 6251
TEST_TIME 22 6367
TEST_TIME 23 6483
TEST_TIME 24 6928
TEST_TIME 25 7032
TEST_TIME 26 7401
TEST_TIME 27 7420
TEST_TIME 28 7561
TEST_TIME 29 7714
TEST_TIME 30 7681
TEST_TIME 31 8379
TEST_TIME 32 8261
TEST_TIME 33 8498
TEST_TIME 34 8389
TEST_TIME 35 8678
TEST_TIME 36 8848
TEST_TIME 37 9053
TEST_TIME 38 9409
TEST_TIME 39 9658
TEST_TIME 40 9648
TEST_TIME 41 9579
TEST_TIME 42 9474
TEST_TIME 43 9309
TEST_TIME 44 9181
TEST_TIME 45 9017
TEST_TIME 46 9032
TEST_TIME 47 9006
TEST_TIME 48 9002
TEST_TIME 49 8993
TEST_TIME 50 8928
TEST_TIME 51 8884
TEST_TIME 52 8961
TEST_TIME 53 8947
TEST_TIME 54 9064
TEST_TIME 55 9018
TEST_TIME 56 8955
TEST_TIME 57 8868
TEST_TIME 58 8860
TEST_TIME 59 8802
TEST_TIME 60 8793
TEST_TIME 61 8827
TEST_TIME 62 8836
TEST_TIME 63 8844
TEST_TIME 64 8789
TEST_TIME 65 8788
TEST_TIME 66 9041
TEST_TIME 67 9064
TEST_TIME 68 9038
TEST_TIME 69 9114
TEST_TIME 70 9150
TEST_TIME 71 9111
TEST_TIME 72 9178
TEST_TIME 73 9102
TEST_TIME 74 8983
TEST_TIME 75 8975
TEST_TIME 76 8994
TEST_TIME 77 8931
TEST_TIME 78 8880
TEST_TIME 79 8853
TEST_TIME 80 8843
TEST_TIME 81 8826
TEST_TIME 82 8842
TEST_TIME 83 8817
TEST_TIME 84 8794
TEST_TIME 85 8810
TEST_TIME 86 8886
TEST_TIME 87 8913
TEST_TIME 88 8910
TEST_TIME 89 8938
TEST_TIME 90 8988
TEST_TIME 91 9063
TEST_TIME 92 9036
TEST_TIME 93 8942
TEST_TIME 94 8909
TEST_TIME 95 8877
TEST_TIME 96 8867
TEST_TIME 97 8884
TEST_TIME 98 8842
TEST_TIME 99 8813
TEST_TIME 100 8817
TEST_TIME 101 8875
TEST_TIME 102 8884
TEST_TIME 103 8891
TEST_TIME 104 8917
TEST_TIME 105 8875
TEST_TIME 106 8897
TEST_TIME 107 8906
TEST_TIME 108 8970
TEST_TIME 109 8888
TEST_TIME 110 8819
TEST_TIME 111 8823
TEST_TIME 112 8801
TEST_TIME 113 8773
TEST_TIME 114 8740
TEST_TIME 115 8779
TEST_TIME 116 8750
TEST_TIME 117 8757
TEST_TIME 118 8739
TEST_TIME 119 8790
TEST_TIME 120 8822

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 18-08-2020 12:52:35 to 18-08-2020 12:52:38.

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 1448566 1448566 0 0.00 0 27113 8 0 0
doc_list_biud 1448564 1448564 0 0.00 0 55215 38 0 0
sp_add_invoice_to_stock 4701 4599 102 2.16 34 189363 21103 0 102
sp_cancel_adding_invoice 5662 5662 0 0.00 4 84408 1043 0 0
sp_cancel_client_order 1577 1577 0 0.00 4 10967 484 0 0
sp_cancel_customer_reserve 556257 556257 0 0.00 0 55217 79 0 0
sp_cancel_pay_from_customer 597 597 0 0.00 2 3869 104 0 0
sp_cancel_pay_to_supplier 409 409 0 0.00 3 6006 121 0 0
sp_cancel_supplier_invoice 6359 6353 6 0.09 1 45844 1017 0 6
sp_cancel_supplier_order 408 408 0 0.00 8 1228735 137175 0 0
sp_cancel_write_off 10225 10225 0 0.00 1 8985 139 0 0
sp_client_order 18596 18596 0 0.00 3 26294 447 0 0
sp_customer_reserve 421445 421445 0 0.00 0 124183 207 0 0
sp_fill_shopping_cart 28766 28766 0 0.00 1 27065 46 0 0
sp_fill_shopping_cart_clo_res 421442 421442 0 0.00 0 34680 20 0 0
sp_get_clo_for_invoice 4594 4594 0 0.00 0 61596 529 0 0
sp_kill_cost_storno 16893 16893 0 0.00 0 11262 87 0 0
sp_kill_qstorno_ret_qs2qd 1129500 1129500 0 0.00 0 55195 43 0 0
sp_kill_qty_storno 593964 593964 0 0.00 0 55214 91 0 0
sp_lock_selected_doc 18733 18733 0 0.00 0 10956 5 0 0
sp_make_cost_storno 25358 25358 0 0.00 0 20944 72 0 0
sp_multiply_rows_for_pdistr 25358 25358 0 0.00 0 9601 38 0 0
sp_multiply_rows_for_qdistr 18596 18596 0 0.00 0 20809 247 0 0
sp_pay_from_customer 6503 6503 0 0.00 3 16178 128 0 0
sp_pay_to_supplier 5372 5372 0 0.00 5 15628 141 0 0
sp_payment_common 11875 11875 0 0.00 1 16107 18 0 0
sp_qd_handle_on_cancel_clo 1577 1577 0 0.00 0 8372 445 0 0
sp_qd_handle_on_invoice_upd_sts 10253 10253 0 0.00 0 14292 432 0 0
sp_qd_handle_on_reserve_upd_sts 8891 8891 0 0.00 0 11096 59 0 0
sp_reserve_write_off 8891 8891 0 0.00 6 20966 216 0 0
sp_supplier_invoice 5065 5065 0 0.00 6 108008 4286 0 0
sp_supplier_order 5110 5110 0 0.00 4 111234 1649 0 0
srv_aggregate_perf_data 86 86 0 0.00 2488 924794 26857 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1382 149681 43174 0 0
srv_find_qd_qs_mism 1014828 1014828 0 0.00 0 27112 6 0 0
srv_make_invnt_saldo 403 403 0 0.00 47 147106 5212 0 0
srv_make_money_saldo 670 670 0 0.00 1 20724 927 0 0
srv_recalc_idx_stat 4 4 0 0.00 124081 637509 331478 0 0
t$perf-abend:sp_add_invoice_to_stock 102 102 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 4599 4599 0 0.00 0 17729 244 0 0
t$perf-norm:sp_cancel_adding_invoice 377 377 0 0.00 0 13351 159 0 0
t$perf-norm:sp_cancel_client_order 1577 1577 0 0.00 0 2196 13 0 0
t$perf-norm:sp_cancel_customer_reserve 619 619 0 0.00 0 1899 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 597 597 0 0.00 0 1236 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 409 409 0 0.00 0 344 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 364 364 0 0.00 0 420 9 0 0
t$perf-norm:sp_cancel_supplier_order 408 408 0 0.00 0 30105 2630 0 0
t$perf-norm:sp_cancel_write_off 900 900 0 0.00 0 1943 10 0 0
t$perf-norm:sp_client_order 18596 18596 0 0.00 0 7605 24 0 0
t$perf-norm:sp_customer_reserve 887 887 0 0.00 0 1197 10 0 0
t$perf-norm:sp_pay_from_customer 6503 6503 0 0.00 0 5933 10 0 0
t$perf-norm:sp_pay_to_supplier 5372 5372 0 0.00 0 6807 10 0 0
t$perf-norm:sp_reserve_write_off 8891 8891 0 0.00 0 12859 15 0 0
t$perf-norm:sp_supplier_invoice 5065 5065 0 0.00 0 10380 25 0 0
t$perf-norm:sp_supplier_order 5110 5110 0 0.00 0 4524 24 0 0
t$perf-norm:srv_aggregate_perf_data 86 86 0 0.00 0 361 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 245 5 0 0
t$perf-norm:srv_make_invnt_saldo 403 403 0 0.00 0 480 5 0 0
t$perf-norm:srv_make_money_saldo 670 670 0 0.00 0 931 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 9387 9182 205 2.18 0 5331 17 0 205
v_all_customers 34572 34572 0 0.00 0 53 1 0 0
v_all_suppliers 28496 28496 0 0.00 0 70 1 0 0
v_all_wares 37192 37192 0 0.00 0 68 1 0 0
v_cancel_adding_invoice 754 754 0 0.00 0 611 8 0 0
v_cancel_client_order 3154 3154 0 0.00 0 5332 8 0 0
v_cancel_customer_prepayment 1194 1194 0 0.00 0 1771 10 0 0
v_cancel_customer_reserve 1238 1238 0 0.00 0 1770 5 0 0
v_cancel_payment_to_supplier 818 818 0 0.00 0 810 8 0 0
v_cancel_supplier_invoice 740 728 12 1.62 0 242 11 0 12
v_cancel_supplier_order 816 816 0 0.00 0 2945 19 0 0
v_cancel_write_off 1800 1800 0 0.00 0 4057 21 0 0
v_max_id_clo_ord 5110 5110 0 0.00 0 857 2 0 0
v_max_id_clo_res 887 887 0 0.00 0 3840 17 0 0
v_max_id_ord_sup 5060 5060 0 0.00 0 5969 4 0 0
v_max_non_paid_invoice 1294 1294 0 0.00 0 302 2 0 0
v_max_non_paid_realizn 3117 3117 0 0.00 0 10028 6 0 0
v_min_id_clo_ord 5110 5110 0 0.00 0 3701 7 0 0
v_min_id_clo_res 887 887 0 0.00 0 9947 14 0 0
v_min_id_ord_sup 5060 5060 0 0.00 0 5895 9 0 0
v_min_non_paid_invoice 5372 5372 0 0.00 0 11518 10 0 0
v_min_non_paid_realizn 6503 6503 0 0.00 0 6075 12 0 0
v_our_firm 9392 9392 0 0.00 0 16 1 0 0
v_reserve_write_off 17782 17782 0 0.00 0 5819 6 0 0
x_lock_depdocs_on_canc_invoice 11651 11651 0 0.00 0 42105 136 0 0
x_lock_depdocs_on_canc_sup_ord 408 408 0 0.00 0 14938 553 0 0
x_make_qstorno_1000_1200 5110 5110 0 0.00 2 111224 1556 0 0
x_make_qstorno_1200_2000 5065 5065 0 0.00 2 107815 4111 0 0
x_make_qstorno_2100_3300 421445 421445 0 0.00 0 124182 184 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-08-2020 12:52:39 to 18-08-2020 12:52:43.

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
10:21:54 4 8192000000 8796612016 8807043072 3144832 0.038 1 1 2 0 3242128 61344 35952
10:22:54 32790 8192000000 9722692736 10015297536 799155088 8.888 100 92 183 0 829108576 7539792 22413696
10:24:36 16470 8192000000 9759739312 10060042240 828115664 9.180 100 90 180 1 865801296 16747600 20938032
10:25:58 21661 8192000000 9783393136 10090270720 854111712 9.441 100 82 164 0 890295120 17099472 19083936
10:27:25 16380 8192000000 9837190928 10161315840 897152368 9.870 100 76 152 0 954320496 40228176 16939952
10:28:46 19947 8192000000 9872414736 10206138368 933024288 10.224 100 69 137 0 985255520 37891088 14340144
10:30:10 11085 8192000000 9949165888 10293567488 973747232 10.623 100 78 156 1 1048513104 58475696 16290176
10:31:33 8199 8192000000 9969351168 10328641536 1000277936 10.881 100 71 140 0 1093964944 79734432 13952576
10:32:46 3557 8192000000 10006471680 10362888192 1024625488 11.117 100 84 168 2 1105747984 63869888 17252608
10:33:52 1506 8192000000 10012561648 10373804032 1040790816 11.272 100 91 183 0 1123098592 63361456 18946320
10:34:58 1283 8192000000 10036797712 10403647488 1056501728 11.423 100 90 180 2 1152092624 76828368 18762528
10:36:03 12320 8192000000 9999454608 10373730304 1059687680 11.453 100 66 132 0 1125842544 51732320 14422544
10:37:22 11498 8192000000 9988347248 10384855040 1061161696 11.468 100 48 94 1 1130894800 59528288 10204816
10:38:39 5831 8192000000 9992047632 10385723392 1070903776 11.561 100 48 96 4 1120430304 38908192 10618336
10:39:45 14022 8192000000 9983901776 10386321408 1075736640 11.607 100 22 45 0 1108520272 28316112 4467520
10:41:01 67580 8192000000 9989210480 10388381696 1081224752 11.659 100 25 50 0 1104542720 17955840 5362128
10:43:09 6586 8192000000 9994181008 10388041728 1084712032 11.692 100 39 79 0 1118486352 24876896 8897424
10:44:20 5303 8192000000 9987370144 10387910656 1069240432 11.545 99 39 77 3 1094334384 16820288 8273664
10:45:25 3616 8192000000 9985700592 10372182016 1023263856 11.104 98 34 68 1 1078477760 48326992 6886912
10:46:31 31240 8192000000 9937822608 10334400512 930412640 10.199 88 17 34 1 949017952 15169952 3435360
10:48:03 9731 8192000000 9919351248 10310868992 975788032 10.643 95 31 63 0 998450384 15279760 7382592
10:49:14 7381 8192000000 9922738544 10306916352 998527040 10.864 98 51 102 0 1033462704 22372368 12563296
10:50:24 54729 8192000000 9927793504 10315599872 1027776880 11.147 99 16 32 2 1055620016 24075600 3767536
10:52:34 14389 8192000000 9997564272 10385211392 1068681664 11.539 100 46 92 0 1109989040 32191760 9115616
10:53:58 55901 8192000000 10070063984 10460516352 1090690912 11.749 100 47 94 4 1180172640 79056656 10425072
10:56:10 18021 8192000000 10085346608 10487771136 1099985168 11.837 100 43 86 2 1193531136 83690736 9855232
10:57:32 17171 8192000000 10124132448 10528489472 1114819248 11.978 100 46 92 2 1244017088 118559360 10638480
10:58:57 27628 8192000000 10160755280 10568933376 1121258720 12.039 100 48 96 2 1264331728 132201680 10871328
11:00:41 17853 8192000000 10155143792 10569678848 1134027024 12.159 100 37 74 0 1272985888 130701840 8257024
11:02:05 7664 8192000000 10229142528 10625835008 1145596624 12.268 100 66 132 0 1326815456 167166416 14052416
11:03:18 4497 8192000000 10233576992 10642923520 1155749840 12.363 100 59 113 2 1336617072 168018096 12849136
11:04:29 4860 8192000000 10263497488 10666119168 1163135568 12.433 100 67 132 0 1360827808 183119152 14573088
11:05:41 1947 8192000000 10315036816 10717298688 1173980352 12.534 100 80 159 1 1414991152 222817952 18192848
11:06:46 13926 8192000000 10305705008 10739671040 1171487264 12.511 100 56 112 1 1406236672 221472784 13276624
11:08:07 8962 8192000000 10346315776 10759938048 1178573280 12.577 100 53 105 1 1453806208 263024528 12208400
11:09:25 10013 8192000000 10379711984 10812084224 1177147280 12.564 100 60 120 2 1487376256 295647264 14581712
11:10:43 2525 8192000000 10404877792 10811908096 1182006096 12.609 100 81 160 2 1502402336 301673376 18722864
11:11:52 3547 8192000000 10377424544 10809606144 1175032992 12.544 100 82 164 1 1475004768 280749392 19222384
11:13:07 7918 8192000000 10393838192 10815483904 1184386432 12.631 100 76 152 3 1489708752 288601664 16720656
11:14:23 7192 8192000000 10394544224 10805547008 1165382816 12.454 99 75 150 2 1478149296 294909648 17856832
11:15:39 2780 8192000000 10362640880 10765148160 1160074192 12.404 100 88 171 1 1451454816 272001024 19379600
11:16:49 4462 8192000000 10385362112 10798837760 1170626688 12.503 100 87 176 0 1487332640 297563872 19142080
11:18:00 14614 8192000000 10355763568 10790674432 1157098368 12.376 100 32 64 2 1461328224 295942368 8287488
11:19:36 5885 8192000000 10357466832 10800349184 1146245376 12.274 99 41 82 3 1446551744 290263488 10042880
11:20:55 5190 8192000000 10362387424 10774032384 1153560336 12.343 100 79 158 3 1449025184 275566832 19898016
11:22:12 1381 8192000000 10404731456 10815803392 1162687088 12.428 100 93 186 1 1511167552 326513072 21967392
11:23:17 4950 8192000000 10300527408 10726088704 1155997872 12.366 100 37 74 1 1393688448 228205328 9485248
11:24:44 16863 8192000000 10310008416 10772668416 1138720336 12.203 99 22 44 1 1404668560 260375424 5572800
11:26:05 24137 8192000000 10289647968 10743791616 1119072720 12.018 99 25 50 1 1378264208 252812000 6379488
11:28:07 30645 8192000000 10190103856 10666164224 1131689392 12.137 100 21 41 4 1319974720 183567440 4717888
11:29:42 39446 8192000000 10118091568 10654089216 1131239856 12.133 100 16 32 2 1226114592 91086928 3787808
11:31:24 53813 8192000000 10072947456 10522419200 1124689136 12.071 99 29 57 1 1158304112 27106528 6508448
11:33:21 117769 8192000000 10090748848 10536636416 1099812976 11.836 97 14 28 1 1161392544 58159264 3420304
11:36:24 101469 8192000000 10057141232 10502332416 1074848864 11.598 95 27 54 0 1138578544 57467776 6261904
11:39:08 31590 8192000000 10044263488 10480922624 1075038272 11.600 96 22 44 0 1119957264 40398848 4520144
11:40:44 24190 8192000000 10121847056 10536800256 1108654352 11.920 99 53 106 1 1180037136 59702400 11680384
11:42:09 43659 8192000000 10152516992 10590199808 1111956288 11.951 99 34 66 1 1240501248 121628016 6916944
11:43:55 134799 8192000000 10079053376 10551316480 1097536112 11.814 97 18 36 1 1185477008 84162928 3777968
11:48:06 85443 8192000000 10052971824 10538164224 1128062112 12.103 100 17 34 2 1173688864 42266816 3359936
11:50:32 80088 8192000000 10074168992 10541146112 1110420256 11.936 98 14 28 2 1146970160 33559024 2990880
11:53:03 30136 8192000000 10065431792 10509336576 1119471376 12.022 99 17 34 0 1161587056 38729120 3386560
11:54:35 28676 8192000000 10065067024 10496167936 1129174576 12.114 99 28 56 2 1175564256 40040544 6349136
11:56:06 57151 8192000000 10198502688 10680811520 1151358224 12.322 100 20 38 1 1279819232 123713296 4747712
11:58:10 67899 8192000000 10088044080 10525450240 1120819408 12.035 98 21 42 3 1167498096 42030688 4648000
12:00:29 89756 8192000000 10087772368 10564300800 1107181600 11.906 97 12 24 1 1175598720 66079760 2337360
12:03:00 47144 8192000000 10085448480 10577448960 1122090000 12.047 99 20 40 0 1188711984 62887200 3734784
12:04:50 149681 8192000000 10092135920 10581024768 1121540608 12.042 99 23 46 0 1192611904 66271360 4799936
12:08:23 66516 8192000000 10040196080 10509746176 1104221104 11.878 98 15 30 0 1133449056 26158800 3069152
12:10:37 79212 8192000000 10032015904 10474467328 1069167344 11.544 95 14 28 0 1103829840 31979568 2682928
12:12:58 44099 8192000000 10039052624 10483404800 1105788064 11.893 99 22 45 3 1137301952 26883808 4630080
12:14:45 56795 8192000000 10113713856 10561708032 1111715568 11.949 98 21 42 1 1177412416 61379568 4317280
12:16:53 80840 8192000000 10056025328 10556669952 1067649696 11.530 95 9 18 1 1150564432 80921584 1993152
12:19:15 67517 8192000000 10104158624 10575585280 1096843728 11.808 98 31 62 4 1172939680 69787248 6308704
12:21:36 65538 8192000000 10102951408 10587693056 1109840816 11.931 98 19 38 1 1191338384 77758832 3738736
12:23:42 119644 8192000000 10092384544 10573279232 1119601776 12.023 99 26 52 3 1204917904 80033504 5282624
12:27:03 41459 8192000000 10066828912 10551754752 1104687984 11.882 98 7 14 0 1152893712 46506848 1698880
12:28:47 71582 8192000000 10073986128 10495459328 1089876544 11.741 97 21 42 0 1119307280 24967216 4463520
12:31:05 66524 8192000000 10110593200 10556903424 1114902704 11.979 98 19 38 1 1207388016 88227920 4257392
12:33:12 57572 8192000000 10052031408 10469736448 1068570016 11.538 96 27 52 2 1120154592 45437280 6147296
12:35:11 48113 8192000000 10048904000 10518810624 1069361696 11.546 97 29 58 1 1128904016 53929360 5612960
12:37:04 27050 8192000000 10068914704 10511216640 1097584528 11.815 100 37 74 1 1153616384 48639824 7392032
12:38:37 21923 8192000000 10114570672 10544316416 1104921872 11.884 100 41 83 5 1169093392 55956208 8215312
12:40:04 77010 8192000000 10053219344 10501627904 1112829184 11.959 100 6 12 0 1170588656 56708208 1051264
12:43:00 45881 8192000000 10037361728 10532638720 1113486624 11.965 100 4 8 1 1143407488 29217872 702992
12:45:00 45779 8192000000 10078458064 10532782080 1123916864 12.064 100 11 22 1 1176054128 49759856 2377408
12:46:56 102886 8192000000 10095522816 10539651072 1121694800 12.043 99 13 26 1 1176810000 52396064 2719136
12:49:58 59499 8192000000 10087133872 10526621696 1094804928 11.788 97 9 18 0 1157414544 60804496 1805120

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 18-08-2020 12:52:44 to 18-08-2020 12:52:58.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 102 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 18-08-2020 12:52:59 to 18-08-2020 12:53:01.

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          210488
MON$OLDEST_ACTIVE               212015
MON$OLDEST_SNAPSHOT             212015
MON$NEXT_TRANSACTION            212015
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                       2078432
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             669
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2164 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2164 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2164 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.2164 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 ---
:11: 
:12: mscs-test-161.budzdorov.ru    Tue Aug 18 12:51:53 2020
:13:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:14: 
:15: 
:16: mscs-test-161.budzdorov.ru    Tue Aug 18 12:51:55 2020
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:18: 
:19: 
:20: mscs-test-161.budzdorov.ru    Tue Aug 18 12:51:55 2020
:21:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:22: 
--- end of diff output ---

Final processing ISQL logs in /var/tmp/logs.oltp40 according to config parameter 'remove_isql_logs'

18.08.20 12:53:03. 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-18 12:53:05 NO_SEVERE_ERRORS_FOUND
18.08.20 12:53:07. End of report.