Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.08.2020 12: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 1800.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 16700588 kB
MemAvailable 30984020 kB
Buffers 0 kB
Cached 14993884 kB
SwapCached 6000 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-17 12:51:51.4690
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(141:26) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(31:12) ==> SP_HALT_ON_ERROR(46:13)
IP                              ::1/35002
TRN_ID                          214069
ATT_ID                          487
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 8275.90 24258 993108
customer order: creation 159.00 435 19080
customer order: refuse 13.85 502 1662
order to supplier: creation 42.12 1612 5054
order to supplier: removal 3.20 130767 384
invoice (draft): creation 42.43 4838 5091
invoice (draft): removal 46.62 1114 5594
invoice accept: apply 39.73 23332 4767
invoice accept: cancel 41.33 1242 4960
customer reserve: creation 3586.01 231 430321
customer reserve: removal 4042.51 80 485101
realization accept: apply 77.19 219 9263
realization accept: cancel 63.53 162 7623
payment from customer: creation 56.08 125 6730
payment from customer: removal 5.05 110 606
payment to supplier: creation 44.37 144 5324
payment to supplier: removal 3.15 102 378
service: total inventory turnovers 3.10 5801 372
service: total monetary turnovers 5.73 1006 687
service: aggregate perf. data 0.89 15777 107
service: refresh index statistics 0.03 297556 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2932
TEST_TIME 2 4097
TEST_TIME 3 4862
TEST_TIME 4 5448
TEST_TIME 5 4957
TEST_TIME 6 4886
TEST_TIME 7 4873
TEST_TIME 8 4991
TEST_TIME 9 4980
TEST_TIME 10 5104
TEST_TIME 11 5486
TEST_TIME 12 5376
TEST_TIME 13 5449
TEST_TIME 14 5561
TEST_TIME 15 5467
TEST_TIME 16 5614
TEST_TIME 17 5820
TEST_TIME 18 5946
TEST_TIME 19 6003
TEST_TIME 20 6003
TEST_TIME 21 5951
TEST_TIME 22 6358
TEST_TIME 23 6631
TEST_TIME 24 6719
TEST_TIME 25 7052
TEST_TIME 26 7133
TEST_TIME 27 7362
TEST_TIME 28 7618
TEST_TIME 29 7561
TEST_TIME 30 7993
TEST_TIME 31 8082
TEST_TIME 32 8214
TEST_TIME 33 8166
TEST_TIME 34 8576
TEST_TIME 35 8709
TEST_TIME 36 9266
TEST_TIME 37 9167
TEST_TIME 38 8998
TEST_TIME 39 8816
TEST_TIME 40 8774
TEST_TIME 41 8963
TEST_TIME 42 8963
TEST_TIME 43 8816
TEST_TIME 44 8787
TEST_TIME 45 8929
TEST_TIME 46 8924
TEST_TIME 47 8902
TEST_TIME 48 8922
TEST_TIME 49 8829
TEST_TIME 50 8782
TEST_TIME 51 8643
TEST_TIME 52 8588
TEST_TIME 53 8442
TEST_TIME 54 8396
TEST_TIME 55 8411
TEST_TIME 56 8424
TEST_TIME 57 8371
TEST_TIME 58 8380
TEST_TIME 59 8341
TEST_TIME 60 8328
TEST_TIME 61 8249
TEST_TIME 62 8163
TEST_TIME 63 8122
TEST_TIME 64 8175
TEST_TIME 65 8131
TEST_TIME 66 8198
TEST_TIME 67 8251
TEST_TIME 68 8196
TEST_TIME 69 8280
TEST_TIME 70 8257
TEST_TIME 71 8399
TEST_TIME 72 8418
TEST_TIME 73 8434
TEST_TIME 74 8489
TEST_TIME 75 8482
TEST_TIME 76 8511
TEST_TIME 77 8535
TEST_TIME 78 8565
TEST_TIME 79 8466
TEST_TIME 80 8400
TEST_TIME 81 8384
TEST_TIME 82 8343
TEST_TIME 83 8270
TEST_TIME 84 8212
TEST_TIME 85 8133
TEST_TIME 86 8183
TEST_TIME 87 8211
TEST_TIME 88 8193
TEST_TIME 89 8215
TEST_TIME 90 8258
TEST_TIME 91 8246
TEST_TIME 92 8280
TEST_TIME 93 8284
TEST_TIME 94 8307
TEST_TIME 95 8328
TEST_TIME 96 8331
TEST_TIME 97 8378
TEST_TIME 98 8370
TEST_TIME 99 8392
TEST_TIME 100 8365
TEST_TIME 101 8323
TEST_TIME 102 8279
TEST_TIME 103 8243
TEST_TIME 104 8211
TEST_TIME 105 8197
TEST_TIME 106 8219
TEST_TIME 107 8244
TEST_TIME 108 8214
TEST_TIME 109 8220
TEST_TIME 110 8269
TEST_TIME 111 8227
TEST_TIME 112 8301
TEST_TIME 113 8367
TEST_TIME 114 8359
TEST_TIME 115 8366
TEST_TIME 116 8411
TEST_TIME 117 8381
TEST_TIME 118 8319
TEST_TIME 119 8298
TEST_TIME 120 8282

