Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.11.2020 04:28
Common Performance Final Results
  1. Hardware and OS info
  2. 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 was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. 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'
Manufacturer HP
Product Name ProLiant DL380 Gen9
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 16833328 kB
MemAvailable 31416120 kB
Buffers 20 kB
Cached 14802444 kB
SwapCached 2856 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2020-11-13 04:26:53
DTS_END                         2020-11-13 04:26:53.8080
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/58348
TRN_ID                          225915
ATT_ID                          584
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: 8810.83 24093 1057299
customer order: creation 158.31 453 18997
customer order: refuse 13.58 302 1630
order to supplier: creation 42.53 1355 5103
order to supplier: removal 3.38 129685 405
invoice (draft): creation 42.85 3694 5142
invoice (draft): removal 51.05 1020 6126
invoice accept: apply 39.07 16855 4688
invoice accept: cancel 45.93 807 5512
customer reserve: creation 3650.62 162 438074
customer reserve: removal 4488.43 77 538612
realization accept: apply 77.16 168 9259
realization accept: cancel 77.39 121 9287
payment from customer: creation 56.07 117 6728
payment from customer: removal 5.48 67 658
payment to supplier: creation 45.49 118 5459
payment to supplier: removal 3.00 47 360
service: total inventory turnovers 3.78 3031 454
service: total monetary turnovers 5.83 554 699
service: aggregate perf. data 0.85 15022 102
service: refresh index statistics 0.03 308195 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-11-2020 04:28:28 to 13-11-2020 04:28:30.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3038
TEST_TIME 2 3428
TEST_TIME 3 4201
TEST_TIME 4 3960
TEST_TIME 5 4020
TEST_TIME 6 4133
TEST_TIME 7 4645
TEST_TIME 8 4582
TEST_TIME 9 5092
TEST_TIME 10 5276
TEST_TIME 11 5537
TEST_TIME 12 5870
TEST_TIME 13 6010
TEST_TIME 14 6740
TEST_TIME 15 7752
TEST_TIME 16 8016
TEST_TIME 17 8462
TEST_TIME 18 9034
TEST_TIME 19 8875
TEST_TIME 20 8786
TEST_TIME 21 8890
TEST_TIME 22 9371
TEST_TIME 23 9432
TEST_TIME 24 9405
TEST_TIME 25 9996
TEST_TIME 26 10177
TEST_TIME 27 11615
TEST_TIME 28 11693
TEST_TIME 29 11473
TEST_TIME 30 11306
TEST_TIME 31 11174
TEST_TIME 32 10948
TEST_TIME 33 10763
TEST_TIME 34 10588
TEST_TIME 35 10485
TEST_TIME 36 10389
TEST_TIME 37 10177
TEST_TIME 38 9980
TEST_TIME 39 9957
TEST_TIME 40 9892
TEST_TIME 41 9805
TEST_TIME 42 9740
TEST_TIME 43 9737
TEST_TIME 44 9659
TEST_TIME 45 9585
TEST_TIME 46 9519
TEST_TIME 47 9558
TEST_TIME 48 9490
TEST_TIME 49 9378
TEST_TIME 50 9333
TEST_TIME 51 9300
TEST_TIME 52 9409
TEST_TIME 53 9434
TEST_TIME 54 9443
TEST_TIME 55 9500
TEST_TIME 56 9503
TEST_TIME 57 9591
TEST_TIME 58 9540
TEST_TIME 59 9558
TEST_TIME 60 9423
TEST_TIME 61 9308
TEST_TIME 62 9250
TEST_TIME 63 9167
TEST_TIME 64 9175
TEST_TIME 65 9165
TEST_TIME 66 9131
TEST_TIME 67 9168
TEST_TIME 68 9148
TEST_TIME 69 9138
TEST_TIME 70 9095
TEST_TIME 71 9212
TEST_TIME 72 9298
TEST_TIME 73 9253
TEST_TIME 74 9345
TEST_TIME 75 9360
TEST_TIME 76 9328
TEST_TIME 77 9279
TEST_TIME 78 9222
TEST_TIME 79 9206
TEST_TIME 80 9147
TEST_TIME 81 9067
TEST_TIME 82 9021
TEST_TIME 83 8990
TEST_TIME 84 9012
TEST_TIME 85 9053
TEST_TIME 86 9041
TEST_TIME 87 9052
TEST_TIME 88 9057
TEST_TIME 89 9085
TEST_TIME 90 9113
TEST_TIME 91 9159
TEST_TIME 92 9179
TEST_TIME 93 9229
TEST_TIME 94 9227
TEST_TIME 95 9134
TEST_TIME 96 9072
TEST_TIME 97 9024
TEST_TIME 98 9002
TEST_TIME 99 8962
TEST_TIME 100 8943
TEST_TIME 101 8917
TEST_TIME 102 8875
TEST_TIME 103 8893
TEST_TIME 104 8854
TEST_TIME 105 8837
TEST_TIME 106 8843
TEST_TIME 107 8912
TEST_TIME 108 8918
TEST_TIME 109 9075
TEST_TIME 110 9041
TEST_TIME 111 9074
TEST_TIME 112 9043
TEST_TIME 113 9004
TEST_TIME 114 8966
TEST_TIME 115 8952
TEST_TIME 116 8913
TEST_TIME 117 8872
TEST_TIME 118 8859
TEST_TIME 119 8837
TEST_TIME 120 8818

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 13-11-2020 04:28:31 to 13-11-2020 04:28: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 1462189 1462189 0 0.00 0 19356 6 0 0
doc_list_biud 1462186 1462186 0 0.00 0 43987 35 0 0
sp_add_invoice_to_stock 4786 4688 98 2.04 36 203284 16856 0 98
sp_cancel_adding_invoice 5512 5512 0 0.00 5 62096 808 0 0
sp_cancel_client_order 1630 1630 0 0.00 4 14048 303 0 0
sp_cancel_customer_reserve 538612 538612 0 0.00 1 18798 78 0 0
sp_cancel_pay_from_customer 658 658 0 0.00 3 1068 67 0 0
sp_cancel_pay_to_supplier 360 360 0 0.00 3 554 47 0 0
sp_cancel_supplier_invoice 6136 6126 10 0.16 3 43998 1021 0 10
sp_cancel_supplier_order 405 405 0 0.00 8 1001395 129686 0 0
sp_cancel_write_off 9287 9287 0 0.00 1 8833 121 0 0
sp_client_order 18997 18997 0 0.00 4 40345 453 0 0
sp_customer_reserve 438074 438074 0 0.00 0 51034 163 0 0
sp_fill_shopping_cart 29216 29216 0 0.00 1 13524 43 0 0
sp_fill_shopping_cart_clo_res 438070 438070 0 0.00 0 17437 20 0 0
sp_get_clo_for_invoice 4682 4682 0 0.00 0 38092 458 0 0
sp_kill_cost_storno 15817 15817 0 0.00 0 18593 59 0 0
sp_kill_qstorno_ret_qs2qd 1093042 1093042 0 0.00 0 43986 42 0 0
sp_kill_qty_storno 575508 575508 0 0.00 0 43987 88 0 0
sp_lock_selected_doc 19292 19292 0 0.00 0 8241 5 0 0
sp_make_cost_storno 26126 26126 0 0.00 0 15003 58 0 0
sp_multiply_rows_for_pdistr 26124 26124 0 0.00 0 8409 27 0 0
sp_multiply_rows_for_qdistr 18997 18997 0 0.00 0 40326 266 0 0
sp_pay_from_customer 6728 6728 0 0.00 4 15055 117 0 0
sp_pay_to_supplier 5459 5459 0 0.00 5 13145 118 0 0
sp_payment_common 12187 12187 0 0.00 1 6905 22 0 0
sp_qd_handle_on_cancel_clo 1630 1630 0 0.00 0 13984 274 0 0
sp_qd_handle_on_invoice_upd_sts 10189 10189 0 0.00 0 18621 359 0 0
sp_qd_handle_on_reserve_upd_sts 9259 9259 0 0.00 0 22668 48 0 0
sp_reserve_write_off 9259 9259 0 0.00 7 22704 169 0 0
sp_supplier_invoice 5142 5142 0 0.00 6 92057 3694 0 0
sp_supplier_order 5103 5103 0 0.00 6 29440 1355 0 0
srv_aggregate_perf_data 102 102 0 0.00 2482 240620 15022 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1359 136494 44014 0 0
srv_find_qd_qs_mism 1012258 1012258 0 0.00 0 19356 4 0 0
srv_make_invnt_saldo 454 454 0 0.00 52 84109 3031 0 0
srv_make_money_saldo 699 699 0 0.00 1 21642 554 0 0
srv_recalc_idx_stat 4 4 0 0.00 232131 418575 308196 0 0
t$perf-abend:sp_add_invoice_to_stock 98 98 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4692 4692 0 0.00 0 13490 157 0 0
t$perf-norm:sp_cancel_adding_invoice 385 385 0 0.00 0 3797 77 0 0
t$perf-norm:sp_cancel_client_order 1630 1630 0 0.00 0 1171 10 0 0
t$perf-norm:sp_cancel_customer_reserve 656 656 0 0.00 0 1090 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 658 658 0 0.00 0 253 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 360 360 0 0.00 0 620 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 379 379 0 0.00 0 645 12 0 0
t$perf-norm:sp_cancel_supplier_order 405 405 0 0.00 0 19798 1628 0 0
t$perf-norm:sp_cancel_write_off 884 884 0 0.00 0 8486 16 0 0
t$perf-norm:sp_client_order 18997 18997 0 0.00 0 7614 18 0 0
t$perf-norm:sp_customer_reserve 957 957 0 0.00 0 5753 11 0 0
t$perf-norm:sp_pay_from_customer 6728 6728 0 0.00 0 2076 7 0 0
t$perf-norm:sp_pay_to_supplier 5459 5459 0 0.00 0 3878 10 0 0
t$perf-norm:sp_reserve_write_off 9259 9259 0 0.00 0 8486 11 0 0
t$perf-norm:sp_supplier_invoice 5142 5142 0 0.00 0 5990 16 0 0
t$perf-norm:sp_supplier_order 5103 5103 0 0.00 0 8711 14 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 1469 22 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 262 5 0 0
t$perf-norm:srv_make_invnt_saldo 454 454 0 0.00 0 680 4 0 0
t$perf-norm:srv_make_money_saldo 699 699 0 0.00 0 2298 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 9548 9352 196 2.05 0 6367 20 0 196
v_all_customers 35102 35102 0 0.00 0 62 1 0 0
v_all_suppliers 28796 28796 0 0.00 0 63 1 0 0
v_all_wares 37994 37994 0 0.00 0 35 1 0 0
v_cancel_adding_invoice 770 770 0 0.00 0 2255 8 0 0
v_cancel_client_order 3260 3260 0 0.00 0 3390 6 0 0
v_cancel_customer_prepayment 1316 1316 0 0.00 0 988 8 0 0
v_cancel_customer_reserve 1312 1312 0 0.00 0 558 4 0 0
v_cancel_payment_to_supplier 720 720 0 0.00 0 524 5 0 0
v_cancel_supplier_invoice 778 758 20 2.57 0 2804 16 0 20
v_cancel_supplier_order 810 810 0 0.00 0 1042 5 0 0
v_cancel_write_off 1768 1768 0 0.00 0 3054 15 0 0
v_max_id_clo_ord 5099 5099 0 0.00 0 202 1 0 0
v_max_id_clo_res 957 957 0 0.00 0 10758 23 0 0
v_max_id_ord_sup 5117 5117 0 0.00 0 3923 4 0 0
v_max_non_paid_invoice 1277 1277 0 0.00 0 182 2 0 0
v_max_non_paid_realizn 3313 3313 0 0.00 0 656 3 0 0
v_min_id_clo_ord 5099 5099 0 0.00 0 2376 5 0 0
v_min_id_clo_res 957 957 0 0.00 0 7767 27 0 0
v_min_id_ord_sup 5117 5117 0 0.00 0 4913 7 0 0
v_min_non_paid_invoice 5459 5459 0 0.00 0 6097 13 0 0
v_min_non_paid_realizn 6728 6728 0 0.00 0 6902 18 0 0
v_our_firm 9722 9722 0 0.00 0 12 1 0 0
v_reserve_write_off 18518 18518 0 0.00 0 11612 7 0 0
x_lock_depdocs_on_canc_invoice 11259 11259 0 0.00 0 11800 108 0 0
x_lock_depdocs_on_canc_sup_ord 405 405 0 0.00 0 13836 364 0 0
x_make_qstorno_1000_1200 5103 5103 0 0.00 2 29421 1284 0 0
x_make_qstorno_1200_2000 5142 5142 0 0.00 3 92049 3519 0 0
x_make_qstorno_2100_3300 438074 438074 0 0.00 0 51020 140 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-11-2020 04:28:36 to 13-11-2020 04:28: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
01:56:57 2 8192000000 8796633152 8807174144 3163616 0.038 1 1 2 0 3261312 61840 35856
01:57:58 26769 8192000000 9716723888 10011201536 791558528 8.811 100 90 180 0 822218816 8157392 22502896
01:59:30 21225 8192000000 9757846960 10062839808 824959648 9.148 100 85 170 0 857735200 12097136 20678416
02:01:00 19867 8192000000 9805852624 10124525568 865228688 9.552 100 83 166 1 907520416 23038672 19253056
02:02:28 12222 8192000000 9874348064 10207309824 916799904 10.064 100 75 150 0 987830800 54359968 16670928
02:03:45 5692 8192000000 9932838512 10278326272 970102432 10.588 100 81 162 0 1043672720 56549312 17020976
02:04:53 3654 8192000000 9993792176 10349494272 1002426384 10.902 100 82 162 0 1095840800 76922256 16492160
02:05:59 2704 8192000000 10016780736 10377240576 1034673168 11.213 100 90 178 0 1127263568 74318672 18271728
02:07:03 1673 8192000000 10046871440 10414125056 1059690096 11.454 100 98 191 2 1155455136 75763888 20001152
02:08:05 3654 8192000000 10004139632 10389012480 1066341056 11.517 100 51 101 1 1127442304 50441216 10660032
02:09:14 4214 8192000000 10003246352 10389532672 1073902928 11.589 100 58 118 0 1120212592 33609200 12700464
02:10:19 61083 8192000000 10013127520 10400116736 1081143104 11.658 100 37 73 0 1112718288 23015840 8559344
02:12:20 6656 8192000000 10010733840 10414727168 1091617216 11.758 100 45 90 1 1122992176 22417104 8957856
02:13:28 4484 8192000000 10014190448 10408345600 1092369104 11.765 100 46 88 5 1128404800 26317568 9718128
02:14:34 8043 8192000000 10003117568 10407092224 1097733776 11.816 100 30 60 4 1127506544 23095792 6676976
02:15:46 41367 8192000000 10003618880 10406641664 1090679104 11.749 99 38 77 1 1107543088 8431472 8432512
02:17:32 5477 8192000000 10003610240 10404204544 1058023072 11.438 98 41 83 2 1101423264 34651744 8748448
02:18:40 5392 8192000000 9928911408 10317934592 1004033168 10.918 97 36 69 2 1027642496 16284736 7324592
02:19:47 6497 8192000000 9914076224 10286653440 975445200 10.640 97 37 74 1 1017848624 34198064 8205360
02:20:56 48913 8192000000 9916109568 10309386240 963585792 10.524 95 27 53 2 991845760 21982848 6277120
02:22:46 7777 8192000000 9956177296 10350501888 1037170528 11.237 100 39 78 1 1066176064 19809280 9196256
02:23:57 7857 8192000000 9974730592 10353139712 1050710544 11.367 100 46 89 5 1088502672 28156624 9635504
02:25:07 9623 8192000000 9973910320 10382086144 1061273360 11.469 100 27 53 0 1086455552 19462064 5720128
02:26:17 48865 8192000000 9987944096 10382499840 1072109472 11.572 100 36 72 2 1106993216 27234144 7649600
02:28:12 27262 8192000000 10077064176 10468311040 1106509792 11.899 100 56 111 0 1181714368 64239152 10965424
02:29:44 37822 8192000000 10093959488 10499969024 1128285216 12.105 100 32 62 2 1207156352 72352560 6518576
02:31:25 6184 8192000000 10158325776 10558050304 1147564464 12.287 100 43 85 4 1264353072 106759248 10029360
02:32:36 22499 8192000000 10209493616 10632306688 1156089184 12.367 100 48 96 1 1330039104 162726560 11223360
02:34:07 8709 8192000000 10243450256 10645868544 1164104192 12.442 100 57 109 0 1343034064 166967136 11962736
02:35:20 1352 8192000000 10325072768 10722152448 1170189616 12.499 100 88 175 1 1426236288 235842688 20203984
02:36:25 16563 8192000000 10313625792 10727596032 1182012560 12.609 100 49 95 1 1421500224 228183840 11303824
02:37:51 4416 8192000000 10346436416 10757095424 1192146592 12.703 100 64 127 1 1449393856 242583712 14663552
02:39:00 2469 8192000000 10397605984 10800541696 1180762576 12.597 100 89 177 1 1498999056 298233984 20002496
02:40:05 2953 8192000000 10429808624 10855706624 1183470480 12.623 100 74 149 2 1533354224 331085008 18798736
02:41:17 2318 8192000000 10395849984 10798411776 1190120992 12.684 100 81 161 0 1489224240 280405312 18697936
02:42:23 6704 8192000000 10322850576 10734125056 1143274848 12.246 98 61 122 1 1417168176 259147440 14745888
02:43:33 2626 8192000000 10345000304 10758434816 1155015984 12.357 100 54 108 3 1443469200 275052864 13400352
02:44:43 5637 8192000000 10303692688 10752921600 1160261824 12.406 100 41 82 0 1403765824 234253456 9250544
02:45:55 8583 8192000000 10363789888 10788081664 1171778800 12.513 100 36 71 1 1461658352 280671616 9207936
02:47:16 3037 8192000000 10422004624 10843049984 1171920032 12.515 100 81 161 1 1514196656 323244448 19032176
02:48:21 15026 8192000000 10419004240 10848727040 1174521136 12.539 100 38 76 0 1513052032 328473648 10057248
02:49:38 15521 8192000000 10446758816 10912370688 1167926512 12.477 100 39 77 1 1580956048 402181984 10847552
02:51:12 11453 8192000000 10446030576 10912731136 1146049152 12.272 99 33 66 0 1551406864 396506768 8850944
02:52:29 52115 8192000000 10325120896 10897432576 1139724288 12.213 100 18 36 1 1522454816 377501856 5228672
02:54:22 90054 8192000000 10053529696 10485645312 1110615488 11.938 98 32 64 0 1140668288 23745456 6307344
02:56:55 54723 8192000000 10053022576 10529632256 1122113328 12.047 99 10 20 0 1168787184 44789280 1884576
02:59:00 65092 8192000000 10057423696 10495262720 1088958512 11.733 96 14 28 1 1118040272 26380656 2701104
03:01:13 61847 8192000000 10059913600 10488897536 1076769456 11.617 96 24 48 0 1146947920 65353152 4825312
03:03:21 84599 8192000000 10103602784 10526752768 1111053696 11.942 100 30 58 1 1179441744 61624976 6763072
03:05:47 55343 8192000000 10027250624 10480074752 1058450080 11.442 95 27 54 1 1120759744 56569376 5740288
03:07:49 58983 8192000000 10088233008 10550702080 1113515440 11.966 100 16 32 1 1200151712 83124800 3511472
03:09:59 42072 8192000000 10086171168 10528157696 1105412592 11.889 98 19 38 1 1186069504 76606560 4050352
03:11:50 60911 8192000000 10085307200 10584535040 1117158816 12.000 100 10 20 1 1207982240 88563968 2259456
03:13:58 32737 8192000000 10067351904 10508001280 1118301456 12.011 99 31 62 0 1149140832 24190464 6648912
03:15:32 19913 8192000000 10051319408 10468876288 1113537056 11.966 99 42 84 3 1138781408 15882432 9361920
03:16:53 80866 8192000000 10092722304 10608693248 1138547008 12.202 100 26 52 2 1219820960 75659760 5614192
03:19:18 73055 8192000000 10112092624 10583932928 1149910848 12.309 100 16 32 1 1206889248 52856576 4121824
03:21:31 60244 8192000000 10106893216 10534866944 1128142992 12.104 98 30 59 2 1189684080 55548576 5992512
03:23:36 77285 8192000000 10083540352 10508627968 1104545792 11.881 96 27 52 1 1145132576 34849728 5737056
03:25:58 134233 8192000000 10092006448 10533744640 1139621424 12.212 99 23 45 1 1169319440 25102576 4595440
03:29:19 34484 8192000000 10074484368 10518093824 1141147296 12.226 100 8 16 1 1183376752 40726720 1502736
03:30:59 53878 8192000000 10096875680 10605101056 1139372304 12.210 99 13 26 2 1205260752 63135584 2752864
03:32:53 38086 8192000000 10063910384 10524827648 1110856512 11.941 97 17 33 2 1138390736 24027136 3507088
03:34:31 32035 8192000000 10118171040 10550431744 1135752176 12.176 99 30 60 1 1197271872 54974064 6545632
03:36:09 77522 8192000000 10176430768 10643120128 1120298000 12.030 97 24 48 1 1254649840 128949328 5402512
03:38:26 35945 8192000000 10079890768 10500927488 1130187568 12.123 99 30 60 1 1162711744 26662288 5861888
03:40:05 56465 8192000000 10092710976 10573340672 1128317424 12.105 99 22 44 0 1182598896 49843376 4438096
03:42:02 37002 8192000000 10055075376 10485018624 1093556000 11.776 98 13 26 0 1138843648 42829664 2457984
03:43:44 48553 8192000000 10081823632 10536411136 1107549392 11.909 99 39 78 0 1195789376 80646336 7593648
03:46:21 50305 8192000000 10035037168 10478915584 1120553760 12.032 100 12 24 2 1152038160 28825296 2659104
03:48:12 70579 8192000000 10103424016 10553720832 1077808192 11.627 95 17 34 1 1149167600 67655136 3704272
03:50:23 70190 8192000000 10100774432 10587451392 1099115632 11.829 98 25 50 0 1218413344 113598672 5699040
03:53:08 52349 8192000000 10067815888 10497601536 1091196336 11.754 97 21 43 2 1149030864 53099968 4734560
03:55:06 30665 8192000000 10094116432 10524430336 1091470704 11.757 98 34 67 1 1172020960 73292928 7257328
03:56:38 77691 8192000000 10074401328 10552344576 1102626928 11.863 99 11 22 1 1175234112 70077104 2530080
03:59:04 65625 8192000000 10072470352 10533339136 1107231072 11.906 99 9 18 0 1141831232 32844992 1755168
04:01:12 43565 8192000000 10033718448 10492162048 1103375600 11.870 99 10 20 1 1135105536 29539184 2190752
04:02:56 68988 8192000000 10074653728 10523049984 1125340176 12.077 100 16 32 0 1171368496 42603456 3424864
04:05:11 58038 8192000000 10052323104 10523774976 1115571136 11.985 99 7 14 4 1143549760 26148032 1830592
04:07:09 29050 8192000000 10073579664 10529316864 1128812352 12.110 100 19 38 2 1171708160 39104688 3791120
04:08:41 33106 8192000000 10126442352 10578866176 1136954416 12.187 100 47 93 1 1225347712 78351008 10042288
04:10:15 31201 8192000000 10108339104 10544746496 1122608992 12.052 99 23 46 0 1183615008 56064784 4941232
04:11:48 21534 8192000000 10168544496 10596851712 1138064688 12.197 99 36 72 4 1251514784 104824272 8625824
04:13:33 63111 8192000000 10144936864 10648682496 1111830080 11.950 98 13 26 1 1224550592 109665952 3054560
04:16:58 81022 8192000000 10058545936 10507296768 1120892304 12.035 99 13 26 0 1156860768 32978976 2989488
04:19:19 136494 8192000000 10064867344 10533830656 1141264720 12.227 100 22 44 1 1199585456 53789888 4530848
04:23:14 46351 8192000000 10060112160 10516594688 1130173920 12.123 100 8 16 0 1177413360 45655824 1583616
04:25:02 62936 8192000000 10080498896 10521714688 1092220480 11.764 96 15 30 1 1130491568 35353392 2917696

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 13-11-2020 04:28:42 to 13-11-2020 04:28:56.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 98 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 13-11-2020 04:28:57 to 13-11-2020 04:28:59.

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          223298
MON$OLDEST_ACTIVE               226141
MON$OLDEST_SNAPSHOT             226141
MON$NEXT_TRANSACTION            226141
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                       2092096
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             783
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2250 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2250 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2250 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.2250 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 ---
:97: 
:98: mscs-test-161.budzdorov.ru    Fri Nov 13 04:26:58 2020
:99:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:100: 
:101: 
:102: mscs-test-161.budzdorov.ru    Fri Nov 13 04:26:59 2020
:103:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:104: 
:105: 
:106: mscs-test-161.budzdorov.ru    Fri Nov 13 04:27:01 2020
:107:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:108: 
:109: 
:110: mscs-test-161.budzdorov.ru    Fri Nov 13 04:27:02 2020
:111:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:112: 
--- end of diff output ---

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

13.11.20 04:29: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: 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-11-13 04:29:03 No severe PSQL-related problems occured
13.11.20 04:29:04. End of report.