Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.08.2020 09:54
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 16432844 kB
MemAvailable 30939680 kB
Buffers 0 kB
Cached 15209492 kB
SwapCached 5992 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-18 09:53:52.1870
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/47998
TRN_ID                          213628
ATT_ID                          525
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: 8545.65 24446 1025478
customer order: creation 157.35 446 18882
customer order: refuse 13.59 524 1631
order to supplier: creation 41.48 1593 4978
order to supplier: removal 3.08 140440 370
invoice (draft): creation 42.53 4387 5104
invoice (draft): removal 48.13 1026 5775
invoice accept: apply 38.53 23040 4623
invoice accept: cancel 43.89 1075 5267
customer reserve: creation 3584.98 221 430197
customer reserve: removal 4296.99 79 515639
realization accept: apply 76.05 231 9126
realization accept: cancel 82.33 153 9879
payment from customer: creation 54.01 133 6481
payment from customer: removal 5.28 126 634
payment to supplier: creation 44.80 152 5376
payment to supplier: removal 3.03 102 363
service: total inventory turnovers 3.12 5928 374
service: total monetary turnovers 5.79 951 695
service: aggregate perf. data 0.67 25256 80
service: refresh index statistics 0.03 283051 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-08-2020 09:54:28 to 18-08-2020 09:54:30.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2088
TEST_TIME 2 2851
TEST_TIME 3 3505
TEST_TIME 4 3478
TEST_TIME 5 3572
TEST_TIME 6 3856
TEST_TIME 7 4100
TEST_TIME 8 4129
TEST_TIME 9 4252
TEST_TIME 10 4846
TEST_TIME 11 4845
TEST_TIME 12 4829
TEST_TIME 13 5233
TEST_TIME 14 5087
TEST_TIME 15 5242
TEST_TIME 16 5144
TEST_TIME 17 5217
TEST_TIME 18 5481
TEST_TIME 19 5672
TEST_TIME 20 5724
TEST_TIME 21 5913
TEST_TIME 22 5926
TEST_TIME 23 6077
TEST_TIME 24 6351
TEST_TIME 25 6574
TEST_TIME 26 6706
TEST_TIME 27 6782
TEST_TIME 28 7049
TEST_TIME 29 7458
TEST_TIME 30 7506
TEST_TIME 31 7504
TEST_TIME 32 7882
TEST_TIME 33 7984
TEST_TIME 34 8494
TEST_TIME 35 8982
TEST_TIME 36 9089
TEST_TIME 37 9274
TEST_TIME 38 9177
TEST_TIME 39 9114
TEST_TIME 40 9082
TEST_TIME 41 8974
TEST_TIME 42 8833
TEST_TIME 43 8857
TEST_TIME 44 8879
TEST_TIME 45 8868
TEST_TIME 46 8953
TEST_TIME 47 8972
TEST_TIME 48 8914
TEST_TIME 49 8791
TEST_TIME 50 8748
TEST_TIME 51 8719
TEST_TIME 52 8649
TEST_TIME 53 8508
TEST_TIME 54 8449
TEST_TIME 55 8394
TEST_TIME 56 8384
TEST_TIME 57 8382
TEST_TIME 58 8494
TEST_TIME 59 8407
TEST_TIME 60 8303
TEST_TIME 61 8204
TEST_TIME 62 8260
TEST_TIME 63 8311
TEST_TIME 64 8427
TEST_TIME 65 8466
TEST_TIME 66 8421
TEST_TIME 67 8441
TEST_TIME 68 8531
TEST_TIME 69 8520
TEST_TIME 70 8570
TEST_TIME 71 8518
TEST_TIME 72 8532
TEST_TIME 73 8605
TEST_TIME 74 8616
TEST_TIME 75 8642
TEST_TIME 76 8682
TEST_TIME 77 8593
TEST_TIME 78 8496
TEST_TIME 79 8442
TEST_TIME 80 8452
TEST_TIME 81 8440
TEST_TIME 82 8405
TEST_TIME 83 8398
TEST_TIME 84 8367
TEST_TIME 85 8348
TEST_TIME 86 8270
TEST_TIME 87 8264
TEST_TIME 88 8243
TEST_TIME 89 8197
TEST_TIME 90 8205
TEST_TIME 91 8237
TEST_TIME 92 8283
TEST_TIME 93 8334
TEST_TIME 94 8296
TEST_TIME 95 8433
TEST_TIME 96 8516
TEST_TIME 97 8508
TEST_TIME 98 8479
TEST_TIME 99 8550
TEST_TIME 100 8496
TEST_TIME 101 8563
TEST_TIME 102 8604
TEST_TIME 103 8528
TEST_TIME 104 8474
TEST_TIME 105 8442
TEST_TIME 106 8403
TEST_TIME 107 8367
TEST_TIME 108 8349
TEST_TIME 109 8323
TEST_TIME 110 8329
TEST_TIME 111 8315
TEST_TIME 112 8288
TEST_TIME 113 8295
TEST_TIME 114 8362
TEST_TIME 115 8371
TEST_TIME 116 8366
TEST_TIME 117 8413
TEST_TIME 118 8470
TEST_TIME 119 8452
TEST_TIME 120 8547

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 18-08-2020 09:54:32 to 18-08-2020 09:54:35.

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 1420951 1420951 0 0.00 0 17892 8 0 0
doc_list_biud 1420950 1420950 0 0.00 0 39708 36 0 0
sp_add_invoice_to_stock 4706 4623 83 1.76 18 239431 23040 0 83
sp_cancel_adding_invoice 5267 5267 0 0.00 4 65068 1075 0 0
sp_cancel_client_order 1631 1631 0 0.00 4 13723 525 0 0
sp_cancel_customer_reserve 515639 515639 0 0.00 1 27754 80 0 0
sp_cancel_pay_from_customer 634 634 0 0.00 3 4814 127 0 0
sp_cancel_pay_to_supplier 363 363 0 0.00 2 2549 102 0 0
sp_cancel_supplier_invoice 5781 5775 6 0.10 1 39710 1027 0 6
sp_cancel_supplier_order 370 370 0 0.00 8 1345262 140440 0 0
sp_cancel_write_off 9879 9879 0 0.00 1 10308 154 0 0
sp_client_order 18882 18882 0 0.00 3 28114 447 0 0
sp_customer_reserve 430197 430197 0 0.00 0 51873 221 0 0
sp_fill_shopping_cart 28952 28952 0 0.00 1 60561 57 0 0
sp_fill_shopping_cart_clo_res 430196 430196 0 0.00 0 27485 19 0 0
sp_get_clo_for_invoice 4622 4622 0 0.00 0 36823 511 0 0
sp_kill_cost_storno 16143 16143 0 0.00 0 10296 90 0 0
sp_kill_qstorno_ret_qs2qd 1047302 1047302 0 0.00 0 39707 43 0 0
sp_kill_qty_storno 552308 552308 0 0.00 0 39707 93 0 0
sp_lock_selected_doc 19010 19010 0 0.00 0 10257 7 0 0
sp_make_cost_storno 25605 25605 0 0.00 0 13576 72 0 0
sp_multiply_rows_for_pdistr 25604 25604 0 0.00 0 10367 38 0 0
sp_multiply_rows_for_qdistr 18882 18882 0 0.00 0 27880 236 0 0
sp_pay_from_customer 6481 6481 0 0.00 4 17869 133 0 0
sp_pay_to_supplier 5376 5376 0 0.00 4 15136 152 0 0
sp_payment_common 11857 11857 0 0.00 1 7661 22 0 0
sp_qd_handle_on_cancel_clo 1631 1631 0 0.00 0 13713 499 0 0
sp_qd_handle_on_invoice_upd_sts 9889 9889 0 0.00 0 24865 470 0 0
sp_qd_handle_on_reserve_upd_sts 9125 9125 0 0.00 0 23324 80 0 0
sp_reserve_write_off 9126 9126 0 0.00 6 23340 232 0 0
sp_supplier_invoice 5104 5104 0 0.00 6 95527 4388 0 0
sp_supplier_order 4978 4978 0 0.00 5 29353 1594 0 0
srv_aggregate_perf_data 80 80 0 0.00 2205 553321 25256 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1106 163599 40622 0 0
srv_find_qd_qs_mism 980208 980208 0 0.00 0 15149 7 0 0
srv_make_invnt_saldo 374 374 0 0.00 50 129603 5929 0 0
srv_make_money_saldo 695 695 0 0.00 1 17438 951 0 0
srv_recalc_idx_stat 4 4 0 0.00 63197 513154 283052 0 0
t$perf-abend:sp_add_invoice_to_stock 83 83 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4623 4623 0 0.00 0 45943 277 0 0
t$perf-norm:sp_cancel_adding_invoice 393 393 0 0.00 0 4987 126 0 0
t$perf-norm:sp_cancel_client_order 1631 1631 0 0.00 0 2484 15 0 0
t$perf-norm:sp_cancel_customer_reserve 619 619 0 0.00 0 703 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 634 634 0 0.00 0 1023 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 363 363 0 0.00 0 1752 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 349 349 0 0.00 0 863 17 0 0
t$perf-norm:sp_cancel_supplier_order 370 370 0 0.00 0 31632 2381 0 0
t$perf-norm:sp_cancel_write_off 904 904 0 0.00 0 6787 21 0 0
t$perf-norm:sp_client_order 18882 18882 0 0.00 0 23775 28 0 0
t$perf-norm:sp_customer_reserve 884 884 0 0.00 0 773 7 0 0
t$perf-norm:sp_pay_from_customer 6481 6481 0 0.00 0 7399 11 0 0
t$perf-norm:sp_pay_to_supplier 5376 5376 0 0.00 0 5796 11 0 0
t$perf-norm:sp_reserve_write_off 9126 9126 0 0.00 0 35199 21 0 0
t$perf-norm:sp_supplier_invoice 5104 5104 0 0.00 0 20885 33 0 0
t$perf-norm:sp_supplier_order 4978 4978 0 0.00 0 5260 25 0 0
t$perf-norm:srv_aggregate_perf_data 80 80 0 0.00 0 492 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 563 9 0 0
t$perf-norm:srv_make_invnt_saldo 374 374 0 0.00 0 903 16 0 0
t$perf-norm:srv_make_money_saldo 695 695 0 0.00 0 2217 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 49 14 0 0
v_add_invoice_to_stock 9410 9244 166 1.76 0 7978 20 0 166
v_all_customers 34734 34734 0 0.00 0 149 1 0 0
v_all_suppliers 27614 27614 0 0.00 0 149 1 0 0
v_all_wares 37764 37764 0 0.00 0 23 1 0 0
v_cancel_adding_invoice 786 786 0 0.00 0 3056 11 0 0
v_cancel_client_order 3262 3262 0 0.00 0 2594 5 0 0
v_cancel_customer_prepayment 1268 1268 0 0.00 0 3985 15 0 0
v_cancel_customer_reserve 1238 1238 0 0.00 0 3071 7 0 0
v_cancel_payment_to_supplier 726 726 0 0.00 0 2525 9 0 0
v_cancel_supplier_invoice 710 698 12 1.69 0 1989 19 0 12
v_cancel_supplier_order 740 740 0 0.00 0 2187 10 0 0
v_cancel_write_off 1808 1808 0 0.00 0 1687 16 0 0
v_max_id_clo_ord 4977 4977 0 0.00 0 6103 3 0 0
v_max_id_clo_res 884 884 0 0.00 0 2410 14 0 0
v_max_id_ord_sup 5093 5093 0 0.00 0 3595 3 0 0
v_max_non_paid_invoice 1638 1638 0 0.00 0 3875 6 0 0
v_max_non_paid_realizn 3389 3389 0 0.00 0 6682 6 0 0
v_min_id_clo_ord 4977 4977 0 0.00 0 4306 7 0 0
v_min_id_clo_res 884 884 0 0.00 0 10250 18 0 0
v_min_id_ord_sup 5092 5092 0 0.00 0 7392 7 0 0
v_min_non_paid_invoice 5376 5376 0 0.00 0 4482 11 0 0
v_min_non_paid_realizn 6481 6481 0 0.00 0 7656 14 0 0
v_our_firm 9214 9214 0 0.00 0 26 1 0 0
v_reserve_write_off 18248 18248 0 0.00 0 5151 6 0 0
x_lock_depdocs_on_canc_invoice 10693 10693 0 0.00 0 11719 160 0 0
x_lock_depdocs_on_canc_sup_ord 370 370 0 0.00 0 9298 523 0 0
x_make_qstorno_1000_1200 4978 4978 0 0.00 1 29346 1486 0 0
x_make_qstorno_1200_2000 5104 5104 0 0.00 2 85597 4164 0 0
x_make_qstorno_2100_3300 430197 430197 0 0.00 0 42916 198 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-08-2020 09:54:36 to 18-08-2020 09:54:40.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
07:23:55 8 8192000000 8796612016 8807043072 3144832 0.038 1 1 2 0 3242128 61344 35952
07:24:56 31204 8192000000 9708155440 10000310272 784864368 8.743 100 88 176 0 813352864 7216784 21271712
07:26:35 25863 8192000000 9750664752 10054094848 826655840 9.166 100 86 171 2 858856960 11739296 20461824
07:28:04 21168 8192000000 9783942816 10093768704 852593792 9.426 100 89 178 2 891293024 18028736 20670496
07:29:29 17619 8192000000 9841686416 10167554048 896522912 9.864 100 76 152 1 951785104 38389328 16872864
07:30:50 13931 8192000000 9903335376 10237382656 942482032 10.317 100 85 170 1 1015070608 54792848 17795728
07:32:07 8854 8192000000 9929008176 10276569088 963615168 10.524 100 84 168 0 1043308640 62279088 17414384
07:33:22 4766 8192000000 9962343616 10313535488 997275664 10.852 100 77 153 3 1074359936 61887136 15197136
07:34:30 4145 8192000000 9995119520 10358099968 1018508448 11.058 100 69 138 2 1107792544 75584960 13699136
07:35:36 3781 8192000000 10034705504 10401296384 1046587360 11.328 100 86 172 0 1146362480 82510192 17264928
07:36:42 2000 8192000000 10043264352 10412716032 1057408384 11.432 100 83 164 2 1148581440 75231168 15941888
07:37:50 2616 8192000000 10035812448 10407923712 1063475280 11.490 100 89 176 0 1156151808 74133088 18543440
07:38:55 7847 8192000000 9984418208 10368917504 1068452032 11.537 100 32 63 2 1111806848 36175104 7179712
07:40:06 4663 8192000000 9994428912 10381955072 1076649232 11.616 100 34 68 3 1108736016 25505600 6581184
07:41:12 51835 8192000000 10000845696 10394275840 1083067008 11.677 100 40 80 2 1114813072 22789696 8956368
07:43:05 9373 8192000000 10007883200 10411331584 1088187968 11.725 100 22 45 2 1128092256 35429696 4474592
07:44:16 5185 8192000000 10003459472 10409041920 1094417184 11.785 100 32 64 2 1121745968 20555232 6773552
07:45:22 9753 8192000000 10002810880 10393165824 1097249584 11.812 100 30 59 1 1121611824 18058720 6303520
07:46:33 41076 8192000000 10005478400 10390241280 1057490016 11.432 97 48 98 4 1081967664 13704240 10773408
07:48:15 8092 8192000000 9996266864 10390908928 1062984640 11.485 100 41 83 4 1099205936 27722528 8498768
07:49:25 8955 8192000000 9942181632 10335002624 1033639664 11.203 100 29 55 3 1052709488 12457904 6611920
07:50:38 4846 8192000000 9921837008 10290552832 976625488 10.651 96 48 98 1 1002105792 14483888 10996416
07:51:43 36395 8192000000 9936780512 10327412736 1020297952 11.075 99 43 86 3 1039746224 9834160 9614112
07:53:21 3978 8192000000 9978885584 10349035520 1052510864 11.385 100 57 115 1 1087488496 21944720 13032912
07:54:30 10048 8192000000 9996536704 10375745536 1068046112 11.533 100 55 109 1 1097492336 17690592 11755632
07:55:54 7881 8192000000 10042418432 10435026944 1080411696 11.651 100 54 109 4 1156966368 64981008 11573664
07:57:10 26223 8192000000 10053944160 10448003072 1092751776 11.769 100 48 94 1 1166653312 63900848 10000688
07:58:52 37446 8192000000 10078327024 10484031488 1099630240 11.834 100 32 63 3 1197821056 90435888 7754928
08:00:47 12666 8192000000 10114864304 10510409728 1115453952 11.984 100 62 121 0 1229479200 100139328 13885920
08:02:11 19371 8192000000 10155061152 10547666944 1129753920 12.119 100 60 117 3 1258776800 116986768 12036112
08:03:38 24368 8192000000 10153664144 10567888896 1136295776 12.181 100 50 100 2 1283533264 136844464 10393024
08:05:11 8528 8192000000 10185949840 10586656768 1141005024 12.225 100 63 125 1 1292382640 138554368 12823248
08:06:25 22695 8192000000 10201020992 10617430016 1151515888 12.324 100 50 100 1 1308997008 146638976 10842144
08:08:03 3965 8192000000 10240325920 10646519808 1162225216 12.424 100 65 126 1 1342346240 165948016 14173008
08:09:17 6553 8192000000 10258154960 10668625920 1164008800 12.441 100 61 122 1 1362892512 184184576 14699136
08:10:30 3952 8192000000 10302827088 10711519232 1175442368 12.548 100 58 115 4 1411452608 222860944 13149296
08:11:38 5062 8192000000 10289760176 10690699264 1158529008 12.389 98 69 138 2 1403264400 228396368 16339024
08:12:53 5113 8192000000 10344188800 10750939136 1180553696 12.595 100 73 143 0 1447876848 250710528 16612624
08:14:05 3100 8192000000 10345515040 10753171456 1181998000 12.609 100 74 146 1 1450723488 251712832 17012656
08:15:19 1105 8192000000 10382470576 10785402880 1184794256 12.635 100 82 161 2 1480239792 275927888 19517648
08:16:35 4449 8192000000 10399084928 10809171968 1174941968 12.543 100 73 146 1 1501709280 310135568 16631744
08:17:50 3354 8192000000 10393124576 10819239936 1150877920 12.318 99 85 169 1 1478254416 307766272 19610224
08:19:00 1463 8192000000 10397407776 10795819008 1150641296 12.316 99 83 167 0 1494664640 324089152 19934192
08:20:12 2994 8192000000 10415663056 10816536576 1159314032 12.397 100 80 157 2 1510116544 332748880 18053632
08:21:25 2109 8192000000 10435748880 10846658560 1152210896 12.330 100 91 179 0 1530455408 356996800 21247712
08:22:30 12961 8192000000 10399033472 10823729152 1152312032 12.331 100 40 80 0 1493826832 331289584 10225216
08:23:49 9014 8192000000 10401366080 10818355200 1141936160 12.234 100 40 80 1 1510021840 359001392 9084288
08:25:03 15968 8192000000 10426408864 10892988416 1127261600 12.096 100 32 64 1 1553225440 417842240 8121600
08:26:23 6211 8192000000 10320060592 10738470912 1106647856 11.901 100 41 79 0 1419462400 303326160 9488384
08:27:37 12556 8192000000 10286735280 10734641152 1124075904 12.065 100 42 84 1 1390797888 256982848 9739136
08:28:58 1766 8192000000 10161534368 10585006080 1128610256 12.108 100 93 184 1 1252186160 101833504 21742400
08:30:03 37641 8192000000 10143575520 10648907776 1119854128 12.026 100 28 56 2 1270933792 144758000 6321664
08:31:46 26924 8192000000 10095952544 10514317312 1089697552 11.740 98 25 49 3 1163975776 69262736 5015488
08:33:16 75507 8192000000 10100442784 10572890112 1100546800 11.843 99 11 21 1 1201349696 98152432 2650464
08:35:33 60743 8192000000 10136875680 10590273536 1131472992 12.135 100 40 82 1 1264096992 122447408 10176592
08:37:37 58353 8192000000 10148044176 10631020544 1126677808 12.090 99 19 39 1 1266080336 135095504 4307024
08:39:40 47367 8192000000 10050259984 10480128000 1103893520 11.875 98 24 46 1 1158333168 49065584 5374064
08:41:31 47928 8192000000 10035902928 10456444928 1108589824 11.919 99 20 39 0 1150645280 38475776 3579680
08:43:24 10005 8192000000 10085942512 10509099008 1114629296 11.976 99 51 102 3 1172112048 46381056 11101696
08:44:44 105095 8192000000 10039503136 10511085568 1095665728 11.796 98 17 34 1 1129905136 30213568 4025840
08:47:33 17946 8192000000 10078570656 10533695488 1124112592 12.066 100 36 72 0 1173968464 42921648 6934224
08:48:54 53129 8192000000 10138616432 10578214912 1125324832 12.077 100 25 50 2 1210693152 79523536 5844784
08:50:57 71667 8192000000 10074013648 10554056704 1118053456 12.009 99 15 30 1 1194210832 72773696 3383680
08:53:11 89242 8192000000 10134545904 10573402112 1111200080 11.944 97 18 36 0 1179009888 63980624 3829184
08:56:03 50237 8192000000 10140903360 10620207104 1130798688 12.129 100 11 23 0 1237999568 104953840 2247040
08:57:54 120440 8192000000 10089396736 10540662784 1084915504 11.694 95 12 24 1 1128794016 41749168 2129344
09:00:55 67725 8192000000 10098861536 10587869184 1121102640 12.037 98 11 22 0 1193856800 69663568 3090592
09:03:06 81387 8192000000 10086221488 10525220864 1111933232 11.951 97 24 48 0 1163159648 45759856 5466560
09:05:29 73882 8192000000 10091671104 10567319552 1146437392 12.276 100 19 38 1 1222117216 71953280 3726544
09:08:02 60311 8192000000 10069196592 10549059584 1106382608 11.898 97 9 18 0 1149503920 40841408 2279904
09:10:05 163599 8192000000 10054925744 10494480384 1094150352 11.782 97 22 44 1 1129356416 30656112 4549952
09:13:53 5689 8192000000 10051173536 10481684480 1091645232 11.758 99 55 112 4 1146323280 42714784 11963264
09:15:02 32184 8192000000 10036019936 10478440448 1096542720 11.805 100 9 18 1 1142646528 44196336 1907472
09:16:46 46534 8192000000 10033598448 10468597760 1084642240 11.692 98 23 46 1 1126103168 36273008 5187920
09:18:36 81790 8192000000 10020287360 10453422080 1072870864 11.579 97 17 34 2 1105794688 29385264 3538560
09:21:01 79063 8192000000 10060462832 10517176320 1073831024 11.589 97 7 13 0 1113548624 38599392 1118208
09:23:21 79342 8192000000 10127162656 10577883136 1101959184 11.856 97 18 36 1 1213783408 107755184 4069040
09:25:50 34983 8192000000 10121268432 10574721024 1115418256 11.984 99 22 42 2 1212523680 92699696 4405728
09:27:27 64212 8192000000 10089125408 10590523392 1105028000 11.885 98 17 34 1 1238820064 130172848 3619216
09:29:39 68363 8192000000 10048903216 10493214720 1086699616 11.711 97 27 54 3 1141411632 49093264 5618752
09:31:54 24936 8192000000 10084999232 10508517376 1095878928 11.799 98 53 106 2 1165744288 59162992 10702368
09:34:23 39347 8192000000 10101316544 10595778560 1103562464 11.871 99 27 54 1 1221844224 112825248 5456512
09:36:12 65737 8192000000 10043886080 10484314112 1111888048 11.950 100 12 24 1 1155911424 41475632 2547744
09:38:38 80175 8192000000 10052280464 10508238848 1101250784 11.850 98 16 32 1 1142102800 37687840 3164176
09:40:59 44804 8192000000 10056070336 10532192256 1123385104 12.059 100 10 20 0 1165138784 39759392 1994288
09:42:45 89751 8192000000 10112808160 10584023040 1103677392 11.873 98 26 53 4 1168822528 59832816 5312320
09:45:48 69397 8192000000 10137815392 10596913152 1123430160 12.059 99 37 73 3 1237276640 105299760 8546720
09:47:57 61385 8192000000 10112378960 10559991808 1108656064 11.920 97 21 42 1 1175130160 61513536 4960560
09:50:01 85261 8192000000 10141066912 10635472896 1121685104 12.043 98 16 32 2 1247815456 122508976 3621376
09:52:32 37981 8192000000 10125505392 10590081024 1094551664 11.786 97 37 74 1 1195923392 93851728 7520000

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 18-08-2020 09:54:41 to 18-08-2020 09:54:55.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 83 v_add_invoice_to_stock 335544517
user_exc 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-08-2020 09:54:56 to 18-08-2020 09:54:58.

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          212889
MON$OLDEST_ACTIVE               213782
MON$OLDEST_SNAPSHOT             213782
MON$NEXT_TRANSACTION            213782
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                       2083232
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000003c06020000000000
MON$NEXT_ATTACHMENT             674
MON$NEXT_STATEMENT              53


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

18.08.20 09:55:00. 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-18 09:55:02 NO_SEVERE_ERRORS_FOUND
18.08.20 09:55:05. End of report.