Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.11.2020 07:37
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 16541164 kB
MemAvailable 31460340 kB
Buffers 0 kB
Cached 15147388 kB
SwapCached 3456 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-14 07:35:43
DTS_END                         2020-11-14 07:35:43.3540
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/48254
TRN_ID                          230787
ATT_ID                          637
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: 9192.44 24461 1103093
customer order: creation 163.74 379 19649
customer order: refuse 14.35 291 1722
order to supplier: creation 43.75 1375 5250
order to supplier: removal 3.39 131959 407
invoice (draft): creation 43.98 3228 5278
invoice (draft): removal 52.96 1133 6355
invoice accept: apply 40.28 16229 4834
invoice accept: cancel 47.68 883 5721
customer reserve: creation 3774.47 156 452936
customer reserve: removal 4724.29 74 566915
realization accept: apply 79.89 180 9587
realization accept: cancel 79.88 118 9585
payment from customer: creation 57.13 106 6856
payment from customer: removal 5.40 70 648
payment to supplier: creation 47.49 112 5699
payment to supplier: removal 3.43 57 411
service: total inventory turnovers 3.72 3223 446
service: total monetary turnovers 5.69 551 683
service: aggregate perf. data 0.88 19250 106
service: refresh index statistics 0.04 309848 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-11-2020 07:37:28 to 14-11-2020 07:37:30.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2386
TEST_TIME 2 3174
TEST_TIME 3 3063
TEST_TIME 4 3023
TEST_TIME 5 3301
TEST_TIME 6 3924
TEST_TIME 7 4603
TEST_TIME 8 5169
TEST_TIME 9 5826
TEST_TIME 10 6031
TEST_TIME 11 6228
TEST_TIME 12 6171
TEST_TIME 13 6379
TEST_TIME 14 6730
TEST_TIME 15 7176
TEST_TIME 16 7082
TEST_TIME 17 7211
TEST_TIME 18 7449
TEST_TIME 19 7536
TEST_TIME 20 7496
TEST_TIME 21 7661
TEST_TIME 22 8156
TEST_TIME 23 7947
TEST_TIME 24 8296
TEST_TIME 25 8245
TEST_TIME 26 9228
TEST_TIME 27 10980
TEST_TIME 28 11616
TEST_TIME 29 11567
TEST_TIME 30 11517
TEST_TIME 31 11445
TEST_TIME 32 11210
TEST_TIME 33 11200
TEST_TIME 34 11182
TEST_TIME 35 11152
TEST_TIME 36 11151
TEST_TIME 37 11068
TEST_TIME 38 11027
TEST_TIME 39 10934
TEST_TIME 40 10736
TEST_TIME 41 10864
TEST_TIME 42 10756
TEST_TIME 43 10518
TEST_TIME 44 10418
TEST_TIME 45 10305
TEST_TIME 46 10220
TEST_TIME 47 10143
TEST_TIME 48 10158
TEST_TIME 49 10093
TEST_TIME 50 10021
TEST_TIME 51 10029
TEST_TIME 52 10002
TEST_TIME 53 9993
TEST_TIME 54 9957
TEST_TIME 55 9921
TEST_TIME 56 9865
TEST_TIME 57 9868
TEST_TIME 58 9823
TEST_TIME 59 9829
TEST_TIME 60 9849
TEST_TIME 61 9954
TEST_TIME 62 10114
TEST_TIME 63 10128
TEST_TIME 64 10141
TEST_TIME 65 10119
TEST_TIME 66 10079
TEST_TIME 67 10220
TEST_TIME 68 10096
TEST_TIME 69 9994
TEST_TIME 70 9966
TEST_TIME 71 9935
TEST_TIME 72 9851
TEST_TIME 73 9800
TEST_TIME 74 9742
TEST_TIME 75 9648
TEST_TIME 76 9648
TEST_TIME 77 9607
TEST_TIME 78 9567
TEST_TIME 79 9652
TEST_TIME 80 9639
TEST_TIME 81 9651
TEST_TIME 82 9792
TEST_TIME 83 9716
TEST_TIME 84 9739
TEST_TIME 85 9844
TEST_TIME 86 9797
TEST_TIME 87 9792
TEST_TIME 88 9844
TEST_TIME 89 9742
TEST_TIME 90 9676
TEST_TIME 91 9634
TEST_TIME 92 9592
TEST_TIME 93 9573
TEST_TIME 94 9573
TEST_TIME 95 9546
TEST_TIME 96 9516
TEST_TIME 97 9486
TEST_TIME 98 9447
TEST_TIME 99 9478
TEST_TIME 100 9508
TEST_TIME 101 9514
TEST_TIME 102 9542
TEST_TIME 103 9607
TEST_TIME 104 9613
TEST_TIME 105 9557
TEST_TIME 106 9635
TEST_TIME 107 9649
TEST_TIME 108 9638
TEST_TIME 109 9583
TEST_TIME 110 9522
TEST_TIME 111 9481
TEST_TIME 112 9441
TEST_TIME 113 9399
TEST_TIME 114 9375
TEST_TIME 115 9355
TEST_TIME 116 9293
TEST_TIME 117 9258
TEST_TIME 118 9261
TEST_TIME 119 9227
TEST_TIME 120 9194

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 14-11-2020 07:37:31 to 14-11-2020 07:37: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 1522196 1522196 0 0.00 0 21524 6 0 0
doc_list_biud 1522196 1522196 0 0.00 0 86175 35 0 0
sp_add_invoice_to_stock 4929 4834 95 1.92 44 183471 16229 0 95
sp_cancel_adding_invoice 5721 5721 0 0.00 5 85384 883 0 0
sp_cancel_client_order 1722 1722 0 0.00 5 11572 291 0 0
sp_cancel_customer_reserve 566915 566915 0 0.00 1 26188 74 0 0
sp_cancel_pay_from_customer 648 648 0 0.00 3 3296 71 0 0
sp_cancel_pay_to_supplier 411 411 0 0.00 3 1646 57 0 0
sp_cancel_supplier_invoice 6361 6355 6 0.09 2 86226 1133 0 6
sp_cancel_supplier_order 407 407 0 0.00 10 1441125 131959 0 0
sp_cancel_write_off 9585 9585 0 0.00 1 8499 118 0 0
sp_client_order 19649 19649 0 0.00 4 16539 380 0 0
sp_customer_reserve 452936 452936 0 0.00 0 70545 157 0 0
sp_fill_shopping_cart 30168 30168 0 0.00 1 12998 40 0 0
sp_fill_shopping_cart_clo_res 452934 452934 0 0.00 0 31314 14 0 0
sp_get_clo_for_invoice 4832 4832 0 0.00 0 34682 353 0 0
sp_kill_cost_storno 16365 16365 0 0.00 0 6313 56 0 0
sp_kill_qstorno_ret_qs2qd 1150177 1150177 0 0.00 0 86172 41 0 0
sp_kill_qty_storno 605124 605124 0 0.00 0 86173 87 0 0
sp_lock_selected_doc 20063 20063 0 0.00 0 8426 6 0 0
sp_make_cost_storno 26974 26974 0 0.00 0 16157 54 0 0
sp_multiply_rows_for_pdistr 26974 26974 0 0.00 0 12588 29 0 0
sp_multiply_rows_for_qdistr 19649 19649 0 0.00 0 15183 215 0 0
sp_pay_from_customer 6856 6856 0 0.00 4 14052 107 0 0
sp_pay_to_supplier 5699 5699 0 0.00 4 14565 113 0 0
sp_payment_common 12555 12555 0 0.00 1 14029 18 0 0
sp_qd_handle_on_cancel_clo 1722 1722 0 0.00 0 11565 263 0 0
sp_qd_handle_on_invoice_upd_sts 10553 10553 0 0.00 0 40396 395 0 0
sp_qd_handle_on_reserve_upd_sts 9587 9587 0 0.00 0 9864 53 0 0
sp_reserve_write_off 9587 9587 0 0.00 7 15537 181 0 0
sp_supplier_invoice 5278 5278 0 0.00 7 95622 3229 0 0
sp_supplier_order 5250 5250 0 0.00 6 24638 1375 0 0
srv_aggregate_perf_data 106 106 0 0.00 2656 637993 19250 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1981 120792 45720 0 0
srv_find_qd_qs_mism 1056858 1056858 0 0.00 0 21523 5 0 0
srv_make_invnt_saldo 446 446 0 0.00 41 98894 3224 0 0
srv_make_money_saldo 683 683 0 0.00 1 13053 551 0 0
srv_recalc_idx_stat 5 5 0 0.00 111068 468095 309849 0 0
t$perf-abend:sp_add_invoice_to_stock 95 95 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4834 4834 0 0.00 0 13211 161 0 0
t$perf-norm:sp_cancel_adding_invoice 393 393 0 0.00 0 2579 67 0 0
t$perf-norm:sp_cancel_client_order 1722 1722 0 0.00 0 4414 10 0 0
t$perf-norm:sp_cancel_customer_reserve 690 690 0 0.00 0 3003 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 648 648 0 0.00 0 1150 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 411 411 0 0.00 0 353 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 413 413 0 0.00 0 1084 16 0 0
t$perf-norm:sp_cancel_supplier_order 407 407 0 0.00 0 20910 1737 0 0
t$perf-norm:sp_cancel_write_off 962 962 0 0.00 0 888 5 0 0
t$perf-norm:sp_client_order 19649 19649 0 0.00 0 9300 16 0 0
t$perf-norm:sp_customer_reserve 1036 1036 0 0.00 0 2370 5 0 0
t$perf-norm:sp_pay_from_customer 6856 6856 0 0.00 0 3964 7 0 0
t$perf-norm:sp_pay_to_supplier 5699 5699 0 0.00 0 7942 9 0 0
t$perf-norm:sp_reserve_write_off 9587 9587 0 0.00 0 6805 10 0 0
t$perf-norm:sp_supplier_invoice 5278 5278 0 0.00 0 12493 21 0 0
t$perf-norm:sp_supplier_order 5250 5250 0 0.00 0 7132 15 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 123 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 289 14 0 0
t$perf-norm:srv_make_invnt_saldo 446 446 0 0.00 0 3449 13 0 0
t$perf-norm:srv_make_money_saldo 683 683 0 0.00 0 550 7 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 7 3 0 0
v_add_invoice_to_stock 9850 9660 190 1.92 0 5588 17 0 190
v_all_customers 35832 35832 0 0.00 0 15 1 0 0
v_all_suppliers 29592 29592 0 0.00 0 23 1 0 0
v_all_wares 39296 39296 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 786 786 0 0.00 0 4685 16 0 0
v_cancel_client_order 3444 3444 0 0.00 0 3901 7 0 0
v_cancel_customer_prepayment 1296 1296 0 0.00 0 3256 8 0 0
v_cancel_customer_reserve 1380 1380 0 0.00 0 3257 6 0 0
v_cancel_payment_to_supplier 822 822 0 0.00 0 775 5 0 0
v_cancel_supplier_invoice 838 826 12 1.43 0 2697 22 0 12
v_cancel_supplier_order 814 814 0 0.00 0 911 5 0 0
v_cancel_write_off 1924 1924 0 0.00 0 4731 17 0 0
v_max_id_clo_ord 5244 5244 0 0.00 0 418 1 0 0
v_max_id_clo_res 1036 1036 0 0.00 0 4206 14 0 0
v_max_id_ord_sup 5272 5272 0 0.00 0 3872 4 0 0
v_max_non_paid_invoice 1419 1419 0 0.00 0 1184 3 0 0
v_max_non_paid_realizn 3730 3730 0 0.00 0 830 2 0 0
v_min_id_clo_ord 5244 5244 0 0.00 0 11379 7 0 0
v_min_id_clo_res 1036 1036 0 0.00 0 1160 6 0 0
v_min_id_ord_sup 5272 5272 0 0.00 0 3530 5 0 0
v_min_non_paid_invoice 5699 5699 0 0.00 0 11635 10 0 0
v_min_non_paid_realizn 6856 6856 0 0.00 0 14023 13 0 0
v_our_firm 9716 9716 0 0.00 0 14 1 0 0
v_reserve_write_off 19174 19174 0 0.00 0 4671 7 0 0
x_lock_depdocs_on_canc_invoice 11663 11663 0 0.00 0 18789 118 0 0
x_lock_depdocs_on_canc_sup_ord 407 407 0 0.00 0 18478 446 0 0
x_make_qstorno_1000_1200 5250 5250 0 0.00 2 24245 1299 0 0
x_make_qstorno_1200_2000 5278 5278 0 0.00 4 95614 3081 0 0
x_make_qstorno_2100_3300 452936 452936 0 0.00 0 70480 140 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-11-2020 07:37:35 to 14-11-2020 07:37:39.

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
05:05:46 2 8192000000 8796641312 8807108608 3168704 0.038 1 1 2 0 3266400 61840 35856
05:06:47 25217 8192000000 9736888624 10037301248 808495008 8.982 100 86 171 2 836474736 7175072 20804656
05:08:15 25059 8192000000 9777432544 10085421056 852636640 9.426 100 89 179 1 885225200 11669600 20918960
05:09:46 21210 8192000000 9806088304 10125512704 874989472 9.650 100 82 164 0 911334928 17064816 19280640
05:11:13 15024 8192000000 9874523984 10208894976 921118480 10.107 100 74 147 2 975004384 37668752 16217152
05:12:31 8174 8192000000 9920505328 10266484736 962712272 10.516 100 84 168 3 1038983744 58808864 17462608
05:13:42 5072 8192000000 9952251088 10311544832 988004496 10.762 100 66 132 0 1069796960 68167040 13625424
05:14:51 2897 8192000000 9995052352 10359197696 1018427024 11.057 100 81 161 3 1110018000 76030208 15560768
05:15:56 1944 8192000000 10017786288 10381950976 1042816768 11.292 100 94 189 1 1133731040 72091008 18823264
05:17:00 2079 8192000000 10025690864 10396340224 1059555424 11.452 100 93 186 3 1137891056 58814992 19520640
05:18:04 5618 8192000000 10015459488 10392530944 1063699232 11.492 100 65 130 1 1125779680 48577712 13502736
05:19:11 7630 8192000000 9985759552 10382188544 1072846272 11.579 100 36 72 2 1109227856 28486944 7894640
05:20:19 9760 8192000000 10001183408 10404802560 1079948432 11.647 100 25 50 1 1100706096 15256624 5501040
05:21:30 8625 8192000000 10001715312 10403250176 1085194992 11.697 100 31 59 4 1106283632 14218992 6869648
05:22:45 8126 8192000000 10001715104 10394456064 1096293456 11.802 100 45 89 6 1116623184 8832576 11497152
05:23:57 10742 8192000000 10024819920 10413555712 1103165904 11.868 100 75 150 1 1129848368 9670448 17012016
05:25:09 5700 8192000000 10029533184 10431135744 1095637392 11.796 100 31 59 2 1143031856 41174544 6219920
05:26:14 11009 8192000000 10010029760 10423934976 1096184608 11.801 100 32 64 0 1127204608 23809696 7210304
05:27:26 55442 8192000000 9951606416 10340519936 970385216 10.590 90 37 70 4 1005494320 26644336 8464768
05:29:25 8899 8192000000 9942765248 10332274688 982133504 10.705 95 38 76 2 1017332880 26927360 8272016
05:30:39 5563 8192000000 9930981392 10307002368 997065376 10.850 99 42 84 2 1029743200 22758048 9919776
05:31:49 9820 8192000000 9953009200 10350788608 1037512528 11.241 100 25 51 3 1066458944 22975520 5970896
05:32:59 44352 8192000000 9957774848 10361626624 1045792640 11.320 100 24 47 5 1076767968 26074128 4901200
05:34:45 5538 8192000000 9967647136 10361208832 1056438608 11.422 100 45 90 1 1075244704 9177152 9628944
05:35:57 21372 8192000000 9992603632 10390171648 1084279760 11.688 100 46 90 1 1105216960 10687648 10249552
05:37:27 14523 8192000000 10074635904 10476781568 1109082688 11.924 100 54 108 1 1177295136 57395584 10816864
05:38:44 80865 8192000000 10123928208 10531098624 1118916304 12.017 100 36 71 0 1228428384 102208656 7303424
05:41:19 4129 8192000000 10188054064 10594349056 1139818624 12.214 100 53 106 0 1298233264 147129568 11285072
05:42:25 7528 8192000000 10203078352 10617024512 1150263808 12.312 100 59 118 0 1320465792 156859456 13342528
05:43:43 9919 8192000000 10237891168 10654023680 1161330560 12.416 100 72 145 0 1340374080 163922176 15121344
05:44:59 13203 8192000000 10290943008 10697785344 1168803040 12.486 100 63 126 0 1400612912 217652848 14157024
05:46:17 2471 8192000000 10296073312 10701000704 1173035776 12.525 100 78 151 2 1397146464 207254224 16856464
05:47:26 5673 8192000000 10361696176 10771492864 1180774368 12.597 100 73 145 1 1465110032 267312784 17022880
05:48:38 5686 8192000000 10399605952 10810937344 1193375184 12.715 100 81 162 1 1504822096 293374592 18072320
05:49:50 1979 8192000000 10414665936 10836819968 1186339648 12.649 100 93 187 0 1510535088 302303744 21891696
05:50:58 3499 8192000000 10412827984 10825101312 1171453472 12.510 99 66 132 3 1502942224 316213504 15275248
05:52:16 3187 8192000000 10462950672 10880364544 1166829360 12.467 99 68 136 2 1550452592 367283152 16340080
05:53:28 8327 8192000000 10496313680 10931666944 1182801568 12.616 100 50 100 0 1585522976 389863392 12858016
05:54:46 5804 8192000000 10515922992 10939535360 1165337968 12.453 99 52 104 0 1611320624 432745600 13237056
05:56:00 5653 8192000000 10588798688 11009359872 1179385776 12.584 100 56 110 3 1687896768 494023664 14487328
05:57:10 5432 8192000000 10589514384 11011952640 1181582992 12.605 100 55 110 2 1687792688 492209984 13999712
05:58:38 12123 8192000000 10641480928 11077058560 1144061216 12.254 99 41 80 0 1729699296 574401248 11236832
06:00:20 9121 8192000000 10633271312 11084443648 1138922544 12.205 100 40 80 0 1747371792 597006912 11442336
06:01:31 20318 8192000000 10522076416 11039567872 1127128416 12.094 99 21 42 1 1660090256 526513632 6448208
06:02:53 22393 8192000000 10087260560 10570305536 1097755920 11.816 98 10 20 0 1196705744 96440192 2509632
06:04:16 23315 8192000000 10063727136 10531389440 1126524016 12.089 100 16 32 0 1183456528 53186304 3746208
06:05:40 83387 8192000000 10057884864 10557321216 1097230256 11.811 97 5 10 0 1139980112 42165920 583936
06:08:04 59756 8192000000 10058467168 10511523840 1108972256 11.923 98 32 63 0 1161134992 46020432 6142304
06:10:06 113332 8192000000 10050223088 10538754048 1067644944 11.530 95 5 10 0 1104734384 36352256 737184
06:13:52 34504 8192000000 10070220288 10492542976 1126245760 12.086 100 40 76 1 1167229936 32252352 8731824
06:15:38 24766 8192000000 10132244192 10560757760 1133681776 12.156 100 41 82 0 1204247696 62548704 8017216
06:17:10 120792 8192000000 10077424304 10521022464 1092133504 11.763 97 15 29 2 1150525680 55251584 3140592
06:20:30 80427 8192000000 10066749824 10501525504 1095230400 11.792 97 23 44 0 1122642400 22299296 5112704
06:22:54 41746 8192000000 10104956816 10570457088 1125323728 12.077 99 25 50 0 1206771744 75600512 5847504
06:24:45 48232 8192000000 10128292096 10571288576 1147838352 12.289 100 25 49 3 1235679872 82543424 5298096
06:26:44 46170 8192000000 10125502912 10565267456 1133523888 12.155 99 9 17 0 1210783488 75375040 1884560
06:28:33 38395 8192000000 10079977376 10555232256 1138928048 12.205 99 10 20 1 1174097040 33107168 2061824
06:30:11 49009 8192000000 10068546640 10511003648 1117531408 12.004 98 14 29 1 1142001312 21379040 3090864
06:32:02 40860 8192000000 10093491376 10551349248 1133348944 12.153 99 26 52 0 1179597360 41091488 5156928
06:33:53 61145 8192000000 10159020032 10631581696 1138905840 12.205 99 22 44 1 1256352176 112398224 5048112
06:35:55 53345 8192000000 10141626032 10649837568 1140330960 12.219 100 16 32 1 1277945856 133910352 3704544
06:37:49 74522 8192000000 10083281984 10548707328 1134616160 12.165 99 16 32 1 1195231744 57326400 3289184
06:40:04 41285 8192000000 10073714032 10524532736 1097238304 11.811 96 16 32 0 1122902256 22533264 3130688
06:42:11 101909 8192000000 10088290480 10623422464 1105524256 11.890 98 12 25 0 1182794896 74749360 2521280
06:45:45 47057 8192000000 10032708560 10516611072 1090666528 11.749 99 23 47 0 1137104752 41514944 4923280
06:47:33 76705 8192000000 10061470176 10531147776 1097800208 11.817 98 10 19 0 1159422736 58929440 2693088
06:49:51 75119 8192000000 10122694304 10574626816 1112813232 11.959 99 23 46 0 1212505232 94727504 4964496
06:52:14 55470 8192000000 10128549488 10563903488 1118398768 12.012 100 15 30 0 1220333808 98924672 3010368
06:54:19 72204 8192000000 10129356608 10594217984 1109335184 11.926 98 27 54 0 1231559024 115314432 6909408
06:56:31 69251 8192000000 10077956976 10628300800 1124859248 12.073 100 12 24 1 1256271216 128291920 3120048
06:58:41 45993 8192000000 10130077616 10588094464 1108792944 11.921 98 54 108 1 1200903184 81072352 11037888
07:00:27 74639 8192000000 10087236928 10553675776 1121324912 12.040 99 22 44 1 1183164400 57639552 4199936
07:02:43 70323 8192000000 10084009376 10583810048 1090705600 11.749 96 27 54 0 1167087344 71246608 5135136
07:04:55 102653 8192000000 10098583728 10558099456 1077662064 11.625 95 18 37 0 1127539616 45940880 3936672
07:07:46 77771 8192000000 10086473424 10584731648 1122157536 12.047 100 11 22 0 1213504448 89170320 2176592
07:10:04 46177 8192000000 10036386448 10475442176 1071321392 11.565 95 12 24 0 1111629776 38091952 2216432
07:11:51 71702 8192000000 10128887312 10576105472 1134863744 12.167 99 29 57 0 1208188624 67521424 5803456
07:14:04 30158 8192000000 10113609152 10609127424 1127691408 12.100 99 31 63 1 1232117680 97229552 7196720
07:15:35 91904 8192000000 10143647056 10647093248 1123147664 12.057 98 25 51 0 1191635904 63037888 5450352
07:18:07 79753 8192000000 10103118016 10601267200 1146199904 12.274 100 13 26 1 1214355472 65472880 2682688
07:20:30 33499 8192000000 10127153088 10548588544 1136287280 12.181 99 51 101 1 1198238848 50667456 11284112
07:22:08 39251 8192000000 10113263184 10594508800 1146452048 12.276 100 14 28 0 1197385440 48161856 2771536
07:23:48 117510 8192000000 10063551296 10530938880 1148473776 12.295 100 22 44 0 1186619632 33544384 4601472
07:26:46 54455 8192000000 10056296560 10541428736 1112874336 11.960 97 8 15 0 1145709024 31068480 1766208
07:28:51 57766 8192000000 10091634720 10564136960 1138929568 12.205 100 17 34 0 1169486688 26896656 3660464
07:32:21 53839 8192000000 10148341072 10611085312 1127154592 12.095 97 24 48 2 1228672304 95876416 5641296
07:34:19 36944 8192000000 10177099264 10622988288 1127057456 12.094 97 25 50 1 1228439968 95566048 5816464

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 14-11-2020 07:37:41 to 14-11-2020 07:37:56.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 95 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-11-2020 07:37:57 to 14-11-2020 07:37: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          225432
MON$OLDEST_ACTIVE               230915
MON$OLDEST_SNAPSHOT             230915
MON$NEXT_TRANSACTION            230915
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                       2132608
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             788
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2265 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2265 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2265 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.2265 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Sat Nov 14 07:35:47 2020
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
--- end of diff output ---

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

14.11.20 07:38: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-14 07:38:02 No severe PSQL-related problems occured
14.11.20 07:38:03. End of report.