Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.10.2020 00:58
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 17302236 kB
MemAvailable 31671556 kB
Buffers 12 kB
Cached 14413140 kB
SwapCached 6104 kB
Command: fdisk -l
./oltp_isql_run_worker.sh: line 45: fdisk: command not found

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.7
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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 00:56:58
DTS_END                         2020-10-10 00:56:58.5880
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(134:18) ==> SP_CANCEL_SUPPLIER_INVOICE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(56:17)
IP                              ::1/55248
TRN_ID                          254303
ATT_ID                          678
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: 10023.61 16633 1202833
customer order: creation 190.63 305 22875
customer order: refuse 16.07 269 1928
order to supplier: creation 49.35 1117 5922
order to supplier: removal 3.78 67743 453
invoice (draft): creation 48.97 2734 5876
invoice (draft): removal 57.53 610 6904
invoice accept: apply 46.20 15150 5544
invoice accept: cancel 51.75 570 6210
customer reserve: creation 4189.15 152 502698
customer reserve: removal 5049.93 38 605992
realization accept: apply 92.03 149 11043
realization accept: cancel 85.98 91 10318
payment from customer: creation 65.53 97 7863
payment from customer: removal 6.43 70 771
payment to supplier: creation 53.35 99 6402
payment to supplier: removal 4.12 63 494
service: total inventory turnovers 4.97 1138 596
service: total monetary turnovers 6.83 30 819
service: aggregate perf. data 1.01 14330 121
service: refresh index statistics 0.03 227911 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-10-2020 00:59:07 to 10-10-2020 00:59:09.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3254
TEST_TIME 2 5330
TEST_TIME 3 5676
TEST_TIME 4 5700
TEST_TIME 5 5559
TEST_TIME 6 6621
TEST_TIME 7 6842
TEST_TIME 8 7312
TEST_TIME 9 7472
TEST_TIME 10 8191
TEST_TIME 11 8088
TEST_TIME 12 8282
TEST_TIME 13 8696
TEST_TIME 14 8898
TEST_TIME 15 9071
TEST_TIME 16 9766
TEST_TIME 17 10219
TEST_TIME 18 10160
TEST_TIME 19 10076
TEST_TIME 20 10567
TEST_TIME 21 12043
TEST_TIME 22 11767
TEST_TIME 23 11481
TEST_TIME 24 11926
TEST_TIME 25 11646
TEST_TIME 26 11613
TEST_TIME 27 11556
TEST_TIME 28 11494
TEST_TIME 29 11328
TEST_TIME 30 11275
TEST_TIME 31 11293
TEST_TIME 32 11215
TEST_TIME 33 11205
TEST_TIME 34 11085
TEST_TIME 35 10952
TEST_TIME 36 11139
TEST_TIME 37 11238
TEST_TIME 38 11149
TEST_TIME 39 11002
TEST_TIME 40 10810
TEST_TIME 41 10646
TEST_TIME 42 10776
TEST_TIME 43 10754
TEST_TIME 44 10690
TEST_TIME 45 10703
TEST_TIME 46 10593
TEST_TIME 47 10614
TEST_TIME 48 10577
TEST_TIME 49 10481
TEST_TIME 50 10375
TEST_TIME 51 10242
TEST_TIME 52 10363
TEST_TIME 53 10341
TEST_TIME 54 10324
TEST_TIME 55 10283
TEST_TIME 56 10340
TEST_TIME 57 10394
TEST_TIME 58 10381
TEST_TIME 59 10375
TEST_TIME 60 10353
TEST_TIME 61 10440
TEST_TIME 62 10480
TEST_TIME 63 10589
TEST_TIME 64 10635
TEST_TIME 65 10698
TEST_TIME 66 10658
TEST_TIME 67 10510
TEST_TIME 68 10512
TEST_TIME 69 10492
TEST_TIME 70 10509
TEST_TIME 71 10405
TEST_TIME 72 10372
TEST_TIME 73 10305
TEST_TIME 74 10290
TEST_TIME 75 10226
TEST_TIME 76 10167
TEST_TIME 77 10118
TEST_TIME 78 10132
TEST_TIME 79 10099
TEST_TIME 80 10087
TEST_TIME 81 10025
TEST_TIME 82 10032
TEST_TIME 83 9996
TEST_TIME 84 9999
TEST_TIME 85 9998
TEST_TIME 86 10087
TEST_TIME 87 10122
TEST_TIME 88 10102
TEST_TIME 89 10212
TEST_TIME 90 10233
TEST_TIME 91 10212
TEST_TIME 92 10207
TEST_TIME 93 10290
TEST_TIME 94 10291
TEST_TIME 95 10309
TEST_TIME 96 10222
TEST_TIME 97 10228
TEST_TIME 98 10176
TEST_TIME 99 10174
TEST_TIME 100 10143
TEST_TIME 101 10097
TEST_TIME 102 10064
TEST_TIME 103 10021
TEST_TIME 104 9977
TEST_TIME 105 10031
TEST_TIME 106 10009
TEST_TIME 107 9992
TEST_TIME 108 9958
TEST_TIME 109 9992
TEST_TIME 110 10079
TEST_TIME 111 10061
TEST_TIME 112 10015
TEST_TIME 113 10150
TEST_TIME 114 10139
TEST_TIME 115 10134
TEST_TIME 116 10143
TEST_TIME 117 10136
TEST_TIME 118 10105
TEST_TIME 119 10069
TEST_TIME 120 10027

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 10-10-2020 00:59:11 to 10-10-2020 00:59:14.

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 1667584 1667584 0 0.00 0 15872 5 0 0
doc_list_biud 1667583 1667583 0 0.00 0 44529 19 0 0
sp_add_invoice_to_stock 5605 5544 61 1.08 35 183048 15150 0 61
sp_cancel_adding_invoice 6210 6210 0 0.00 5 49062 570 0 0
sp_cancel_client_order 1928 1928 0 0.00 4 12488 269 0 0
sp_cancel_customer_reserve 605992 605992 0 0.00 1 44531 39 0 0
sp_cancel_pay_from_customer 771 771 0 0.00 3 6855 71 0 0
sp_cancel_pay_to_supplier 494 494 0 0.00 3 5407 64 0 0
sp_cancel_supplier_invoice 6907 6904 3 0.04 2 25341 611 0 3
sp_cancel_supplier_order 453 453 0 0.00 6 844868 67743 0 0
sp_cancel_write_off 10318 10318 0 0.00 1 9153 91 0 0
sp_client_order 22875 22875 0 0.00 4 32343 305 0 0
sp_customer_reserve 502698 502698 0 0.00 0 49704 152 0 0
sp_fill_shopping_cart 34662 34662 0 0.00 1 9438 31 0 0
sp_fill_shopping_cart_clo_res 502684 502684 0 0.00 0 17120 12 0 0
sp_get_clo_for_invoice 5522 5522 0 0.00 0 21439 267 0 0
sp_kill_cost_storno 17793 17793 0 0.00 0 15858 39 0 0
sp_kill_qstorno_ret_qs2qd 1229659 1229659 0 0.00 0 44508 22 0 0
sp_kill_qty_storno 648365 648365 0 0.00 0 44529 48 0 0
sp_lock_selected_doc 22888 22888 0 0.00 0 8031 5 0 0
sp_make_cost_storno 30826 30826 0 0.00 0 15387 41 0 0
sp_multiply_rows_for_pdistr 30825 30825 0 0.00 0 12028 25 0 0
sp_multiply_rows_for_qdistr 22875 22875 0 0.00 0 32327 132 0 0
sp_pay_from_customer 7863 7863 0 0.00 4 12048 98 0 0
sp_pay_to_supplier 6402 6402 0 0.00 4 8946 100 0 0
sp_payment_common 14265 14265 0 0.00 1 8911 25 0 0
sp_qd_handle_on_cancel_clo 1928 1928 0 0.00 0 12481 246 0 0
sp_qd_handle_on_invoice_upd_sts 11728 11728 0 0.00 0 19626 256 0 0
sp_qd_handle_on_reserve_upd_sts 11042 11042 0 0.00 0 18040 52 0 0
sp_reserve_write_off 11043 11043 0 0.00 6 18313 149 0 0
sp_supplier_invoice 5876 5876 0 0.00 7 76902 2735 0 0
sp_supplier_order 5922 5922 0 0.00 5 34031 1117 0 0
srv_aggregate_perf_data 122 121 1 0.81 3008 101235 14331 0 1
srv_fill_mon_cache_memory 62 62 0 0.00 2175 142919 48238 0 0
srv_find_qd_qs_mism 1150221 1150221 0 0.00 0 10362 4 0 0
srv_make_invnt_saldo 596 596 0 0.00 41 91381 1139 0 0
srv_make_money_saldo 819 819 0 0.00 1 4889 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 127892 298452 227912 0 0
t$perf-abend:sp_add_invoice_to_stock 61 61 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5544 5544 0 0.00 0 8234 149 0 0
t$perf-norm:sp_cancel_adding_invoice 406 406 0 0.00 0 4271 104 0 0
t$perf-norm:sp_cancel_client_order 1928 1928 0 0.00 0 1357 9 0 0
t$perf-norm:sp_cancel_customer_reserve 743 743 0 0.00 0 2432 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 771 771 0 0.00 0 1414 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 494 494 0 0.00 0 2759 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 449 449 0 0.00 0 2622 19 0 0
t$perf-norm:sp_cancel_supplier_order 453 453 0 0.00 0 19211 1246 0 0
t$perf-norm:sp_cancel_write_off 1086 1086 0 0.00 0 4039 12 0 0
t$perf-norm:sp_client_order 22875 22875 0 0.00 0 7173 18 0 0
t$perf-norm:sp_customer_reserve 1049 1049 0 0.00 0 3710 9 0 0
t$perf-norm:sp_pay_from_customer 7863 7863 0 0.00 0 6357 8 0 0
t$perf-norm:sp_pay_to_supplier 6402 6402 0 0.00 0 3493 8 0 0
t$perf-norm:sp_reserve_write_off 11043 11043 0 0.00 0 6802 13 0 0
t$perf-norm:sp_supplier_invoice 5876 5876 0 0.00 0 7716 19 0 0
t$perf-norm:sp_supplier_order 5922 5922 0 0.00 0 5836 17 0 0
t$perf-norm:srv_aggregate_perf_data 121 121 0 0.00 0 144 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 657 11 0 0
t$perf-norm:srv_make_invnt_saldo 596 596 0 0.00 0 3510 20 0 0
t$perf-norm:srv_make_money_saldo 819 819 0 0.00 0 1869 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 68 19 0 0
v_add_invoice_to_stock 11154 11032 122 1.09 0 9302 17 0 122
v_all_customers 41032 41032 0 0.00 0 14 1 0 0
v_all_suppliers 32178 32178 0 0.00 0 24 1 0 0
v_all_wares 45750 45750 0 0.00 0 104 1 0 0
v_cancel_adding_invoice 812 812 0 0.00 0 1248 8 0 0
v_cancel_client_order 3856 3856 0 0.00 0 2316 6 0 0
v_cancel_customer_prepayment 1542 1542 0 0.00 0 3122 6 0 0
v_cancel_customer_reserve 1486 1486 0 0.00 0 2805 8 0 0
v_cancel_payment_to_supplier 988 988 0 0.00 0 581 3 0 0
v_cancel_supplier_invoice 904 898 6 0.66 0 469 14 0 6
v_cancel_supplier_order 906 906 0 0.00 0 5385 12 0 0
v_cancel_write_off 2172 2172 0 0.00 0 4681 14 0 0
v_max_id_clo_ord 5921 5921 0 0.00 0 460 1 0 0
v_max_id_clo_res 1049 1049 0 0.00 0 1900 8 0 0
v_max_id_ord_sup 5866 5866 0 0.00 0 6847 4 0 0
v_max_non_paid_invoice 2100 2100 0 0.00 0 1151 3 0 0
v_max_non_paid_realizn 4494 4494 0 0.00 0 651 3 0 0
v_min_id_clo_ord 5921 5921 0 0.00 0 3641 7 0 0
v_min_id_clo_res 1049 1049 0 0.00 0 4506 16 0 0
v_min_id_ord_sup 5866 5866 0 0.00 0 4262 4 0 0
v_min_non_paid_invoice 6402 6402 0 0.00 0 8908 16 0 0
v_min_non_paid_realizn 7863 7863 0 0.00 0 7372 15 0 0
v_our_firm 11456 11456 0 0.00 0 13 1 0 0
v_reserve_write_off 22084 22084 0 0.00 0 4346 5 0 0
x_lock_depdocs_on_canc_invoice 12665 12665 0 0.00 0 11726 92 0 0
x_lock_depdocs_on_canc_sup_ord 453 453 0 0.00 0 23856 325 0 0
x_make_qstorno_1000_1200 5922 5922 0 0.00 1 34022 1052 0 0
x_make_qstorno_1200_2000 5876 5876 0 0.00 2 76878 2628 0 0
x_make_qstorno_2100_3300 502698 502698 0 0.00 0 49702 139 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-10-2020 00:59:15 to 10-10-2020 00:59:19.

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
22:27:02 2 8192000000 8798422528 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
22:28:03 18874 8192000000 10371295536 10688094208 1411884736 14.701 100 87 173 1 1446420976 6418336 28117904
22:29:31 12102 8192000000 10455539248 10789097472 1512934704 15.589 100 89 178 0 1549369136 8310944 28123488
22:30:48 19728 8192000000 10506300352 10851020800 1555238880 15.955 100 85 170 1 1592424192 10320704 26864608
22:32:11 12005 8192000000 10581577856 10942398464 1642811392 16.704 100 87 174 0 1681494928 12229456 26454080
22:33:28 4458 8192000000 10644733936 11010093056 1701995504 17.202 100 89 178 1 1743070544 14996656 26078384
22:34:34 5201 8192000000 10687957792 11068997632 1758856096 17.675 100 82 163 1 1794580544 13129856 22594592
22:35:41 3157 8192000000 10758181408 11142819840 1819911264 18.177 100 89 179 0 1857074320 12217984 24945072
22:36:46 1996 8192000000 10804556864 11193602048 1858702464 18.493 100 87 176 2 1895136672 12895856 23538352
22:37:49 11137 8192000000 10812183536 11210874880 1876443088 18.636 100 78 154 1 1906744992 7176896 23125008
22:39:02 4813 8192000000 10790118432 11217158144 1881157152 18.674 100 33 65 0 1897916784 7085520 9674112
22:40:08 7414 8192000000 10792503264 11224117248 1889693680 18.743 100 23 43 1 1901473216 5619824 6159712
22:41:18 11018 8192000000 10803795392 11232923648 1894826544 18.785 100 18 36 0 1905935568 5576592 5532432
22:42:32 59706 8192000000 10823794192 11255635968 1904642928 18.864 100 21 42 2 1915578816 5170880 5765008
22:44:35 6535 8192000000 10837134560 11274883072 1915035376 18.947 100 25 50 2 1933758304 12103168 6619760
22:45:42 6524 8192000000 10809493936 11237646336 1872215392 18.602 99 18 36 1 1887005280 10002336 4787552
22:46:53 52818 8192000000 10731585984 11163201536 1725816672 17.401 92 30 59 1 1739813376 4356720 9639984
22:48:48 7364 8192000000 10720376720 11133587456 1732499136 17.456 95 23 46 0 1748135408 8963632 6672640
22:49:57 5456 8192000000 10710648160 11123941376 1762609200 17.706 98 29 56 0 1776666064 5289776 8767088
22:51:02 47879 8192000000 10736439248 11159203840 1792693376 17.954 98 37 74 2 1809699216 5737296 11268544
22:53:05 6328 8192000000 10772140400 11195994112 1853988016 18.455 100 30 60 0 1869984368 7647920 8348432
22:54:12 10376 8192000000 10784572816 11209048064 1864640048 18.541 100 24 48 0 1877312496 5770672 6901776
22:55:22 17009 8192000000 10782847952 11218223104 1871778096 18.599 100 11 22 1 1880344704 5348192 3218416
22:56:45 51793 8192000000 10841481840 11264618496 1903159616 18.852 100 54 108 0 1925811488 8211296 14440576
22:58:58 12714 8192000000 10873883904 11304964096 1946378576 19.198 100 46 92 0 1968585360 8769504 13437280
23:00:16 47771 8192000000 10900968480 11346034688 1972160000 19.403 100 33 66 1 1994448672 12451376 9837296
23:02:11 6082 8192000000 10936660320 11365982208 1988551488 19.532 100 60 120 1 2024665840 18803904 17310448
23:03:20 6311 8192000000 10948104720 11382198272 2005844640 19.669 100 58 116 1 2041019664 18548208 16626816
23:04:29 5467 8192000000 10955794560 11392475136 1997299440 19.601 99 60 121 0 2033119680 17856880 17963360
23:05:37 9126 8192000000 10953924400 11384713216 2003284464 19.649 100 68 136 2 2046504912 23265152 19955296
23:06:53 5362 8192000000 10967168912 11391524864 2007760400 19.684 100 76 154 2 2051716976 21813136 22143440
23:08:04 2173 8192000000 10943679808 11371417600 1972400960 19.404 99 67 129 1 2016417104 23981136 20035008
23:09:11 4873 8192000000 10903655440 11327537152 1937527360 19.127 99 59 119 2 1978712704 23094544 18090800
23:10:19 2863 8192000000 10889506416 11315191808 1926467040 19.039 99 57 112 0 1973033648 27713408 18853200
23:11:27 2972 8192000000 10899548000 11302252544 1916784480 18.961 99 75 150 1 1973149008 31539072 24825456
23:12:32 7716 8192000000 10892090880 11318190080 1921900624 19.002 100 51 102 0 1978668624 39794528 16973472
23:13:40 32453 8192000000 10879180160 11317370880 1902582128 18.847 99 28 56 0 1944583232 32755984 9245120
23:15:14 18467 8192000000 10844138608 11282042880 1864492784 18.540 97 28 56 2 1907376944 33437744 9446416
23:16:33 21866 8192000000 10901439840 11330805760 1907921792 18.890 99 32 65 1 1960104688 41382816 10800080
23:17:56 28911 8192000000 10871767904 11327475712 1923086160 19.012 99 28 56 2 1951063968 20275024 7702784
23:19:33 25993 8192000000 10855258272 11313995776 1919123216 18.980 99 23 46 1 1944893904 18783088 6987600
23:21:00 74734 8192000000 10895033056 11334230016 1936849376 19.122 100 23 44 3 1956512544 12467984 7195184
23:23:25 56662 8192000000 10849437568 11304415232 1846016112 18.390 95 12 24 0 1858415568 9510864 2888592
23:25:27 87577 8192000000 10889277456 11348774912 1952447968 19.246 100 24 48 1 1975400608 16586240 6366400
23:28:07 142918 8192000000 10910672560 11364421632 1923445856 19.014 97 7 14 1 1937544976 12538048 1561072
23:31:30 62370 8192000000 10941739600 11406888960 1999079904 19.615 100 18 34 1 2017262880 13136544 5046432
23:34:06 76368 8192000000 10936153600 11394285568 1970967152 19.393 98 33 64 2 1991215840 11065168 9183520
23:36:24 63399 8192000000 10961593856 11410300928 1996457888 19.595 99 45 89 2 2023173632 13115008 13600736
23:38:45 29593 8192000000 10927079728 11396390912 1986077760 19.513 99 35 70 1 2006488048 10601200 9809088
23:40:17 19560 8192000000 10913822048 11379200000 1988113344 19.529 100 18 34 0 2000365200 7425568 4826288
23:41:38 26216 8192000000 10919811424 11374817280 1944111984 19.180 97 28 56 1 1960692944 8753984 7826976
23:43:07 47146 8192000000 10884639216 11351437312 1924092048 19.020 97 14 27 1 1944165552 16147840 3925664
23:45:37 58578 8192000000 10880451168 11351949312 1886043424 18.714 95 10 20 1 1899417792 10362096 3012272
23:47:46 54207 8192000000 10876305712 11368497152 1948759312 19.217 99 20 40 1 1973419168 18650976 6008880
23:49:41 62298 8192000000 10864117712 11328110592 1852226336 18.440 94 7 14 0 1867290352 13044272 2019744
23:52:08 68726 8192000000 10846348400 11318411264 1842881408 18.364 94 14 28 0 1858832272 11718864 4232000
23:54:17 52344 8192000000 10841105552 11304284160 1878097008 18.650 98 29 57 3 1902665152 15924976 8643168
23:56:18 35458 8192000000 10843098256 11287191552 1872335104 18.603 98 34 68 3 1895812224 14071936 9405184
23:57:57 50119 8192000000 10847857552 11316170752 1854824080 18.461 96 17 34 1 1876600464 16809328 4967056
23:59:47 54302 8192000000 10865040656 11315298304 1874524496 18.621 98 14 28 0 1897151664 18319600 4307568
00:02:40 101838 8192000000 10863728720 11327365120 1919453744 18.982 99 30 60 1 1947273344 18617808 9201792
00:05:23 65484 8192000000 10858741088 11340341248 1929046384 19.059 99 33 66 2 1949662288 11236288 9379616
00:08:00 66753 8192000000 10860441392 11336593408 1932018448 19.083 99 10 20 0 1942257952 7546512 2692992
00:10:11 51000 8192000000 10879811776 11348942848 1959887968 19.305 100 14 28 0 1973254960 9437648 3929344
00:12:05 66536 8192000000 10881719872 11350048768 1943128496 19.172 99 19 36 1 1956942032 8362192 5451344
00:14:33 35451 8192000000 10899000432 11364016128 1970333744 19.388 100 18 36 2 1982552784 6899760 5319280
00:16:11 70937 8192000000 10888320400 11380027392 1973952704 19.417 100 8 17 1 1986149072 10056832 2139536
00:19:31 39647 8192000000 10899666112 11381919744 1984082352 19.497 100 11 22 0 1996375520 9268064 3025104
00:21:11 51620 8192000000 10931646432 11405758464 2002957408 19.646 100 30 60 1 2028278208 17485920 7834880
00:23:18 83347 8192000000 10942449392 11417575424 2009042880 19.694 100 21 41 2 2034218416 20022784 5152752
00:26:03 84045 8192000000 10946642496 11425480704 2021043024 19.788 100 19 38 1 2044944992 18678496 5223472
00:28:28 55639 8192000000 10935397344 11420823552 1998552544 19.611 99 30 60 1 2022166448 15770384 7843520
00:30:25 61704 8192000000 10920941024 11406430208 1940044016 19.147 97 15 30 0 1958728640 14160128 4524496
00:32:28 90986 8192000000 10903655264 11385831424 1901957408 18.842 95 18 36 0 1916114896 9012976 5144512
00:34:59 56736 8192000000 10874096304 11348873216 1903139552 18.852 96 21 42 0 1920172640 10336096 6696992
00:37:06 62185 8192000000 10872937088 11352662016 1949159680 19.220 98 19 39 2 1966064768 11221360 5683728
00:39:16 74054 8192000000 10870954240 11354529792 1928033424 19.051 98 10 20 0 1945987408 14661920 3292064
00:41:30 56533 8192000000 10833939552 11307077632 1895274400 18.788 97 16 32 2 1910081568 10162688 4644480
00:43:28 39578 8192000000 10902152368 11370385408 1959642688 19.303 100 23 46 0 1976264560 9624112 6997760
00:45:14 43457 8192000000 10852823792 11327205376 1889888384 18.745 97 15 30 0 1915445424 21115888 4441152
00:47:00 74036 8192000000 10851133392 11307900928 1781206352 17.859 91 25 49 2 1804021632 15417904 7397376
00:49:27 82344 8192000000 10846100688 11309924352 1837111200 18.317 95 18 34 3 1853043344 11349232 4582912
00:51:51 26155 8192000000 10846415712 11297325056 1894090896 18.779 99 36 72 2 1918048848 13552688 10405264
00:53:26 24678 8192000000 10856593248 11297816576 1891968352 18.762 99 35 70 1 1913361568 12019696 9373520
00:54:52 110446 8192000000 10839267456 11311652864 1891064048 18.754 98 29 58 0 1913036896 12621952 9350896

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 1 srv_aggregate_perf_data 335544517
user_exc 61 v_add_invoice_to_stock 335544517
user_exc 3 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-10-2020 00:59:36 to 10-10-2020 00:59:38.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          254302
MON$OLDEST_ACTIVE               255491
MON$OLDEST_SNAPSHOT             255491
MON$NEXT_TRANSACTION            255491
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-03 16:41:55.3470
MON$PAGES                       2060064
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.7.33371 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.7.33371 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.7.33371 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.7.33371 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:37: 
:38: mscs-test-161.budzdorov.ru    Sat Oct 10 00:57:00 2020
:39:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:40: 
:41: 
:42: mscs-test-161.budzdorov.ru    Sat Oct 10 00:57:00 2020
:43:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:44: 
:45: 
:46: mscs-test-161.budzdorov.ru    Sat Oct 10 00:57:14 2020
:47:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:48: 
--- end of diff output ---

Final processing ISQL logs in /var/tmp/logs-oltp30 according to config parameter 'remove_isql_logs'

10.10.20 00:59:39. There are 3 logs in /var/tmp/logs-oltp30 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 00:59:42 No severe PSQL-related problems occured
10.10.20 00:59:43. End of report.