Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.08.2020 09:55
Common Performance Final Results
  1. Hardware and OS info
  2. DB and test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database Statistics, full
  3. Ratio "Versions / Records" for tables
  4. Database Validation Results
  5. New in firebird.log while test was run
  6. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 369f65bbd7e5442bbb810a88d29f958d
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-957.5.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2019-11-01 09:39
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.03
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32644004 kB
MemFree 16276044 kB
MemAvailable 30956064 kB
Buffers 0 kB
Cached 15395428 kB
SwapCached 6004 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-20 09:53:55.1130
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(31:12) ==> SP_HALT_ON_ERROR(46:13)
IP                              ::1/47576
TRN_ID                          217571
ATT_ID                          530
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: 8199.48 27440 983938
customer order: creation 147.44 463 17693
customer order: refuse 13.11 499 1573
order to supplier: creation 39.10 1679 4692
order to supplier: removal 2.91 180797 349
invoice (draft): creation 39.34 5099 4721
invoice (draft): removal 47.98 1230 5758
invoice accept: apply 36.49 23087 4379
invoice accept: cancel 43.31 1311 5197
customer reserve: creation 3308.16 225 396979
customer reserve: removal 4265.93 96 511911
realization accept: apply 71.68 277 8602
realization accept: cancel 75.68 164 9082
payment from customer: creation 49.90 148 5988
payment from customer: removal 4.67 116 560
payment to supplier: creation 42.05 182 5046
payment to supplier: removal 2.82 125 338
service: total inventory turnovers 2.78 7171 334
service: total monetary turnovers 5.48 1135 657
service: aggregate perf. data 0.63 27956 75
service: refresh index statistics 0.03 297034 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 20-08-2020 09:56:11 to 20-08-2020 09:56:14.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4700
TEST_TIME 2 3960
TEST_TIME 3 3457
TEST_TIME 4 3658
TEST_TIME 5 3970
TEST_TIME 6 4376
TEST_TIME 7 4590
TEST_TIME 8 4532
TEST_TIME 9 4333
TEST_TIME 10 4387
TEST_TIME 11 4263
TEST_TIME 12 4211
TEST_TIME 13 4303
TEST_TIME 14 4405
TEST_TIME 15 4438
TEST_TIME 16 4496
TEST_TIME 17 4727
TEST_TIME 18 4689
TEST_TIME 19 4856
TEST_TIME 20 5080
TEST_TIME 21 5144
TEST_TIME 22 5366
TEST_TIME 23 5487
TEST_TIME 24 5467
TEST_TIME 25 5468
TEST_TIME 26 5610
TEST_TIME 27 5557
TEST_TIME 28 5804
TEST_TIME 29 5891
TEST_TIME 30 6117
TEST_TIME 31 6424
TEST_TIME 32 6682
TEST_TIME 33 6790
TEST_TIME 34 6846
TEST_TIME 35 7004
TEST_TIME 36 7397
TEST_TIME 37 7447
TEST_TIME 38 7696
TEST_TIME 39 7992
TEST_TIME 40 8098
TEST_TIME 41 8301
TEST_TIME 42 8714
TEST_TIME 43 8755
TEST_TIME 44 8663
TEST_TIME 45 8523
TEST_TIME 46 8459
TEST_TIME 47 8387
TEST_TIME 48 8366
TEST_TIME 49 8357
TEST_TIME 50 8284
TEST_TIME 51 8227
TEST_TIME 52 8381
TEST_TIME 53 8318
TEST_TIME 54 8244
TEST_TIME 55 8207
TEST_TIME 56 8198
TEST_TIME 57 8072
TEST_TIME 58 8006
TEST_TIME 59 7966
TEST_TIME 60 7919
TEST_TIME 61 7966
TEST_TIME 62 7958
TEST_TIME 63 7932
TEST_TIME 64 7935
TEST_TIME 65 7935
TEST_TIME 66 7879
TEST_TIME 67 7855
TEST_TIME 68 7870
TEST_TIME 69 7965
TEST_TIME 70 7970
TEST_TIME 71 7996
TEST_TIME 72 8013
TEST_TIME 73 8040
TEST_TIME 74 8035
TEST_TIME 75 8020
TEST_TIME 76 8141
TEST_TIME 77 8124
TEST_TIME 78 8219
TEST_TIME 79 8150
TEST_TIME 80 8066
TEST_TIME 81 8034
TEST_TIME 82 8024
TEST_TIME 83 7968
TEST_TIME 84 7973
TEST_TIME 85 7932
TEST_TIME 86 7930
TEST_TIME 87 7888
TEST_TIME 88 7966
TEST_TIME 89 8024
TEST_TIME 90 8038
TEST_TIME 91 8040
TEST_TIME 92 8009
TEST_TIME 93 8165
TEST_TIME 94 8269
TEST_TIME 95 8348
TEST_TIME 96 8334
TEST_TIME 97 8396
TEST_TIME 98 8376
TEST_TIME 99 8338
TEST_TIME 100 8263
TEST_TIME 101 8266
TEST_TIME 102 8218
TEST_TIME 103 8178
TEST_TIME 104 8133
TEST_TIME 105 8167
TEST_TIME 106 8159
TEST_TIME 107 8136
TEST_TIME 108 8158
TEST_TIME 109 8142
TEST_TIME 110 8145
TEST_TIME 111 8249
TEST_TIME 112 8283
TEST_TIME 113 8255
TEST_TIME 114 8318
TEST_TIME 115 8323
TEST_TIME 116 8397
TEST_TIME 117 8360
TEST_TIME 118 8297
TEST_TIME 119 8252
TEST_TIME 120 8204

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 20-08-2020 09:56:15 to 20-08-2020 09:56:18.

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 1350658 1350658 0 0.00 0 32085 9 0 0
doc_list_biud 1350657 1350657 0 0.00 0 89639 46 0 0
sp_add_invoice_to_stock 4489 4379 110 2.45 25 238993 23087 0 110
sp_cancel_adding_invoice 5197 5197 0 0.00 3 98824 1312 0 0
sp_cancel_client_order 1573 1573 0 0.00 4 11899 500 0 0
sp_cancel_customer_reserve 511911 511911 0 0.00 0 89640 97 0 0
sp_cancel_pay_from_customer 560 560 0 0.00 3 2630 116 0 0
sp_cancel_pay_to_supplier 338 338 0 0.00 2 6256 126 0 0
sp_cancel_supplier_invoice 5767 5758 9 0.15 2 14857 1230 0 9
sp_cancel_supplier_order 349 349 0 0.00 13 1406180 180798 0 0
sp_cancel_write_off 9082 9082 0 0.00 1 9278 164 0 0
sp_client_order 17693 17693 0 0.00 3 31894 464 0 0
sp_customer_reserve 396979 396979 0 0.00 0 108716 225 0 0
sp_fill_shopping_cart 27085 27085 0 0.00 1 38707 59 0 0
sp_fill_shopping_cart_clo_res 396972 396972 0 0.00 0 23049 21 0 0
sp_get_clo_for_invoice 4372 4372 0 0.00 0 31090 497 0 0
sp_kill_cost_storno 15177 15177 0 0.00 0 10504 96 0 0
sp_kill_qstorno_ret_qs2qd 1039011 1039011 0 0.00 0 89594 52 0 0
sp_kill_qty_storno 546843 546843 0 0.00 0 89639 111 0 0
sp_lock_selected_doc 18018 18018 0 0.00 0 9481 6 0 0
sp_make_cost_storno 24008 24008 0 0.00 0 32081 92 0 0
sp_multiply_rows_for_pdistr 24008 24008 0 0.00 0 9667 42 0 0
sp_multiply_rows_for_qdistr 17693 17693 0 0.00 0 20240 233 0 0
sp_pay_from_customer 5988 5988 0 0.00 4 14081 148 0 0
sp_pay_to_supplier 5046 5046 0 0.00 4 24323 183 0 0
sp_payment_common 11034 11034 0 0.00 1 10560 24 0 0
sp_qd_handle_on_cancel_clo 1573 1573 0 0.00 0 11893 444 0 0
sp_qd_handle_on_invoice_upd_sts 9569 9569 0 0.00 0 28353 566 0 0
sp_qd_handle_on_reserve_upd_sts 8601 8601 0 0.00 0 9694 77 0 0
sp_reserve_write_off 8602 8602 0 0.00 6 25129 277 0 0
sp_supplier_invoice 4721 4721 0 0.00 6 87893 5099 0 0
sp_supplier_order 4692 4692 0 0.00 4 36607 1680 0 0
srv_aggregate_perf_data 75 75 0 0.00 3493 189917 27956 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 941 136637 38032 0 0
srv_find_qd_qs_mism 942797 942797 0 0.00 0 31771 7 0 0
srv_make_invnt_saldo 334 334 0 0.00 79 144831 7172 0 0
srv_make_money_saldo 657 657 0 0.00 1 23779 1135 0 0
srv_recalc_idx_stat 4 4 0 0.00 68462 530392 297034 0 0
t$perf-abend:sp_add_invoice_to_stock 110 110 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4380 4380 0 0.00 0 13077 285 0 0
t$perf-norm:sp_cancel_adding_invoice 366 366 0 0.00 1 6206 164 0 0
t$perf-norm:sp_cancel_client_order 1573 1573 0 0.00 0 3402 24 0 0
t$perf-norm:sp_cancel_customer_reserve 573 573 0 0.00 0 2871 28 0 0
t$perf-norm:sp_cancel_pay_from_customer 560 560 0 0.00 0 962 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 338 338 0 0.00 0 117 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 385 385 0 0.00 0 10110 80 0 0
t$perf-norm:sp_cancel_supplier_order 349 349 0 0.00 0 40684 3263 0 0
t$perf-norm:sp_cancel_write_off 901 901 0 0.00 0 7435 15 0 0
t$perf-norm:sp_client_order 17693 17693 0 0.00 0 9337 29 0 0
t$perf-norm:sp_customer_reserve 886 886 0 0.00 0 2074 12 0 0
t$perf-norm:sp_pay_from_customer 5988 5988 0 0.00 0 3600 11 0 0
t$perf-norm:sp_pay_to_supplier 5046 5046 0 0.00 0 6528 15 0 0
t$perf-norm:sp_reserve_write_off 8602 8602 0 0.00 0 7955 13 0 0
t$perf-norm:sp_supplier_invoice 4721 4721 0 0.00 0 8454 35 0 0
t$perf-norm:sp_supplier_order 4692 4692 0 0.00 0 4055 28 0 0
t$perf-norm:srv_aggregate_perf_data 75 75 0 0.00 0 990 34 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 3894 62 0 0
t$perf-norm:srv_make_invnt_saldo 334 334 0 0.00 0 1426 17 0 0
t$perf-norm:srv_make_money_saldo 657 657 0 0.00 0 2719 23 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 104 34 0 0
v_add_invoice_to_stock 8964 8744 220 2.45 0 9303 21 0 220
v_all_customers 31876 31876 0 0.00 0 85 1 0 0
v_all_suppliers 25860 25860 0 0.00 0 24 1 0 0
v_all_wares 35386 35386 0 0.00 0 66 1 0 0
v_cancel_adding_invoice 732 732 0 0.00 0 1064 11 0 0
v_cancel_client_order 3146 3146 0 0.00 0 5119 8 0 0
v_cancel_customer_prepayment 1120 1120 0 0.00 0 1955 7 0 0
v_cancel_customer_reserve 1146 1146 0 0.00 0 4037 9 0 0
v_cancel_payment_to_supplier 676 676 0 0.00 0 2911 15 0 0
v_cancel_supplier_invoice 788 770 18 2.28 0 1145 15 0 18
v_cancel_supplier_order 698 698 0 0.00 0 2997 12 0 0
v_cancel_write_off 1802 1802 0 0.00 0 1636 19 0 0
v_max_id_clo_ord 4686 4686 0 0.00 0 600 2 0 0
v_max_id_clo_res 886 886 0 0.00 0 4365 20 0 0
v_max_id_ord_sup 4705 4705 0 0.00 0 8829 9 0 0
v_max_non_paid_invoice 1507 1507 0 0.00 0 142 2 0 0
v_max_non_paid_realizn 3388 3388 0 0.00 0 1687 4 0 0
v_min_id_clo_ord 4686 4686 0 0.00 0 3808 11 0 0
v_min_id_clo_res 886 886 0 0.00 0 1286 7 0 0
v_min_id_ord_sup 4705 4705 0 0.00 0 6716 10 0 0
v_min_non_paid_invoice 5046 5046 0 0.00 0 7214 14 0 0
v_min_non_paid_realizn 5988 5988 0 0.00 0 3926 13 0 0
v_our_firm 8710 8710 0 0.00 0 14 1 0 0
v_reserve_write_off 17202 17202 0 0.00 0 6478 9 0 0
x_lock_depdocs_on_canc_invoice 10570 10570 0 0.00 0 30570 177 0 0
x_lock_depdocs_on_canc_sup_ord 349 349 0 0.00 0 39301 857 0 0
x_make_qstorno_1000_1200 4692 4692 0 0.00 1 36599 1559 0 0
x_make_qstorno_1200_2000 4721 4721 0 0.00 2 79931 4861 0 0
x_make_qstorno_2100_3300 396979 396979 0 0.00 0 108713 199 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-08-2020 09:56:19 to 20-08-2020 09:56:23.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
07:23:58 1 8192000000 8796612016 8807043072 3144832 0.038 1 1 2 0 3242128 61344 35952
07:24:59 47443 8192000000 9743239664 10040786944 810274512 9.000 100 85 170 1 838417776 7851520 20291744
07:26:54 32526 8192000000 9781965728 10089287680 848601328 9.386 100 92 184 1 885592080 14911776 22078976
07:28:33 26012 8192000000 9829278704 10151579648 884259776 9.742 100 86 172 1 937627840 34083104 19284960
07:30:06 14061 8192000000 9865418688 10199924736 915426672 10.051 100 78 156 0 983706592 50835088 17444832
07:31:24 7345 8192000000 9920670768 10267168768 952223952 10.413 100 75 148 0 1042015936 74666368 15125616
07:32:35 4446 8192000000 9955663728 10306408448 990520528 10.787 100 88 174 1 1071706704 63739728 17446448
07:33:42 3025 8192000000 9987163120 10349457408 1021983200 11.091 100 72 143 1 1099033760 63133392 13917168
07:34:48 2317 8192000000 10031792128 10398412800 1044031344 11.303 100 93 186 2 1143163360 80200800 18931216
07:35:54 1694 8192000000 10027315744 10399969280 1057779968 11.435 100 82 163 1 1144136720 70053632 16303120
07:36:59 6829 8192000000 10002808064 10388217856 1062753168 11.483 100 42 82 2 1120688640 49276080 8659392
07:38:07 6624 8192000000 9968226432 10363691008 1068641104 11.539 100 35 70 2 1093185360 17436656 7107600
07:39:17 71936 8192000000 9991372064 10406559744 1075285584 11.603 100 29 58 0 1096631968 14992368 6354016
07:41:44 4145 8192000000 10010916352 10408267776 1082251808 11.669 100 59 118 1 1122729568 27850672 12627088
07:42:49 11657 8192000000 9986716656 10395553792 1071254944 11.564 99 19 38 2 1100415376 25310912 3849520
07:44:01 11600 8192000000 9983616832 10394038272 1068927856 11.542 99 19 38 4 1108821712 35871200 4022656
07:45:16 15932 8192000000 9996826272 10411069440 1051358480 11.374 98 37 74 1 1093065616 34001344 7705792
07:46:34 8346 8192000000 9945466144 10328436736 1021362544 11.085 97 33 67 2 1049227280 19428176 8436560
07:47:45 7881 8192000000 9945315760 10331181056 1001646016 10.894 97 28 56 1 1029711696 20859904 7205776
07:48:55 11715 8192000000 9928309664 10308993024 1008502144 10.961 99 33 65 1 1031325696 15266560 7556992
07:50:08 15037 8192000000 9938927008 10334408704 1023234512 11.103 99 27 54 2 1052440800 23887728 5318560
07:51:24 8272 8192000000 9950911920 10345271296 1044493376 11.308 100 38 76 3 1071716400 18887264 8335760
07:52:35 8038 8192000000 9978101424 10371788800 1055472752 11.413 100 35 70 1 1077393600 13758384 8162464
07:53:44 13029 8192000000 9996673856 10396516352 1075587200 11.605 100 15 30 1 1102701520 23949840 3164480
07:55:03 13084 8192000000 10016991760 10413154304 1087946048 11.723 100 42 80 0 1126225936 29148784 9131104
07:56:27 30425 8192000000 10048260496 10443378688 1104004400 11.876 100 54 108 2 1150867312 35814336 11048576
07:58:04 9598 8192000000 10057465648 10455076864 1110452192 11.937 100 43 84 0 1166250496 46192512 9605792
07:59:19 23387 8192000000 10100584336 10511212544 1124341168 12.068 100 43 87 1 1208791168 75550032 8899968
08:00:52 20537 8192000000 10128288880 10529005568 1132303744 12.143 100 46 91 2 1239890944 97959424 9627776
08:02:22 12455 8192000000 10143211264 10545004544 1137159056 12.189 100 50 100 4 1244966384 96529104 11278224
08:03:41 22789 8192000000 10155806864 10566103040 1140972608 12.225 100 40 79 3 1265679600 115432864 9274128
08:05:14 3155 8192000000 10186675776 10589032448 1143679120 12.250 99 64 128 1 1287262528 130134816 13448592
08:06:24 14855 8192000000 10225976656 10628435968 1161130672 12.414 100 58 115 2 1330471696 156186000 13155024
08:07:42 10972 8192000000 10249781728 10669584384 1167973328 12.478 100 49 98 2 1360321744 180738224 11610192
08:08:59 12319 8192000000 10278827344 10687553536 1153676400 12.344 99 63 127 0 1373176128 205354592 14145136
08:10:19 11038 8192000000 10279829152 10688565248 1153470304 12.342 100 58 112 1 1382347248 215860528 13016416
08:11:39 1824 8192000000 10295261760 10701234176 1164317984 12.444 100 65 127 0 1400371360 220816192 15237184
08:12:45 5003 8192000000 10315382960 10713792512 1164788512 12.448 100 71 142 1 1411202656 229783488 16630656
08:13:55 5420 8192000000 10329940416 10732994560 1167318736 12.472 100 69 137 0 1424042160 240747344 15976080
08:15:04 13513 8192000000 10317858048 10742132736 1161335472 12.416 99 52 101 1 1439074640 265555136 12184032
08:16:27 10086 8192000000 10293673184 10719420416 1153695872 12.344 100 65 128 1 1396534544 228031232 14807440
08:17:47 3898 8192000000 10330407600 10717224960 1127661824 12.099 100 85 168 1 1420358944 272807568 19889552
08:19:02 1417 8192000000 10393852176 10784038912 1142882784 12.243 100 94 184 0 1482732208 319042048 20807376
08:20:17 941 8192000000 10373630000 10764230656 1139084912 12.207 99 85 165 1 1464203904 306602000 18516992
08:21:31 4304 8192000000 10424789200 10844454912 1147475856 12.286 100 83 161 2 1517792144 351667504 18648784
08:22:42 3815 8192000000 10458125936 10862231552 1161810912 12.420 100 87 173 1 1550973520 368036048 21126560
08:23:51 3133 8192000000 10470431840 10884812800 1176026272 12.553 100 94 186 0 1573223408 376079168 21117968
08:25:01 11728 8192000000 10438305056 10886881280 1165389088 12.454 100 49 98 1 1535254336 357704256 12160992
08:26:25 5519 8192000000 10429317568 10836795392 1161804096 12.420 100 78 156 2 1526812592 346657232 18351264
08:27:39 7175 8192000000 10452873216 10877640704 1149702640 12.307 100 63 124 2 1553240416 388243680 15294096
08:28:54 2027 8192000000 10454241456 10890883072 1160557440 12.408 100 92 186 1 1545631232 362953024 22120768
08:30:04 12516 8192000000 10410038672 10854813696 1170395184 12.501 100 43 84 0 1513514976 332915392 10204400
08:31:39 5662 8192000000 10420906608 10870108160 1168613776 12.484 100 52 102 0 1526764624 345944480 12206368
08:33:04 14671 8192000000 10352495808 10773635072 1157999872 12.385 100 38 77 1 1445840816 278561264 9279680
08:34:26 7826 8192000000 10272073024 10738925568 1150538800 12.315 100 25 50 0 1378243440 222062304 5642336
08:35:39 14923 8192000000 10108958896 10573824000 1136770944 12.185 99 27 54 0 1218098304 74811280 6516080
08:37:01 10855 8192000000 10091326976 10530205696 1120858208 12.035 99 44 89 0 1175642112 45771152 9012752
08:38:19 31605 8192000000 10112098928 10540175360 1133764736 12.157 100 31 62 1 1204622608 64507344 6350528
08:39:53 58366 8192000000 10117542752 10571956224 1140231056 12.218 100 32 63 1 1217870592 70371648 7267888
08:42:02 78762 8192000000 10068742944 10546999296 1113036704 11.961 99 15 30 0 1167892896 51750464 3105728
08:44:43 89616 8192000000 10048701248 10511527936 1127036192 12.093 100 12 25 1 1180942432 51272816 2633424
08:47:21 12246 8192000000 10089032752 10536878080 1137195744 12.189 100 23 47 0 1187473072 45548768 4728560
08:48:39 121727 8192000000 10108125680 10574983168 1081053856 11.658 95 23 46 1 1132778400 46465344 5259200
08:52:12 68468 8192000000 10111680176 10580574208 1127186560 12.095 100 27 51 3 1216983920 84252960 5544400
08:54:22 42724 8192000000 10072099504 10513940480 1122236928 12.048 99 22 44 1 1167839136 40977184 4625024
08:56:10 17316 8192000000 10084583680 10513833984 1122711664 12.053 99 44 87 0 1177160544 45643456 8805424
08:57:33 61936 8192000000 10045093408 10541735936 1115478464 11.984 99 13 26 1 1172335872 54359232 2498176
09:00:21 25558 8192000000 10088378272 10528264192 1111556160 11.947 99 36 72 1 1187383264 68188144 7638960
09:01:48 53222 8192000000 10037800928 10528276480 1099118320 11.829 98 14 28 1 1136521264 34387584 3015360
09:03:42 56866 8192000000 10056628848 10496344064 1079001472 11.638 96 24 48 0 1124734416 40541136 5191808
09:06:10 58245 8192000000 10056059664 10539667456 1079468960 11.642 97 16 31 3 1177242384 94096784 3676640
09:08:13 91662 8192000000 10036390368 10494259200 1050879872 11.369 94 15 30 0 1137099008 83377552 2841584
09:10:46 112814 8192000000 10048571280 10504183808 1098065152 11.819 99 29 58 2 1151668720 47827136 5776432
09:14:06 43354 8192000000 10023947328 10462670848 1068324128 11.536 97 15 30 1 1108978688 37594544 3060016
09:16:01 43522 8192000000 10056124032 10526797824 1103224736 11.868 100 8 16 0 1165121712 60038592 1858384
09:17:47 53615 8192000000 10094413392 10552352768 1113546880 11.966 100 19 38 0 1192186560 74475264 4164416
09:19:45 97569 8192000000 10124358368 10568781824 1074000528 11.590 96 11 21 2 1177821600 101374144 2446928
09:22:26 57355 8192000000 10102266048 10539847680 1103328496 11.869 99 18 36 1 1175434144 68082208 4023440
09:24:35 73431 8192000000 10178798080 10604683264 1112587936 11.957 98 18 35 1 1227041600 110037120 4416544
09:26:53 61348 8192000000 10119674944 10592931840 1108639216 11.920 98 13 26 0 1253456960 141888800 2928944
09:28:59 51473 8192000000 10111481840 10556747776 1121962624 12.046 99 36 72 2 1196597120 66919568 7714928
09:31:00 58194 8192000000 10055983328 10468769792 1109992224 11.932 99 23 46 1 1148900352 33900304 5007824
09:33:00 113209 8192000000 10048596768 10524508160 1113755840 11.968 99 18 35 1 1173896976 56602240 3538896
09:36:00 57745 8192000000 10052201024 10505457664 1108753008 11.921 98 6 12 0 1137142880 27106848 1283024
09:38:41 87109 8192000000 10053484032 10504204288 1084548080 11.691 97 13 26 1 1132413184 45080912 2784192
09:41:17 40929 8192000000 10083245328 10559090688 1125054224 12.075 100 24 48 1 1185711920 54910704 5746992
09:43:01 66489 8192000000 10113748672 10579750912 1131196400 12.133 99 25 50 2 1229550816 92949056 5405360
09:45:16 74497 8192000000 10101644560 10579828736 1100673024 11.844 97 22 44 0 1156843264 51538992 4631248
09:47:30 95823 8192000000 10070366736 10508296192 1070505872 11.557 95 14 28 0 1135725536 62681664 2538000
09:50:07 136637 8192000000 10035001520 10473504768 1087694832 11.721 97 13 26 0 1124568224 34070192 2803200
09:53:24 48735 8192000000 9357471168 9687617536 497530208 5.725 44 4 8 0 534812080 36595152 686720

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 20-08-2020 09:56:25 to 20-08-2020 09:56:39.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 110 v_add_invoice_to_stock 335544517
user_exc 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 20-08-2020 09:56:40 to 20-08-2020 09:56:42.

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          216999
MON$OLDEST_ACTIVE               217790
MON$OLDEST_SNAPSHOT             217790
MON$NEXT_TRANSACTION            217790
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                       2110208
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             674
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2170 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2170 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2170 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.2170 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Thu Aug 20 09:54:31 2020
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
--- end of diff output ---

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

20.08.20 09:56:44. 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-20 09:56:46 NO_SEVERE_ERRORS_FOUND
20.08.20 09:56:48. End of report.