Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.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 16399988 kB
MemAvailable 30958192 kB
Buffers 0 kB
Cached 15276292 kB
SwapCached 6052 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-21 06:51:51.0980
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/60232
TRN_ID                          212763
ATT_ID                          491
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: 8056.05 27249 966726
customer order: creation 147.48 484 17698
customer order: refuse 12.43 529 1492
order to supplier: creation 39.04 1727 4685
order to supplier: removal 3.07 167101 368
invoice (draft): creation 38.88 4619 4666
invoice (draft): removal 47.72 1255 5726
invoice accept: apply 35.62 24277 4274
invoice accept: cancel 43.13 1213 5176
customer reserve: creation 3275.64 236 393077
customer reserve: removal 4149.77 96 497973
realization accept: apply 72.45 245 8694
realization accept: cancel 80.03 163 9604
payment from customer: creation 51.87 136 6224
payment from customer: removal 4.74 121 569
payment to supplier: creation 42.31 157 5077
payment to supplier: removal 2.96 128 355
service: total inventory turnovers 2.92 6511 350
service: total monetary turnovers 5.31 1013 637
service: aggregate perf. data 0.64 27409 77
service: refresh index statistics 0.03 307559 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-08-2020 06:53:35 to 21-08-2020 06:53:37.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 534
TEST_TIME 2 1682
TEST_TIME 3 3000
TEST_TIME 4 4037
TEST_TIME 5 4149
TEST_TIME 6 5038
TEST_TIME 7 4831
TEST_TIME 8 4486
TEST_TIME 9 4443
TEST_TIME 10 4433
TEST_TIME 11 4380
TEST_TIME 12 4442
TEST_TIME 13 4639
TEST_TIME 14 4599
TEST_TIME 15 4749
TEST_TIME 16 4743
TEST_TIME 17 4865
TEST_TIME 18 4971
TEST_TIME 19 5068
TEST_TIME 20 5406
TEST_TIME 21 5440
TEST_TIME 22 5617
TEST_TIME 23 5699
TEST_TIME 24 5668
TEST_TIME 25 5684
TEST_TIME 26 5741
TEST_TIME 27 6193
TEST_TIME 28 6365
TEST_TIME 29 6911
TEST_TIME 30 7263
TEST_TIME 31 7261
TEST_TIME 32 7498
TEST_TIME 33 7393
TEST_TIME 34 7745
TEST_TIME 35 7903
TEST_TIME 36 7931
TEST_TIME 37 8658
TEST_TIME 38 8833
TEST_TIME 39 8912
TEST_TIME 40 8703
TEST_TIME 41 8708
TEST_TIME 42 8743
TEST_TIME 43 8799
TEST_TIME 44 8694
TEST_TIME 45 8577
TEST_TIME 46 8504
TEST_TIME 47 8503
TEST_TIME 48 8515
TEST_TIME 49 8425
TEST_TIME 50 8404
TEST_TIME 51 8331
TEST_TIME 52 8347
TEST_TIME 53 8286
TEST_TIME 54 8186
TEST_TIME 55 8180
TEST_TIME 56 8122
TEST_TIME 57 8101
TEST_TIME 58 8026
TEST_TIME 59 7994
TEST_TIME 60 8000
TEST_TIME 61 7996
TEST_TIME 62 7892
TEST_TIME 63 7910
TEST_TIME 64 7929
TEST_TIME 65 7945
TEST_TIME 66 7944
TEST_TIME 67 7927
TEST_TIME 68 8015
TEST_TIME 69 8081
TEST_TIME 70 8192
TEST_TIME 71 8175
TEST_TIME 72 8186
TEST_TIME 73 8226
TEST_TIME 74 8327
TEST_TIME 75 8271
TEST_TIME 76 8272
TEST_TIME 77 8207
TEST_TIME 78 8168
TEST_TIME 79 8119
TEST_TIME 80 8088
TEST_TIME 81 8029
TEST_TIME 82 8078
TEST_TIME 83 8044
TEST_TIME 84 8062
TEST_TIME 85 8030
TEST_TIME 86 8026
TEST_TIME 87 8024
TEST_TIME 88 8017
TEST_TIME 89 8088
TEST_TIME 90 8123
TEST_TIME 91 8176
TEST_TIME 92 8190
TEST_TIME 93 8210
TEST_TIME 94 8223
TEST_TIME 95 8160
TEST_TIME 96 8122
TEST_TIME 97 8110
TEST_TIME 98 8060
TEST_TIME 99 8064
TEST_TIME 100 8010
TEST_TIME 101 7972
TEST_TIME 102 7937
TEST_TIME 103 7993
TEST_TIME 104 7967
TEST_TIME 105 7975
TEST_TIME 106 7971
TEST_TIME 107 7978
TEST_TIME 108 8012
TEST_TIME 109 8071
TEST_TIME 110 8137
TEST_TIME 111 8117
TEST_TIME 112 8197
TEST_TIME 113 8216
TEST_TIME 114 8245
TEST_TIME 115 8225
TEST_TIME 116 8157
TEST_TIME 117 8127
TEST_TIME 118 8133
TEST_TIME 119 8092
TEST_TIME 120 8057

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 21-08-2020 06:53:39 to 21-08-2020 06:53:42.

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 1331400 1331400 0 0.00 0 23561 9 0 0
doc_list_biud 1331399 1331399 0 0.00 0 66859 45 0 0
sp_add_invoice_to_stock 4386 4274 112 2.55 38 231334 24278 0 112
sp_cancel_adding_invoice 5176 5176 0 0.00 4 78837 1214 0 0
sp_cancel_client_order 1492 1492 0 0.00 4 17722 529 0 0
sp_cancel_customer_reserve 497973 497973 0 0.00 1 41818 97 0 0
sp_cancel_pay_from_customer 569 569 0 0.00 2 3710 122 0 0
sp_cancel_pay_to_supplier 355 355 0 0.00 2 4399 128 0 0
sp_cancel_supplier_invoice 5736 5726 10 0.17 1 66873 1256 0 10
sp_cancel_supplier_order 368 368 0 0.00 5 1689409 167101 0 0
sp_cancel_write_off 9604 9604 0 0.00 1 23563 164 0 0
sp_client_order 17698 17698 0 0.00 3 30407 485 0 0
sp_customer_reserve 393077 393077 0 0.00 0 74713 237 0 0
sp_fill_shopping_cart 27047 27047 0 0.00 1 13456 54 0 0
sp_fill_shopping_cart_clo_res 393074 393074 0 0.00 0 25329 24 0 0
sp_get_clo_for_invoice 4271 4271 0 0.00 0 39115 461 0 0
sp_kill_cost_storno 15704 15704 0 0.00 0 23559 97 0 0
sp_kill_qstorno_ret_qs2qd 1011644 1011644 0 0.00 0 66853 52 0 0
sp_kill_qty_storno 533303 533303 0 0.00 0 66858 112 0 0
sp_lock_selected_doc 17886 17886 0 0.00 0 10311 9 0 0
sp_make_cost_storno 24266 24266 0 0.00 0 14798 78 0 0
sp_multiply_rows_for_pdistr 24266 24266 0 0.00 0 13736 44 0 0
sp_multiply_rows_for_qdistr 17698 17698 0 0.00 0 30395 254 0 0
sp_pay_from_customer 6224 6224 0 0.00 4 17708 136 0 0
sp_pay_to_supplier 5077 5077 0 0.00 4 18267 157 0 0
sp_payment_common 11301 11301 0 0.00 1 14397 26 0 0
sp_qd_handle_on_cancel_clo 1492 1492 0 0.00 0 17716 493 0 0
sp_qd_handle_on_invoice_upd_sts 9447 9447 0 0.00 0 26025 544 0 0
sp_qd_handle_on_reserve_upd_sts 8693 8693 0 0.00 0 10690 72 0 0
sp_reserve_write_off 8694 8694 0 0.00 6 19690 246 0 0
sp_supplier_invoice 4666 4666 0 0.00 7 87065 4619 0 0
sp_supplier_order 4685 4685 0 0.00 5 76735 1727 0 0
srv_aggregate_perf_data 77 77 0 0.00 3140 389322 27409 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1574 131828 39630 0 0
srv_find_qd_qs_mism 926241 926241 0 0.00 0 21114 7 0 0
srv_make_invnt_saldo 350 350 0 0.00 46 160125 6512 0 0
srv_make_money_saldo 637 637 0 0.00 1 19554 1013 0 0
srv_recalc_idx_stat 4 4 0 0.00 212229 450977 307559 0 0
t$perf-abend:sp_add_invoice_to_stock 112 112 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4274 4274 0 0.00 0 9472 272 0 0
t$perf-norm:sp_cancel_adding_invoice 382 382 0 0.00 0 17254 184 0 0
t$perf-norm:sp_cancel_client_order 1492 1492 0 0.00 0 2649 18 0 0
t$perf-norm:sp_cancel_customer_reserve 559 559 0 0.00 0 637 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 569 569 0 0.00 0 1923 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 355 355 0 0.00 0 1706 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 344 344 0 0.00 0 1879 30 0 0
t$perf-norm:sp_cancel_supplier_order 368 368 0 0.00 0 46285 2755 0 0
t$perf-norm:sp_cancel_write_off 853 853 0 0.00 0 2216 14 0 0
t$perf-norm:sp_client_order 17698 17698 0 0.00 0 14145 28 0 0
t$perf-norm:sp_customer_reserve 866 866 0 0.00 0 2517 11 0 0
t$perf-norm:sp_pay_from_customer 6224 6224 0 0.00 0 5383 11 0 0
t$perf-norm:sp_pay_to_supplier 5077 5077 0 0.00 0 8666 20 0 0
t$perf-norm:sp_reserve_write_off 8694 8694 0 0.00 0 20949 16 0 0
t$perf-norm:sp_supplier_invoice 4666 4666 0 0.00 0 10358 34 0 0
t$perf-norm:sp_supplier_order 4685 4685 0 0.00 0 4458 29 0 0
t$perf-norm:srv_aggregate_perf_data 77 77 0 0.00 0 941 28 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 8785 140 0 0
t$perf-norm:srv_make_invnt_saldo 350 350 0 0.00 0 1969 17 0 0
t$perf-norm:srv_make_money_saldo 637 637 0 0.00 0 4578 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 15 6 0 0
v_add_invoice_to_stock 8766 8542 224 2.55 0 7623 20 0 224
v_all_customers 32478 32478 0 0.00 0 146 1 0 0
v_all_suppliers 26114 26114 0 0.00 0 27 1 0 0
v_all_wares 35396 35396 0 0.00 0 83 1 0 0
v_cancel_adding_invoice 764 764 0 0.00 0 562 8 0 0
v_cancel_client_order 2984 2984 0 0.00 0 1524 5 0 0
v_cancel_customer_prepayment 1138 1138 0 0.00 0 2282 9 0 0
v_cancel_customer_reserve 1118 1118 0 0.00 0 1864 7 0 0
v_cancel_payment_to_supplier 710 710 0 0.00 0 568 6 0 0
v_cancel_supplier_invoice 708 688 20 2.82 0 4686 27 0 20
v_cancel_supplier_order 736 736 0 0.00 0 583 7 0 0
v_cancel_write_off 1706 1706 0 0.00 0 1427 15 0 0
v_max_id_clo_ord 4685 4685 0 0.00 0 172 1 0 0
v_max_id_clo_res 866 866 0 0.00 0 1817 9 0 0
v_max_id_ord_sup 4664 4664 0 0.00 0 7002 6 0 0
v_max_non_paid_invoice 1369 1369 0 0.00 0 65 2 0 0
v_max_non_paid_realizn 3254 3254 0 0.00 0 7768 7 0 0
v_min_id_clo_ord 4685 4685 0 0.00 0 1835 7 0 0
v_min_id_clo_res 866 866 0 0.00 0 2251 4 0 0
v_min_id_ord_sup 4664 4664 0 0.00 0 3209 5 0 0
v_min_non_paid_invoice 5077 5077 0 0.00 0 9475 17 0 0
v_min_non_paid_realizn 6224 6224 0 0.00 0 9931 17 0 0
v_our_firm 8858 8858 0 0.00 0 19 1 0 0
v_reserve_write_off 17386 17386 0 0.00 0 1493 4 0 0
x_lock_depdocs_on_canc_invoice 10558 10558 0 0.00 0 11587 159 0 0
x_lock_depdocs_on_canc_sup_ord 368 368 0 0.00 0 32881 655 0 0
x_make_qstorno_1000_1200 4685 4685 0 0.00 1 76726 1612 0 0
x_make_qstorno_1200_2000 4666 4666 0 0.00 3 87020 4410 0 0
x_make_qstorno_2100_3300 393077 393077 0 0.00 0 74711 209 0 0

