Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.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 16470120 kB
MemAvailable 30997020 kB
Buffers 0 kB
Cached 15220936 kB
SwapCached 5908 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-16 06:51:50.5450
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/45382
TRN_ID                          217309
ATT_ID                          543
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: 8641.28 24767 1036953
customer order: creation 162.02 391 19442
customer order: refuse 13.89 490 1667
order to supplier: creation 43.70 1522 5244
order to supplier: removal 3.29 140836 395
invoice (draft): creation 45.30 4039 5436
invoice (draft): removal 48.48 1155 5817
invoice accept: apply 39.63 22714 4756
invoice accept: cancel 43.08 1143 5170
customer reserve: creation 3670.41 223 440449
customer reserve: removal 4292.29 83 515075
realization accept: apply 78.47 216 9417
realization accept: cancel 77.59 163 9311
payment from customer: creation 57.01 125 6841
payment from customer: removal 5.67 92 680
payment to supplier: creation 46.58 131 5589
payment to supplier: removal 3.31 121 397
service: total inventory turnovers 3.69 4950 443
service: total monetary turnovers 6.03 898 723
service: aggregate perf. data 0.81 21768 97
service: refresh index statistics 0.03 294281 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-08-2020 06:53:54 to 16-08-2020 06:53:56.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3417
TEST_TIME 2 3251
TEST_TIME 3 4468
TEST_TIME 4 4515
TEST_TIME 5 4584
TEST_TIME 6 4712
TEST_TIME 7 4779
TEST_TIME 8 4846
TEST_TIME 9 4911
TEST_TIME 10 5202
TEST_TIME 11 5346
TEST_TIME 12 5269
TEST_TIME 13 5158
TEST_TIME 14 5360
TEST_TIME 15 5273
TEST_TIME 16 5474
TEST_TIME 17 5436
TEST_TIME 18 5641
TEST_TIME 19 5706
TEST_TIME 20 5996
TEST_TIME 21 6178
TEST_TIME 22 6290
TEST_TIME 23 6353
TEST_TIME 24 6452
TEST_TIME 25 6489
TEST_TIME 26 6509
TEST_TIME 27 6554
TEST_TIME 28 6778
TEST_TIME 29 7271
TEST_TIME 30 7266
TEST_TIME 31 7518
TEST_TIME 32 7593
TEST_TIME 33 7623
TEST_TIME 34 8247
TEST_TIME 35 8633
TEST_TIME 36 9045
TEST_TIME 37 9299
TEST_TIME 38 9342
TEST_TIME 39 9328
TEST_TIME 40 9427
TEST_TIME 41 9334
TEST_TIME 42 9308
TEST_TIME 43 9248
TEST_TIME 44 9197
TEST_TIME 45 9155
TEST_TIME 46 9294
TEST_TIME 47 9318
TEST_TIME 48 9260
TEST_TIME 49 9271
TEST_TIME 50 9132
TEST_TIME 51 8969
TEST_TIME 52 8916
TEST_TIME 53 8845
TEST_TIME 54 8905
TEST_TIME 55 8932
TEST_TIME 56 8894
TEST_TIME 57 8852
TEST_TIME 58 8828
TEST_TIME 59 8829
TEST_TIME 60 8762
TEST_TIME 61 8706
TEST_TIME 62 8695
TEST_TIME 63 8671
TEST_TIME 64 8840
TEST_TIME 65 8857
TEST_TIME 66 8849
TEST_TIME 67 8797
TEST_TIME 68 8977
TEST_TIME 69 8931
TEST_TIME 70 8967
TEST_TIME 71 8974
TEST_TIME 72 9038
TEST_TIME 73 8989
TEST_TIME 74 9051
TEST_TIME 75 9069
TEST_TIME 76 9105
TEST_TIME 77 9031
TEST_TIME 78 8992
TEST_TIME 79 8933
TEST_TIME 80 8888
TEST_TIME 81 8888
TEST_TIME 82 8834
TEST_TIME 83 8771
TEST_TIME 84 8743
TEST_TIME 85 8687
TEST_TIME 86 8664
TEST_TIME 87 8668
TEST_TIME 88 8708
TEST_TIME 89 8674
TEST_TIME 90 8721
TEST_TIME 91 8722
TEST_TIME 92 8722
TEST_TIME 93 8712
TEST_TIME 94 8765
TEST_TIME 95 8759
TEST_TIME 96 8816
TEST_TIME 97 8876
TEST_TIME 98 8860
TEST_TIME 99 8865
TEST_TIME 100 8822
TEST_TIME 101 8750
TEST_TIME 102 8745
TEST_TIME 103 8731
TEST_TIME 104 8692
TEST_TIME 105 8665
TEST_TIME 106 8647
TEST_TIME 107 8643
TEST_TIME 108 8641
TEST_TIME 109 8617
TEST_TIME 110 8628
TEST_TIME 111 8631
TEST_TIME 112 8676
TEST_TIME 113 8709
TEST_TIME 114 8716
TEST_TIME 115 8687
TEST_TIME 116 8833
TEST_TIME 117 8798
TEST_TIME 118 8728
TEST_TIME 119 8698
TEST_TIME 120 8645

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 16-08-2020 06:53:57 to 16-08-2020 06:54:00.

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 1444204 1444204 0 0.00 0 20196 8 0 0
doc_list_biud 1444204 1444204 0 0.00 0 44291 38 0 0
sp_add_invoice_to_stock 4830 4756 74 1.53 24 201109 22714 0 74
sp_cancel_adding_invoice 5170 5170 0 0.00 4 100746 1144 0 0
sp_cancel_client_order 1667 1667 0 0.00 4 8484 491 0 0
sp_cancel_customer_reserve 515075 515075 0 0.00 0 36996 84 0 0
sp_cancel_pay_from_customer 680 680 0 0.00 2 2555 93 0 0
sp_cancel_pay_to_supplier 397 397 0 0.00 2 5422 121 0 0
sp_cancel_supplier_invoice 5821 5817 4 0.06 1 22500 1155 0 4
sp_cancel_supplier_order 395 395 0 0.00 12 1418231 140836 0 0
sp_cancel_write_off 9311 9311 0 0.00 1 18572 164 0 0
sp_client_order 19442 19442 0 0.00 3 25120 391 0 0
sp_customer_reserve 440449 440449 0 0.00 0 80476 223 0 0
sp_fill_shopping_cart 30100 30100 0 0.00 1 19019 46 0 0
sp_fill_shopping_cart_clo_res 440446 440446 0 0.00 0 22556 19 0 0
sp_get_clo_for_invoice 4752 4752 0 0.00 0 45753 379 0 0
sp_kill_cost_storno 15558 15558 0 0.00 0 7301 94 0 0
sp_kill_qstorno_ret_qs2qd 1045673 1045673 0 0.00 0 28921 45 0 0
sp_kill_qty_storno 551604 551604 0 0.00 0 44291 97 0 0
sp_lock_selected_doc 19793 19793 0 0.00 0 10452 5 0 0
sp_make_cost_storno 26599 26599 0 0.00 0 15676 65 0 0
sp_multiply_rows_for_pdistr 26599 26599 0 0.00 0 7164 37 0 0
sp_multiply_rows_for_qdistr 19442 19442 0 0.00 0 25028 192 0 0
sp_pay_from_customer 6841 6841 0 0.00 3 18450 126 0 0
sp_pay_to_supplier 5589 5589 0 0.00 5 17284 131 0 0
sp_payment_common 12430 12430 0 0.00 1 9425 19 0 0
sp_qd_handle_on_cancel_clo 1667 1667 0 0.00 0 8476 458 0 0
sp_qd_handle_on_invoice_upd_sts 9922 9922 0 0.00 0 44290 449 0 0
sp_qd_handle_on_reserve_upd_sts 9417 9417 0 0.00 0 14118 57 0 0
sp_reserve_write_off 9417 9417 0 0.00 6 14278 216 0 0
sp_supplier_invoice 5436 5436 0 0.00 6 140956 4040 0 0
sp_supplier_order 5244 5244 0 0.00 5 30325 1522 0 0
srv_aggregate_perf_data 97 97 0 0.00 2568 195425 21768 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1125 152737 40882 0 0
srv_find_qd_qs_mism 991162 991162 0 0.00 0 20190 7 0 0
srv_make_invnt_saldo 443 443 0 0.00 46 130517 4951 0 0
srv_make_money_saldo 723 723 0 0.00 1 15865 898 0 0
srv_recalc_idx_stat 4 4 0 0.00 65124 583587 294281 0 0
t$perf-abend:sp_add_invoice_to_stock 74 74 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4758 4758 0 0.00 0 8650 230 0 0
t$perf-norm:sp_cancel_adding_invoice 384 384 0 0.00 0 2899 121 0 0
t$perf-norm:sp_cancel_client_order 1667 1667 0 0.00 0 8259 19 0 0
t$perf-norm:sp_cancel_customer_reserve 720 720 0 0.00 0 4297 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 680 680 0 0.00 0 540 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 397 397 0 0.00 0 792 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 416 416 0 0.00 0 4110 36 0 0
t$perf-norm:sp_cancel_supplier_order 395 395 0 0.00 0 39500 2604 0 0
t$perf-norm:sp_cancel_write_off 968 968 0 0.00 0 3601 15 0 0
t$perf-norm:sp_client_order 19442 19442 0 0.00 0 8285 24 0 0
t$perf-norm:sp_customer_reserve 945 945 0 0.00 0 1218 8 0 0
t$perf-norm:sp_pay_from_customer 6841 6841 0 0.00 0 8696 11 0 0
t$perf-norm:sp_pay_to_supplier 5589 5589 0 0.00 0 8278 13 0 0
t$perf-norm:sp_reserve_write_off 9417 9417 0 0.00 0 8418 13 0 0
t$perf-norm:sp_supplier_invoice 5436 5436 0 0.00 0 2988 23 0 0
t$perf-norm:sp_supplier_order 5244 5244 0 0.00 0 8330 22 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 359 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 307 9 0 0
t$perf-norm:srv_make_invnt_saldo 443 443 0 0.00 0 5116 23 0 0
t$perf-norm:srv_make_money_saldo 723 723 0 0.00 0 3269 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 3 0 0
v_add_invoice_to_stock 9650 9502 148 1.53 0 7256 18 0 148
v_all_customers 35934 35934 0 0.00 0 20 1 0 0
v_all_suppliers 29646 29646 0 0.00 0 24 1 0 0
v_all_wares 38884 38884 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 768 768 0 0.00 0 2131 12 0 0
v_cancel_client_order 3334 3334 0 0.00 0 2409 6 0 0
v_cancel_customer_prepayment 1360 1360 0 0.00 0 2537 8 0 0
v_cancel_customer_reserve 1440 1440 0 0.00 0 2998 9 0 0
v_cancel_payment_to_supplier 794 794 0 0.00 0 5328 12 0 0
v_cancel_supplier_invoice 840 832 8 0.95 0 1760 15 0 8
v_cancel_supplier_order 790 790 0 0.00 0 1043 6 0 0
v_cancel_write_off 1936 1936 0 0.00 0 18520 32 0 0
v_max_id_clo_ord 5239 5239 0 0.00 0 784 2 0 0
v_max_id_clo_res 945 945 0 0.00 0 3687 16 0 0
v_max_id_ord_sup 5419 5419 0 0.00 0 10057 7 0 0
v_max_non_paid_invoice 1424 1424 0 0.00 0 644 2 0 0
v_max_non_paid_realizn 3537 3537 0 0.00 0 4038 5 0 0
v_min_id_clo_ord 5239 5239 0 0.00 0 2152 7 0 0
v_min_id_clo_res 945 945 0 0.00 0 1979 12 0 0
v_min_id_ord_sup 5419 5419 0 0.00 0 7854 8 0 0
v_min_non_paid_invoice 5589 5589 0 0.00 0 5493 10 0 0
v_min_non_paid_realizn 6841 6841 0 0.00 0 6684 11 0 0
v_our_firm 9558 9558 0 0.00 0 13 1 0 0
v_reserve_write_off 18830 18830 0 0.00 0 14207 9 0 0
x_lock_depdocs_on_canc_invoice 10571 10571 0 0.00 0 41365 167 0 0
x_lock_depdocs_on_canc_sup_ord 395 395 0 0.00 0 4804 418 0 0
x_make_qstorno_1000_1200 5244 5244 0 0.00 1 30314 1432 0 0
x_make_qstorno_1200_2000 5436 5436 0 0.00 3 136890 3865 0 0
x_make_qstorno_2100_3300 440449 440449 0 0.00 0 80399 200 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-08-2020 06:54:01 to 16-08-2020 06:54:05.

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:53 7 8192000000 8796612016 8807043072 3144832 0.038 1 1 2 0 3242128 61344 35952
04:22:55 33128 8192000000 9714174384 10006618112 788932800 8.784 100 84 168 0 817840432 7343136 21564496
04:24:39 15622 8192000000 9754819792 10059649024 834886224 9.248 100 85 170 0 866460000 11850512 19723264
04:26:03 14756 8192000000 9793021936 10102173696 857186688 9.472 100 90 180 0 894783312 16658864 20937760
04:27:31 17215 8192000000 9835639488 10163621888 889142768 9.791 100 74 147 2 947469264 41556384 16770112
04:28:55 12481 8192000000 9874388672 10212966400 935842256 10.252 100 73 144 3 985503392 34000176 15660960
04:30:11 17868 8192000000 9927193072 10275217408 976682160 10.652 100 78 156 2 1036297216 43004336 16610720
04:31:31 6406 8192000000 9986583472 10339663872 1000788288 10.886 100 76 152 0 1095918592 79931184 15199120
04:32:40 6231 8192000000 9979329472 10345111552 1017023392 11.043 100 74 148 2 1113768464 81756656 14988416
04:33:49 2952 8192000000 10017089616 10380316672 1036745136 11.233 100 80 158 1 1131218272 78010640 16462496
04:34:55 2219 8192000000 10023097584 10389250048 1056865280 11.426 100 94 188 3 1133893488 58131248 18896960
04:36:00 6495 8192000000 10027660496 10398507008 1062749104 11.483 100 80 159 1 1142027920 63225280 16053536
04:37:10 50471 8192000000 10005877168 10386587648 1067867424 11.532 100 50 100 0 1119449168 41311216 10270528
04:39:03 3487 8192000000 9996624512 10397892608 1072665264 11.578 100 36 72 2 1124785744 45120576 6999904
04:40:10 6830 8192000000 9997951232 10396946432 1085102928 11.696 100 38 79 1 1111408976 17767472 8538576
04:41:20 53799 8192000000 10021962320 10410397696 1086993424 11.714 100 53 106 4 1132854800 32773680 13087696
04:43:15 4359 8192000000 10004892208 10400038912 1069708400 11.549 98 46 92 1 1103084688 23335456 10040832
04:44:20 7173 8192000000 9984957904 10389491712 1074325744 11.593 99 35 70 0 1093554208 10998688 8229776
04:45:29 7291 8192000000 9986794800 10383798272 1039913568 11.264 97 43 86 1 1058776832 9288704 9574560
04:46:40 8287 8192000000 9957744816 10329169920 1020671920 11.078 97 47 94 2 1045483200 15253760 9557520
04:47:49 8717 8192000000 9943505088 10326220800 1016582448 11.039 98 24 48 4 1040001136 17337984 6080704
04:48:59 7139 8192000000 9915655264 10280505344 990162832 10.783 98 33 66 0 1007986928 9884048 7940048
04:50:07 45655 8192000000 9941684768 10327867392 1028599232 11.155 100 40 78 1 1060502800 22489584 9413984
04:52:15 18023 8192000000 9990546240 10374934528 1066918816 11.523 100 57 114 2 1104726784 25131312 12676656
04:53:40 27644 8192000000 10068260608 10449752064 1083378704 11.680 100 62 124 1 1160397024 64779520 12238800
04:55:18 20118 8192000000 10078046912 10470080512 1099480976 11.833 100 63 126 1 1201423712 88169104 13773632
04:56:43 44894 8192000000 10111531264 10518462464 1115297696 11.983 100 40 80 1 1219337616 95730256 8309664
04:58:30 28211 8192000000 10167886896 10566995968 1125001248 12.074 100 54 106 1 1266942432 130566112 11375072
05:00:07 23522 8192000000 10210882592 10616647680 1132657520 12.146 100 51 100 2 1299930496 156293920 10979056
05:01:46 14460 8192000000 10216031904 10624176128 1143760800 12.251 100 50 100 0 1313314432 158846096 10707536
05:03:29 14332 8192000000 10191640960 10597756928 1154092576 12.348 100 47 94 1 1295827696 131448160 10286960
05:04:51 4769 8192000000 10254023024 10675560448 1159996560 12.403 100 69 139 0 1362716400 186755008 15964832
05:06:07 8631 8192000000 10271922768 10680897536 1166307152 12.462 100 57 114 2 1366345072 187581024 12456896
05:07:20 4634 8192000000 10281837664 10707337216 1179122096 12.582 100 64 128 0 1388187088 194812608 14252384
05:08:35 4271 8192000000 10363134640 10775990272 1178937312 12.580 100 69 137 3 1466061056 271337040 15786704
05:09:46 13613 8192000000 10328188720 10777096192 1177895296 12.571 100 53 106 1 1434801984 243763424 13143264
05:11:10 4585 8192000000 10373795072 10808832000 1191835520 12.700 100 86 170 0 1474713744 263316352 19561872
05:12:29 2110 8192000000 10374532944 10789638144 1173945216 12.534 99 87 171 3 1476611808 283591232 19075360
05:13:40 1380 8192000000 10400968576 10802798592 1174510896 12.539 99 92 183 0 1502690800 307915232 20264672
05:14:44 3670 8192000000 10439738080 10847760384 1163723488 12.438 100 83 165 0 1537208272 353941920 19542864
05:15:57 5430 8192000000 10443852352 10850312192 1173436656 12.529 99 78 156 1 1533002704 341198352 18367696
05:17:10 3646 8192000000 10479666160 10885689344 1188437936 12.669 100 91 180 0 1576004240 366364672 21201632
05:18:20 4549 8192000000 10516379424 10919849984 1180792480 12.598 100 86 172 0 1614685648 413055344 20837824
05:19:34 1124 8192000000 10567403392 10974187520 1170514528 12.502 100 93 183 1 1656365984 463914688 21936768
05:20:43 8216 8192000000 10461718656 10909933568 1161554560 12.418 99 46 92 0 1565222688 392166560 11501568
05:22:08 5070 8192000000 10463141328 10930315264 1159701264 12.400 100 48 96 0 1561093536 389523040 11869232
05:23:29 6819 8192000000 10448159744 10898116608 1130236048 12.124 98 38 76 0 1551895312 412548960 9110304
05:24:43 7460 8192000000 10420818720 10862338048 1133473184 12.154 100 41 82 1 1531308928 387767328 10068416
05:25:56 8328 8192000000 10294805248 10755588096 1146444272 12.276 100 41 82 0 1384231792 228212544 9574976
05:27:12 16431 8192000000 10187910288 10671321088 1150576784 12.315 100 37 74 0 1310876192 152077040 8222368
05:28:32 52924 8192000000 10080203664 10566668288 1104533536 11.881 98 10 20 1 1197889856 90688416 2667904
05:30:34 58292 8192000000 10072695440 10555617280 1101636000 11.853 98 15 30 0 1185230096 79788624 3805472
05:32:45 24478 8192000000 10061629280 10490691584 1121604784 12.042 100 40 75 3 1159783440 30909760 7268896
05:34:19 53402 8192000000 10073153952 10497003520 1098890848 11.827 97 25 48 3 1132708080 28790080 5027152
05:36:21 59102 8192000000 10086572736 10540519424 1097179696 11.811 97 17 34 0 1157497632 56408528 3909408
05:38:21 82520 8192000000 10071291792 10506600448 1087620224 11.720 96 16 32 2 1125456656 33321728 4514704
05:40:48 152736 8192000000 10094737536 10554306560 1101634224 11.853 98 24 48 1 1170539696 63429632 5475840
05:44:39 30387 8192000000 10070978928 10526081024 1095273888 11.793 98 18 36 2 1174735728 74858064 4603776
05:46:14 20579 8192000000 10054320832 10494795776 1111894224 11.950 100 49 97 2 1155663088 33470064 10298800
05:47:41 27556 8192000000 10098992464 10531831808 1122676816 12.052 100 63 125 1 1203825856 67547120 13601920
05:49:12 30730 8192000000 10106997104 10557063168 1130229120 12.124 100 24 49 1 1199395408 63258944 5907344
05:50:50 144149 8192000000 10180136176 10623594496 1090453776 11.747 95 18 35 0 1188515920 94337872 3724272
05:54:15 43990 8192000000 10147943664 10605150208 1139428496 12.210 100 19 37 2 1249058816 105741232 3889088
05:56:01 83494 8192000000 10072259424 10501648384 1092807184 11.769 96 13 26 0 1143655376 48354864 2493328
05:58:26 75023 8192000000 10129089568 10573295616 1116797360 11.997 98 15 30 1 1202752464 82257264 3697840
06:00:41 59799 8192000000 10079295952 10513448960 1124813648 12.072 99 31 61 0 1174959648 43970704 6175296
06:02:43 45052 8192000000 10108262752 10584854528 1132856128 12.148 99 24 48 0 1210090448 72276448 4957872
06:04:38 10234 8192000000 10085004544 10508742656 1125428704 12.078 99 62 123 1 1164617088 27319520 11868864
06:05:53 102594 8192000000 10080627504 10583965696 1073996784 11.590 94 11 22 1 1123916048 47719616 2199648
06:08:36 94837 8192000000 10104918048 10573377536 1127235504 12.095 99 25 50 2 1196129952 63929760 4964688
06:11:13 20327 8192000000 10070146560 10559922176 1129691008 12.118 99 23 46 1 1163938320 29163760 5083552
06:12:34 53081 8192000000 10055576912 10521571328 1124059088 12.065 99 8 15 1 1154295296 28873536 1362672
06:15:12 55408 8192000000 10078527040 10551336960 1140030016 12.216 100 13 26 0 1202320480 59349120 2941344
06:17:13 77694 8192000000 10113555424 10581782528 1145119360 12.264 100 10 20 0 1196065072 48689376 2256336
06:19:33 72211 8192000000 10060726336 10496212992 1080590016 11.653 95 13 26 1 1124678848 41152560 2936272
06:21:47 42121 8192000000 10072532768 10503917568 1078512592 11.633 96 25 50 0 1129964080 46718240 4733248
06:23:39 47362 8192000000 10100601456 10525863936 1107810080 11.912 98 32 64 1 1161546560 47126080 6610400
06:25:26 34306 8192000000 10054348272 10468597760 1063448944 11.489 95 36 71 1 1102545328 31490176 7606208
06:27:05 64662 8192000000 10081434640 10575212544 1108436000 11.918 99 39 78 0 1229394528 112887696 8070832
06:29:10 55006 8192000000 10066346336 10481868800 1092359440 11.765 98 40 80 0 1127796384 27571936 7865008
06:31:08 61701 8192000000 10105507168 10542448640 1107761136 11.911 99 47 94 0 1172559552 55137984 9660432
06:33:30 77549 8192000000 10044408432 10534862848 1120493328 12.032 100 13 26 0 1181485840 58281216 2711296
06:36:11 64533 8192000000 10037977648 10501349376 1105573232 11.890 99 10 20 1 1142021600 34334736 2113632
06:38:42 45092 8192000000 10035847632 10474450944 1078861728 11.637 96 24 48 3 1112241296 28360768 5018800
06:40:36 50437 8192000000 10122427392 10581463040 1115275952 11.982 99 22 44 2 1197403568 77436720 4690896
06:42:31 87651 8192000000 10105245248 10566971392 1118785056 12.016 99 16 31 1 1246251664 124012432 3454176
06:44:59 40094 8192000000 10121379280 10547425280 1128438320 12.107 99 33 66 4 1188412480 53097920 6876240
06:46:42 58530 8192000000 10102615504 10626736128 1125213136 12.076 100 23 46 0 1216179264 86242880 4723248
06:48:40 132432 8192000000 10035494176 10482360320 1101630752 11.853 98 16 32 0 1130559152 25889744 3038656

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 16-08-2020 06:54:07 to 16-08-2020 06:54:21.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 74 v_add_invoice_to_stock 335544517
user_exc 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 16-08-2020 06:54:22 to 16-08-2020 06:54:23.

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          216169
MON$OLDEST_ACTIVE               217434
MON$OLDEST_SNAPSHOT             217434
MON$NEXT_TRANSACTION            217434
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                       2089824
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             677
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'

16.08.20 06:54:25. 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-16 06:54:28 NO_SEVERE_ERRORS_FOUND
16.08.20 06:54:30. End of report.