Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.08.2020 09:52
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 16253592 kB
MemAvailable 30933440 kB
Buffers 0 kB
Cached 15377368 kB
SwapCached 6004 kB
Command: fdisk -l

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.0
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED.  
DTS_END                         2020-08-17 09:51:50.7680
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> SP_CHECK_TO_STOP_WORK(31:12) ==> SP_HALT_ON_ERROR(46:13)
IP                              ::1/33446
TRN_ID                          216570
ATT_ID                          539
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: 8750.58 23086 1050069
customer order: creation 157.83 409 18940
customer order: refuse 13.48 532 1617
order to supplier: creation 43.50 1558 5220
order to supplier: removal 3.49 130150 419
invoice (draft): creation 43.06 3984 5167
invoice (draft): removal 51.18 978 6141
invoice accept: apply 37.96 19846 4555
invoice accept: cancel 45.92 1032 5510
customer reserve: creation 3503.39 193 420407
customer reserve: removal 4573.41 78 548809
realization accept: apply 76.43 235 9172
realization accept: cancel 81.98 150 9837
payment from customer: creation 54.62 124 6554
payment from customer: removal 5.28 102 633
payment to supplier: creation 45.53 133 5464
payment to supplier: removal 3.47 134 416
service: total inventory turnovers 3.71 4818 445
service: total monetary turnovers 5.55 954 666
service: aggregate perf. data 0.78 22178 93
service: refresh index statistics 0.03 274126 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-08-2020 09:52:31 to 17-08-2020 09:52:33.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1889
TEST_TIME 2 3145
TEST_TIME 3 2778
TEST_TIME 4 2944
TEST_TIME 5 3289
TEST_TIME 6 3655
TEST_TIME 7 3804
TEST_TIME 8 4181
TEST_TIME 9 4340
TEST_TIME 10 4262
TEST_TIME 11 4387
TEST_TIME 12 4503
TEST_TIME 13 4482
TEST_TIME 14 4541
TEST_TIME 15 4687
TEST_TIME 16 4903
TEST_TIME 17 5064
TEST_TIME 18 5113
TEST_TIME 19 5765
TEST_TIME 20 6081
TEST_TIME 21 6121
TEST_TIME 22 6109
TEST_TIME 23 6296
TEST_TIME 24 6389
TEST_TIME 25 6407
TEST_TIME 26 6688
TEST_TIME 27 6920
TEST_TIME 28 7057
TEST_TIME 29 7293
TEST_TIME 30 7448
TEST_TIME 31 7773
TEST_TIME 32 7998
TEST_TIME 33 8497
TEST_TIME 34 8693
TEST_TIME 35 8918
TEST_TIME 36 8859
TEST_TIME 37 8871
TEST_TIME 38 8831
TEST_TIME 39 8739
TEST_TIME 40 8792
TEST_TIME 41 8865
TEST_TIME 42 8814
TEST_TIME 43 8782
TEST_TIME 44 8900
TEST_TIME 45 8941
TEST_TIME 46 8874
TEST_TIME 47 8921
TEST_TIME 48 8894
TEST_TIME 49 8827
TEST_TIME 50 8686
TEST_TIME 51 8699
TEST_TIME 52 8664
TEST_TIME 53 8641
TEST_TIME 54 8625
TEST_TIME 55 8628
TEST_TIME 56 8594
TEST_TIME 57 8620
TEST_TIME 58 8583
TEST_TIME 59 8615
TEST_TIME 60 8635
TEST_TIME 61 8568
TEST_TIME 62 8472
TEST_TIME 63 8543
TEST_TIME 64 8564
TEST_TIME 65 8506
TEST_TIME 66 8608
TEST_TIME 67 8663
TEST_TIME 68 8695
TEST_TIME 69 8686
TEST_TIME 70 8693
TEST_TIME 71 8672
TEST_TIME 72 8711
TEST_TIME 73 8813
TEST_TIME 74 8861
TEST_TIME 75 8883
TEST_TIME 76 8889
TEST_TIME 77 9021
TEST_TIME 78 8956
TEST_TIME 79 8846
TEST_TIME 80 8785
TEST_TIME 81 8783
TEST_TIME 82 8736
TEST_TIME 83 8686
TEST_TIME 84 8619
TEST_TIME 85 8567
TEST_TIME 86 8550
TEST_TIME 87 8616
TEST_TIME 88 8600
TEST_TIME 89 8596
TEST_TIME 90 8607
TEST_TIME 91 8607
TEST_TIME 92 8665
TEST_TIME 93 8696
TEST_TIME 94 8776
TEST_TIME 95 8867
TEST_TIME 96 8861
TEST_TIME 97 8869
TEST_TIME 98 8824
TEST_TIME 99 8935
TEST_TIME 100 8846
TEST_TIME 101 8767
TEST_TIME 102 8718
TEST_TIME 103 8721
TEST_TIME 104 8671
TEST_TIME 105 8649
TEST_TIME 106 8610
TEST_TIME 107 8581
TEST_TIME 108 8563
TEST_TIME 109 8552
TEST_TIME 110 8543
TEST_TIME 111 8539
TEST_TIME 112 8532
TEST_TIME 113 8575
TEST_TIME 114 8618
TEST_TIME 115 8618
TEST_TIME 116 8629
TEST_TIME 117 8720
TEST_TIME 118 8780
TEST_TIME 119 8756
TEST_TIME 120 8753

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-08-2020 09:52:35 to 17-08-2020 09:52:38.

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 1440432 1440432 0 0.00 0 23585 8 0 0
doc_list_biud 1440432 1440432 0 0.00 0 56934 37 0 0
sp_add_invoice_to_stock 4710 4555 155 3.29 50 207210 19847 0 155
sp_cancel_adding_invoice 5510 5510 0 0.00 4 77294 1033 0 0
sp_cancel_client_order 1617 1617 0 0.00 4 24711 532 0 0
sp_cancel_customer_reserve 548809 548809 0 0.00 1 28738 78 0 0
sp_cancel_pay_from_customer 633 633 0 0.00 2 3856 103 0 0
sp_cancel_pay_to_supplier 416 416 0 0.00 3 8739 134 0 0
sp_cancel_supplier_invoice 6147 6141 6 0.09 2 15610 979 0 6
sp_cancel_supplier_order 419 419 0 0.00 15 1121611 130151 0 0
sp_cancel_write_off 9837 9837 0 0.00 1 8565 150 0 0
sp_client_order 18940 18940 0 0.00 3 41782 410 0 0
sp_customer_reserve 420407 420407 0 0.00 0 131630 193 0 0
sp_fill_shopping_cart 29299 29299 0 0.00 1 21743 52 0 0
sp_fill_shopping_cart_clo_res 420404 420404 0 0.00 0 23427 17 0 0
sp_get_clo_for_invoice 4552 4552 0 0.00 0 26379 370 0 0
sp_kill_cost_storno 16396 16396 0 0.00 0 8733 87 0 0
sp_kill_qstorno_ret_qs2qd 1114015 1114015 0 0.00 0 24075 42 0 0
sp_kill_qty_storno 586057 586057 0 0.00 0 56934 90 0 0
sp_lock_selected_doc 19143 19143 0 0.00 0 9207 6 0 0
sp_make_cost_storno 25742 25742 0 0.00 0 14816 71 0 0
sp_multiply_rows_for_pdistr 25742 25742 0 0.00 0 9698 38 0 0
sp_multiply_rows_for_qdistr 18940 18940 0 0.00 0 17970 225 0 0
sp_pay_from_customer 6554 6554 0 0.00 3 19168 124 0 0
sp_pay_to_supplier 5464 5464 0 0.00 4 22310 134 0 0
sp_payment_common 12018 12018 0 0.00 1 10877 21 0 0
sp_qd_handle_on_cancel_clo 1617 1617 0 0.00 0 24705 503 0 0
sp_qd_handle_on_invoice_upd_sts 10062 10062 0 0.00 0 56934 425 0 0
sp_qd_handle_on_reserve_upd_sts 9172 9172 0 0.00 0 11661 62 0 0
sp_reserve_write_off 9172 9172 0 0.00 5 25652 236 0 0
sp_supplier_invoice 5167 5167 0 0.00 6 133872 3984 0 0
sp_supplier_order 5220 5220 0 0.00 5 109660 1559 0 0
srv_aggregate_perf_data 93 93 0 0.00 2510 796730 22178 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1605 127717 40534 0 0
srv_find_qd_qs_mism 1006725 1006725 0 0.00 0 11036 6 0 0
srv_make_invnt_saldo 445 445 0 0.00 35 141751 4818 0 0
srv_make_money_saldo 666 666 0 0.00 0 23050 954 0 0
srv_recalc_idx_stat 4 4 0 0.00 76194 411739 274126 0 0
t$perf-abend:sp_add_invoice_to_stock 155 155 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 4555 4555 0 0.00 0 7879 237 0 0
t$perf-norm:sp_cancel_adding_invoice 357 357 0 0.00 1 8156 161 0 0
t$perf-norm:sp_cancel_client_order 1617 1617 0 0.00 0 11198 24 0 0
t$perf-norm:sp_cancel_customer_reserve 670 670 0 0.00 0 2775 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 633 633 0 0.00 0 4699 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 416 416 0 0.00 0 2581 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 383 383 0 0.00 0 1271 21 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 28201 2396 0 0
t$perf-norm:sp_cancel_write_off 925 925 0 0.00 0 1320 5 0 0
t$perf-norm:sp_client_order 18940 18940 0 0.00 0 7116 24 0 0
t$perf-norm:sp_customer_reserve 924 924 0 0.00 0 4534 15 0 0
t$perf-norm:sp_pay_from_customer 6554 6554 0 0.00 0 5265 11 0 0
t$perf-norm:sp_pay_to_supplier 5464 5464 0 0.00 0 5001 9 0 0
t$perf-norm:sp_reserve_write_off 9172 9172 0 0.00 0 7831 15 0 0
t$perf-norm:sp_supplier_invoice 5167 5167 0 0.00 0 5428 23 0 0
t$perf-norm:sp_supplier_order 5220 5220 0 0.00 0 6362 21 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 378 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 94 2 0 0
t$perf-norm:srv_make_invnt_saldo 445 445 0 0.00 0 1463 18 0 0
t$perf-norm:srv_make_money_saldo 666 666 0 0.00 0 3875 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 22 9 0 0
v_add_invoice_to_stock 9412 9102 310 3.29 0 8043 20 0 310
v_all_customers 34732 34732 0 0.00 0 15 1 0 0
v_all_suppliers 28764 28764 0 0.00 0 19 1 0 0
v_all_wares 37872 37872 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 714 714 0 0.00 0 1336 10 0 0
v_cancel_client_order 3234 3234 0 0.00 0 4462 7 0 0
v_cancel_customer_prepayment 1266 1266 0 0.00 0 3848 11 0 0
v_cancel_customer_reserve 1340 1340 0 0.00 0 2239 6 0 0
v_cancel_payment_to_supplier 832 832 0 0.00 0 2785 8 0 0
v_cancel_supplier_invoice 778 766 12 1.54 0 1588 16 0 12
v_cancel_supplier_order 838 838 0 0.00 0 736 5 0 0
v_cancel_write_off 1850 1850 0 0.00 0 3200 16 0 0
v_max_id_clo_ord 5220 5220 0 0.00 0 1751 2 0 0
v_max_id_clo_res 924 924 0 0.00 0 3920 15 0 0
v_max_id_ord_sup 5143 5143 0 0.00 0 3031 5 0 0
v_max_non_paid_invoice 1445 1445 0 0.00 0 793 2 0 0
v_max_non_paid_realizn 3416 3416 0 0.00 0 3675 3 0 0
v_min_id_clo_ord 5220 5220 0 0.00 0 7159 10 0 0
v_min_id_clo_res 924 924 0 0.00 0 2066 6 0 0
v_min_id_ord_sup 5143 5143 0 0.00 0 18121 17 0 0
v_min_non_paid_invoice 5464 5464 0 0.00 0 8926 16 0 0
v_min_non_paid_realizn 6554 6554 0 0.00 0 6028 12 0 0
v_our_firm 9416 9416 0 0.00 0 33 1 0 0
v_reserve_write_off 18344 18344 0 0.00 0 8468 8 0 0
x_lock_depdocs_on_canc_invoice 11268 11268 0 0.00 0 29831 150 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 5865 444 0 0
x_make_qstorno_1000_1200 5220 5220 0 0.00 2 109616 1456 0 0
x_make_qstorno_1200_2000 5167 5167 0 0.00 3 133865 3782 0 0
x_make_qstorno_2100_3300 420407 420407 0 0.00 0 131629 173 0 0

