Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.01.2021 13: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 16470360 kB
MemAvailable 31264140 kB
Buffers 0 kB
Cached 15140024 kB
SwapCached 4864 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 2021-01-18 13:26:56
DTS_END                         2021-01-18 13:26:56.0530
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/58274
TRN_ID                          234614
ATT_ID                          634
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: 9070.42 25408 1088450
customer order: creation 169.30 416 20316
customer order: refuse 14.62 323 1754
order to supplier: creation 44.47 1361 5336
order to supplier: removal 3.34 110121 401
invoice (draft): creation 45.47 3709 5456
invoice (draft): removal 50.88 821 6105
invoice accept: apply 41.53 17876 4983
invoice accept: cancel 46.51 916 5581
customer reserve: creation 3827.39 175 459287
customer reserve: removal 4537.63 65 544516
realization accept: apply 84.71 204 10165
realization accept: cancel 77.43 136 9292
payment from customer: creation 57.51 159 6901
payment from customer: removal 5.65 136 678
payment to supplier: creation 49.71 152 5965
payment to supplier: removal 3.61 76 433
service: total inventory turnovers 3.55 3591 426
service: total monetary turnovers 6.07 519 728
service: aggregate perf. data 1.03 13361 123
service: refresh index statistics 0.03 354051 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 18-01-2021 13:28:53 to 18-01-2021 13:28:56.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3150
TEST_TIME 2 2232
TEST_TIME 3 3055
TEST_TIME 4 3786
TEST_TIME 5 3848
TEST_TIME 6 4379
TEST_TIME 7 4843
TEST_TIME 8 5138
TEST_TIME 9 5199
TEST_TIME 10 5686
TEST_TIME 11 5744
TEST_TIME 12 6183
TEST_TIME 13 6135
TEST_TIME 14 6378
TEST_TIME 15 7015
TEST_TIME 16 7429
TEST_TIME 17 7529
TEST_TIME 18 8095
TEST_TIME 19 8106
TEST_TIME 20 8626
TEST_TIME 21 8741
TEST_TIME 22 8956
TEST_TIME 23 9321
TEST_TIME 24 9379
TEST_TIME 25 9655
TEST_TIME 26 10150
TEST_TIME 27 10849
TEST_TIME 28 10930
TEST_TIME 29 10940
TEST_TIME 30 10744
TEST_TIME 31 10715
TEST_TIME 32 10391
TEST_TIME 33 10155
TEST_TIME 34 10174
TEST_TIME 35 10116
TEST_TIME 36 10007
TEST_TIME 37 10156
TEST_TIME 38 10090
TEST_TIME 39 9942
TEST_TIME 40 9886
TEST_TIME 41 9791
TEST_TIME 42 9649
TEST_TIME 43 9766
TEST_TIME 44 9931
TEST_TIME 45 9798
TEST_TIME 46 9921
TEST_TIME 47 10029
TEST_TIME 48 9990
TEST_TIME 49 9941
TEST_TIME 50 9969
TEST_TIME 51 9961
TEST_TIME 52 10040
TEST_TIME 53 10011
TEST_TIME 54 10075
TEST_TIME 55 10010
TEST_TIME 56 9972
TEST_TIME 57 9810
TEST_TIME 58 9799
TEST_TIME 59 9687
TEST_TIME 60 9604
TEST_TIME 61 9489
TEST_TIME 62 9571
TEST_TIME 63 9515
TEST_TIME 64 9501
TEST_TIME 65 9502
TEST_TIME 66 9491
TEST_TIME 67 9509
TEST_TIME 68 9441
TEST_TIME 69 9560
TEST_TIME 70 9542
TEST_TIME 71 9479
TEST_TIME 72 9549
TEST_TIME 73 9604
TEST_TIME 74 9603
TEST_TIME 75 9606
TEST_TIME 76 9589
TEST_TIME 77 9489
TEST_TIME 78 9459
TEST_TIME 79 9408
TEST_TIME 80 9349
TEST_TIME 81 9295
TEST_TIME 82 9268
TEST_TIME 83 9223
TEST_TIME 84 9230
TEST_TIME 85 9185
TEST_TIME 86 9257
TEST_TIME 87 9233
TEST_TIME 88 9254
TEST_TIME 89 9294
TEST_TIME 90 9301
TEST_TIME 91 9261
TEST_TIME 92 9333
TEST_TIME 93 9250
TEST_TIME 94 9178
TEST_TIME 95 9151
TEST_TIME 96 9125
TEST_TIME 97 9115
TEST_TIME 98 9138
TEST_TIME 99 9100
TEST_TIME 100 9087
TEST_TIME 101 9086
TEST_TIME 102 9076
TEST_TIME 103 9106
TEST_TIME 104 9052
TEST_TIME 105 9094
TEST_TIME 106 9334
TEST_TIME 107 9346
TEST_TIME 108 9373
TEST_TIME 109 9438
TEST_TIME 110 9391
TEST_TIME 111 9316
TEST_TIME 112 9266
TEST_TIME 113 9230
TEST_TIME 114 9186
TEST_TIME 115 9177
TEST_TIME 116 9169
TEST_TIME 117 9166
TEST_TIME 118 9144
TEST_TIME 119 9111
TEST_TIME 120 9074

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 18-01-2021 13:28:57 to 18-01-2021 13:29:01.

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 1513123 1513123 0 0.00 0 21163 7 0 0
doc_list_biud 1513123 1513123 0 0.00 0 49131 30 0 0
sp_add_invoice_to_stock 5140 4983 157 3.05 25 189821 17876 0 157
sp_cancel_adding_invoice 5581 5581 0 0.00 4 91011 917 0 0
sp_cancel_client_order 1754 1754 0 0.00 5 37292 324 0 0
sp_cancel_customer_reserve 544516 544516 0 0.00 1 20505 66 0 0
sp_cancel_pay_from_customer 678 678 0 0.00 3 39966 137 0 0
sp_cancel_pay_to_supplier 433 433 0 0.00 3 5575 76 0 0
sp_cancel_supplier_invoice 6118 6105 13 0.21 2 49136 822 0 13
sp_cancel_supplier_order 401 401 0 0.00 12 1178060 110121 0 0
sp_cancel_write_off 9292 9292 0 0.00 1 11047 137 0 0
sp_client_order 20316 20316 0 0.00 4 45169 416 0 0
sp_customer_reserve 459287 459287 0 0.00 0 83702 175 0 0
sp_fill_shopping_cart 31098 31098 0 0.00 1 26516 42 0 0
sp_fill_shopping_cart_clo_res 459282 459282 0 0.00 0 23348 18 0 0
sp_get_clo_for_invoice 4978 4978 0 0.00 0 42347 371 0 0
sp_kill_cost_storno 15984 15984 0 0.00 0 7837 57 0 0
sp_kill_qstorno_ret_qs2qd 1104830 1104830 0 0.00 0 49130 35 0 0
sp_kill_qty_storno 582792 582792 0 0.00 0 49131 76 0 0
sp_lock_selected_doc 20957 20957 0 0.00 0 8275 6 0 0
sp_make_cost_storno 28009 28009 0 0.00 0 17303 62 0 0
sp_multiply_rows_for_pdistr 28009 28009 0 0.00 0 10429 35 0 0
sp_multiply_rows_for_qdistr 20316 20316 0 0.00 0 22632 229 0 0
sp_pay_from_customer 6901 6901 0 0.00 4 44210 159 0 0
sp_pay_to_supplier 5965 5965 0 0.00 5 39712 152 0 0
sp_payment_common 12866 12866 0 0.00 1 14169 28 0 0
sp_qd_handle_on_cancel_clo 1754 1754 0 0.00 0 9939 269 0 0
sp_qd_handle_on_invoice_upd_sts 10559 10559 0 0.00 0 21206 360 0 0
sp_qd_handle_on_reserve_upd_sts 10165 10165 0 0.00 0 18709 50 0 0
sp_reserve_write_off 10165 10165 0 0.00 7 44802 205 0 0
sp_supplier_invoice 5456 5456 0 0.00 6 83355 3710 0 0
sp_supplier_order 5336 5336 0 0.00 5 37109 1362 0 0
srv_aggregate_perf_data 123 123 0 0.00 2465 150908 13361 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 895 139756 48621 0 0
srv_find_qd_qs_mism 1040975 1040975 0 0.00 0 15207 5 0 0
srv_make_invnt_saldo 426 426 0 0.00 41 86508 3592 0 0
srv_make_money_saldo 728 728 0 0.00 1 11259 520 0 0
srv_recalc_idx_stat 4 4 0 0.00 296555 515059 354052 0 0
t$perf-abend:sp_add_invoice_to_stock 157 157 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4983 4983 0 0.00 0 18407 197 0 0
t$perf-norm:sp_cancel_adding_invoice 423 423 0 0.00 0 2804 107 0 0
t$perf-norm:sp_cancel_client_order 1754 1754 0 0.00 0 6905 17 0 0
t$perf-norm:sp_cancel_customer_reserve 710 710 0 0.00 0 3629 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 678 678 0 0.00 0 754 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 433 433 0 0.00 0 7202 20 0 0
t$perf-norm:sp_cancel_supplier_invoice 379 379 0 0.00 0 1357 15 0 0
t$perf-norm:sp_cancel_supplier_order 401 401 0 0.00 0 19732 1351 0 0
t$perf-norm:sp_cancel_write_off 1036 1036 0 0.00 0 5583 24 0 0
t$perf-norm:sp_client_order 20316 20316 0 0.00 0 7411 19 0 0
t$perf-norm:sp_customer_reserve 1014 1014 0 0.00 0 7145 19 0 0
t$perf-norm:sp_pay_from_customer 6901 6901 0 0.00 0 8664 14 0 0
t$perf-norm:sp_pay_to_supplier 5965 5965 0 0.00 0 8662 10 0 0
t$perf-norm:sp_reserve_write_off 10165 10165 0 0.00 0 9793 15 0 0
t$perf-norm:sp_supplier_invoice 5456 5456 0 0.00 0 15581 20 0 0
t$perf-norm:sp_supplier_order 5336 5336 0 0.00 0 5107 16 0 0
t$perf-norm:srv_aggregate_perf_data 123 123 0 0.00 0 591 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 43 2 0 0
t$perf-norm:srv_make_invnt_saldo 426 426 0 0.00 0 3825 16 0 0
t$perf-norm:srv_make_money_saldo 728 728 0 0.00 0 3562 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 5 0 0
v_add_invoice_to_stock 10270 9956 314 3.05 0 46855 33 0 314
v_all_customers 36476 36476 0 0.00 0 42 1 0 0
v_all_suppliers 30188 30188 0 0.00 0 276 1 0 0
v_all_wares 40632 40632 0 0.00 0 30 1 0 0
v_cancel_adding_invoice 846 846 0 0.00 0 2537 9 0 0
v_cancel_client_order 3508 3508 0 0.00 0 36810 17 0 0
v_cancel_customer_prepayment 1356 1356 0 0.00 0 39667 41 0 0
v_cancel_customer_reserve 1420 1420 0 0.00 0 2553 7 0 0
v_cancel_payment_to_supplier 866 866 0 0.00 0 5465 10 0 0
v_cancel_supplier_invoice 784 758 26 3.31 0 2567 16 0 26
v_cancel_supplier_order 802 802 0 0.00 0 5509 26 0 0
v_cancel_write_off 2072 2072 0 0.00 0 6452 17 0 0
v_max_id_clo_ord 5336 5336 0 0.00 0 2447 2 0 0
v_max_id_clo_res 1014 1014 0 0.00 0 4150 32 0 0
v_max_id_ord_sup 5446 5446 0 0.00 0 7540 5 0 0
v_max_non_paid_invoice 1653 1653 0 0.00 0 3373 6 0 0
v_max_non_paid_realizn 3871 3871 0 0.00 0 4161 4 0 0
v_min_id_clo_ord 5336 5336 0 0.00 0 19930 14 0 0
v_min_id_clo_res 1014 1014 0 0.00 0 3223 12 0 0
v_min_id_ord_sup 5446 5446 0 0.00 0 8345 9 0 0
v_min_non_paid_invoice 5965 5965 0 0.00 0 14164 20 0 0
v_min_non_paid_realizn 6901 6901 0 0.00 0 7262 17 0 0
v_our_firm 10216 10216 0 0.00 0 23 1 0 0
v_reserve_write_off 20330 20330 0 0.00 0 44495 15 0 0
x_lock_depdocs_on_canc_invoice 11307 11307 0 0.00 0 24755 122 0 0
x_lock_depdocs_on_canc_sup_ord 401 401 0 0.00 0 7667 320 0 0
x_make_qstorno_1000_1200 5336 5336 0 0.00 2 20919 1273 0 0
x_make_qstorno_1200_2000 5456 5456 0 0.00 2 78347 3561 0 0
x_make_qstorno_2100_3300 459287 459287 0 0.00 0 83701 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-01-2021 13:29:02 to 18-01-2021 13:29:06.

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
10:56:59 9 8192000000 8796638048 8807108608 3168016 0.038 1 1 2 0 3265632 61840 35776
10:58:00 27429 8192000000 9731049328 10032635904 815605728 9.054 100 89 177 0 844591088 7940592 21044768
10:59:31 18094 8192000000 9771011408 10079293440 843856192 9.338 100 86 172 1 876277760 12128144 20293424
11:00:59 16163 8192000000 9801716720 10117509120 868439168 9.584 100 82 164 0 907147200 20133472 18574560
11:02:19 13123 8192000000 9865303744 10197917696 918524832 10.082 100 83 165 2 969579360 32714288 18340240
11:03:39 8859 8192000000 9922798080 10264309760 956195664 10.452 100 85 170 1 1028719504 54761760 17762080
11:04:50 6801 8192000000 9978106960 10328711168 989197136 10.774 100 87 175 1 1090341584 83394000 17750448
11:05:59 2623 8192000000 9979901168 10339196928 1015715248 11.031 100 83 166 1 1101084848 69003552 16366048
11:07:04 2694 8192000000 10016066704 10382901248 1042131776 11.285 100 83 166 1 1123411872 64703728 16576368
11:08:08 1880 8192000000 10032677616 10403794944 1064674400 11.501 100 92 183 0 1150636688 66979536 18982752
11:09:11 4419 8192000000 9988662384 10384519168 1071258752 11.564 100 35 70 1 1108397424 29689680 7448992
11:10:19 3399 8192000000 10002436416 10385956864 1083696304 11.683 100 58 116 1 1117731504 21894576 12140624
11:11:23 4915 8192000000 9987682944 10385182720 1088043904 11.724 100 42 81 1 1113948144 17084224 8820016
11:12:28 67273 8192000000 10023821424 10435457024 1095755296 11.797 100 35 70 1 1116873808 13879616 7238896
11:14:40 7002 8192000000 10024915376 10427019264 1105991872 11.894 100 51 101 2 1143733488 26745712 10995904
11:15:50 6311 8192000000 10035254736 10443149312 1106429280 11.899 100 57 113 3 1152886256 35019728 11437248
11:16:58 9004 8192000000 9999445728 10404106240 1073051712 11.581 98 22 44 2 1106209584 28420224 4737648
11:18:08 26100 8192000000 9961118544 10367111168 1030263360 11.171 95 24 49 1 1056464240 20725888 5474992
11:19:34 6099 8192000000 9966749264 10368696320 1018924096 11.062 96 36 72 2 1047835472 21188352 7723024
11:20:48 7205 8192000000 9942074432 10313068544 991224288 10.793 96 44 88 1 1020998880 20381648 9392944
11:21:56 7677 8192000000 9931477520 10319011840 1013519728 11.009 99 31 61 1 1047077568 27567728 5990112
11:23:08 42325 8192000000 9929674432 10331172864 1008152192 10.957 98 27 54 1 1041467648 27449680 5865776
11:24:54 4520 8192000000 9964237312 10346610688 1046861136 11.331 100 43 87 1 1081163328 25231968 9070224
11:25:59 12190 8192000000 9975076864 10377908224 1057529728 11.433 100 52 104 1 1091515136 22107152 11878256
11:27:18 13421 8192000000 9996211808 10384539648 1077305152 11.622 100 52 104 0 1104905200 16192336 11407712
11:28:36 92255 8192000000 10059785312 10468442112 1093254080 11.774 100 48 96 1 1161542656 57940784 10347792
11:31:17 9974 8192000000 10089647760 10490322944 1115071920 11.980 100 53 105 0 1200760272 74731936 10956416
11:32:33 8776 8192000000 10140119344 10539909120 1124883488 12.073 100 64 127 1 1243398512 105198176 13316848
11:33:47 20507 8192000000 10150129856 10573180928 1136078400 12.179 100 51 99 1 1267062656 119572096 11412160
11:35:10 7742 8192000000 10183651280 10593357824 1143467248 12.248 100 50 99 1 1291958208 137251120 11239840
11:36:21 2841 8192000000 10218136944 10630758400 1145560896 12.268 99 84 168 0 1330529792 165679968 19288928
11:37:32 892 8192000000 10244618352 10653884416 1168156800 12.480 100 72 145 2 1348594096 164752576 15684720
11:38:38 6295 8192000000 10300716272 10719420416 1158738384 12.391 99 65 130 1 1400671136 227307264 14625488
11:39:48 10911 8192000000 10329848272 10734206976 1161492960 12.417 100 70 140 0 1414482752 237298464 15691328
11:41:07 4211 8192000000 10364840256 10779074560 1173515712 12.530 100 64 127 1 1465780784 277722000 14543072
11:42:16 4942 8192000000 10321236224 10725720064 1152715136 12.335 99 70 140 3 1417776576 249350928 15710512
11:43:24 4183 8192000000 10331572512 10738241536 1133992720 12.159 97 63 125 2 1421933024 273657232 14283072
11:44:34 4531 8192000000 10360339936 10783944704 1157650976 12.381 100 61 119 0 1467064784 295634432 13779376
11:45:46 4064 8192000000 10347665776 10756956160 1152185440 12.330 100 68 138 0 1446333808 277488688 16659680
11:46:53 2365 8192000000 10331524624 10740035584 1136038064 12.178 99 77 157 0 1415532320 261193840 18300416
11:47:58 16158 8192000000 10317808880 10760941568 1112086112 11.952 97 33 66 2 1423421472 302967376 8367984
11:49:17 22698 8192000000 10333817568 10795376640 1128214288 12.105 99 32 64 0 1439902832 303253248 8435296
11:50:41 28226 8192000000 10292284976 10757603328 1102802544 11.864 98 22 44 0 1396774688 287996672 5975472
11:52:38 33416 8192000000 10190139472 10658902016 1082761376 11.674 98 16 32 1 1324691280 237602784 4327120
11:54:12 38665 8192000000 10066155760 10508472320 1085376624 11.699 98 11 22 0 1148941232 61205472 2359136
11:55:51 69957 8192000000 10064761984 10494865408 1079968672 11.647 98 26 51 2 1146362576 61448864 4945040
11:58:05 89049 8192000000 10058827584 10484170752 1084306256 11.688 97 42 82 2 1134622640 41897184 8419200
12:00:38 9999 8192000000 10075750736 10498789376 1124227936 12.067 100 45 90 1 1175460304 42435968 8796400
12:01:55 46716 8192000000 10086390416 10518102016 1112762208 11.959 99 31 62 4 1190193184 71357200 6073776
12:03:43 38603 8192000000 10080936016 10557480960 1127967312 12.102 100 12 24 0 1176802544 46330240 2504992
12:05:26 99093 8192000000 10093100912 10531377152 1106142384 11.896 98 18 36 1 1152531248 42492032 3896832
12:08:10 101785 8192000000 10158489696 10678362112 1134966368 12.168 100 22 43 2 1249306400 109261872 5078160
12:10:53 77989 8192000000 10140026480 10580701184 1133814192 12.157 99 33 66 1 1190315808 49573936 6927680
12:13:11 80092 8192000000 10089071248 10577190912 1146537104 12.277 100 17 34 2 1225588592 75493328 3558160
12:15:34 56823 8192000000 10145442960 10585354240 1158572592 12.390 100 29 58 1 1220014992 55601424 5840976
12:17:31 27735 8192000000 10119185264 10563014656 1155902576 12.365 100 21 42 1 1207947184 47884016 4160592
12:19:02 71502 8192000000 10126048272 10577526784 1147467376 12.286 99 14 28 0 1188782432 38725968 2589088
12:21:25 49237 8192000000 10110024400 10553987072 1143576704 12.249 99 36 69 3 1188977376 38335904 7064768
12:23:17 78022 8192000000 10084621472 10527879168 1121738688 12.043 97 35 69 1 1154500960 25296320 7465952
12:25:36 69651 8192000000 10070768656 10536210432 1138860944 12.205 99 12 23 1 1181907616 40476096 2570576
12:27:59 80235 8192000000 10077601184 10596986880 1123419904 12.059 98 8 16 0 1186053200 60939568 1693728
12:30:19 28856 8192000000 10066738896 10497597440 1110999792 11.942 98 24 48 2 1141891712 26295184 4596736
12:32:00 50010 8192000000 10096210848 10540523520 1107334368 11.907 97 22 44 1 1152019424 39630592 5054464
12:33:58 38076 8192000000 10105163696 10534842368 1137296960 12.190 99 23 46 0 1181779984 39748256 4734768
12:35:55 69746 8192000000 10034067920 10456739840 1048113696 11.343 93 22 44 1 1091297200 38788704 4394800
12:38:05 44769 8192000000 10107427920 10582839296 1100015152 11.838 98 41 82 1 1194569664 86026144 8528368
12:40:44 86030 8192000000 10044785344 10480902144 1038317248 11.248 92 25 50 1 1074597888 31200464 5080176
12:43:10 139756 8192000000 10041097696 10503577600 1112477680 11.956 99 21 42 0 1147552880 30730880 4344320
12:46:34 50688 8192000000 10020870304 10475438080 1098325440 11.822 99 8 16 1 1134082656 34365552 1391664
12:48:25 76329 8192000000 10101641536 10548191232 1094694432 11.787 98 15 30 1 1166034704 68209584 3130688
12:50:42 77632 8192000000 10114755920 10544881664 1091398704 11.756 97 21 42 0 1184545424 88568448 4578272
12:53:00 62655 8192000000 10106045728 10542493696 1123921840 12.064 100 32 64 1 1193530976 62910928 6698208
12:55:03 78876 8192000000 10079898944 10526105600 1130999872 12.131 100 26 51 3 1205836544 69607936 5228736
12:57:25 31916 8192000000 10136985328 10566467584 1142939728 12.243 100 46 91 0 1211989488 60477248 8572512
12:58:57 117263 8192000000 10092140288 10536042496 1131486416 12.135 99 29 58 2 1177636368 39346592 6803360
13:02:07 66815 8192000000 10102241456 10569330688 1124930736 12.074 98 9 18 1 1195620640 69120032 1569872
13:04:14 73355 8192000000 10099810912 10535374848 1127096992 12.094 98 19 38 1 1193732784 62268816 4366976
13:06:28 47955 8192000000 10144090848 10592653312 1143447872 12.248 99 22 44 0 1222387856 73866224 5073760
13:08:21 34074 8192000000 10223381680 10683604992 1170261408 12.499 100 33 66 1 1291425376 113557136 7606832
13:10:13 23878 8192000000 10213577856 10646290432 1163715344 12.438 99 41 81 2 1298015088 125001568 9298176
13:11:42 30122 8192000000 10294689984 10830450688 1164638688 12.447 100 12 24 1 1362700672 194954464 3107520
13:14:28 67995 8192000000 10078501952 10603978752 1104082736 11.876 97 14 28 0 1143369456 36536240 2750480
13:16:37 117448 8192000000 10068377968 10512695296 1093422032 11.775 96 25 50 1 1145409120 46223664 5763424
13:20:05 81040 8192000000 10106966048 10551836672 1133955808 12.159 100 11 22 1 1200319584 63938160 2425616
13:22:34 72914 8192000000 10058278912 10519879680 1120072080 12.028 99 16 32 0 1175295440 51751936 3471424
13:24:51 62668 8192000000 10104193632 10566828032 1148287664 12.293 100 21 42 1 1193298880 40636304 4374912

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 18-01-2021 13:29:07 to 18-01-2021 13:29:22.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 157 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-01-2021 13:29:23 to 18-01-2021 13:29:25.

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          234170
MON$OLDEST_ACTIVE               236196
MON$OLDEST_SNAPSHOT             236196
MON$NEXT_TRANSACTION            236196
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                       2115264
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             793
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2325 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2325 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2325 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.2325 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 ---
:257: 
:258: mscs-test-161.budzdorov.ru    Mon Jan 18 13:27:03 2021
:259:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:260: 
:261: 
:262: mscs-test-161.budzdorov.ru    Mon Jan 18 13:27:04 2021
:263:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:264: 
--- end of diff output ---

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

18.01.21 13:29:26. 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
2021-01-18 13:29:28 No severe PSQL-related problems occured
18.01.21 13:29:30. End of report.