Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.11.2020 10: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 16778552 kB
MemAvailable 31506908 kB
Buffers 0 kB
Cached 14908532 kB
SwapCached 2892 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-02 10:27:32
DTS_END                         2020-11-02 10:26:50.6140
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/58026
TRN_ID                          242962
ATT_ID                          259
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: 8781.78 23777 1053813
customer order: creation 173.73 390 20848
customer order: refuse 14.83 348 1779
order to supplier: creation 45.29 1383 5435
order to supplier: removal 3.34 118933 401
invoice (draft): creation 46.52 3473 5582
invoice (draft): removal 49.96 939 5995
invoice accept: apply 41.21 16879 4945
invoice accept: cancel 44.97 886 5396
customer reserve: creation 3692.05 169 443046
customer reserve: removal 4371.61 73 524593
realization accept: apply 84.66 167 10159
realization accept: cancel 83.00 107 9960
payment from customer: creation 60.39 101 7247
payment from customer: removal 5.85 87 702
payment to supplier: creation 49.72 124 5966
payment to supplier: removal 3.39 71 407
service: total inventory turnovers 3.93 3404 471
service: total monetary turnovers 6.24 534 749
service: aggregate perf. data 1.07 12473 128
service: refresh index statistics 0.03 314991 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 02-11-2020 10:28:59 to 02-11-2020 10:29:01.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2531
TEST_TIME 2 3708
TEST_TIME 3 3968
TEST_TIME 4 4537
TEST_TIME 5 4796
TEST_TIME 6 5256
TEST_TIME 7 4926
TEST_TIME 8 5414
TEST_TIME 9 5372
TEST_TIME 10 5535
TEST_TIME 11 5978
TEST_TIME 12 6092
TEST_TIME 13 6461
TEST_TIME 14 6506
TEST_TIME 15 6729
TEST_TIME 16 7043
TEST_TIME 17 7257
TEST_TIME 18 7675
TEST_TIME 19 8057
TEST_TIME 20 8019
TEST_TIME 21 8079
TEST_TIME 22 8457
TEST_TIME 23 8706
TEST_TIME 24 8626
TEST_TIME 25 9078
TEST_TIME 26 9475
TEST_TIME 27 10364
TEST_TIME 28 10532
TEST_TIME 29 10880
TEST_TIME 30 10825
TEST_TIME 31 10699
TEST_TIME 32 10432
TEST_TIME 33 10216
TEST_TIME 34 10091
TEST_TIME 35 9939
TEST_TIME 36 9783
TEST_TIME 37 9741
TEST_TIME 38 9652
TEST_TIME 39 9582
TEST_TIME 40 9530
TEST_TIME 41 9493
TEST_TIME 42 9538
TEST_TIME 43 9496
TEST_TIME 44 9360
TEST_TIME 45 9216
TEST_TIME 46 9288
TEST_TIME 47 9301
TEST_TIME 48 9238
TEST_TIME 49 9244
TEST_TIME 50 9209
TEST_TIME 51 9149
TEST_TIME 52 9264
TEST_TIME 53 9342
TEST_TIME 54 9279
TEST_TIME 55 9299
TEST_TIME 56 9448
TEST_TIME 57 9430
TEST_TIME 58 9405
TEST_TIME 59 9476
TEST_TIME 60 9415
TEST_TIME 61 9297
TEST_TIME 62 9187
TEST_TIME 63 9214
TEST_TIME 64 9178
TEST_TIME 65 9090
TEST_TIME 66 9058
TEST_TIME 67 9017
TEST_TIME 68 9076
TEST_TIME 69 9122
TEST_TIME 70 9204
TEST_TIME 71 9204
TEST_TIME 72 9169
TEST_TIME 73 9170
TEST_TIME 74 9229
TEST_TIME 75 9262
TEST_TIME 76 9244
TEST_TIME 77 9233
TEST_TIME 78 9336
TEST_TIME 79 9301
TEST_TIME 80 9192
TEST_TIME 81 9141
TEST_TIME 82 9056
TEST_TIME 83 8969
TEST_TIME 84 9000
TEST_TIME 85 8930
TEST_TIME 86 8970
TEST_TIME 87 9005
TEST_TIME 88 8989
TEST_TIME 89 8975
TEST_TIME 90 9012
TEST_TIME 91 9005
TEST_TIME 92 9021
TEST_TIME 93 9037
TEST_TIME 94 9145
TEST_TIME 95 9154
TEST_TIME 96 9155
TEST_TIME 97 9156
TEST_TIME 98 9112
TEST_TIME 99 9050
TEST_TIME 100 9014
TEST_TIME 101 8977
TEST_TIME 102 8937
TEST_TIME 103 8880
TEST_TIME 104 8855
TEST_TIME 105 8847
TEST_TIME 106 8831
TEST_TIME 107 8846
TEST_TIME 108 8818
TEST_TIME 109 8865
TEST_TIME 110 8903
TEST_TIME 111 8914
TEST_TIME 112 8937
TEST_TIME 113 8910
TEST_TIME 114 8939
TEST_TIME 115 8976
TEST_TIME 116 8953
TEST_TIME 117 8883
TEST_TIME 118 8847
TEST_TIME 119 8799
TEST_TIME 120 8783

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 02-11-2020 10:29:02 to 02-11-2020 10:29:06.

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 1466009 1466009 0 0.00 0 21262 7 0 0
doc_list_biud 1465996 1465996 0 0.00 0 55900 33 0 0
sp_add_invoice_to_stock 5022 4945 77 1.53 25 215906 16879 0 77
sp_cancel_adding_invoice 5396 5396 0 0.00 5 117827 887 0 0
sp_cancel_client_order 1779 1779 0 0.00 5 16838 349 0 0
sp_cancel_customer_reserve 524593 524593 0 0.00 1 42636 73 0 0
sp_cancel_pay_from_customer 702 702 0 0.00 2 10898 87 0 0
sp_cancel_pay_to_supplier 407 407 0 0.00 3 2112 72 0 0
sp_cancel_supplier_invoice 5999 5995 4 0.06 2 31711 940 0 4
sp_cancel_supplier_order 401 401 0 0.00 7 1068617 118934 0 0
sp_cancel_write_off 9960 9960 0 0.00 1 7284 108 0 0
sp_client_order 20848 20848 0 0.00 4 34756 391 0 0
sp_customer_reserve 443046 443046 0 0.00 0 99390 170 0 0
sp_fill_shopping_cart 31861 31861 0 0.00 1 29601 50 0 0
sp_fill_shopping_cart_clo_res 443045 443045 0 0.00 0 21231 16 0 0
sp_get_clo_for_invoice 4942 4942 0 0.00 0 33143 415 0 0
sp_kill_cost_storno 16465 16465 0 0.00 0 7850 54 0 0
sp_kill_qstorno_ret_qs2qd 1065542 1065542 0 0.00 0 42632 39 0 0
sp_kill_qty_storno 563215 563215 0 0.00 0 55900 84 0 0
sp_lock_selected_doc 20922 20922 0 0.00 0 10892 6 0 0
sp_make_cost_storno 28314 28314 0 0.00 0 19349 56 0 0
sp_multiply_rows_for_pdistr 28310 28310 0 0.00 0 13324 28 0 0
sp_multiply_rows_for_qdistr 20848 20848 0 0.00 0 16065 217 0 0
sp_pay_from_customer 7247 7247 0 0.00 4 14834 101 0 0
sp_pay_to_supplier 5966 5966 0 0.00 5 18794 125 0 0
sp_payment_common 13212 13212 0 0.00 1 11326 19 0 0
sp_qd_handle_on_cancel_clo 1779 1779 0 0.00 0 16830 324 0 0
sp_qd_handle_on_invoice_upd_sts 10333 10333 0 0.00 0 55899 374 0 0
sp_qd_handle_on_reserve_upd_sts 10154 10154 0 0.00 0 13467 54 0 0
sp_reserve_write_off 10159 10159 0 0.00 7 19368 168 0 0
sp_supplier_invoice 5582 5582 0 0.00 7 87243 3473 0 0
sp_supplier_order 5435 5435 0 0.00 6 93842 1384 0 0
srv_aggregate_perf_data 129 128 1 0.77 2873 124885 12474 0 1
srv_fill_mon_cache_memory 64 64 0 0.00 1875 139917 41344 0 0
srv_find_qd_qs_mism 1007449 1007449 0 0.00 0 21262 6 0 0
srv_make_invnt_saldo 471 471 0 0.00 46 112208 3404 0 0
srv_make_money_saldo 749 749 0 0.00 1 16059 535 0 0
srv_recalc_idx_stat 4 4 0 0.00 52323 507529 314992 0 0
t$perf-abend:sp_add_invoice_to_stock 77 77 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-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4945 4945 0 0.00 0 16587 151 0 0
t$perf-norm:sp_cancel_adding_invoice 410 410 0 0.00 0 5943 71 0 0
t$perf-norm:sp_cancel_client_order 1779 1779 0 0.00 0 2894 11 0 0
t$perf-norm:sp_cancel_customer_reserve 707 707 0 0.00 0 518 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 702 702 0 0.00 0 2015 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 407 407 0 0.00 0 2143 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 399 399 0 0.00 0 1408 24 0 0
t$perf-norm:sp_cancel_supplier_order 401 401 0 0.00 0 20957 1285 0 0
t$perf-norm:sp_cancel_write_off 1026 1026 0 0.00 0 6956 18 0 0
t$perf-norm:sp_client_order 20848 20848 0 0.00 0 5183 16 0 0
t$perf-norm:sp_customer_reserve 957 957 0 0.00 0 647 5 0 0
t$perf-norm:sp_pay_from_customer 7247 7247 0 0.00 0 8169 9 0 0
t$perf-norm:sp_pay_to_supplier 5966 5966 0 0.00 0 2922 8 0 0
t$perf-norm:sp_reserve_write_off 10159 10159 0 0.00 0 5888 10 0 0
t$perf-norm:sp_supplier_invoice 5582 5582 0 0.00 0 4865 17 0 0
t$perf-norm:sp_supplier_order 5435 5435 0 0.00 0 17306 19 0 0
t$perf-norm:srv_aggregate_perf_data 128 128 0 0.00 0 190 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 71 2 0 0
t$perf-norm:srv_make_invnt_saldo 471 471 0 0.00 0 2636 10 0 0
t$perf-norm:srv_make_money_saldo 749 749 0 0.00 0 4449 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 2 2 0 0
v_add_invoice_to_stock 10024 9870 154 1.53 0 9486 15 0 154
v_all_customers 38116 38116 0 0.00 0 22 1 0 0
v_all_suppliers 30450 30450 0 0.00 0 19 1 0 0
v_all_wares 41696 41696 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 820 820 0 0.00 0 1113 8 0 0
v_cancel_client_order 3558 3558 0 0.00 0 2425 4 0 0
v_cancel_customer_prepayment 1404 1404 0 0.00 0 1076 4 0 0
v_cancel_customer_reserve 1414 1414 0 0.00 0 1238 5 0 0
v_cancel_payment_to_supplier 814 814 0 0.00 0 1856 6 0 0
v_cancel_supplier_invoice 806 798 8 0.99 0 1071 13 0 8
v_cancel_supplier_order 802 802 0 0.00 0 3764 8 0 0
v_cancel_write_off 2052 2052 0 0.00 0 2080 14 0 0
v_max_id_clo_ord 5435 5435 0 0.00 0 1164 1 0 0
v_max_id_clo_res 957 957 0 0.00 0 1789 12 0 0
v_max_id_ord_sup 5578 5578 0 0.00 0 6743 7 0 0
v_max_non_paid_invoice 1753 1753 0 0.00 0 875 2 0 0
v_max_non_paid_realizn 3921 3921 0 0.00 0 5656 5 0 0
v_min_id_clo_ord 5435 5435 0 0.00 0 3465 6 0 0
v_min_id_clo_res 956 956 0 0.00 0 9308 19 0 0
v_min_id_ord_sup 5578 5578 0 0.00 0 5691 8 0 0
v_min_non_paid_invoice 5965 5965 0 0.00 0 3735 9 0 0
v_min_non_paid_realizn 7247 7247 0 0.00 0 8781 14 0 0
v_our_firm 10232 10232 0 0.00 0 14 1 0 0
v_reserve_write_off 20308 20308 0 0.00 0 5599 5 0 0
x_lock_depdocs_on_canc_invoice 10992 10992 0 0.00 0 20981 110 0 0
x_lock_depdocs_on_canc_sup_ord 401 401 0 0.00 0 4434 345 0 0
x_make_qstorno_1000_1200 5435 5435 0 0.00 2 93828 1297 0 0
x_make_qstorno_1200_2000 5582 5582 0 0.00 3 79327 3279 0 0
x_make_qstorno_2100_3300 443046 443046 0 0.00 0 99365 151 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-11-2020 10:29:07 to 02-11-2020 10:29:11.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
07:56:53 2 8192000000 8796633152 8807174144 3163616 0.038 1 1 2 0 3261312 61840 35856
07:57:54 30644 8192000000 9707259408 9998331904 776926416 8.662 100 91 182 0 807748288 7803744 23018128
07:59:32 12339 8192000000 9743348048 10044096512 818422672 9.083 100 91 182 0 853354432 13278000 21653760
08:00:52 9151 8192000000 9793380672 10107170816 861329152 9.513 100 86 172 0 901996016 21299216 19367648
08:02:04 11097 8192000000 9856354560 10184634368 904673264 9.945 100 85 168 1 966989776 43680576 18635936
08:03:22 6564 8192000000 9924381184 10263728128 946756816 10.359 100 84 168 2 1038078896 73228720 18093360
08:04:32 3261 8192000000 9950529952 10299822080 982773216 10.711 100 82 164 0 1063239984 63473456 16993312
08:05:37 2742 8192000000 9992172512 10355404800 1018657792 11.059 100 82 161 1 1104589280 69807920 16123568
08:06:41 1464 8192000000 10022642032 10388582400 1046755904 11.330 100 94 185 3 1136223952 70608224 18859824
08:07:44 45326 8192000000 9999101968 10399518720 1064926352 11.504 100 38 76 1 1094113152 20811248 8375552
08:09:31 4625 8192000000 10011260368 10403381248 1082265056 11.669 100 32 63 1 1132801472 44198480 6337936
08:10:36 6979 8192000000 9990073200 10388262912 1085304208 11.698 100 32 61 3 1116132784 23516896 7311680
08:11:44 7801 8192000000 9994035312 10398326784 1088523264 11.729 100 32 64 4 1116775840 21711280 6541296
08:12:53 46316 8192000000 10000640288 10417053696 1096552944 11.805 100 26 52 1 1134229696 31949664 5727088
08:14:41 4542 8192000000 10028346880 10426281984 1097548240 11.814 100 31 63 1 1127229392 22605904 7075248
08:15:46 5298 8192000000 10002900496 10399490048 1076517824 11.614 99 42 82 3 1104306320 17897008 9891488
08:16:52 10256 8192000000 9976777568 10380410880 1027932448 11.149 96 41 81 0 1052940208 15269456 9738304
08:18:02 26058 8192000000 9955052096 10350239744 963273536 10.521 92 33 66 0 1001741184 32077936 6389712
08:19:32 9136 8192000000 9939105232 10324090880 988619616 10.768 95 52 100 3 1017181648 16598096 11963936
08:20:41 4508 8192000000 9928866096 10308612096 1016968128 11.043 98 36 69 0 1037136064 12958752 7209184
08:21:47 6739 8192000000 9943792016 10330804224 1026117344 11.131 99 37 73 1 1047509152 13215360 8176448
08:22:56 19613 8192000000 9969184272 10365759488 1031420032 11.182 99 38 72 1 1074054768 35056784 7577952
08:24:19 5727 8192000000 9972971936 10362851328 1043449584 11.298 99 52 103 0 1080674944 25844080 11381280
08:25:31 6218 8192000000 9964444224 10358292480 1061413232 11.470 100 36 70 0 1083073040 14181024 7478784
08:26:38 7720 8192000000 9994141472 10401705984 1081748448 11.664 100 16 32 3 1109208448 24332944 3127056
08:27:48 34923 8192000000 10017574128 10414530560 1097942160 11.818 100 38 76 0 1133127760 27847104 7338496
08:29:38 1869 8192000000 10088968512 10476539904 1110801584 11.940 100 77 150 0 1194020656 68198720 15020352
08:30:45 20606 8192000000 10086437104 10487525376 1118666000 12.014 100 52 104 3 1199938688 70269568 11003120
08:32:11 12240 8192000000 10120346144 10527760384 1132778816 12.148 100 42 84 0 1230310816 87729104 9802896
08:33:28 14154 8192000000 10157633216 10559655936 1142841536 12.242 100 51 100 2 1255816080 102306336 10668208
08:34:46 10866 8192000000 10179198784 10608803840 1148488576 12.295 100 43 86 1 1282163328 123792448 9882304
08:36:03 5935 8192000000 10195520976 10601693184 1160892624 12.412 100 56 112 1 1299652160 126513536 12246000
08:37:13 4589 8192000000 10244098048 10643099648 1153606240 12.343 100 65 130 2 1344150560 176269424 14274896
08:38:20 3200 8192000000 10238620016 10632101888 1134312992 12.162 99 78 154 1 1331989040 180947936 16728112
08:39:27 2913 8192000000 10242281008 10641002496 1139454400 12.210 100 63 124 1 1347840816 194551040 13835376
08:40:32 2791 8192000000 10266569936 10657460224 1132731472 12.147 100 76 152 3 1362189488 211597296 17860720
08:41:40 4920 8192000000 10312233840 10707832832 1137406960 12.191 100 76 151 2 1410896144 256101888 17387296
08:42:50 2699 8192000000 10356569504 10748321792 1143527696 12.249 100 83 165 0 1456864704 294273904 19063104
08:44:00 2904 8192000000 10338998688 10768773120 1153440752 12.342 100 61 122 0 1435972928 267698800 14833376
08:45:07 3517 8192000000 10379576096 10799210496 1162702752 12.429 100 64 126 3 1475328256 297569472 15056032
08:46:17 5738 8192000000 10373702448 10790326272 1155111488 12.357 100 47 94 3 1469778464 302458544 12208432
08:47:28 12076 8192000000 10400469664 10814009344 1144894240 12.262 99 40 80 1 1490352448 334698848 10759360
08:48:44 8184 8192000000 10411397184 10842542080 1153528688 12.343 99 34 68 0 1501626848 339078416 9019744
08:50:00 8547 8192000000 10427945024 10847555584 1150124768 12.311 99 44 88 2 1508054496 346985728 10944000
08:51:19 4331 8192000000 10417461264 10851930112 1149641968 12.306 100 39 77 0 1520474160 360754864 10077328
08:52:28 22401 8192000000 10348095856 10827292672 1116870576 11.997 99 24 48 2 1479967360 356433872 6662912
08:54:04 33913 8192000000 10191885584 10675257344 1105728464 11.892 98 12 24 2 1267971680 159330400 2912816
08:55:39 45976 8192000000 10076646000 10502242304 1116585024 11.995 100 21 42 0 1203368848 82220128 4563696
08:57:25 60771 8192000000 10063362592 10505785344 1116808208 11.997 98 19 39 1 1153344240 32862576 3673456
09:00:13 59665 8192000000 10070977744 10525929472 1124227872 12.067 98 11 22 0 1156368688 30362752 1778064
09:02:13 56217 8192000000 10101307728 10530578432 1114904496 11.979 97 32 63 2 1166302736 44680736 6717504
09:04:28 35856 8192000000 10113840272 10551652352 1152744048 12.335 100 19 38 1 1208991152 52111248 4135856
09:06:07 27828 8192000000 10135672864 10567446528 1163075808 12.432 100 55 111 0 1243141712 67982448 12083456
09:07:36 139917 8192000000 10118604160 10563297280 1145527600 12.267 98 22 44 1 1212128976 62024928 4576448
09:10:57 56131 8192000000 10161855888 10618560512 1167694512 12.475 100 18 35 3 1267247648 95112128 4441008
09:12:53 64930 8192000000 10077441648 10562486272 1155048192 12.357 99 14 28 0 1221801072 63592688 3160192
09:14:59 89866 8192000000 10121421568 10586882048 1143446272 12.248 98 26 50 3 1203576464 54190048 5940144
09:17:34 77295 8192000000 10088091504 10547441664 1091772048 11.760 95 7 14 0 1151501120 58251664 1477408
09:19:59 54575 8192000000 10128489472 10576089088 1143324256 12.247 99 25 50 0 1210587056 61656464 5606336
09:22:16 42985 8192000000 10066669184 10561564672 1105131472 11.886 97 8 16 3 1163940800 57040752 1768576
09:24:04 55722 8192000000 10086887824 10533994496 1122718000 12.053 99 16 32 1 1172836192 46444928 3673264
09:26:00 31430 8192000000 10096175408 10529882112 1121853968 12.045 99 15 27 3 1146435984 21953216 2628800
09:28:41 63996 8192000000 10089665408 10552008704 1120119520 12.028 98 23 46 2 1184267024 59731696 4415808
09:31:07 48237 8192000000 10102165424 10561900544 1123361984 12.059 99 10 20 0 1170414208 45016384 2035840
09:32:55 39804 8192000000 10143122000 10626576384 1106894256 11.903 97 16 32 0 1213650640 103604672 3151712
09:34:39 51600 8192000000 10094962112 10567421952 1094570592 11.786 97 16 32 1 1202136016 103621488 3943936
09:36:57 31378 8192000000 10088336944 10510807040 1126564000 12.089 100 44 88 1 1175226224 39031936 9630288
09:38:32 67327 8192000000 10084101248 10536648704 1066159136 11.515 95 24 48 0 1128611936 57272880 5179920
09:40:50 45741 8192000000 10102108384 10543538176 1060961712 11.466 96 50 100 0 1127281248 56196704 10122832
09:42:46 34867 8192000000 10054079280 10477158400 1058455296 11.442 96 32 64 0 1102727824 38474576 5797952
09:44:21 42628 8192000000 10053457424 10553868288 1097236560 11.811 99 37 72 1 1198739312 94066304 7436448
09:46:05 133410 8192000000 10073487872 10527248384 1095238144 11.792 98 18 36 0 1155233040 56199968 3794928
09:49:28 73490 8192000000 10102142384 10603245568 1115706464 11.986 100 13 25 1 1229317248 110663744 2947040
09:51:44 61514 8192000000 10097814544 10572718080 1109405104 11.927 99 29 58 0 1188874096 73276432 6192560
09:53:46 53117 8192000000 10071179408 10541875200 1127164272 12.095 100 23 46 2 1184411184 52669280 4577632
09:55:43 79389 8192000000 10086121712 10542862336 1120265792 12.030 99 21 42 1 1162337664 37373280 4698592
09:58:09 70980 8192000000 10062751200 10543878144 1127862944 12.101 99 5 10 0 1166016624 37302256 851424
10:00:28 84714 8192000000 10088654192 10546544640 1137026480 12.188 99 10 20 1 1178016816 39267792 1722544
10:03:31 32096 8192000000 10120932080 10571976704 1148226224 12.293 100 35 70 0 1189767568 34617024 6924320
10:06:28 31446 8192000000 10039406704 10489905152 1115185728 11.981 97 9 18 1 1136893648 20251312 1456608
10:08:22 51758 8192000000 10053308560 10517012480 1122555024 12.051 99 7 14 0 1145567712 21973760 1038928
10:10:15 30429 8192000000 10055448528 10507599872 1109586176 11.928 98 5 10 0 1146608224 36016656 1005392
10:11:45 39765 8192000000 10093131984 10528247808 1098149728 11.820 97 31 62 1 1159984448 55039168 6795552
10:13:30 53409 8192000000 10123920880 10595266560 1141797184 12.232 100 26 52 0 1229947664 81883904 6266576
10:15:27 48158 8192000000 10135143264 10584309760 1141556608 12.230 100 17 34 0 1227612624 82216976 3839040
10:17:19 53454 8192000000 10076363856 10542497792 1108592976 11.919 98 23 46 0 1182675488 68772544 5309968
10:19:29 47717 8192000000 10112031008 10549391360 1128020784 12.103 100 45 90 2 1204586944 67502832 9063328
10:21:23 106302 8192000000 10036775168 10536124416 1045094752 11.314 93 10 20 2 1089086416 42240624 1751040
10:24:14 46708 8192000000 10026319632 10469093376 1088271408 11.726 98 13 26 0 1120325952 29545104 2509440

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 02-11-2020 10:29:13 to 02-11-2020 10:29:28.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 77 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 02-11-2020 10:29:29 to 02-11-2020 10:29:31.

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          243218
MON$OLDEST_ACTIVE               245233
MON$OLDEST_SNAPSHOT             245233
MON$NEXT_TRANSACTION            245233
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                       2108352
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             816
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2243 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2243 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2243 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.2243 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
--- end of diff output ---

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

02.11.20 10:29:32. 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-02 10:29:34 No severe PSQL-related problems occured
02.11.20 10:29:36. End of report.