Completed "Performance in DETAILS". Done for 00:00:03, from 17-08-2020 09:52:39 to 17-08-2020 09:52:42.

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:21:54 3 8192000000 8796612016 8807043072 3144832 0.038 1 1 2 0 3242128 61344 35952
07:22:54 45933 8192000000 9729623872 10026254336 799981536 8.896 100 85 170 1 828315024 7941248 20392240
07:24:44 27466 8192000000 9777180640 10082152448 848320016 9.383 100 90 180 1 882328336 12740128 21268192
07:26:21 28123 8192000000 9809835344 10128306176 878411968 9.684 100 80 160 0 915736992 19208880 18116144
07:27:56 15449 8192000000 9868387264 10198085632 921478384 10.111 100 82 165 0 977708256 37842928 18386944
07:29:19 10949 8192000000 9913378256 10255511552 943825520 10.331 100 66 132 0 1022083328 63966960 14290848
07:30:38 8307 8192000000 9933709616 10282049536 978651312 10.671 100 73 143 3 1045527360 52681328 14194720
07:31:51 4091 8192000000 9993075152 10344210432 1005495344 10.932 100 83 166 0 1104597488 82405072 16697072
07:32:59 2706 8192000000 10009571680 10370375680 1028175392 11.151 100 78 155 1 1126651120 83265664 15210064
07:34:04 2173 8192000000 10012835664 10374496256 1045375296 11.316 100 81 158 1 1121506912 59307008 16824608
07:35:08 1696 8192000000 10021230976 10388135936 1052737472 11.387 100 78 153 2 1129392960 61792848 14862640
07:36:12 14397 8192000000 10009500272 10385461248 1057367936 11.431 100 60 121 0 1133806960 64048064 12390960
07:37:29 6092 8192000000 9988961136 10373234688 1064877664 11.503 100 43 85 3 1112385936 38729568 8778704
07:38:38 5668 8192000000 9977252928 10369212416 1071326576 11.565 100 33 66 2 1093975600 14741008 7908016
07:39:46 54442 8192000000 9986207584 10377650176 1074510608 11.595 100 38 74 1 1102163744 19095504 8557632
07:41:42 4681 8192000000 9995463984 10385358848 1083406624 11.680 100 37 75 2 1111710992 19602592 8701776
07:42:51 5392 8192000000 10002780592 10395910144 1080190224 11.649 100 29 56 1 1106809968 20638112 5981632
07:44:00 62537 8192000000 9983226800 10374643712 1050487792 11.365 97 28 56 0 1079585536 22720960 6376784
07:46:15 8146 8192000000 9977542272 10351570944 1007119056 10.947 96 45 90 3 1064951616 47665440 10167120
07:47:25 7042 8192000000 9948792944 10335002624 1016196000 11.035 98 48 96 2 1050050896 22428784 11426112
07:48:33 63238 8192000000 9926311552 10308083712 1008476976 10.961 98 36 72 2 1037793424 21406160 7910288
07:50:37 9947 8192000000 9949942032 10330370048 1020982688 11.081 98 42 85 1 1046858176 14917632 10957856
07:51:48 29791 8192000000 9989454480 10372100096 1071965728 11.571 100 42 83 1 1092112032 10433408 9712896
07:53:25 18951 8192000000 10030967632 10414174208 1078178032 11.630 99 51 102 6 1128214880 39328752 10708096
07:54:59 31965 8192000000 10080667328 10475732992 1099743856 11.835 100 47 93 3 1189382688 79724656 9914176
07:56:35 35877 8192000000 10111131696 10513596416 1111656096 11.948 100 46 92 3 1235114000 113586800 9871104
07:58:17 25079 8192000000 10116087392 10516533248 1122951536 12.055 100 46 90 5 1230562416 97980480 9630400
07:59:47 19669 8192000000 10131919808 10540130304 1136540608 12.183 100 42 82 2 1244137808 98737280 8859920
08:01:10 31623 8192000000 10193458944 10600779776 1146129872 12.273 100 56 112 2 1299923888 141915216 11878800
08:03:02 9127 8192000000 10199335456 10606350336 1151813728 12.327 100 45 90 3 1312376736 150115696 10447312
08:04:20 8095 8192000000 10252445424 10652749824 1161273056 12.415 100 71 141 2 1350276368 172728848 16274464
08:05:40 4555 8192000000 10286393504 10694500352 1172460592 12.520 100 67 132 0 1396379216 209154144 14764480
08:06:54 3099 8192000000 10322015296 10727636992 1172275392 12.518 100 76 151 1 1428996752 239143488 17577872
08:08:05 10583 8192000000 10338696672 10766163968 1178413152 12.575 100 67 133 0 1446372256 252058992 15900112
08:09:25 13524 8192000000 10373239472 10782883840 1181061136 12.600 100 77 153 1 1476636576 277486560 18088880
08:10:50 7561 8192000000 10341710720 10750976000 1178535056 12.577 100 77 154 1 1446420528 250208832 17676640
08:12:17 3437 8192000000 10300795344 10714095616 1175538368 12.549 99 69 136 0 1389405424 198493632 15373424
08:13:30 4873 8192000000 10340185920 10749431808 1177945264 12.571 99 68 136 1 1440030512 246341520 15743728
08:14:48 3473 8192000000 10333000768 10752225280 1179517392 12.586 100 79 155 1 1429571968 232267168 17787408
08:16:01 2201 8192000000 10382190240 10784583680 1173281264 12.527 100 87 174 2 1472990592 278766752 20942576
08:17:11 3128 8192000000 10379951328 10803884032 1178424560 12.575 100 85 168 0 1479623712 282270304 18928848
08:18:21 1598 8192000000 10421245152 10839748608 1175805216 12.551 100 92 185 3 1514147776 315574784 22767776
08:19:28 8603 8192000000 10425906480 10851262464 1164738448 12.448 99 50 100 0 1512574304 334340512 13495344
08:20:45 11461 8192000000 10388342464 10827284480 1167014544 12.469 100 39 78 1 1478293504 301542496 9736464
08:22:02 12281 8192000000 10385356992 10824515584 1163902256 12.440 100 36 72 0 1476879952 304074064 8903632
08:23:23 9350 8192000000 10319463040 10800418816 1157085360 12.376 100 26 52 1 1421981872 258839312 6057200
08:24:47 25617 8192000000 10218744560 10676998144 1142721984 12.241 99 24 47 1 1311323424 163308992 5292448
08:26:15 17416 8192000000 10086748544 10554826752 1111499664 11.947 98 25 48 1 1199751872 83019472 5232736
08:27:40 28642 8192000000 10071995600 10536079360 1127680128 12.099 100 26 51 2 1178597760 45686160 5231472
08:29:17 50869 8192000000 10074098960 10526273536 1093053248 11.772 97 21 41 2 1142851264 45297264 4500752
08:31:18 22704 8192000000 10087618400 10538811392 1066988096 11.523 96 15 30 0 1175372384 105201056 3183232
08:32:52 57862 8192000000 10053333232 10483163136 1107713728 11.911 100 20 38 2 1141805648 30287120 3804800
08:34:52 66996 8192000000 10065164880 10533371904 1106855728 11.903 100 19 38 0 1158305568 48188208 3261632
08:37:02 61075 8192000000 10032915360 10463621120 1079150672 11.639 97 19 37 2 1133145088 49254208 4740208
08:39:10 73031 8192000000 10065306768 10496339968 1066254528 11.516 95 10 20 0 1100607504 32272208 2080768
08:41:38 102579 8192000000 10060557424 10539511808 1092325984 11.765 98 43 82 1 1187974896 86921200 8727712
08:44:48 29938 8192000000 10043267136 10460192768 1098391120 11.822 100 29 58 1 1142068592 37274352 6403120
08:46:20 22661 8192000000 10073608544 10496696320 1113230720 11.963 100 60 119 3 1163971376 38482944 12257712
08:47:45 12371 8192000000 10076748240 10495344640 1103305184 11.869 100 42 82 1 1168555648 57178000 8072464
08:49:03 127715 8192000000 10063930272 10526322688 1106069168 11.895 99 22 44 1 1175784912 64961104 4754640
08:52:15 44920 8192000000 10096340688 10532560896 1124894240 12.073 99 28 57 0 1187354720 56303760 6156720
08:55:30 26998 8192000000 10069431648 10535346176 1136506224 12.183 100 17 34 1 1167053488 27108032 3439232
08:56:57 54019 8192000000 10089010160 10541015040 1116564064 11.995 98 13 26 2 1199823424 80155312 3104048
08:58:52 12156 8192000000 10096348224 10511024128 1142433072 12.238 100 48 98 2 1181168672 28168944 10566656
09:00:09 43916 8192000000 10094877728 10535276544 1130955936 12.130 99 21 42 0 1172090704 37189616 3945152
09:01:57 41215 8192000000 10130908160 10574356480 1157394048 12.379 100 22 44 1 1221255552 59638816 4222688
09:03:39 75813 8192000000 10111803008 10584309760 1116522784 11.994 97 15 30 1 1176867280 56835120 3509376
09:06:01 53332 8192000000 10096162240 10562932736 1132098560 12.141 99 12 24 1 1189753760 54890976 2764224
09:07:59 105842 8192000000 10048469152 10510639104 1056296448 11.421 93 6 12 0 1115520768 58394032 830288
09:10:48 75725 8192000000 10030688848 10471383040 1099062576 11.829 98 19 38 1 1126932560 23922976 3947008
09:13:03 58086 8192000000 10039656976 10519580672 1105077280 11.886 99 7 14 0 1137137792 30897008 1163504
09:15:57 76708 8192000000 10104668656 10563080192 1112076272 11.952 99 13 26 0 1192782448 77578704 3127472
09:18:22 80971 8192000000 10029154688 10473689088 1079667952 11.644 97 11 22 2 1120364752 38473472 2223328
09:20:46 79967 8192000000 10116172576 10591203328 1102967200 11.866 98 22 44 1 1169808528 62358496 4482832
09:23:47 65802 8192000000 10086344992 10540609536 1088322256 11.727 97 12 24 1 1161026176 70445280 2258640
09:26:19 125813 8192000000 10088382352 10568753152 1041080336 11.275 92 10 20 0 1081655424 38731536 1843552
09:29:52 45745 8192000000 10096553968 10623143936 1136569728 12.183 100 34 67 2 1211929296 68769536 6590032
09:31:49 31771 8192000000 10087492784 10614919168 1126542352 12.089 99 7 14 0 1181398896 53487584 1368960
09:33:23 63653 8192000000 10044190816 10525130752 1129305808 12.115 100 10 20 1 1173353936 42035696 2012432
09:35:27 53590 8192000000 10043254576 10486407168 1108973584 11.923 99 11 21 0 1131605968 20808256 1824128
09:37:27 63334 8192000000 10037279008 10481827840 1102336272 11.860 99 12 24 0 1143499488 38670144 2493072
09:39:45 60530 8192000000 10083838320 10524663808 1111968880 11.951 98 16 32 1 1153827520 38677360 3181280
09:41:47 36651 8192000000 10115558864 10552860672 1127864896 12.101 99 32 64 2 1197319536 62519936 6934704
09:43:31 85123 8192000000 10127752032 10584096768 1140985328 12.225 100 16 32 0 1214505744 70021824 3498592
09:45:57 41178 8192000000 10115895440 10548068352 1146326960 12.275 100 33 65 0 1222454176 69244352 6882864
09:47:43 59836 8192000000 10163136656 10709082112 1148086464 12.292 99 21 42 0 1261199392 108720560 4392368
09:50:00 62558 8192000000 10125189856 10563530752 1150777440 12.317 100 31 59 1 1183578768 26889616 5911712

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 17-08-2020 09:52:44 to 17-08-2020 09:52:58.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 155 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 17-08-2020 09:52:59 to 17-08-2020 09:53:01.

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          214435
MON$OLDEST_ACTIVE               216695
MON$OLDEST_SNAPSHOT             216695
MON$NEXT_TRANSACTION            216695
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                       2107456
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             671
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 ---
:141: 
:142: mscs-test-161.budzdorov.ru    Mon Aug 17 09:51:52 2020
:143:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:144: 
--- end of diff output ---

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

17.08.20 09:53:03. 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-17 09:53:05 NO_SEVERE_ERRORS_FOUND
17.08.20 09:53:07. End of report.