Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.10.2020 10:06
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 e0e7b46250ba4f9795ceb55e0face73f
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2020-09-25 13:42
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
./oltp_isql_run_worker.sh line 45: dmidecode: command not found
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.00
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 32643792 kB
MemFree 16750168 kB
MemAvailable 31577452 kB
Buffers 76 kB
Cached 14870212 kB
SwapCached 6064 kB
Command: fdisk -l
./oltp_isql_run_worker.sh: line 45: fdisk: command not found

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=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2020-10-10 10:05:48
DTS_END                         2020-10-10 10:05:27.6590
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(67:17)
IP                              ::1/36394
TRN_ID                          231870
ATT_ID                          242
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: 8774.71 21756 1052965
customer order: creation 163.65 464 19638
customer order: refuse 13.67 326 1640
order to supplier: creation 42.96 1421 5155
order to supplier: removal 3.48 118103 418
invoice (draft): creation 43.68 3897 5241
invoice (draft): removal 52.11 929 6253
invoice accept: apply 38.79 17743 4655
invoice accept: cancel 46.38 899 5565
customer reserve: creation 3545.88 176 425505
customer reserve: removal 4547.47 74 545696
realization accept: apply 78.93 187 9471
realization accept: cancel 77.31 130 9277
payment from customer: creation 55.25 109 6630
payment from customer: removal 5.39 75 647
payment to supplier: creation 46.35 130 5562
payment to supplier: removal 3.14 67 377
service: total inventory turnovers 3.56 3537 427
service: total monetary turnovers 5.82 484 698
service: aggregate perf. data 0.88 17100 106
service: refresh index statistics 0.03 269276 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-10-2020 10:06:45 to 10-10-2020 10:06:47.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8911
TEST_TIME 2 6737
TEST_TIME 3 6651
TEST_TIME 4 6297
TEST_TIME 5 5866
TEST_TIME 6 5774
TEST_TIME 7 5701
TEST_TIME 8 5663
TEST_TIME 9 6416
TEST_TIME 10 6514
TEST_TIME 11 6519
TEST_TIME 12 7245
TEST_TIME 13 7586
TEST_TIME 14 7836
TEST_TIME 15 7918
TEST_TIME 16 7901
TEST_TIME 17 8102
TEST_TIME 18 8399
TEST_TIME 19 8473
TEST_TIME 20 8529
TEST_TIME 21 8512
TEST_TIME 22 8744
TEST_TIME 23 8804
TEST_TIME 24 9171
TEST_TIME 25 9443
TEST_TIME 26 9992
TEST_TIME 27 11291
TEST_TIME 28 11369
TEST_TIME 29 11028
TEST_TIME 30 10844
TEST_TIME 31 10654
TEST_TIME 32 10504
TEST_TIME 33 10456
TEST_TIME 34 10246
TEST_TIME 35 10070
TEST_TIME 36 10065
TEST_TIME 37 9907
TEST_TIME 38 10056
TEST_TIME 39 9889
TEST_TIME 40 9746
TEST_TIME 41 9655
TEST_TIME 42 9523
TEST_TIME 43 9498
TEST_TIME 44 9396
TEST_TIME 45 9555
TEST_TIME 46 9502
TEST_TIME 47 9563
TEST_TIME 48 9504
TEST_TIME 49 9546
TEST_TIME 50 9531
TEST_TIME 51 9551
TEST_TIME 52 9462
TEST_TIME 53 9525
TEST_TIME 54 9585
TEST_TIME 55 9627
TEST_TIME 56 9605
TEST_TIME 57 9457
TEST_TIME 58 9338
TEST_TIME 59 9294
TEST_TIME 60 9321
TEST_TIME 61 9205
TEST_TIME 62 9121
TEST_TIME 63 9157
TEST_TIME 64 9079
TEST_TIME 65 9096
TEST_TIME 66 9102
TEST_TIME 67 9118
TEST_TIME 68 9154
TEST_TIME 69 9178
TEST_TIME 70 9236
TEST_TIME 71 9233
TEST_TIME 72 9321
TEST_TIME 73 9319
TEST_TIME 74 9279
TEST_TIME 75 9339
TEST_TIME 76 9322
TEST_TIME 77 9316
TEST_TIME 78 9248
TEST_TIME 79 9190
TEST_TIME 80 9100
TEST_TIME 81 9028
TEST_TIME 82 8985
TEST_TIME 83 8982
TEST_TIME 84 8981
TEST_TIME 85 8957
TEST_TIME 86 8946
TEST_TIME 87 8943
TEST_TIME 88 8910
TEST_TIME 89 8895
TEST_TIME 90 8922
TEST_TIME 91 8885
TEST_TIME 92 9208
TEST_TIME 93 9196
TEST_TIME 94 9189
TEST_TIME 95 9187
TEST_TIME 96 9139
TEST_TIME 97 9052
TEST_TIME 98 9026
TEST_TIME 99 8959
TEST_TIME 100 8900
TEST_TIME 101 8910
TEST_TIME 102 8918
TEST_TIME 103 8912
TEST_TIME 104 8903
TEST_TIME 105 8882
TEST_TIME 106 8883
TEST_TIME 107 8902
TEST_TIME 108 8945
TEST_TIME 109 9080
TEST_TIME 110 9073
TEST_TIME 111 9107
TEST_TIME 112 9083
TEST_TIME 113 9049
TEST_TIME 114 8975
TEST_TIME 115 8941
TEST_TIME 116 8885
TEST_TIME 117 8881
TEST_TIME 118 8847
TEST_TIME 119 8826
TEST_TIME 120 8779

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 10-10-2020 10:06:49 to 10-10-2020 10:06:52.

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 1449978 1449978 0 0.00 0 22024 6 0 0
doc_list_biud 1449976 1449976 0 0.00 0 77864 34 0 0
sp_add_invoice_to_stock 4758 4655 103 2.16 42 155521 17744 0 103
sp_cancel_adding_invoice 5565 5565 0 0.00 6 81732 900 0 0
sp_cancel_client_order 1640 1640 0 0.00 4 15276 326 0 0
sp_cancel_customer_reserve 545696 545696 0 0.00 1 77866 74 0 0
sp_cancel_pay_from_customer 647 647 0 0.00 3 2235 76 0 0
sp_cancel_pay_to_supplier 377 377 0 0.00 3 2285 67 0 0
sp_cancel_supplier_invoice 6261 6253 8 0.12 2 25327 929 0 8
sp_cancel_supplier_order 418 418 0 0.00 13 1034232 118104 0 0
sp_cancel_write_off 9277 9277 0 0.00 1 7947 131 0 0
sp_client_order 19638 19638 0 0.00 4 44495 464 0 0
sp_customer_reserve 425505 425505 0 0.00 0 54362 176 0 0
sp_fill_shopping_cart 30025 30025 0 0.00 1 26092 51 0 0
sp_fill_shopping_cart_clo_res 425498 425498 0 0.00 0 23830 19 0 0
sp_get_clo_for_invoice 4645 4645 0 0.00 0 51369 420 0 0
sp_kill_cost_storno 15866 15866 0 0.00 0 7881 55 0 0
sp_kill_qstorno_ret_qs2qd 1107340 1107340 0 0.00 0 77861 40 0 0
sp_kill_qty_storno 582964 582964 0 0.00 0 77864 84 0 0
sp_lock_selected_doc 19586 19586 0 0.00 0 9246 6 0 0
sp_make_cost_storno 26307 26307 0 0.00 0 14920 61 0 0
sp_multiply_rows_for_pdistr 26307 26307 0 0.00 0 11878 32 0 0
sp_multiply_rows_for_qdistr 19638 19638 0 0.00 0 44484 296 0 0
sp_pay_from_customer 6630 6630 0 0.00 4 11886 110 0 0
sp_pay_to_supplier 5562 5562 0 0.00 5 12513 131 0 0
sp_payment_common 12190 12190 0 0.00 1 6691 25 0 0
sp_qd_handle_on_cancel_clo 1640 1640 0 0.00 0 15266 300 0 0
sp_qd_handle_on_invoice_upd_sts 10209 10209 0 0.00 0 11377 356 0 0
sp_qd_handle_on_reserve_upd_sts 9471 9471 0 0.00 0 9238 48 0 0
sp_reserve_write_off 9471 9471 0 0.00 6 13537 187 0 0
sp_supplier_invoice 5241 5241 0 0.00 6 116238 3898 0 0
sp_supplier_order 5155 5155 0 0.00 6 56233 1421 0 0
srv_aggregate_perf_data 106 106 0 0.00 3032 162701 17100 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1736 141682 47746 0 0
srv_find_qd_qs_mism 1009870 1009870 0 0.00 0 22024 5 0 0
srv_make_invnt_saldo 427 427 0 0.00 59 90780 3537 0 0
srv_make_money_saldo 698 698 0 0.00 1 22114 484 0 0
srv_recalc_idx_stat 4 4 0 0.00 49124 482978 269276 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 4655 4655 0 0.00 0 10253 153 0 0
t$perf-norm:sp_cancel_adding_invoice 388 388 0 0.00 1 6059 93 0 0
t$perf-norm:sp_cancel_client_order 1640 1640 0 0.00 0 3342 12 0 0
t$perf-norm:sp_cancel_customer_reserve 660 660 0 0.00 0 7547 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 647 647 0 0.00 0 200 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 377 377 0 0.00 0 4060 18 0 0
t$perf-norm:sp_cancel_supplier_invoice 392 392 0 0.00 0 1354 21 0 0
t$perf-norm:sp_cancel_supplier_order 418 418 0 0.00 0 16969 1392 0 0
t$perf-norm:sp_cancel_write_off 949 949 0 0.00 0 1713 5 0 0
t$perf-norm:sp_client_order 19638 19638 0 0.00 0 7450 17 0 0
t$perf-norm:sp_customer_reserve 958 958 0 0.00 0 1636 6 0 0
t$perf-norm:sp_pay_from_customer 6630 6630 0 0.00 0 6548 9 0 0
t$perf-norm:sp_pay_to_supplier 5562 5562 0 0.00 0 6961 10 0 0
t$perf-norm:sp_reserve_write_off 9471 9471 0 0.00 0 3154 8 0 0
t$perf-norm:sp_supplier_invoice 5241 5241 0 0.00 0 7936 19 0 0
t$perf-norm:sp_supplier_order 5155 5155 0 0.00 0 4911 17 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 233 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 159 3 0 0
t$perf-norm:srv_make_invnt_saldo 427 427 0 0.00 0 2344 11 0 0
t$perf-norm:srv_make_money_saldo 698 698 0 0.00 0 1982 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 9494 9288 206 2.16 0 7105 18 0 206
v_all_customers 35504 35504 0 0.00 0 79 1 0 0
v_all_suppliers 29032 29032 0 0.00 0 23 1 0 0
v_all_wares 39276 39276 0 0.00 0 137 1 0 0
v_cancel_adding_invoice 776 776 0 0.00 0 65 6 0 0
v_cancel_client_order 3280 3280 0 0.00 0 4877 5 0 0
v_cancel_customer_prepayment 1294 1294 0 0.00 0 2095 7 0 0
v_cancel_customer_reserve 1320 1320 0 0.00 0 1230 4 0 0
v_cancel_payment_to_supplier 754 754 0 0.00 0 411 4 0 0
v_cancel_supplier_invoice 800 784 16 2.00 0 908 11 0 16
v_cancel_supplier_order 836 836 0 0.00 0 2107 11 0 0
v_cancel_write_off 1898 1898 0 0.00 0 5763 21 0 0
v_max_id_clo_ord 5154 5154 0 0.00 0 1327 2 0 0
v_max_id_clo_res 958 958 0 0.00 0 3174 19 0 0
v_max_id_ord_sup 5233 5233 0 0.00 0 8649 8 0 0
v_max_non_paid_invoice 1432 1432 0 0.00 0 6078 8 0 0
v_max_non_paid_realizn 3666 3666 0 0.00 0 923 3 0 0
v_min_id_clo_ord 5154 5154 0 0.00 0 5001 9 0 0
v_min_id_clo_res 958 958 0 0.00 0 6716 22 0 0
v_min_id_ord_sup 5233 5233 0 0.00 0 10473 8 0 0
v_min_non_paid_invoice 5561 5561 0 0.00 0 6641 16 0 0
v_min_non_paid_realizn 6629 6629 0 0.00 0 6686 17 0 0
v_our_firm 9698 9698 0 0.00 0 14 1 0 0
v_reserve_write_off 18942 18942 0 0.00 0 5674 5 0 0
x_lock_depdocs_on_canc_invoice 11426 11426 0 0.00 0 19761 103 0 0
x_lock_depdocs_on_canc_sup_ord 418 418 0 0.00 0 2346 268 0 0
x_make_qstorno_1000_1200 5155 5155 0 0.00 2 56106 1340 0 0
x_make_qstorno_1200_2000 5241 5241 0 0.00 3 102766 3694 0 0
x_make_qstorno_2100_3300 425505 425505 0 0.00 0 54362 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-10-2020 10:06:53 to 10-10-2020 10:06:57.

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:35:31 2 8192000000 8796624000 8807108608 3156752 0.038 1 1 2 0 3254112 61504 35856
07:36:31 20210 8192000000 9711798832 10006446080 786502272 8.759 100 85 170 0 816362416 7809936 22050208
07:37:55 9902 8192000000 9774462496 10080096256 843669056 9.337 100 88 176 0 876540752 11997312 20874384
07:39:13 19334 8192000000 9810877184 10130300928 874391584 9.644 100 85 171 2 912871072 18890240 19589248
07:40:47 10604 8192000000 9884816656 10216771584 922146016 10.117 100 80 160 1 990031040 50655280 17229744
07:42:00 5759 8192000000 9933053776 10276311040 952563344 10.416 100 79 157 1 1044688480 75795968 16329168
07:43:07 3079 8192000000 9957658080 10314981376 997829488 10.857 100 79 157 1 1076104544 62743120 15531936
07:44:12 7152 8192000000 9998760912 10365337600 1025626752 11.126 100 75 152 1 1122527088 82285168 14615168
07:45:20 1630 8192000000 10030938736 10397360128 1050318064 11.364 100 97 194 2 1142060288 71956880 19785344
07:46:22 5526 8192000000 9992022464 10392764416 1059447776 11.451 100 35 70 0 1102801024 36496672 6856576
07:47:28 6602 8192000000 9986871360 10378518528 1065319952 11.507 100 28 55 0 1104020976 33466992 5234032
07:48:35 6451 8192000000 9984313408 10377547776 1073076944 11.581 100 35 69 1 1095895888 15752000 7066944
07:49:44 41211 8192000000 9976229152 10387501056 1075857280 11.608 100 15 30 0 1104280048 25331888 3090880
07:51:25 5613 8192000000 10007104512 10409852928 1086880384 11.713 100 45 91 2 1114681824 18298128 9503312
07:52:33 5305 8192000000 10021173920 10423873536 1087253824 11.717 100 37 72 1 1133009776 38083696 7672256
07:53:38 5398 8192000000 9998676800 10395705344 1073817200 11.589 99 28 54 2 1108842192 29455568 5569424
07:54:50 18753 8192000000 9995685216 10400260096 1091589472 11.758 100 27 55 0 1114675808 17204816 5881520
07:56:09 6660 8192000000 9990380080 10382352384 1039835984 11.263 97 38 72 1 1070728256 22960112 7932160
07:57:16 7159 8192000000 9906446416 10284154880 964980432 10.538 94 33 67 3 989283936 16846720 7456784
07:58:24 78823 8192000000 9931056608 10317987840 964378480 10.532 94 24 47 2 982402640 11818608 6205552
08:00:43 4890 8192000000 9959812064 10338873344 1033921552 11.206 100 44 89 1 1067508240 24187024 9399664
08:01:49 4972 8192000000 9975621744 10361729024 1047357440 11.335 100 54 108 2 1080454064 21493456 11603168
08:02:57 8688 8192000000 9968711440 10361667584 1055335072 11.412 100 33 66 3 1094177424 31137456 7704896
08:04:10 25575 8192000000 9973226736 10379624448 1058040880 11.438 100 28 53 5 1094721040 30897120 5783040
08:05:41 11443 8192000000 10004720544 10395684864 1084818256 11.693 100 48 96 0 1112623216 18048000 9756960
08:06:56 4038 8192000000 10053560336 10450468864 1097464464 11.814 100 58 114 2 1163530256 54032144 12033648
08:08:08 5979 8192000000 10067358880 10461528064 1110845984 11.940 100 55 107 1 1170791616 48578464 11367168
08:09:20 4353 8192000000 10090560720 10488156160 1122695008 12.052 100 52 102 1 1199334448 66182688 10456752
08:10:30 18405 8192000000 10111805936 10519994368 1125674848 12.081 100 36 72 0 1215441120 81222800 8543472
08:11:55 14363 8192000000 10148053152 10552377344 1135731680 12.175 100 44 88 2 1252200736 106266560 10202496
08:13:14 5059 8192000000 10154612480 10564390912 1140989664 12.225 100 47 93 4 1258087264 106808752 10288848
08:14:22 6559 8192000000 10203661424 10629505024 1147675568 12.288 100 62 123 0 1305269344 143707184 13886592
08:15:35 7600 8192000000 10219032320 10626850816 1146280160 12.275 100 74 147 1 1317578640 155341184 15957296
08:16:49 3134 8192000000 10234043408 10628812800 1142250080 12.237 100 74 143 4 1324850272 166545568 16054624
08:17:59 1734 8192000000 10236754832 10640084992 1145866368 12.271 100 85 170 0 1337255360 172078592 19310400
08:19:04 5909 8192000000 10251539296 10652164096 1153256496 12.340 100 77 153 1 1356474928 185898976 17319456
08:20:15 9013 8192000000 10248285664 10641436672 1114517504 11.975 98 79 158 1 1330669376 198440128 17711744
08:21:28 4634 8192000000 10304468288 10701148160 1151058352 12.319 100 82 164 0 1393990576 223938928 18993296
08:22:42 2090 8192000000 10325225904 10724061184 1145561168 12.268 100 83 166 1 1427445584 263082128 18802288
08:23:46 14690 8192000000 10331645728 10753372160 1136899056 12.186 99 35 70 2 1419178976 273186656 9093264
08:25:10 5039 8192000000 10376539600 10790281216 1157445712 12.379 100 46 92 1 1479077632 308741264 12890656
08:26:23 8272 8192000000 10400377968 10829242368 1144939920 12.262 99 41 82 0 1493501872 338250128 10311824
08:27:42 16723 8192000000 10416327552 10836557824 1122965264 12.055 98 43 86 1 1492472768 358912912 10594592
08:29:08 13592 8192000000 10462131872 10885644288 1122184736 12.048 100 31 62 0 1557029456 426034576 8810144
08:30:36 47569 8192000000 10425449984 10860015616 1106243168 11.897 99 24 48 0 1500830832 387850288 6737376
08:32:56 30714 8192000000 10026663120 10476957696 1097771808 11.816 99 8 16 1 1138865984 39319360 1774816
08:34:28 22070 8192000000 10040612688 10456870912 1102448816 11.861 99 68 135 2 1131297264 14323200 14525248
08:35:56 13267 8192000000 10065955424 10486706176 1108609792 11.919 99 56 112 1 1158266080 38335664 11320624
08:37:17 95177 8192000000 10066228016 10524073984 1125460368 12.079 100 27 55 0 1190605808 58979968 6165472
08:39:53 99500 8192000000 10108736192 10579271680 1134089552 12.160 99 19 38 0 1226516176 88045488 4381136
08:42:41 120829 8192000000 10086040928 10604691456 1138323424 12.200 100 22 44 0 1223610928 80268160 5019344
08:46:55 86011 8192000000 10100295920 10548400128 1134994720 12.168 99 11 22 0 1172662368 35263488 2404160
08:49:27 33865 8192000000 10130571632 10560163840 1154746464 12.354 100 48 92 2 1227201056 62133536 10321056
08:51:05 91969 8192000000 10068922048 10538479616 1122102976 12.047 98 8 16 1 1181273520 57461760 1708784
08:53:38 53992 8192000000 10103406272 10561294336 1149838160 12.308 100 17 34 0 1188271280 33984736 4448384
08:55:39 55771 8192000000 10089379280 10533167104 1114234672 11.972 97 17 34 1 1161612080 43559936 3817472
08:57:39 64174 8192000000 10103361216 10569273344 1127025792 12.093 98 20 39 1 1191158832 59756032 4377008
08:59:43 74436 8192000000 10075903712 10545344512 1097426672 11.813 96 9 18 0 1173843216 74504688 1911856
09:02:04 96794 8192000000 10062210528 10498813952 1139715392 12.213 100 14 28 0 1174303056 31920288 2667376
09:04:42 76685 8192000000 10093946736 10597703680 1117077344 11.999 97 19 39 2 1207451888 86427248 3947296
09:07:30 36605 8192000000 10115225792 10619596800 1121082704 12.037 98 13 26 0 1199601120 76208512 2309904
09:09:40 79491 8192000000 10080348352 10538373120 1073380736 11.584 94 15 30 0 1137119344 60537168 3201440
09:11:59 51703 8192000000 10100986768 10547548160 1109241904 11.925 97 24 48 1 1166680624 52334912 5103808
09:13:55 43572 8192000000 10110344768 10572382208 1075153520 11.601 95 16 32 1 1149035408 70487472 3394416
09:15:53 49197 8192000000 10099507856 10519789568 1110052768 11.933 98 29 56 1 1154115984 38001952 6061264
09:17:48 73413 8192000000 10069022192 10485846016 1092212320 11.764 97 33 66 2 1129876672 30486672 7177680
09:20:04 43638 8192000000 10057942848 10530242560 1093110576 11.772 97 22 44 1 1174067408 76579856 4376976
09:21:48 120475 8192000000 10063216912 10523324416 1086113296 11.706 97 30 60 1 1165743296 73497840 6132160
09:24:54 72656 8192000000 10026907776 10466643968 1093050144 11.772 98 9 18 0 1129324480 34562928 1711408
09:27:09 68938 8192000000 10070141040 10494726144 1091594592 11.758 98 11 22 0 1165039728 71151328 2293808
09:29:19 63149 8192000000 10064731600 10494943232 1072432032 11.575 96 11 22 0 1129883616 55028640 2422944
09:31:34 43297 8192000000 10132528528 10560905216 1117868560 12.007 99 25 47 0 1196187872 72880256 5439056
09:33:18 71229 8192000000 10199881600 10648600576 1136328256 12.181 99 21 42 0 1279913744 138571408 5014080
09:35:32 44025 8192000000 10224794048 10648289280 1142501904 12.239 99 25 48 0 1286315392 138381456 5432032
09:37:23 70573 8192000000 10096910032 10585354240 1130943536 12.130 99 10 20 0 1241343504 108470384 1929584
09:39:44 24675 8192000000 10126127216 10576072704 1149621472 12.306 100 40 80 2 1206686832 48615856 8449504
09:41:13 140759 8192000000 10106510208 10561179648 1144332944 12.256 99 31 62 2 1205331600 54432512 6566144
09:44:36 80448 8192000000 10125544736 10579865600 1146839264 12.280 99 8 16 0 1217690816 69132336 1719216
09:48:19 40339 8192000000 10119246624 10576990208 1162007520 12.422 100 24 48 0 1208808000 41290896 5509584
09:50:01 44472 8192000000 10163236368 10606374912 1147097632 12.282 98 29 58 2 1233480672 79738272 6644768
09:51:57 78809 8192000000 10192836176 10646069248 1151589296 12.324 99 25 49 4 1253859664 96105872 6164496
09:54:16 81811 8192000000 10137438272 10666434560 1143507840 12.249 99 12 24 0 1239205328 93383376 2314112
09:56:42 65249 8192000000 10072112208 10501869568 1098583040 11.824 96 12 24 1 1127225040 26450272 2191728
09:58:59 141682 8192000000 10070192912 10539610112 1111701552 11.949 98 15 30 0 1163160624 48173376 3285696
10:03:30 56780 8192000000 10076921280 10542653440 1106967360 11.904 97 11 22 0 1156582112 47246768 2367984

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 10-10-2020 10:06:59 to 10-10-2020 10:07:14.

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 10-10-2020 10:07:15 to 10-10-2020 10:07:17.

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          239170
MON$OLDEST_ACTIVE               239171
MON$OLDEST_SNAPSHOT             239171
MON$NEXT_TRANSACTION            239171
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                       2124864
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                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             771
MON$NEXT_STATEMENT              53


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

10.10.20 10:07:18. 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: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 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-10-10 10:07:20 No severe PSQL-related problems occured
10.10.20 10:07:22. End of report.