Performance per minute, chart


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

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 1389699 1389699 0 0.00 0 21200 9 0 0
doc_list_biud 1389699 1389699 0 0.00 0 63286 36 0 0
sp_add_invoice_to_stock 4875 4767 108 2.21 18 218751 23332 0 108
sp_cancel_adding_invoice 4960 4960 0 0.00 4 80866 1243 0 0
sp_cancel_client_order 1662 1662 0 0.00 4 14254 503 0 0
sp_cancel_customer_reserve 485101 485101 0 0.00 0 36956 81 0 0
sp_cancel_pay_from_customer 606 606 0 0.00 3 3217 111 0 0
sp_cancel_pay_to_supplier 378 378 0 0.00 3 1402 103 0 0
sp_cancel_supplier_invoice 5601 5594 7 0.12 1 63291 1115 0 7
sp_cancel_supplier_order 384 384 0 0.00 14 1342512 130767 0 0
sp_cancel_write_off 7623 7623 0 0.00 1 14595 163 0 0
sp_client_order 19080 19080 0 0.00 3 17289 436 0 0
sp_customer_reserve 430321 430321 0 0.00 0 45085 231 0 0
sp_fill_shopping_cart 29216 29216 0 0.00 1 24425 53 0 0
sp_fill_shopping_cart_clo_res 430311 430311 0 0.00 0 30097 20 0 0
sp_get_clo_for_invoice 4757 4757 0 0.00 0 47800 482 0 0
sp_kill_cost_storno 13567 13567 0 0.00 0 14592 101 0 0
sp_kill_qstorno_ret_qs2qd 983803 983803 0 0.00 0 63285 44 0 0
sp_kill_qty_storno 519344 519344 0 0.00 0 63285 96 0 0
sp_lock_selected_doc 19446 19446 0 0.00 0 9221 4 0 0
sp_make_cost_storno 26074 26074 0 0.00 0 21192 74 0 0
sp_multiply_rows_for_pdistr 26074 26074 0 0.00 0 10414 38 0 0
sp_multiply_rows_for_qdistr 19080 19080 0 0.00 0 17183 226 0 0
sp_pay_from_customer 6730 6730 0 0.00 3 17366 126 0 0
sp_pay_to_supplier 5324 5324 0 0.00 4 18353 144 0 0
sp_payment_common 12054 12054 0 0.00 1 9205 20 0 0
sp_qd_handle_on_cancel_clo 1662 1662 0 0.00 0 14249 470 0 0
sp_qd_handle_on_invoice_upd_sts 9717 9717 0 0.00 0 48186 496 0 0
sp_qd_handle_on_reserve_upd_sts 9263 9263 0 0.00 0 10236 61 0 0
sp_reserve_write_off 9263 9263 0 0.00 6 21213 219 0 0
sp_supplier_invoice 5091 5091 0 0.00 5 137840 4839 0 0
sp_supplier_order 5054 5054 0 0.00 5 58586 1612 0 0
srv_aggregate_perf_data 107 107 0 0.00 2732 136131 15778 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 800 142463 40165 0 0
srv_find_qd_qs_mism 948005 948005 0 0.00 0 16732 7 0 0
srv_make_invnt_saldo 372 372 0 0.00 48 130956 5801 0 0
srv_make_money_saldo 687 687 0 0.00 1 16035 1006 0 0
srv_recalc_idx_stat 4 4 0 0.00 74981 439560 297556 0 0
t$perf-abend:sp_add_invoice_to_stock 108 108 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4767 4767 0 0.00 0 13835 268 0 0
t$perf-norm:sp_cancel_adding_invoice 397 397 0 0.00 0 5162 190 0 0
t$perf-norm:sp_cancel_client_order 1662 1662 0 0.00 0 1011 16 0 0
t$perf-norm:sp_cancel_customer_reserve 669 669 0 0.00 0 1287 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 606 606 0 0.00 0 1488 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 378 378 0 0.00 0 1055 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 419 419 0 0.00 0 2044 25 0 0
t$perf-norm:sp_cancel_supplier_order 384 384 0 0.00 0 44064 2308 0 0
t$perf-norm:sp_cancel_write_off 912 912 0 0.00 0 1244 9 0 0
t$perf-norm:sp_client_order 19080 19080 0 0.00 0 5576 26 0 0
t$perf-norm:sp_customer_reserve 945 945 0 0.00 0 772 6 0 0
t$perf-norm:sp_pay_from_customer 6730 6730 0 0.00 0 4883 14 0 0
t$perf-norm:sp_pay_to_supplier 5324 5324 0 0.00 0 3011 11 0 0
t$perf-norm:sp_reserve_write_off 9263 9263 0 0.00 0 6267 14 0 0
t$perf-norm:sp_supplier_invoice 5091 5091 0 0.00 0 5638 32 0 0
t$perf-norm:sp_supplier_order 5054 5054 0 0.00 0 12122 29 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 2256 49 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 381 10 0 0
t$perf-norm:srv_make_invnt_saldo 372 372 0 0.00 0 2641 17 0 0
t$perf-norm:srv_make_money_saldo 687 687 0 0.00 0 3722 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 4 0 0
v_add_invoice_to_stock 9730 9514 216 2.21 0 5609 16 0 216
v_all_customers 35220 35220 0 0.00 0 128 1 0 0
v_all_suppliers 27858 27858 0 0.00 0 96 1 0 0
v_all_wares 38160 38160 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 794 794 0 0.00 0 74 7 0 0
v_cancel_client_order 3324 3324 0 0.00 0 2016 7 0 0
v_cancel_customer_prepayment 1212 1212 0 0.00 0 1872 8 0 0
v_cancel_customer_reserve 1338 1338 0 0.00 0 1283 5 0 0
v_cancel_payment_to_supplier 756 756 0 0.00 0 766 6 0 0
v_cancel_supplier_invoice 852 838 14 1.64 0 1310 16 0 14
v_cancel_supplier_order 768 768 0 0.00 0 640 6 0 0
v_cancel_write_off 1824 1824 0 0.00 0 2626 17 0 0
v_max_id_clo_ord 5049 5049 0 0.00 0 822 2 0 0
v_max_id_clo_res 945 945 0 0.00 0 3049 12 0 0
v_max_id_ord_sup 5086 5086 0 0.00 0 6684 5 0 0
v_max_non_paid_invoice 1530 1530 0 0.00 0 728 3 0 0
v_max_non_paid_realizn 3444 3444 0 0.00 0 988 3 0 0
v_min_id_clo_ord 5049 5049 0 0.00 0 14029 10 0 0
v_min_id_clo_res 945 945 0 0.00 0 7870 20 0 0
v_min_id_ord_sup 5086 5086 0 0.00 0 5131 9 0 0
v_min_non_paid_invoice 5324 5324 0 0.00 0 9033 13 0 0
v_min_non_paid_realizn 6730 6730 0 0.00 0 4650 11 0 0
v_our_firm 9512 9512 0 0.00 0 16 1 0 0
v_reserve_write_off 18524 18524 0 0.00 0 1965 6 0 0
x_lock_depdocs_on_canc_invoice 10135 10135 0 0.00 0 22350 171 0 0
x_lock_depdocs_on_canc_sup_ord 384 384 0 0.00 0 19821 517 0 0
x_make_qstorno_1000_1200 5054 5054 0 0.00 2 58571 1496 0 0
x_make_qstorno_1200_2000 5091 5091 0 0.00 3 137742 4638 0 0
x_make_qstorno_2100_3300 430321 430321 0 0.00 0 36000 207 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-08-2020 12:54:10 to 17-08-2020 12:54:14.

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 5 8192000000 8796612016 8807043072 3144832 0.038 1 1 2 0 3242128 61344 35952
10:22:55 31471 8192000000 9723019584 10018865152 802889040 8.926 100 89 178 1 832753168 7134912 22729216
10:24:31 25365 8192000000 9759730048 10060681216 831853840 9.218 100 91 180 1 865270208 11826256 21590112
10:26:04 20291 8192000000 9786467504 10095960064 853244608 9.433 100 88 176 0 891739088 17889856 20604624
10:27:31 20794 8192000000 9842197840 10165538816 891005744 9.809 100 74 148 2 946620016 39418352 16195920
10:29:00 14947 8192000000 9896879968 10238545920 938685472 10.280 100 81 160 2 1011328160 55745984 16896704
10:30:19 9572 8192000000 9929169472 10273054720 966976928 10.557 100 85 168 0 1040253024 56016672 17259424
10:31:31 5134 8192000000 9968305984 10321432576 992120448 10.802 100 84 167 0 1089642112 80269632 17252032
10:32:40 4169 8192000000 9968267840 10328522752 1015271136 11.026 100 75 150 1 1087911056 57548832 15091088
10:33:46 3503 8192000000 10001512112 10366132224 1038617648 11.251 100 91 181 1 1120740864 62337424 19785792
10:34:52 1777 8192000000 10025547344 10392829952 1052969168 11.389 100 89 177 1 1147867584 77010784 17887632
10:35:56 10936 8192000000 9995233040 10370236416 1052595360 11.386 100 68 135 2 1109765488 43756320 13413808
10:37:09 6570 8192000000 9973560192 10356752384 1060020160 11.457 100 52 104 0 1088682416 17274512 11387744
10:38:17 8338 8192000000 9978090480 10366177280 1064635456 11.501 100 35 70 5 1101134496 29567120 6931920
10:39:29 5259 8192000000 9984260192 10374205440 1068990400 11.542 100 44 86 5 1105626208 28060160 8575648
10:40:37 60819 8192000000 9999697392 10407657472 1078469072 11.633 100 30 60 3 1107576480 21857984 7249424
10:42:39 6280 8192000000 9998919232 10410323968 1085294304 11.698 100 45 88 2 1138358544 43454608 9609632
10:43:46 7523 8192000000 9997966368 10398863360 1086394208 11.708 100 23 46 0 1129004400 37552192 5058000
10:44:57 7074 8192000000 10002762896 10387570688 1080095536 11.648 100 37 72 0 1101859088 13234448 8529104
10:46:07 34425 8192000000 9972998928 10377404416 1015755152 11.031 94 32 64 1 1040714800 17019280 7940368
10:47:43 4803 8192000000 9943503872 10312802304 1016876704 11.042 99 55 110 0 1038426256 9468608 12080944
10:48:50 7192 8192000000 9930077104 10306949120 1013434672 11.009 100 30 60 0 1049780912 29465408 6880832
10:49:58 45799 8192000000 9949214432 10316197888 1027943904 11.149 100 34 68 3 1058848240 24143280 6761056
10:51:47 21357 8192000000 9977107248 10358640640 1042729088 11.291 99 40 78 2 1076674992 25244496 8701408
10:53:19 2940 8192000000 10024153344 10401189888 1070108928 11.553 100 78 155 2 1125330912 38025696 17196288
10:54:24 28603 8192000000 10067082656 10455379968 1073932768 11.590 99 61 121 2 1169441600 83294016 12214816
10:56:00 51257 8192000000 10097157856 10490400768 1103586912 11.872 100 53 105 3 1212034064 97538336 10908816
10:57:58 43424 8192000000 10090789728 10497994752 1110784880 11.940 100 29 58 3 1220863312 103564064 6514368
10:59:54 11310 8192000000 10146659600 10538135552 1125819184 12.082 100 67 134 2 1253692000 113523472 14349344
11:01:13 8893 8192000000 10186247536 10576789504 1138976864 12.206 100 70 139 1 1285732448 132114704 14640880
11:02:31 5754 8192000000 10183656912 10582261760 1143947920 12.253 100 59 118 1 1293211808 135922176 13341712
11:03:44 10022 8192000000 10196926256 10594066432 1147709680 12.288 100 60 118 3 1296533968 135503968 13320320
11:05:04 3113 8192000000 10243987952 10633523200 1159762272 12.401 100 79 156 0 1338606400 161849136 16994992
11:06:14 4728 8192000000 10269891632 10668179456 1165817744 12.458 100 67 130 1 1365954976 185832016 14305216
11:07:26 2155 8192000000 10306970304 10711986176 1163895792 12.440 100 72 142 1 1410625616 230665136 16064688
11:08:35 7057 8192000000 10304580256 10718371840 1169952544 12.496 100 66 132 0 1399819424 214606832 15260048
11:09:47 5753 8192000000 10305888016 10707210240 1164716800 12.447 99 77 152 3 1390654752 208939152 16998800
11:10:58 4517 8192000000 10342071024 10756468736 1176263024 12.555 100 66 132 2 1441723120 250322720 15137376
11:12:08 3336 8192000000 10360171312 10767446016 1176961952 12.562 100 79 159 2 1460636688 265112272 18562464
11:13:18 3240 8192000000 10323044624 10746519552 1160104656 12.404 99 77 155 2 1415781280 238328160 17348464
11:14:28 3347 8192000000 10297514256 10730954752 1161679056 12.419 100 61 120 1 1397449424 221854064 13916304
11:15:39 3317 8192000000 10288052032 10726379520 1147265616 12.284 100 79 158 3 1393419872 227628272 18525984
11:16:50 2384 8192000000 10351065952 10746630144 1158433216 12.389 100 85 168 1 1447976960 270987248 18556496
11:18:01 1177 8192000000 10348192528 10744655872 1137258400 12.190 99 83 163 0 1441597440 284615792 19723248
11:19:10 7707 8192000000 10315419936 10734678016 1124916656 12.073 100 44 88 3 1414880000 278901504 11061840
11:20:25 3445 8192000000 10311337136 10707492864 1129933600 12.121 100 82 161 0 1401158752 252664928 18560224
11:21:36 797 8192000000 10310170256 10730045440 1126782560 12.091 100 87 176 1 1400504512 253929792 19792160
11:22:45 6090 8192000000 10265808800 10679422976 1136805936 12.185 100 43 86 0 1362209440 215524896 9878608
11:23:57 10961 8192000000 10258637152 10685071360 1134887120 12.167 100 44 88 0 1359602736 214650624 10064992
11:25:12 10788 8192000000 10261672832 10692833280 1139565536 12.211 100 29 58 1 1362998704 216685344 6747824
11:26:29 16894 8192000000 10278336464 10712920064 1147362512 12.285 100 39 78 0 1383325744 227339344 8623888
11:27:49 31090 8192000000 10128896672 10541715456 1141695248 12.231 99 42 83 4 1241315360 90610032 9010080
11:29:29 60846 8192000000 10164747744 10583420928 1141095168 12.226 100 60 115 4 1256294064 99957760 15241136
11:31:33 10876 8192000000 10156136912 10578362368 1139962064 12.215 100 60 119 3 1272097424 118430400 13704960
11:32:53 24023 8192000000 10140078256 10607431680 1135695840 12.175 99 28 55 3 1268790384 126840944 6253600
11:34:29 91335 8192000000 10148503024 10659299328 1119735264 12.024 98 23 46 2 1282958832 157955024 5268544
11:37:05 45466 8192000000 10103698272 10576531456 1125565056 12.080 100 17 34 1 1229531536 100088928 3877552
11:38:51 61326 8192000000 10059270624 10489925632 1123506672 12.060 99 13 26 1 1168800784 42804688 2489424
11:40:59 40797 8192000000 10055444672 10508140544 1114585936 11.976 98 15 30 0 1151903120 34054816 3262368
11:42:40 142463 8192000000 10061694928 10490646528 1108017168 11.914 98 27 53 1 1157198128 43226352 5954608
11:46:22 31269 8192000000 10081917968 10543132672 1128003888 12.103 100 14 28 0 1187713136 56781280 2927968
11:47:55 18064 8192000000 10062224320 10518863872 1134465120 12.163 100 25 50 1 1173894896 34399600 5030176
11:49:17 60068 8192000000 10054204064 10521800704 1111130768 11.943 98 7 14 0 1159191312 46401904 1658640
11:51:26 43191 8192000000 10063852656 10539249664 1129734352 12.119 100 10 21 1 1180494368 49059328 1700688
11:53:14 72372 8192000000 10091484880 10541682688 1136771408 12.185 100 23 46 2 1206130880 64149952 5209520
11:55:32 57296 8192000000 10068730688 10515906560 1131650688 12.137 100 9 17 1 1179399072 45877824 1870560
11:58:16 44364 8192000000 10081888160 10518994944 1139112224 12.207 99 19 39 0 1173156224 29524800 4519200
12:00:00 68674 8192000000 10108289152 10570448896 1113312592 11.964 97 13 26 1 1207083216 90842864 2927760
12:03:19 100885 8192000000 10077401424 10493149184 1066899520 11.522 93 15 31 1 1109041168 39013632 3128016
12:06:02 60484 8192000000 10109713952 10569367552 1128494416 12.107 99 32 65 1 1202433696 66348112 7591168
12:08:12 54183 8192000000 10114142448 10574667776 1101901536 11.856 98 22 45 2 1167764096 61522832 4339728
12:10:08 98786 8192000000 10057712528 10484387840 1117565840 12.004 99 38 76 1 1166017440 41074000 7377600
12:12:53 50800 8192000000 10031687168 10492706816 1102899536 11.865 98 9 18 0 1129501312 24976688 1625088
12:14:43 69052 8192000000 10065600912 10503491584 1079686496 11.644 96 15 31 2 1135062672 52192976 3183200
12:16:58 61615 8192000000 10088135968 10580451328 1110525040 11.937 100 17 34 2 1217637216 103151808 3960368
12:19:01 40806 8192000000 10059008976 10495848448 1088270848 11.726 99 23 46 1 1148243088 54742688 5229552
12:21:01 72313 8192000000 10051479728 10490265600 1046405856 11.326 94 18 36 1 1094386592 44318816 3661920
12:23:14 48555 8192000000 10020832160 10434850816 1045463536 11.317 95 24 45 1 1077411408 27283792 4664080
12:25:07 77779 8192000000 10070665744 10506076160 1086367312 11.708 98 42 83 1 1188639216 92931360 9340544
12:27:43 37580 8192000000 10075620848 10541477888 1104411184 11.879 99 47 93 1 1179631504 66236432 8983888
12:29:34 130697 8192000000 10054105424 10508099584 1073865952 11.589 96 21 42 1 1125022288 46978080 4178256
12:33:17 70646 8192000000 10063022656 10525282304 1120117776 12.028 100 6 12 0 1170334784 48983024 1233984
12:35:28 58281 8192000000 10086084816 10547150848 1093760768 11.778 98 11 22 1 1156022304 59863776 2397760
12:37:32 81090 8192000000 10109752752 10581389312 1121540944 12.042 100 20 40 2 1196774592 70575648 4658000
12:39:54 63043 8192000000 10125098496 10560077824 1113942320 11.970 99 14 28 3 1201267072 83996144 3328608
12:41:59 32980 8192000000 10127713440 10551373824 1125514576 12.079 100 20 39 2 1215322288 85286096 4521616
12:43:35 81558 8192000000 10084326144 10560901120 1098514944 11.824 98 15 30 0 1187283872 85499472 3269456
12:46:06 81921 8192000000 10075659024 10557632512 1116769648 11.996 99 16 30 0 1180263376 60311744 3181984
12:48:29 97401 8192000000 10107233136 10540703744 1122949200 12.055 100 36 72 0 1183121648 52825504 7346944
12:51:32 32620 8192000000 9560875824 9951125504 675139280 7.613 60 7 14 0 713831584 37445808 1246496

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 17-08-2020 12:54:15 to 17-08-2020 12:54:30.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 108 v_add_invoice_to_stock 335544517
user_exc 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-08-2020 12:54:30 to 17-08-2020 12:54:32.

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          214290
MON$OLDEST_ACTIVE               214291
MON$OLDEST_SNAPSHOT             214291
MON$NEXT_TRANSACTION            214291
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                       2065248
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             671
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2158 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2158 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2158 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.2158 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'

17.08.20 12:54:34. 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-17 12:54:37 NO_SEVERE_ERRORS_FOUND
17.08.20 12:54:39. End of report.