Completed "Performance in DETAILS". Done for 00:00:03, from 21-08-2020 06:53:43 to 21-08-2020 06:53:46.

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:54 2 8192000000 8796612016 8807043072 3144832 0.038 1 1 2 0 3242128 61344 35952
04:22:54 29410 8192000000 9713423056 10005811200 791753056 8.813 100 88 176 1 820422224 7344704 21324464
04:24:29 7716 8192000000 9740712272 10039762944 817751568 9.076 100 84 168 0 848979776 11270032 19958176
04:25:40 13138 8192000000 9771415952 10078605312 843704560 9.337 100 87 174 1 880845968 16879632 20261776
04:26:59 13495 8192000000 9827172320 10148397056 884318288 9.743 100 83 164 1 934243136 31533472 18391376
04:28:16 10861 8192000000 9887320832 10227728384 930494320 10.199 100 75 150 0 999111360 52578288 16038752
04:29:31 8491 8192000000 9953336480 10299686912 973051520 10.616 100 86 170 2 1063709168 73150848 17506800
04:30:43 5126 8192000000 9975603856 10331410432 1002300400 10.901 100 81 163 2 1090870864 72230720 16339744
04:31:50 2851 8192000000 9989628208 10353344512 1024367952 11.114 100 79 158 0 1100170544 59876992 15925600
04:32:55 1563 8192000000 10010720016 10374688768 1047048512 11.332 100 88 175 0 1121508800 56194576 18265712
04:33:59 11724 8192000000 10029280080 10402058240 1062275856 11.478 100 66 130 0 1137595008 62407712 12911440
04:35:15 11235 8192000000 10008230480 10393288704 1071360240 11.565 100 52 106 2 1128120336 45564240 11195856
04:36:33 3422 8192000000 10021235600 10407194624 1078616672 11.634 100 56 112 0 1133817184 42954288 12246224
04:37:37 5155 8192000000 10013499216 10395676672 1084723760 11.692 100 58 117 5 1114501200 17004368 12773072
04:38:45 5304 8192000000 10002046848 10396577792 1096495744 11.804 100 41 81 4 1132973184 27805312 8672128
04:39:51 17394 8192000000 10020395408 10402177024 1095339664 11.793 100 77 154 1 1126782160 14533264 16909232
04:41:09 8579 8192000000 9999977984 10395594752 1090268800 11.745 100 32 62 0 1111823376 14270416 7284160
04:42:18 21012 8192000000 9985361168 10393800704 1055591824 11.414 97 21 42 3 1081246640 21516960 4137856
04:43:45 6466 8192000000 9982830224 10368536576 1033144624 11.199 96 33 66 1 1071012080 30224096 7643360
04:44:52 4180 8192000000 9943338528 10321715200 1020500224 11.077 99 35 72 2 1047315280 19284064 7530992
04:45:57 58107 8192000000 9912449680 10300194816 920987744 10.106 90 23 46 0 950050176 24507408 4555024
04:47:59 8961 8192000000 9943098800 10324537344 1007360704 10.950 99 30 60 2 1048471888 34877040 6234144
04:49:09 5270 8192000000 9952639808 10332684288 1040463088 11.269 100 59 115 3 1063642832 9756720 13423024
04:50:15 9199 8192000000 9968089344 10365800448 1050477008 11.365 100 40 79 3 1080409440 20911216 9021216
04:51:26 16735 8192000000 10001559824 10395611136 1066463120 11.518 100 42 85 4 1108276144 33187664 8625360
04:52:53 33556 8192000000 10012770480 10404921344 1085253936 11.698 100 48 96 1 1119813264 24526048 10033280
04:54:30 18037 8192000000 10066315120 10454671360 1099795648 11.836 100 55 110 0 1173271600 61908704 11567248
04:55:52 9420 8192000000 10086706224 10482753536 1109557168 11.928 100 52 100 3 1202189904 82028032 10604704
04:57:12 19179 8192000000 10133725168 10537525248 1120435536 12.031 100 60 120 1 1248916160 115493440 12987184
04:58:51 26202 8192000000 10123127600 10522337280 1130360560 12.125 100 54 104 1 1214611456 73415360 10835536
05:00:23 16454 8192000000 10195889808 10595647488 1152065824 12.329 100 61 122 2 1300216768 135031104 13119840
05:01:49 11478 8192000000 10217163744 10617446400 1155615600 12.362 100 57 113 1 1314578768 146281808 12681360
05:03:03 16330 8192000000 10278783328 10684448768 1159957744 12.403 100 56 112 1 1370120272 196697520 13465008
05:04:30 18013 8192000000 10271989584 10690211840 1160856608 12.411 100 51 102 0 1383471072 210963744 11650720
05:06:04 4201 8192000000 10320510736 10731225088 1172089184 12.516 100 71 141 3 1422018448 234140224 15789040
05:07:15 7663 8192000000 10335360032 10737070080 1164420080 12.445 100 65 130 1 1434066128 254940432 14705616
05:08:34 8699 8192000000 10337555024 10746023936 1170686720 12.503 100 64 127 0 1431448304 245910464 14851120
05:09:48 9074 8192000000 10339467312 10760060928 1178816800 12.579 100 60 120 2 1452810832 259825200 14168832
05:11:02 5289 8192000000 10391729984 10808422400 1189015760 12.674 100 83 166 1 1495464016 287067376 19380880
05:12:13 7033 8192000000 10425471488 10830200832 1191893040 12.701 100 78 155 1 1520163776 310061536 18209200
05:13:29 3466 8192000000 10426017552 10828476416 1176894288 12.561 100 80 161 1 1520422800 324599088 18929424
05:14:42 2441 8192000000 10435873024 10840031232 1172385776 12.519 100 81 163 1 1533758544 342765040 18607728
05:15:54 1571 8192000000 10473584704 10871754752 1158829760 12.392 99 80 160 0 1560083440 382050800 19202880
05:17:04 1903 8192000000 10513912400 10908999680 1142140880 12.236 100 94 186 1 1608225152 444058336 22025936
05:18:16 2425 8192000000 10484199952 10940805120 1151563376 12.324 100 87 170 1 1612441056 440198688 20678992
05:19:26 3844 8192000000 10475286736 10888773632 1153283600 12.340 100 75 148 0 1565411024 394231232 17896192
05:20:40 10097 8192000000 10443937232 10880655360 1158038240 12.385 100 58 116 0 1553518864 381078336 14402288
05:21:54 10349 8192000000 10371041616 10795790336 1166317408 12.462 100 43 86 3 1469926912 293017744 10591760
05:23:13 12352 8192000000 10364406960 10823192576 1161588768 12.418 100 39 78 1 1467490816 296277616 9624432
05:24:54 10548 8192000000 10388357584 10847293440 1158662352 12.391 100 40 80 0 1495388272 326881680 9844240
05:26:24 21196 8192000000 10358363216 10884263936 1152053776 12.329 100 21 42 0 1465646048 308101504 5490768
05:28:09 8011 8192000000 10280600064 10757337088 1132838416 12.148 100 36 72 1 1385674128 244086016 8749696
05:29:22 6359 8192000000 10134680752 10586767360 1150366720 12.313 100 43 82 1 1224179264 64982592 8829952
05:30:35 87746 8192000000 10154292304 10592374784 1149182992 12.302 100 23 45 3 1265957200 111338224 5435984
05:33:05 27711 8192000000 10138807216 10565173248 1143139328 12.245 100 62 120 3 1241785168 84559680 14086160
05:34:35 61131 8192000000 10052462128 10514141184 1110609776 11.938 97 10 20 2 1151877456 38747904 2519776
05:36:39 96302 8192000000 10102794048 10544050176 1116932432 11.998 98 27 54 0 1181980880 58367216 6681232
05:39:23 92225 8192000000 10080318816 10511613952 1098946144 11.828 97 16 32 0 1133813488 31678384 3188960
05:42:00 24391 8192000000 10096585200 10541395968 1131588592 12.136 100 21 43 0 1194050560 58005456 4456512
05:43:42 105660 8192000000 10058540528 10503847936 1080901632 11.656 98 15 30 1 1118395856 33784464 3709760
05:46:40 75146 8192000000 10039031392 10508279808 1110751008 11.940 100 18 35 3 1170312000 55763728 3797264
05:49:08 16368 8192000000 10077459200 10499411968 1126204896 12.086 100 48 95 2 1173132240 36636368 10290976
05:50:34 56348 8192000000 10088886688 10570813440 1135642176 12.175 100 25 50 0 1229768880 88477376 5649328
05:52:38 59904 8192000000 10079490416 10548273152 1142423088 12.238 100 13 26 2 1194258784 49273232 2562464
05:54:48 51990 8192000000 10082950608 10592210944 1135740704 12.175 100 9 18 0 1181274304 43998016 1535584
05:56:43 75652 8192000000 10130519968 10593398784 1128930048 12.111 99 18 36 1 1197105312 64334752 3840512
05:59:45 76840 8192000000 10134482352 10610925568 1132572400 12.146 99 21 42 1 1270549024 133411856 4564768
06:02:05 69052 8192000000 10125986336 10539991040 1094135856 11.782 96 38 74 2 1164031632 62484848 7410928
06:04:15 73011 8192000000 10105834944 10604011520 1104994912 11.885 98 21 41 2 1207837552 98773808 4068832
06:06:28 38120 8192000000 10077424976 10491539456 1059638304 11.453 94 56 107 1 1107850032 37324016 10887712
06:08:07 131828 8192000000 10051852736 10512777216 1064136768 11.496 96 17 34 1 1119760448 51974160 3649520
06:11:22 61830 8192000000 10053526720 10510864384 1094887936 11.789 99 7 14 0 1140673696 44211792 1573968
06:14:08 27235 8192000000 10024493008 10448900096 1099715088 11.835 100 21 43 2 1131268240 26382368 5170784
06:15:45 51064 8192000000 10034717232 10452250624 1056153184 11.420 95 14 27 1 1094847888 35723120 2971584
06:17:41 63589 8192000000 10112614272 10558296064 1102931008 11.865 98 22 44 1 1176222672 68607376 4684288
06:19:48 49579 8192000000 10101424864 10586042368 1112424400 11.955 99 20 39 2 1215269840 98376560 4468880
06:22:00 49105 8192000000 10055889840 10482036736 1124300240 12.068 100 10 19 1 1160452160 34709280 1442640
06:24:05 38233 8192000000 10067018672 10494881792 1116508656 11.994 99 28 56 1 1168606336 46833904 5263776
06:25:44 18591 8192000000 10093637536 10522050560 1125557280 12.079 99 41 82 1 1178257248 43439952 9260016
06:27:07 72205 8192000000 10043327744 10508877824 1105899536 11.894 98 21 42 2 1157032400 46608304 4524560
06:29:31 39428 8192000000 10067675120 10517065728 1118441712 12.012 99 6 12 2 1155027024 34940288 1645024
06:32:02 61370 8192000000 10096606000 10536689664 1100731792 11.845 97 15 30 1 1160707728 56903264 3072672
06:35:35 47561 8192000000 10108071648 10539929600 1117634560 12.005 98 32 64 2 1191155408 66483024 7037824
06:37:33 56599 8192000000 10150822704 10604056576 1116844080 11.997 98 27 54 2 1230870048 107918272 6107696
06:39:41 78569 8192000000 10118981328 10566918144 1115045984 11.980 98 21 41 1 1268644656 148718784 4879888
06:42:19 44442 8192000000 10143747312 10583621632 1134634432 12.165 100 27 54 0 1240610192 100471072 5504688
06:44:05 85847 8192000000 10058246992 10489090048 1108522224 11.918 98 21 41 2 1152656208 40060016 4073968
06:46:31 112331 8192000000 10092984896 10537578496 1135781584 12.176 100 18 36 0 1202066528 62831568 3453376
06:49:24 30012 8192000000 10060848864 10549829632 1116678096 11.996 99 5 10 0 1153431072 35764896 988080
06:50:54 63639 8192000000 9464699696 9831751680 564262400 6.444 51 4 6 1 588491504 23536816 692288

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 112 v_add_invoice_to_stock 335544517
user_exc 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-08-2020 06:54:03 to 21-08-2020 06:54:05.

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          211840
MON$OLDEST_ACTIVE               212971
MON$OLDEST_SNAPSHOT             212971
MON$NEXT_TRANSACTION            212971
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                       2094400
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             670
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 ---
:29: 
:30: mscs-test-161.budzdorov.ru    Fri Aug 21 06:52:10 2020
:31:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:32: 
:33: 
:34: mscs-test-161.budzdorov.ru    Fri Aug 21 06:52:10 2020
:35:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:36: 
--- end of diff output ---

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

21.08.20 06:54:07. 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-21 06:54:10 NO_SEVERE_ERRORS_FOUND
21.08.20 06:54:12. End of report.