Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.08.2020 06: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 16302780 kB
MemAvailable 30956864 kB
Buffers 0 kB
Cached 15361948 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 06:51:51.8770
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/45694
TRN_ID                          223611
ATT_ID                          529
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: 8310.11 27853 997213
customer order: creation 158.37 418 19004
customer order: refuse 13.69 457 1643
order to supplier: creation 42.65 1585 5118
order to supplier: removal 3.19 146802 383
invoice (draft): creation 41.81 4425 5017
invoice (draft): removal 48.79 1093 5855
invoice accept: apply 38.37 21533 4604
invoice accept: cancel 43.93 1244 5271
customer reserve: creation 3424.12 216 410894
customer reserve: removal 4220.34 87 506441
realization accept: apply 76.06 238 9127
realization accept: cancel 80.22 149 9627
payment from customer: creation 53.96 141 6475
payment from customer: removal 5.79 114 695
payment to supplier: creation 45.41 162 5449
payment to supplier: removal 3.31 88 397
service: total inventory turnovers 3.43 5401 412
service: total monetary turnovers 5.73 1053 687
service: aggregate perf. data 0.92 17798 110
service: refresh index statistics 0.03 354049 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-08-2020 06:53:25 to 20-08-2020 06:53:27.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3070
TEST_TIME 2 3460
TEST_TIME 3 4129
TEST_TIME 4 4222
TEST_TIME 5 4214
TEST_TIME 6 4002
TEST_TIME 7 4113
TEST_TIME 8 4536
TEST_TIME 9 4522
TEST_TIME 10 4580
TEST_TIME 11 5033
TEST_TIME 12 5254
TEST_TIME 13 5295
TEST_TIME 14 5222
TEST_TIME 15 5174
TEST_TIME 16 5227
TEST_TIME 17 5166
TEST_TIME 18 5139
TEST_TIME 19 5186
TEST_TIME 20 5107
TEST_TIME 21 5293
TEST_TIME 22 5429
TEST_TIME 23 5478
TEST_TIME 24 5461
TEST_TIME 25 5584
TEST_TIME 26 5621
TEST_TIME 27 5834
TEST_TIME 28 6158
TEST_TIME 29 6336
TEST_TIME 30 6353
TEST_TIME 31 6327
TEST_TIME 32 6787
TEST_TIME 33 7292
TEST_TIME 34 7517
TEST_TIME 35 7665
TEST_TIME 36 8007
TEST_TIME 37 8045
TEST_TIME 38 8404
TEST_TIME 39 8601
TEST_TIME 40 8513
TEST_TIME 41 8454
TEST_TIME 42 8496
TEST_TIME 43 8727
TEST_TIME 44 8762
TEST_TIME 45 8674
TEST_TIME 46 8798
TEST_TIME 47 8643
TEST_TIME 48 8511
TEST_TIME 49 8391
TEST_TIME 50 8337
TEST_TIME 51 8318
TEST_TIME 52 8284
TEST_TIME 53 8411
TEST_TIME 54 8381
TEST_TIME 55 8395
TEST_TIME 56 8358
TEST_TIME 57 8319
TEST_TIME 58 8287
TEST_TIME 59 8271
TEST_TIME 60 8371
TEST_TIME 61 8331
TEST_TIME 62 8313
TEST_TIME 63 8425
TEST_TIME 64 8480
TEST_TIME 65 8500
TEST_TIME 66 8592
TEST_TIME 67 8591
TEST_TIME 68 8629
TEST_TIME 69 8564
TEST_TIME 70 8751
TEST_TIME 71 8730
TEST_TIME 72 8697
TEST_TIME 73 8691
TEST_TIME 74 8602
TEST_TIME 75 8543
TEST_TIME 76 8505
TEST_TIME 77 8459
TEST_TIME 78 8416
TEST_TIME 79 8375
TEST_TIME 80 8299
TEST_TIME 81 8255
TEST_TIME 82 8241
TEST_TIME 83 8300
TEST_TIME 84 8320
TEST_TIME 85 8336
TEST_TIME 86 8337
TEST_TIME 87 8348
TEST_TIME 88 8396
TEST_TIME 89 8384
TEST_TIME 90 8439
TEST_TIME 91 8476
TEST_TIME 92 8451
TEST_TIME 93 8488
TEST_TIME 94 8537
TEST_TIME 95 8497
TEST_TIME 96 8438
TEST_TIME 97 8381
TEST_TIME 98 8419
TEST_TIME 99 8415
TEST_TIME 100 8436
TEST_TIME 101 8410
TEST_TIME 102 8380
TEST_TIME 103 8385
TEST_TIME 104 8359
TEST_TIME 105 8421
TEST_TIME 106 8418
TEST_TIME 107 8449
TEST_TIME 108 8532
TEST_TIME 109 8550
TEST_TIME 110 8599
TEST_TIME 111 8552
TEST_TIME 112 8484
TEST_TIME 113 8440
TEST_TIME 114 8414
TEST_TIME 115 8380
TEST_TIME 116 8336
TEST_TIME 117 8286
TEST_TIME 118 8294
TEST_TIME 119 8277
TEST_TIME 120 8312

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 20-08-2020 06:53:28 to 20-08-2020 06:53:31.

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 1377909 1377909 0 0.00 0 14387 9 0 0
doc_list_biud 1377909 1377909 0 0.00 0 35743 40 0 0
sp_add_invoice_to_stock 4707 4604 103 2.18 27 199322 21533 0 103
sp_cancel_adding_invoice 5271 5271 0 0.00 4 96431 1245 0 0
sp_cancel_client_order 1643 1643 0 0.00 5 11198 458 0 0
sp_cancel_customer_reserve 506441 506441 0 0.00 1 35744 88 0 0
sp_cancel_pay_from_customer 695 695 0 0.00 2 3748 115 0 0
sp_cancel_pay_to_supplier 397 397 0 0.00 2 2314 88 0 0
sp_cancel_supplier_invoice 5863 5855 8 0.13 1 17385 1094 0 8
sp_cancel_supplier_order 383 383 0 0.00 15 1570117 146802 0 0
sp_cancel_write_off 9627 9627 0 0.00 1 16973 150 0 0
sp_client_order 19004 19004 0 0.00 3 20793 418 0 0
sp_customer_reserve 410894 410894 0 0.00 0 99296 216 0 0
sp_fill_shopping_cart 29138 29138 0 0.00 1 14638 52 0 0
sp_fill_shopping_cart_clo_res 410892 410892 0 0.00 0 26180 19 0 0
sp_get_clo_for_invoice 4601 4601 0 0.00 0 38382 379 0 0
sp_kill_cost_storno 15990 15990 0 0.00 0 10372 87 0 0
sp_kill_qstorno_ret_qs2qd 1028747 1028747 0 0.00 0 34446 47 0 0
sp_kill_qty_storno 542948 542948 0 0.00 0 35743 102 0 0
sp_lock_selected_doc 19191 19191 0 0.00 0 6838 3 0 0
sp_make_cost_storno 25652 25652 0 0.00 0 13943 72 0 0
sp_multiply_rows_for_pdistr 25652 25652 0 0.00 0 12264 42 0 0
sp_multiply_rows_for_qdistr 19004 19004 0 0.00 0 12623 191 0 0
sp_pay_from_customer 6475 6475 0 0.00 4 12886 141 0 0
sp_pay_to_supplier 5449 5449 0 0.00 5 38374 163 0 0
sp_payment_common 11924 11924 0 0.00 1 38364 27 0 0
sp_qd_handle_on_cancel_clo 1643 1643 0 0.00 0 11190 428 0 0
sp_qd_handle_on_invoice_upd_sts 9872 9872 0 0.00 0 19657 479 0 0
sp_qd_handle_on_reserve_upd_sts 9127 9127 0 0.00 0 20684 82 0 0
sp_reserve_write_off 9127 9127 0 0.00 6 20781 238 0 0
sp_supplier_invoice 5017 5017 0 0.00 6 83090 4426 0 0
sp_supplier_order 5118 5118 0 0.00 5 31890 1585 0 0
srv_aggregate_perf_data 110 110 0 0.00 2184 224216 17799 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1813 128055 40241 0 0
srv_find_qd_qs_mism 953922 953922 0 0.00 0 12811 7 0 0
srv_make_invnt_saldo 412 412 0 0.00 25 140466 5402 0 0
srv_make_money_saldo 687 687 0 0.00 0 16960 1053 0 0
srv_recalc_idx_stat 4 4 0 0.00 96020 563507 354049 0 0
t$perf-abend:sp_add_invoice_to_stock 103 103 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4604 4604 0 0.00 0 14673 273 0 0
t$perf-norm:sp_cancel_adding_invoice 403 403 0 0.00 0 9733 201 0 0
t$perf-norm:sp_cancel_client_order 1643 1643 0 0.00 0 2247 11 0 0
t$perf-norm:sp_cancel_customer_reserve 646 646 0 0.00 0 1522 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 695 695 0 0.00 0 9026 27 0 0
t$perf-norm:sp_cancel_pay_to_supplier 397 397 0 0.00 0 2257 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 376 376 0 0.00 0 1594 27 0 0
t$perf-norm:sp_cancel_supplier_order 383 383 0 0.00 0 44261 2613 0 0
t$perf-norm:sp_cancel_write_off 920 920 0 0.00 0 1208 6 0 0
t$perf-norm:sp_client_order 19004 19004 0 0.00 0 6379 24 0 0
t$perf-norm:sp_customer_reserve 929 929 0 0.00 0 3298 9 0 0
t$perf-norm:sp_pay_from_customer 6475 6475 0 0.00 0 6925 11 0 0
t$perf-norm:sp_pay_to_supplier 5449 5449 0 0.00 0 5466 13 0 0
t$perf-norm:sp_reserve_write_off 9127 9127 0 0.00 0 6585 13 0 0
t$perf-norm:sp_supplier_invoice 5017 5017 0 0.00 0 15623 33 0 0
t$perf-norm:sp_supplier_order 5118 5118 0 0.00 0 3091 24 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 402 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 1376 27 0 0
t$perf-norm:srv_make_invnt_saldo 412 412 0 0.00 0 2935 32 0 0
t$perf-norm:srv_make_money_saldo 687 687 0 0.00 0 856 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 45 14 0 0
v_add_invoice_to_stock 9408 9202 206 2.18 0 7871 19 0 206
v_all_customers 34490 34490 0 0.00 0 19 1 0 0
v_all_suppliers 27942 27942 0 0.00 0 18 1 0 0
v_all_wares 38008 38008 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 806 806 0 0.00 0 3923 14 0 0
v_cancel_client_order 3286 3286 0 0.00 0 3922 6 0 0
v_cancel_customer_prepayment 1390 1390 0 0.00 0 1171 7 0 0
v_cancel_customer_reserve 1292 1292 0 0.00 0 2806 9 0 0
v_cancel_payment_to_supplier 794 794 0 0.00 0 1945 8 0 0
v_cancel_supplier_invoice 768 752 16 2.08 0 947 16 0 16
v_cancel_supplier_order 766 766 0 0.00 0 3426 15 0 0
v_cancel_write_off 1840 1840 0 0.00 0 1982 18 0 0
v_max_id_clo_ord 5118 5118 0 0.00 0 1115 2 0 0
v_max_id_clo_res 929 929 0 0.00 0 2568 17 0 0
v_max_id_ord_sup 5016 5016 0 0.00 0 6178 8 0 0
v_max_non_paid_invoice 1612 1612 0 0.00 0 742 3 0 0
v_max_non_paid_realizn 3447 3447 0 0.00 0 955 3 0 0
v_min_id_clo_ord 5118 5118 0 0.00 0 3908 10 0 0
v_min_id_clo_res 929 929 0 0.00 0 5771 12 0 0
v_min_id_ord_sup 5016 5016 0 0.00 0 7416 12 0 0
v_min_non_paid_invoice 5449 5449 0 0.00 0 38359 18 0 0
v_min_non_paid_realizn 6475 6475 0 0.00 0 12832 18 0 0
v_our_firm 9574 9574 0 0.00 0 19 1 0 0
v_reserve_write_off 18254 18254 0 0.00 0 10276 7 0 0
x_lock_depdocs_on_canc_invoice 10750 10750 0 0.00 0 23450 181 0 0
x_lock_depdocs_on_canc_sup_ord 383 383 0 0.00 0 7457 547 0 0
x_make_qstorno_1000_1200 5118 5118 0 0.00 1 31658 1478 0 0
x_make_qstorno_1200_2000 5017 5017 0 0.00 2 76301 4221 0 0
x_make_qstorno_2100_3300 410894 410894 0 0.00 0 99294 194 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-08-2020 06:53:32 to 20-08-2020 06:53:36.

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
04:21:55 2 8192000000 8796612016 8807043072 3144832 0.038 1 1 2 0 3242128 61344 35952
04:22:56 28395 8192000000 9717791040 10009956352 798425760 8.880 100 87 174 0 826979152 7348928 21204464
04:24:32 18894 8192000000 9765853664 10066006016 831623984 9.216 100 91 182 2 865588480 12062736 21901760
04:26:01 25850 8192000000 9797734992 10111262720 859384208 9.494 100 83 164 1 902381136 24111872 18885056
04:27:29 14788 8192000000 9854313760 10182377472 908692464 9.984 100 82 164 3 968214208 41910240 17611504
04:28:46 12033 8192000000 9919971888 10260615168 952345200 10.414 100 87 174 0 1038918848 68108176 18465472
04:30:05 6184 8192000000 9935358144 10289197056 987300160 10.755 100 69 134 2 1050027568 49221808 13505600
04:31:13 5155 8192000000 9993365328 10352287744 1011048752 10.986 100 84 168 0 1108206016 80312528 16844736
04:32:26 1242 8192000000 10003963024 10368638976 1036047504 11.227 100 85 169 1 1111615568 58590448 16977616
04:33:29 1587 8192000000 10023379952 10389463040 1052102144 11.381 100 93 185 1 1137456272 65681712 19672416
04:34:34 1278 8192000000 10037880080 10409426944 1065037776 11.505 100 83 164 1 1156094288 74052960 17003552
04:35:39 43878 8192000000 9980925040 10375589888 1072968304 11.580 100 31 62 0 1109344576 29884928 6491344
04:37:28 7319 8192000000 9995936000 10387795968 1075677184 11.606 100 29 58 1 1115071504 32524608 6869712
04:38:36 8024 8192000000 9983640016 10375192576 1081411696 11.661 100 24 48 2 1095965440 9699136 4854608
04:39:47 65430 8192000000 10010723312 10406965248 1084848336 11.694 100 25 48 1 1106300560 16100400 5351824
04:41:53 4905 8192000000 10018066288 10407772160 1087712096 11.721 100 37 73 0 1120644784 26022320 6910368
04:42:59 3511 8192000000 9983990848 10372485120 1064850640 11.503 100 47 93 2 1099812080 25323600 9637840
04:44:05 7445 8192000000 9969349232 10357116928 1048700768 11.348 99 25 50 1 1068904192 14055552 6147872
04:45:12 28847 8192000000 9939175648 10302189568 958410352 10.473 92 64 129 1 992475264 19335088 14729824
04:46:44 5849 8192000000 9938830240 10315206656 980164224 10.686 96 28 56 1 1003111888 16415168 6532496
04:47:50 8580 8192000000 9942442336 10331103232 1021619808 11.088 100 43 88 7 1048756672 16925680 10211184
04:49:00 5682 8192000000 9954175136 10335559680 1034838064 11.215 99 38 77 0 1059642320 15083648 9720608
04:50:09 27307 8192000000 9964909792 10339438592 1043046208 11.294 99 63 124 3 1067942368 11513344 13382816
04:51:37 7394 8192000000 9982872576 10382561280 1068709920 11.540 100 36 73 1 1111526656 34752992 8063744
04:52:52 26624 8192000000 10032665552 10424397824 1088861200 11.732 100 50 100 2 1146210624 46944736 10404688
04:54:24 10028 8192000000 10069815760 10455097344 1108942384 11.922 100 56 112 1 1172739856 52023552 11773920
04:55:42 53971 8192000000 10082036416 10494304256 1115269056 11.982 100 30 60 4 1188394720 66165152 6960512
04:57:39 19734 8192000000 10103174208 10507558912 1127119168 12.094 100 44 88 3 1206702688 70589152 8994368
04:59:08 6332 8192000000 10147193808 10549116928 1138569040 12.202 100 69 138 1 1261624256 107773296 15281920
05:00:17 4172 8192000000 10153223456 10552995840 1132825664 12.148 99 58 116 1 1250181984 104568624 12787696
05:01:31 13131 8192000000 10193560704 10599940096 1142807712 12.242 100 50 97 3 1297125536 143113184 11204640
05:02:49 15130 8192000000 10172893728 10583670784 1127935792 12.102 98 41 80 0 1278122592 141067360 9119440
05:04:08 20814 8192000000 10149441472 10574061568 1138706544 12.203 100 32 64 2 1276149216 129847712 7594960
05:05:35 12143 8192000000 10208371152 10612838400 1157144672 12.377 100 63 126 0 1310837456 139849920 13842864
05:06:52 7837 8192000000 10249159120 10662555648 1164193664 12.443 100 50 99 0 1357114736 181873488 11047584
05:08:14 24438 8192000000 10263167664 10685177856 1158071152 12.385 99 44 87 1 1360552368 191935696 10545520
05:09:44 3398 8192000000 10300251616 10698813440 1175439408 12.548 100 90 181 1 1392693424 196433216 20820800
05:10:52 5889 8192000000 10314328624 10728042496 1171206688 12.508 100 67 133 1 1420347360 233347888 15792784
05:12:05 10099 8192000000 10355604320 10762690560 1162473408 12.426 100 67 135 0 1456157856 277320704 16363744
05:13:24 3805 8192000000 10380185936 10783989760 1171933408 12.515 100 81 160 2 1474784912 284491408 18360096
05:14:38 3819 8192000000 10400416064 10799419392 1163928160 12.440 99 77 152 1 1495647088 314625120 17093808
05:15:50 8161 8192000000 10423028752 10823090176 1154532832 12.352 99 73 146 2 1517714096 346452448 16728816
05:17:13 2047 8192000000 10468157616 10866577408 1167501216 12.473 100 89 173 2 1562521856 373924704 21095936
05:18:26 5267 8192000000 10473310832 10907418624 1160470176 12.408 100 81 163 2 1571558688 391453440 19635072
05:19:39 1813 8192000000 10445202496 10877599744 1159111728 12.395 100 89 177 1 1537890288 357950096 20828464
05:20:52 1934 8192000000 10485258208 10888634368 1164543072 12.446 100 82 163 3 1575987296 392037264 19406960
05:22:02 13315 8192000000 10485892400 10903150592 1134708944 12.166 98 45 89 3 1566114736 419870144 11535648
05:23:23 2537 8192000000 10523806464 10982309888 1165987824 12.459 100 90 182 1 1615949600 429028304 20933472
05:24:31 6548 8192000000 10405900928 10839863296 1153454416 12.342 99 42 84 0 1491658288 327530112 10673760
05:25:45 2832 8192000000 10364309776 10787008512 1161851232 12.421 100 60 119 1 1462841104 287664432 13325440
05:26:53 8401 8192000000 10328323808 10782232576 1150979536 12.319 99 34 68 2 1435900848 276827008 8094304
05:28:07 14611 8192000000 10269781792 10703388672 1154775504 12.354 100 34 69 1 1367225888 204523584 7926800
05:29:35 37694 8192000000 10082252192 10566320128 1079779888 11.645 95 13 26 2 1217411520 134762512 2869120
05:31:20 47725 8192000000 10116123792 10529857536 1107713536 11.911 98 30 59 1 1186443744 72213344 6516864
05:33:27 84197 8192000000 10091662128 10581561344 1093419088 11.775 97 16 32 0 1180008976 83048448 3541440
05:36:11 50817 8192000000 10088120160 10514038784 1112610976 11.957 99 23 46 3 1176298880 58539792 5148112
05:38:17 108896 8192000000 10067974304 10509815808 1120226144 12.029 99 29 58 2 1153482016 26952976 6302896
05:41:07 32345 8192000000 10072254960 10495143936 1098883728 11.827 98 44 85 2 1151370880 43086960 9400192
05:42:43 34545 8192000000 10140696288 10581393408 1135421008 12.172 100 18 36 0 1236142592 96164144 4557440
05:44:24 44056 8192000000 10102778272 10565181440 1118060896 12.009 98 41 81 1 1217957616 91604752 8291968
05:46:13 53771 8192000000 10111546944 10590269440 1140935552 12.224 100 21 42 1 1224887888 78932688 5019648
05:48:17 105617 8192000000 10132283920 10572345344 1124879712 12.073 98 27 54 0 1210728464 80080336 5768416
05:51:03 51085 8192000000 10069290832 10556702720 1143952560 12.253 100 9 18 0 1181341232 35722768 1665904
05:52:55 46766 8192000000 10121488080 10586599424 1132033296 12.141 99 11 21 0 1196618752 61927808 2657648
05:54:43 31568 8192000000 10120032496 10581762048 1139753216 12.213 99 18 35 4 1222703856 79073024 3877616
05:56:30 29138 8192000000 10083711472 10508816384 1076971040 11.619 94 23 46 1 1112876096 31523120 4381936
05:58:00 62850 8192000000 10080178512 10562084864 1123036672 12.056 100 10 19 1 1189066640 63443248 2586720
06:00:15 25367 8192000000 10130358656 10544209920 1136518352 12.183 100 55 108 1 1210468976 62355824 11594800
06:01:44 113205 8192000000 10065598320 10505289728 1101468528 11.852 97 18 36 1 1153634592 48834048 3332016
06:04:40 128055 8192000000 10057578064 10515431424 1134325008 12.162 100 20 40 2 1180315136 41933888 4056240
06:07:51 41424 8192000000 10045895040 10502832128 1105638112 11.891 98 11 22 0 1131441056 23809424 1993520
06:09:32 42365 8192000000 10033923920 10478948352 1097521664 11.814 98 15 30 1 1125242704 24284304 3436736
06:11:20 70536 8192000000 10108044960 10564296704 1111094640 11.943 98 17 34 1 1190845296 75849696 3900960
06:13:33 38505 8192000000 10081901936 10559987712 1123798320 12.063 100 27 54 2 1201296480 71963936 5534224
06:15:21 80120 8192000000 10038072272 10482053120 1090161280 11.744 96 18 36 1 1124851984 30955152 3735552
06:17:45 45175 8192000000 10085782000 10537312256 1116778832 11.997 99 27 54 0 1167804896 45608800 5417264
06:19:34 44905 8192000000 10076994336 10507657216 1131180464 12.132 100 20 41 1 1166592512 31550336 3861712
06:21:20 100058 8192000000 10052300592 10542813184 1061308768 11.469 94 30 58 2 1134301248 67481600 5510880
06:24:04 65781 8192000000 10108494480 10573594624 1111766336 11.949 99 16 33 1 1192898768 77701296 3431136
06:26:13 65785 8192000000 10067343392 10530848768 1121581904 12.042 100 18 36 1 1167346192 41890496 3873792
06:29:04 84632 8192000000 10042303952 10491293696 1085212992 11.697 96 18 36 2 1118328864 29459792 3656080
06:31:31 57578 8192000000 10060295616 10512203776 1120161024 12.029 99 21 40 3 1172465488 47750112 4554352
06:33:31 22073 8192000000 10061116624 10490445824 1107293520 11.907 98 27 54 1 1137180512 24179296 5707696
06:34:58 55413 8192000000 10127353888 10563825664 1112912816 11.960 98 21 42 2 1183455136 66222656 4319664
06:36:55 59699 8192000000 10101940256 10538090496 1101110624 11.848 97 20 40 2 1173864848 68666736 4087488
06:39:03 88903 8192000000 10063736496 10530164736 1102166320 11.858 98 7 14 0 1139146336 35523808 1456208
06:41:38 112440 8192000000 10055936048 10514444288 1101313440 11.850 98 12 24 0 1133807136 30159408 2334288
06:45:12 41195 8192000000 10058966640 10509545472 1125364112 12.078 100 11 22 0 1152076176 24460720 2251344
06:46:54 59680 8192000000 10115172112 10560024576 1138935088 12.206 99 17 34 0 1199312864 56815728 3562048
06:49:02 97893 8192000000 10164474256 10648891392 1124674720 12.071 99 15 30 0 1247747888 119657376 3415792
06:51:40 25368 8192000000 9044154784 9334087680 183137392 2.186 16 4 6 1 218090592 34104128 849072

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 06:53:38 to 20-08-2020 06:53:52.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 103 v_add_invoice_to_stock 335544517
user_exc 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 20-08-2020 06:53:53 to 20-08-2020 06:53:55.

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          224486
MON$OLDEST_ACTIVE               224487
MON$OLDEST_SNAPSHOT             224487
MON$NEXT_TRANSACTION            224487
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                       2101376
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             691
MON$NEXT_STATEMENT              53


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

20.08.20 06:53:57. 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 06:53:59 NO_SEVERE_ERRORS_FOUND
20.08.20 06:54:01. End of report.