Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.08.2020 03: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 16494324 kB
MemAvailable 30900364 kB
Buffers 0 kB
Cached 15094692 kB
SwapCached 5984 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 03:51:50.8930
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/58262
TRN_ID                          215428
ATT_ID                          575
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: 8614.51 27765 1033741
customer order: creation 164.98 455 19798
customer order: refuse 14.48 504 1737
order to supplier: creation 45.57 1492 5468
order to supplier: removal 3.17 133961 380
invoice (draft): creation 44.95 4164 5394
invoice (draft): removal 48.05 987 5766
invoice accept: apply 39.86 22008 4783
invoice accept: cancel 42.97 1130 5156
customer reserve: creation 3698.52 212 443822
customer reserve: removal 4221.83 81 506619
realization accept: apply 82.28 215 9874
realization accept: cancel 81.48 153 9778
payment from customer: creation 58.50 123 7020
payment from customer: removal 5.93 131 711
payment to supplier: creation 48.18 145 5782
payment to supplier: removal 3.41 108 409
service: total inventory turnovers 3.50 5137 420
service: total monetary turnovers 5.98 884 717
service: aggregate perf. data 0.86 13692 103
service: refresh index statistics 0.03 369712 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-08-2020 03:52:34 to 17-08-2020 03:52:36.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3940
TEST_TIME 2 3079
TEST_TIME 3 3568
TEST_TIME 4 3531
TEST_TIME 5 4219
TEST_TIME 6 4503
TEST_TIME 7 4558
TEST_TIME 8 4627
TEST_TIME 9 4749
TEST_TIME 10 4727
TEST_TIME 11 4665
TEST_TIME 12 4842
TEST_TIME 13 4764
TEST_TIME 14 4805
TEST_TIME 15 4881
TEST_TIME 16 4874
TEST_TIME 17 4923
TEST_TIME 18 5121
TEST_TIME 19 5200
TEST_TIME 20 5261
TEST_TIME 21 5335
TEST_TIME 22 5487
TEST_TIME 23 5794
TEST_TIME 24 6299
TEST_TIME 25 6418
TEST_TIME 26 6533
TEST_TIME 27 6827
TEST_TIME 28 6815
TEST_TIME 29 6975
TEST_TIME 30 7042
TEST_TIME 31 7429
TEST_TIME 32 7794
TEST_TIME 33 7923
TEST_TIME 34 8512
TEST_TIME 35 9003
TEST_TIME 36 9010
TEST_TIME 37 9257
TEST_TIME 38 9307
TEST_TIME 39 9200
TEST_TIME 40 9130
TEST_TIME 41 9039
TEST_TIME 42 8969
TEST_TIME 43 8922
TEST_TIME 44 8869
TEST_TIME 45 8778
TEST_TIME 46 8738
TEST_TIME 47 8695
TEST_TIME 48 8748
TEST_TIME 49 8726
TEST_TIME 50 8826
TEST_TIME 51 8844
TEST_TIME 52 8770
TEST_TIME 53 8660
TEST_TIME 54 8731
TEST_TIME 55 8730
TEST_TIME 56 8656
TEST_TIME 57 8849
TEST_TIME 58 8796
TEST_TIME 59 8830
TEST_TIME 60 8794
TEST_TIME 61 8826
TEST_TIME 62 9122
TEST_TIME 63 9040
TEST_TIME 64 9058
TEST_TIME 65 9138
TEST_TIME 66 9162
TEST_TIME 67 9142
TEST_TIME 68 9215
TEST_TIME 69 9122
TEST_TIME 70 9008
TEST_TIME 71 8947
TEST_TIME 72 8921
TEST_TIME 73 8902
TEST_TIME 74 8879
TEST_TIME 75 8817
TEST_TIME 76 8773
TEST_TIME 77 8726
TEST_TIME 78 8695
TEST_TIME 79 8671
TEST_TIME 80 8704
TEST_TIME 81 8757
TEST_TIME 82 8738
TEST_TIME 83 8808
TEST_TIME 84 8780
TEST_TIME 85 8787
TEST_TIME 86 8760
TEST_TIME 87 8792
TEST_TIME 88 8793
TEST_TIME 89 8814
TEST_TIME 90 8725
TEST_TIME 91 8709
TEST_TIME 92 8668
TEST_TIME 93 8644
TEST_TIME 94 8627
TEST_TIME 95 8647
TEST_TIME 96 8696
TEST_TIME 97 8670
TEST_TIME 98 8672
TEST_TIME 99 8704
TEST_TIME 100 8656
TEST_TIME 101 8685
TEST_TIME 102 8726
TEST_TIME 103 8679
TEST_TIME 104 8678
TEST_TIME 105 8703
TEST_TIME 106 8693
TEST_TIME 107 8752
TEST_TIME 108 8820
TEST_TIME 109 8741
TEST_TIME 110 8678
TEST_TIME 111 8672
TEST_TIME 112 8606
TEST_TIME 113 8592
TEST_TIME 114 8553
TEST_TIME 115 8584
TEST_TIME 116 8581
TEST_TIME 117 8576
TEST_TIME 118 8612
TEST_TIME 119 8622
TEST_TIME 120 8619

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-08-2020 03:52:37 to 17-08-2020 03:52:40.

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 1442706 1442706 0 0.00 0 19439 8 0 0
doc_list_biud 1442706 1442706 0 0.00 0 31283 36 0 0
sp_add_invoice_to_stock 4866 4783 83 1.70 58 233138 22009 0 83
sp_cancel_adding_invoice 5156 5156 0 0.00 4 73065 1131 0 0
sp_cancel_client_order 1737 1737 0 0.00 4 9572 505 0 0
sp_cancel_customer_reserve 506619 506619 0 0.00 0 24110 81 0 0
sp_cancel_pay_from_customer 711 711 0 0.00 2 12702 132 0 0
sp_cancel_pay_to_supplier 409 409 0 0.00 3 5324 109 0 0
sp_cancel_supplier_invoice 5770 5766 4 0.06 2 16477 988 0 4
sp_cancel_supplier_order 380 380 0 0.00 9 1620102 133961 0 0
sp_cancel_write_off 9778 9778 0 0.00 1 10816 154 0 0
sp_client_order 19798 19798 0 0.00 3 21561 455 0 0
sp_customer_reserve 443822 443822 0 0.00 0 93437 213 0 0
sp_fill_shopping_cart 30639 30639 0 0.00 1 17137 47 0 0
sp_fill_shopping_cart_clo_res 443816 443816 0 0.00 0 31534 19 0 0
sp_get_clo_for_invoice 4770 4770 0 0.00 0 42892 405 0 0
sp_kill_cost_storno 16054 16054 0 0.00 0 11627 85 0 0
sp_kill_qstorno_ret_qs2qd 1029162 1029162 0 0.00 0 31282 44 0 0
sp_kill_qty_storno 544078 544078 0 0.00 0 31283 94 0 0
sp_lock_selected_doc 20469 20469 0 0.00 0 7976 7 0 0
sp_make_cost_storno 27444 27444 0 0.00 0 13572 64 0 0
sp_multiply_rows_for_pdistr 27444 27444 0 0.00 0 7448 38 0 0
sp_multiply_rows_for_qdistr 19798 19798 0 0.00 0 14645 246 0 0
sp_pay_from_customer 7020 7020 0 0.00 4 11650 124 0 0
sp_pay_to_supplier 5782 5782 0 0.00 4 19443 146 0 0
sp_payment_common 12802 12802 0 0.00 1 7952 18 0 0
sp_qd_handle_on_cancel_clo 1737 1737 0 0.00 0 9565 462 0 0
sp_qd_handle_on_invoice_upd_sts 9924 9924 0 0.00 0 13386 422 0 0
sp_qd_handle_on_reserve_upd_sts 9874 9874 0 0.00 0 19749 71 0 0
sp_reserve_write_off 9874 9874 0 0.00 6 19809 215 0 0
sp_supplier_invoice 5394 5394 0 0.00 5 100044 4164 0 0
sp_supplier_order 5468 5468 0 0.00 4 26758 1492 0 0
srv_aggregate_perf_data 103 103 0 0.00 2108 116969 13692 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1749 120581 43254 0 0
srv_find_qd_qs_mism 986443 986443 0 0.00 0 14967 7 0 0
srv_make_invnt_saldo 420 420 0 0.00 51 138986 5137 0 0
srv_make_money_saldo 717 717 0 0.00 1 17837 884 0 0
srv_recalc_idx_stat 4 4 0 0.00 188192 451774 369712 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 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4783 4783 0 0.00 0 7640 251 0 0
t$perf-norm:sp_cancel_adding_invoice 440 440 0 0.00 0 3504 153 0 0
t$perf-norm:sp_cancel_client_order 1737 1737 0 0.00 0 1541 13 0 0
t$perf-norm:sp_cancel_customer_reserve 697 697 0 0.00 0 2316 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 711 711 0 0.00 0 1159 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 409 409 0 0.00 0 1203 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 430 430 0 0.00 0 1296 30 0 0
t$perf-norm:sp_cancel_supplier_order 380 380 0 0.00 0 39462 2341 0 0
t$perf-norm:sp_cancel_write_off 1023 1023 0 0.00 0 1787 8 0 0
t$perf-norm:sp_client_order 19798 19798 0 0.00 0 6386 23 0 0
t$perf-norm:sp_customer_reserve 934 934 0 0.00 0 6369 17 0 0
t$perf-norm:sp_pay_from_customer 7020 7020 0 0.00 0 5728 9 0 0
t$perf-norm:sp_pay_to_supplier 5782 5782 0 0.00 0 6344 14 0 0
t$perf-norm:sp_reserve_write_off 9874 9874 0 0.00 0 3538 10 0 0
t$perf-norm:sp_supplier_invoice 5394 5394 0 0.00 0 4989 25 0 0
t$perf-norm:sp_supplier_order 5468 5468 0 0.00 0 5114 22 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 718 35 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 1258 20 0 0
t$perf-norm:srv_make_invnt_saldo 420 420 0 0.00 0 7975 31 0 0
t$perf-norm:srv_make_money_saldo 717 717 0 0.00 0 2812 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 4 0 0
v_add_invoice_to_stock 9702 9536 166 1.71 0 5664 21 0 166
v_all_customers 36230 36230 0 0.00 0 77 1 0 0
v_all_suppliers 30322 30322 0 0.00 0 21 1 0 0
v_all_wares 39596 39596 0 0.00 0 68 1 0 0
v_cancel_adding_invoice 880 880 0 0.00 0 340 7 0 0
v_cancel_client_order 3474 3474 0 0.00 0 3168 5 0 0
v_cancel_customer_prepayment 1422 1422 0 0.00 0 1846 9 0 0
v_cancel_customer_reserve 1394 1394 0 0.00 0 1830 5 0 0
v_cancel_payment_to_supplier 818 818 0 0.00 0 2886 11 0 0
v_cancel_supplier_invoice 868 860 8 0.92 0 4097 19 0 8
v_cancel_supplier_order 760 760 0 0.00 0 1599 8 0 0
v_cancel_write_off 2046 2046 0 0.00 0 2496 18 0 0
v_max_id_clo_ord 5468 5468 0 0.00 0 278 1 0 0
v_max_id_clo_res 934 934 0 0.00 0 7198 28 0 0
v_max_id_ord_sup 5372 5372 0 0.00 0 4827 5 0 0
v_max_non_paid_invoice 1461 1461 0 0.00 0 1119 3 0 0
v_max_non_paid_realizn 3710 3710 0 0.00 0 1387 4 0 0
v_min_id_clo_ord 5468 5468 0 0.00 0 3114 8 0 0
v_min_id_clo_res 934 934 0 0.00 0 6931 16 0 0
v_min_id_ord_sup 5372 5372 0 0.00 0 5756 7 0 0
v_min_non_paid_invoice 5782 5782 0 0.00 0 7948 11 0 0
v_min_non_paid_realizn 7020 7020 0 0.00 0 6781 11 0 0
v_our_firm 9986 9986 0 0.00 0 17 1 0 0
v_reserve_write_off 19748 19748 0 0.00 0 4118 6 0 0
x_lock_depdocs_on_canc_invoice 10492 10492 0 0.00 0 16975 139 0 0
x_lock_depdocs_on_canc_sup_ord 380 380 0 0.00 0 5075 409 0 0
x_make_qstorno_1000_1200 5468 5468 0 0.00 1 26732 1392 0 0
x_make_qstorno_1200_2000 5394 5394 0 0.00 3 99996 3982 0 0
x_make_qstorno_2100_3300 443822 443822 0 0.00 0 93436 190 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-08-2020 03:52:41 to 17-08-2020 03:52:45.

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
01:21:54 7 8192000000 8796611856 8807043072 3144672 0.038 1 1 2 0 3241968 61344 35952
01:22:54 37008 8192000000 9721260352 10014310400 792137008 8.817 100 90 180 2 821671232 7558736 21975488
01:24:40 22576 8192000000 9754633648 10059292672 831234016 9.212 100 87 174 2 865052976 13003568 20815392
01:26:05 18058 8192000000 9803047024 10113159168 862800960 9.528 100 82 165 0 902342976 20218048 19323968
01:27:32 15362 8192000000 9851073328 10181206016 904112000 9.939 100 75 151 1 956382768 36006064 16264704
01:28:51 12320 8192000000 9900979504 10238930944 953423648 10.425 100 85 172 0 1021849344 50558208 17867488
01:30:07 6083 8192000000 9951854928 10299740160 985035904 10.733 100 80 160 0 1063811440 62464720 16310816
01:31:17 4875 8192000000 9984149552 10337222656 1007415040 10.950 100 86 171 1 1099119904 74498240 17206624
01:32:24 3562 8192000000 9995201680 10355609600 1021230640 11.084 100 84 169 1 1106467680 67884624 17352416
01:33:31 3798 8192000000 10009478368 10371887104 1039053040 11.256 100 85 170 3 1129724816 73210496 17461280
01:34:39 1977 8192000000 10012932736 10378485760 1050441888 11.365 100 86 172 1 1129899392 62137312 17320192
01:35:46 2350 8192000000 10033491168 10403368960 1054822512 11.407 100 80 160 0 1146191248 75353136 16015600
01:36:55 1208 8192000000 10048454576 10421108736 1063686896 11.492 100 88 174 2 1165635360 84024256 17924208
01:38:02 17108 8192000000 10022318016 10397782016 1066127408 11.515 100 77 154 5 1146939152 64926560 15885184
01:39:21 1463 8192000000 10037094208 10412163072 1071647744 11.568 100 80 159 0 1149631968 62152512 15831712
01:40:28 3247 8192000000 10003133728 10398658560 1077231840 11.621 100 50 101 2 1130327312 42359008 10736464
01:41:34 5392 8192000000 10013206848 10402332672 1083108736 11.677 100 47 95 1 1128224144 35066864 10048544
01:42:43 47567 8192000000 10006172512 10395471872 1086533424 11.710 100 53 106 1 1111457408 12480880 12443104
01:44:35 6619 8192000000 10006281968 10407116800 1074201024 11.592 99 47 94 0 1114909600 29755280 10953296
01:45:47 5481 8192000000 9992739056 10394320896 1083774672 11.683 100 29 58 5 1112080960 21073232 7233056
01:46:56 8500 8192000000 9923334656 10296881152 977574864 10.661 92 22 44 1 1010016400 27644592 4796944
01:48:08 3283 8192000000 9972868512 10342580224 1040414576 11.269 99 66 133 5 1068135328 13337232 14383520
01:49:13 2358 8192000000 9930794336 10298654720 986042384 10.743 96 56 108 1 1015601472 18703632 10855456
01:50:17 6953 8192000000 9945430304 10325065728 1024642352 11.117 100 36 73 2 1060449584 27916736 7890496
01:51:25 54210 8192000000 9959691552 10343124992 1020003056 11.072 98 41 82 2 1059561552 31274928 8283568
01:53:28 39463 8192000000 10014660752 10398806016 1059333136 11.450 100 45 89 1 1118515008 50292784 8889088
01:55:12 29008 8192000000 10027655728 10411442176 1077881392 11.627 100 51 101 2 1139338128 51259392 10197344
01:56:46 6704 8192000000 10075973888 10462167040 1091768608 11.759 100 58 116 0 1184349856 80089664 12491584
01:57:55 19994 8192000000 10094691824 10490183680 1097814048 11.817 100 49 98 2 1203918976 95679472 10425456
01:59:21 25938 8192000000 10110534992 10511265792 1110046736 11.933 100 41 83 1 1216379120 96868304 9464080
02:00:53 8748 8192000000 10124760160 10520817664 1120294160 12.030 100 64 125 0 1230603696 97142416 13167120
02:02:10 21927 8192000000 10184272016 10581098496 1127800224 12.101 100 64 128 4 1290135776 147646224 14689328
02:03:39 14000 8192000000 10203290176 10608320512 1141623616 12.231 100 56 110 2 1310098224 156176272 12298336
02:05:00 9213 8192000000 10244094544 10651385856 1147580144 12.287 100 48 96 2 1345117312 186406384 11130784
02:06:18 14761 8192000000 10277853216 10685992960 1157135392 12.376 100 50 100 0 1383120912 214756848 11228672
02:07:37 12181 8192000000 10325415808 10733404160 1164749664 12.448 100 61 122 0 1429357696 250439488 14168544
02:08:59 4032 8192000000 10350974608 10755784704 1173015248 12.525 100 71 141 3 1451269152 261642800 16611104
02:10:09 4237 8192000000 10412996560 10816913408 1177893200 12.571 100 77 152 0 1515913168 320589936 17430032
02:11:20 5034 8192000000 10449764688 10854187008 1183365024 12.622 100 77 154 0 1544048352 342547904 18135424
02:12:30 2286 8192000000 10472601776 10876076032 1164765680 12.448 99 90 178 1 1568308608 381754192 21788736
02:13:40 4168 8192000000 10478555776 10882080768 1176394448 12.557 100 84 168 1 1577738944 381691216 19653280
02:14:55 4781 8192000000 10435190352 10880700416 1173360416 12.528 100 74 148 1 1541229152 349933296 17935440
02:16:06 4781 8192000000 10433130416 10840903680 1180838704 12.598 100 71 142 0 1532022016 334806368 16376944
02:17:20 2138 8192000000 10460617328 10866028544 1183563376 12.623 100 84 167 0 1557687408 354988368 19135664
02:18:27 13997 8192000000 10434470720 10876563456 1157531568 12.380 98 38 76 2 1521801248 354803344 9466336
02:19:56 1747 8192000000 10481737648 10885984256 1158931120 12.393 100 95 189 1 1575274768 393685056 22658592
02:21:05 9585 8192000000 10419429360 10849980416 1132655600 12.146 99 52 102 2 1521649072 376672480 12320992
02:22:21 10979 8192000000 10412863600 10868805632 1121931248 12.045 100 40 80 1 1543839136 411886208 10021680
02:23:35 28880 8192000000 10353029712 10791403520 1126884256 12.092 100 32 62 1 1458588672 323635360 8069056
02:25:08 7799 8192000000 10301773936 10785619968 1128210768 12.104 100 52 104 0 1391127376 251609440 11307168
02:26:23 32509 8192000000 10114667632 10583715840 1105686528 11.892 98 20 38 1 1186428176 76764576 3977072
02:27:59 58542 8192000000 10073425392 10560303104 1103797280 11.874 97 22 45 2 1172482400 64374896 4310224
02:29:59 55825 8192000000 10031042592 10448797696 1070129712 11.553 96 37 74 4 1102240112 23155888 8954512
02:31:58 117262 8192000000 10036494080 10500116480 1089367264 11.737 98 26 50 3 1128706672 33715968 5623440
02:34:57 120581 8192000000 10051131936 10472443904 1094782832 11.788 97 29 58 2 1140032848 39608416 5641600
02:38:02 19352 8192000000 10071415424 10504843264 1097222144 11.811 98 23 45 1 1165925680 63835072 4868464
02:39:25 23955 8192000000 10133983120 10559787008 1127064272 12.094 100 46 92 2 1233700576 96356992 10279312
02:40:59 2415 8192000000 10104194864 10514931712 1115077008 11.980 99 91 181 0 1192035168 57151968 19806192
02:42:04 55573 8192000000 10056074816 10509012992 1078547808 11.634 96 16 33 2 1175681488 93734944 3398736
02:44:02 29330 8192000000 10081281744 10513756160 1110967840 11.942 99 18 36 1 1185193952 70024288 4201824
02:46:13 33280 8192000000 10046502688 10517037056 1116317824 11.992 100 9 18 0 1192971520 74686272 1967424
02:47:48 91779 8192000000 10053846384 10488983552 1135625920 12.174 100 17 34 2 1213281440 74287072 3368448
02:50:20 62379 8192000000 10104600464 10547539968 1112651536 11.958 98 14 28 2 1198013760 82217744 3144480
02:52:23 77779 8192000000 10143615712 10641113088 1073717968 11.588 94 11 21 2 1159305168 83308928 2278272
02:54:42 30075 8192000000 10100165152 10524037120 1122371888 12.049 98 47 95 4 1188570832 56512640 9686304
02:56:23 76185 8192000000 10082787520 10558078976 1128645424 12.109 99 14 28 3 1176373696 44861952 2866320
02:58:48 43377 8192000000 10105716688 10546130944 1106044112 11.895 97 22 44 2 1189198624 78636352 4518160
03:00:31 108653 8192000000 10065620512 10499534848 1122889728 12.054 99 41 82 1 1153142304 21070752 9181824
03:03:28 62419 8192000000 10067915664 10518458368 1101879504 11.855 97 29 58 1 1141367120 33326704 6160912
03:05:36 37955 8192000000 10050187248 10529144832 1120413328 12.031 99 3 6 0 1146970032 26199920 356784
03:07:15 52774 8192000000 10050744368 10508943360 1131079536 12.132 100 11 22 0 1165958160 32473568 2405056
03:09:32 42832 8192000000 10067235760 10510221312 1118888816 12.016 99 23 47 1 1160753520 36730928 5133776
03:11:15 70313 8192000000 10067383216 10556162048 1110465600 11.937 99 16 32 1 1193714000 79535344 3713056
03:13:25 69644 8192000000 10069235296 10538958848 1101041904 11.848 98 20 39 3 1182085984 76612032 4432048
03:15:35 65240 8192000000 10072621568 10528935936 1105569104 11.890 99 30 60 2 1167903072 56406848 5927120
03:17:47 51120 8192000000 10056163152 10466938880 1091008688 11.752 98 35 70 1 1141740288 43408992 7322608
03:19:38 38780 8192000000 10073635216 10514792448 1088470992 11.728 97 25 50 0 1144925472 51254160 5200320
03:21:18 88375 8192000000 10074457248 10551238656 1116057200 11.990 100 10 20 0 1172557248 54561248 1938800
03:23:55 68222 8192000000 10078114800 10582016000 1123884992 12.064 100 10 20 1 1187593728 61903616 1805120
03:26:04 89510 8192000000 10086858432 10552221696 1119010848 12.018 99 16 32 1 1165267936 42676496 3580592
03:28:38 51247 8192000000 10084137216 10518372352 1117484512 12.003 98 22 43 2 1173293952 51493520 4315920
03:30:33 71044 8192000000 10072778480 10496172032 1064511216 11.500 94 26 52 2 1108980016 39146064 5322736
03:32:51 109908 8192000000 10052493104 10521214976 1090126688 11.744 98 14 28 0 1147268656 54120592 3021376
03:35:47 89272 8192000000 10061178896 10513760256 1069382224 11.546 96 18 36 1 1115793776 42735792 3675760
03:38:17 71725 8192000000 10097840848 10611097600 1111211888 11.944 99 16 31 3 1214784656 100138000 3434768
03:40:29 99283 8192000000 10067959616 10506412032 1100945792 11.847 98 21 42 0 1138903440 32983712 4973936
03:43:11 30335 8192000000 10052407264 10525384704 1107821456 11.912 100 3 6 0 1154782640 46383888 577296
03:45:38 63489 8192000000 10091381824 10568830976 1112991440 11.961 99 28 53 3 1198559024 78586992 6980592
03:48:02 54946 8192000000 10113334944 10565414912 1131931520 12.140 100 23 45 0 1207259552 70112496 5215536
03:49:58 76705 8192000000 10089514320 10536644608 1073155344 11.582 95 23 46 0 1137986064 60057232 4773488

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 03:52:47 to 17-08-2020 03:53:01.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-08-2020 03:53:02 to 17-08-2020 03:53:04.

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          214101
MON$OLDEST_ACTIVE               216691
MON$OLDEST_SNAPSHOT             216691
MON$NEXT_TRANSACTION            216691
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                       2071200
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             679
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 ---
:115: 
:116: mscs-test-161.budzdorov.ru    Mon Aug 17 03:51:52 2020
:117:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:118: 
:119: 
:120: mscs-test-161.budzdorov.ru    Mon Aug 17 03:51:52 2020
:121:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:122: 
:123: 
:124: mscs-test-161.budzdorov.ru    Mon Aug 17 03:51:55 2020
:125:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:126: 
--- end of diff output ---

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

17.08.20 03:53:06. 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 03:53:08 NO_SEVERE_ERRORS_FOUND
17.08.20 03:53:10. End of report.