Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.01.2021 10:27
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 16599456 kB
MemAvailable 31198924 kB
Buffers 0 kB
Cached 14941328 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-17 10:26:54
DTS_END                         2021-01-17 10:26:54.5770
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/40568
TRN_ID                          234215
ATT_ID                          688
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: 8994.66 23166 1079359
customer order: creation 166.40 464 19968
customer order: refuse 13.84 320 1661
order to supplier: creation 43.83 1472 5259
order to supplier: removal 3.33 136269 399
invoice (draft): creation 43.75 3770 5250
invoice (draft): removal 52.16 1105 6259
invoice accept: apply 39.60 18766 4752
invoice accept: cancel 47.48 929 5697
customer reserve: creation 3663.71 181 439645
customer reserve: removal 4629.13 76 555496
realization accept: apply 81.31 184 9757
realization accept: cancel 85.80 131 10296
payment from customer: creation 56.89 105 6827
payment from customer: removal 5.90 73 708
payment to supplier: creation 47.53 138 5704
payment to supplier: removal 3.43 120 412
service: total inventory turnovers 3.60 3887 432
service: total monetary turnovers 6.11 661 733
service: aggregate perf. data 0.83 16734 99
service: refresh index statistics 0.04 277929 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-01-2021 10:27:54 to 17-01-2021 10:27:56.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4580
TEST_TIME 2 3339
TEST_TIME 3 4105
TEST_TIME 4 4557
TEST_TIME 5 5462
TEST_TIME 6 5714
TEST_TIME 7 5696
TEST_TIME 8 5674
TEST_TIME 9 5633
TEST_TIME 10 5945
TEST_TIME 11 6037
TEST_TIME 12 6176
TEST_TIME 13 6350
TEST_TIME 14 6560
TEST_TIME 15 6948
TEST_TIME 16 7069
TEST_TIME 17 7373
TEST_TIME 18 7595
TEST_TIME 19 7916
TEST_TIME 20 7835
TEST_TIME 21 7922
TEST_TIME 22 8068
TEST_TIME 23 8495
TEST_TIME 24 9135
TEST_TIME 25 8960
TEST_TIME 26 9083
TEST_TIME 27 9401
TEST_TIME 28 11449
TEST_TIME 29 11504
TEST_TIME 30 11350
TEST_TIME 31 11103
TEST_TIME 32 10865
TEST_TIME 33 10620
TEST_TIME 34 10552
TEST_TIME 35 10382
TEST_TIME 36 10223
TEST_TIME 37 10142
TEST_TIME 38 10210
TEST_TIME 39 10180
TEST_TIME 40 10145
TEST_TIME 41 10074
TEST_TIME 42 9923
TEST_TIME 43 9881
TEST_TIME 44 9861
TEST_TIME 45 10002
TEST_TIME 46 9979
TEST_TIME 47 9944
TEST_TIME 48 10157
TEST_TIME 49 10006
TEST_TIME 50 10123
TEST_TIME 51 10203
TEST_TIME 52 10095
TEST_TIME 53 10182
TEST_TIME 54 10115
TEST_TIME 55 9950
TEST_TIME 56 9821
TEST_TIME 57 9755
TEST_TIME 58 9663
TEST_TIME 59 9569
TEST_TIME 60 9586
TEST_TIME 61 9534
TEST_TIME 62 9525
TEST_TIME 63 9501
TEST_TIME 64 9490
TEST_TIME 65 9476
TEST_TIME 66 9502
TEST_TIME 67 9472
TEST_TIME 68 9468
TEST_TIME 69 9600
TEST_TIME 70 9631
TEST_TIME 71 9638
TEST_TIME 72 9657
TEST_TIME 73 9616
TEST_TIME 74 9591
TEST_TIME 75 9491
TEST_TIME 76 9400
TEST_TIME 77 9377
TEST_TIME 78 9321
TEST_TIME 79 9296
TEST_TIME 80 9237
TEST_TIME 81 9315
TEST_TIME 82 9373
TEST_TIME 83 9343
TEST_TIME 84 9301
TEST_TIME 85 9251
TEST_TIME 86 9290
TEST_TIME 87 9303
TEST_TIME 88 9334
TEST_TIME 89 9325
TEST_TIME 90 9368
TEST_TIME 91 9280
TEST_TIME 92 9202
TEST_TIME 93 9173
TEST_TIME 94 9100
TEST_TIME 95 9041
TEST_TIME 96 9061
TEST_TIME 97 9120
TEST_TIME 98 9101
TEST_TIME 99 9103
TEST_TIME 100 9074
TEST_TIME 101 9064
TEST_TIME 102 9117
TEST_TIME 103 9090
TEST_TIME 104 9142
TEST_TIME 105 9215
TEST_TIME 106 9194
TEST_TIME 107 9194
TEST_TIME 108 9208
TEST_TIME 109 9136
TEST_TIME 110 9069
TEST_TIME 111 9039
TEST_TIME 112 9006
TEST_TIME 113 8978
TEST_TIME 114 8971
TEST_TIME 115 8941
TEST_TIME 116 8949
TEST_TIME 117 8945
TEST_TIME 118 8921
TEST_TIME 119 8929
TEST_TIME 120 9004

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-01-2021 10:27:57 to 17-01-2021 10:28:00.

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 1487496 1487496 0 0.00 0 16452 7 0 0
doc_list_biud 1487495 1487495 0 0.00 0 43691 36 0 0
sp_add_invoice_to_stock 4908 4752 156 3.17 42 216198 18766 0 156
sp_cancel_adding_invoice 5697 5697 0 0.00 4 70906 929 0 0
sp_cancel_client_order 1661 1661 0 0.00 4 12092 321 0 0
sp_cancel_customer_reserve 555496 555496 0 0.00 1 19288 77 0 0
sp_cancel_pay_from_customer 708 708 0 0.00 3 5104 74 0 0
sp_cancel_pay_to_supplier 412 412 0 0.00 3 9064 120 0 0
sp_cancel_supplier_invoice 6272 6259 13 0.20 1 19373 1106 0 13
sp_cancel_supplier_order 399 399 0 0.00 8 1219347 136269 0 0
sp_cancel_write_off 10296 10296 0 0.00 1 9618 131 0 0
sp_client_order 19968 19968 0 0.00 4 24892 465 0 0
sp_customer_reserve 439645 439645 0 0.00 0 43450 181 0 0
sp_fill_shopping_cart 30464 30464 0 0.00 1 18784 47 0 0
sp_fill_shopping_cart_clo_res 439639 439639 0 0.00 0 23030 19 0 0
sp_get_clo_for_invoice 4733 4733 0 0.00 0 47205 453 0 0
sp_kill_cost_storno 17113 17113 0 0.00 0 7593 64 0 0
sp_kill_qstorno_ret_qs2qd 1127946 1127946 0 0.00 0 19364 41 0 0
sp_kill_qty_storno 594295 594295 0 0.00 0 43690 89 0 0
sp_lock_selected_doc 20137 20137 0 0.00 0 9790 8 0 0
sp_make_cost_storno 27019 27019 0 0.00 0 14873 55 0 0
sp_multiply_rows_for_pdistr 27019 27019 0 0.00 0 7419 31 0 0
sp_multiply_rows_for_qdistr 19968 19968 0 0.00 0 15594 274 0 0
sp_pay_from_customer 6827 6827 0 0.00 4 13125 106 0 0
sp_pay_to_supplier 5704 5704 0 0.00 5 18374 138 0 0
sp_payment_common 12531 12531 0 0.00 1 5669 17 0 0
sp_qd_handle_on_cancel_clo 1661 1661 0 0.00 0 12081 277 0 0
sp_qd_handle_on_invoice_upd_sts 10427 10427 0 0.00 0 43689 432 0 0
sp_qd_handle_on_reserve_upd_sts 9757 9757 0 0.00 0 11744 59 0 0
sp_reserve_write_off 9757 9757 0 0.00 6 15797 184 0 0
sp_supplier_invoice 5250 5250 0 0.00 7 86954 3771 0 0
sp_supplier_order 5259 5259 0 0.00 6 30345 1473 0 0
srv_aggregate_perf_data 99 99 0 0.00 2685 176767 16735 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1370 125192 47738 0 0
srv_find_qd_qs_mism 1034078 1034078 0 0.00 0 14156 6 0 0
srv_make_invnt_saldo 432 432 0 0.00 40 101480 3887 0 0
srv_make_money_saldo 733 733 0 0.00 1 22378 662 0 0
srv_recalc_idx_stat 5 5 0 0.00 102249 408419 277930 0 0
t$perf-abend:sp_add_invoice_to_stock 156 156 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 4752 4752 0 0.00 0 13588 173 0 0
t$perf-norm:sp_cancel_adding_invoice 411 411 0 0.00 0 4088 77 0 0
t$perf-norm:sp_cancel_client_order 1661 1661 0 0.00 0 2580 11 0 0
t$perf-norm:sp_cancel_customer_reserve 683 683 0 0.00 0 10978 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 708 708 0 0.00 0 5066 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 412 412 0 0.00 0 740 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 384 384 0 0.00 0 743 13 0 0
t$perf-norm:sp_cancel_supplier_order 399 399 0 0.00 0 24877 1715 0 0
t$perf-norm:sp_cancel_write_off 992 992 0 0.00 0 1936 14 0 0
t$perf-norm:sp_client_order 19968 19968 0 0.00 0 10950 20 0 0
t$perf-norm:sp_customer_reserve 1010 1010 0 0.00 0 4016 9 0 0
t$perf-norm:sp_pay_from_customer 6827 6827 0 0.00 0 10634 9 0 0
t$perf-norm:sp_pay_to_supplier 5704 5704 0 0.00 0 3348 8 0 0
t$perf-norm:sp_reserve_write_off 9757 9757 0 0.00 0 8972 11 0 0
t$perf-norm:sp_supplier_invoice 5250 5250 0 0.00 0 3637 14 0 0
t$perf-norm:sp_supplier_order 5259 5259 0 0.00 0 4796 16 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 1538 20 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 80 3 0 0
t$perf-norm:srv_make_invnt_saldo 432 432 0 0.00 0 364 6 0 0
t$perf-norm:srv_make_money_saldo 733 733 0 0.00 0 1062 9 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 4 3 0 0
v_add_invoice_to_stock 9772 9460 312 3.19 0 9868 20 0 312
v_all_customers 36530 36530 0 0.00 0 15 1 0 0
v_all_suppliers 29186 29186 0 0.00 0 24 1 0 0
v_all_wares 39936 39936 0 0.00 0 26 1 0 0
v_cancel_adding_invoice 822 822 0 0.00 0 790 8 0 0
v_cancel_client_order 3322 3322 0 0.00 0 4995 13 0 0
v_cancel_customer_prepayment 1416 1416 0 0.00 0 2218 5 0 0
v_cancel_customer_reserve 1366 1366 0 0.00 0 1852 7 0 0
v_cancel_payment_to_supplier 824 824 0 0.00 0 3788 8 0 0
v_cancel_supplier_invoice 794 768 26 3.27 0 3575 18 0 26
v_cancel_supplier_order 798 798 0 0.00 0 438 4 0 0
v_cancel_write_off 1984 1984 0 0.00 0 3960 19 0 0
v_max_id_clo_ord 5258 5258 0 0.00 0 205 1 0 0
v_max_id_clo_res 1010 1010 0 0.00 0 6158 19 0 0
v_max_id_ord_sup 5235 5235 0 0.00 0 14064 7 0 0
v_max_non_paid_invoice 1604 1604 0 0.00 0 2382 5 0 0
v_max_non_paid_realizn 3575 3575 0 0.00 0 459 3 0 0
v_min_id_clo_ord 5258 5258 0 0.00 0 3747 5 0 0
v_min_id_clo_res 1010 1010 0 0.00 0 1808 7 0 0
v_min_id_ord_sup 5235 5235 0 0.00 0 13563 13 0 0
v_min_non_paid_invoice 5704 5704 0 0.00 0 4837 10 0 0
v_min_non_paid_realizn 6827 6827 0 0.00 0 4893 10 0 0
v_our_firm 9910 9910 0 0.00 0 13 1 0 0
v_reserve_write_off 19514 19514 0 0.00 0 4877 6 0 0
x_lock_depdocs_on_canc_invoice 11572 11572 0 0.00 0 23503 124 0 0
x_lock_depdocs_on_canc_sup_ord 399 399 0 0.00 0 6937 351 0 0
x_make_qstorno_1000_1200 5259 5259 0 0.00 2 30334 1398 0 0
x_make_qstorno_1200_2000 5250 5250 0 0.00 4 86445 3579 0 0
x_make_qstorno_2100_3300 439645 439645 0 0.00 0 43449 160 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 17-01-2021 10:28:01 to 17-01-2021 10:28: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
07:56:58 8 8192000000 8796638048 8807108608 3168016 0.038 1 1 2 0 3265632 61840 35776
07:57:59 35580 8192000000 9737751008 10035195904 811038736 9.008 100 85 170 0 838886928 7614416 20233776
07:59:41 17906 8192000000 9771284608 10081271808 845759872 9.358 100 82 164 0 877820992 12724528 19336592
08:01:04 13192 8192000000 9812894512 10130903040 878206720 9.682 100 84 166 0 920730944 23630368 18893856
08:02:22 12240 8192000000 9865815104 10196025344 917161712 10.068 100 84 168 1 973884800 38202816 18520272
08:03:39 8782 8192000000 9924245040 10272006144 961156816 10.500 100 78 156 0 1041771776 64087232 16527728
08:04:50 5971 8192000000 9971646496 10326872064 996888704 10.848 100 85 170 0 1086322976 72391744 17042528
08:05:58 2333 8192000000 10000139824 10360561664 1022888320 11.100 100 93 185 1 1109526864 67662800 18975744
08:07:01 1323 8192000000 10022258032 10392031232 1043567424 11.299 100 91 182 0 1135770672 73175200 19028048
08:08:04 9828 8192000000 10016825744 10396430336 1061712368 11.473 100 68 135 0 1130427888 54113376 14602144
08:09:14 9095 8192000000 9977554112 10371764224 1069594128 11.548 100 34 69 1 1101635872 25014464 7027280
08:10:24 8259 8192000000 10007565184 10409799680 1080065728 11.648 100 38 74 4 1119736976 32331632 7339616
08:11:37 7855 8192000000 9998629824 10399117312 1084705088 11.692 100 33 66 2 1118521280 26392336 7423856
08:12:49 63491 8192000000 10021076352 10432598016 1090437568 11.747 100 34 68 0 1119142464 21416480 7288416
08:14:53 10818 8192000000 10017632064 10419367936 1086168992 11.706 99 35 69 0 1125637040 32158544 7309504
08:16:05 5565 8192000000 10015101760 10416447488 1098947776 11.828 100 42 85 0 1127358992 18628512 9782704
08:17:13 60460 8192000000 9959931792 10360168448 1015138544 11.025 93 31 62 2 1049232592 27540992 6553056
08:19:16 6447 8192000000 9938178656 10317070336 982205824 10.706 94 45 92 3 1009993888 17525152 10262912
08:20:26 6968 8192000000 9917017264 10301775872 975380000 10.639 96 31 60 3 1003304752 20333600 7591152
08:21:37 4292 8192000000 9955125264 10329600000 1019546256 11.068 99 51 103 1 1052234480 21459600 11228624
08:22:42 17872 8192000000 9948626256 10344210432 1016351424 11.037 98 26 50 1 1058761744 36774928 5635392
08:24:00 5233 8192000000 9997701408 10380926976 1056346832 11.422 100 62 125 2 1100792064 31067136 13378096
08:25:06 7158 8192000000 9978122048 10361561088 1060408784 11.460 100 32 66 1 1079951360 12478480 7064096
08:26:14 125192 8192000000 10017310176 10425110528 1089178784 11.735 100 43 85 2 1123024432 25320656 8524992
08:29:23 35161 8192000000 10056695696 10465353728 1113520032 11.966 100 42 84 3 1190214160 67297936 9396192
08:31:01 4786 8192000000 10126550464 10527383552 1129291488 12.115 100 62 121 2 1230720288 89465984 11962816
08:32:13 16279 8192000000 10169586736 10571874304 1145830320 12.270 100 66 132 0 1274753392 114565872 14357200
08:33:32 12993 8192000000 10178035232 10589888512 1162030576 12.422 100 47 94 0 1285975744 113525088 10420080
08:34:49 28053 8192000000 10202586976 10624540672 1161698704 12.419 100 47 94 0 1297508080 125112432 10696944
08:36:33 5026 8192000000 10252976352 10675056640 1184747136 12.634 100 51 102 1 1358844000 162399680 11697184
08:37:41 4848 8192000000 10308169536 10713804800 1185556496 12.642 100 75 145 1 1409575568 208030720 15988352
08:38:52 2242 8192000000 10342477760 10750246912 1174169136 12.536 100 66 132 0 1440610016 251657568 14783312
08:39:58 4035 8192000000 10326771728 10747260928 1170469936 12.501 100 71 142 0 1444019168 257517264 16031968
08:41:12 1780 8192000000 10385595872 10779344896 1157878400 12.383 100 91 184 3 1484540032 305394064 21267568
08:42:23 1367 8192000000 10401689872 10799804416 1152753136 12.335 100 78 153 2 1501381040 330458784 18169120
08:43:27 2046 8192000000 10453918240 10849865728 1153572000 12.343 100 80 159 1 1546118256 374258256 18288000
08:44:38 2360 8192000000 10473170432 10900586496 1165055088 12.451 100 82 160 2 1570058496 385591456 19411952
08:45:47 7002 8192000000 10462662224 10871939072 1162713328 12.429 100 59 118 0 1560078656 382918784 14446544
08:47:13 4712 8192000000 10554331664 10967425024 1175138400 12.545 100 69 138 1 1652855360 460453072 17263888
08:48:27 6077 8192000000 10520214496 10948227072 1163028160 12.432 100 53 106 2 1629075408 451759776 14287472
08:49:34 17583 8192000000 10520938064 10982125568 1150843360 12.317 100 48 94 2 1632194864 468872656 12478848
08:50:54 6848 8192000000 10455446080 10920423424 1146938064 12.281 100 37 71 0 1556961360 400611600 9411696
08:52:18 11090 8192000000 10537331680 10993561600 1129828672 12.120 100 30 60 1 1631315296 492837808 8648816
08:54:12 14915 8192000000 10345924032 10828349440 1125748880 12.081 100 30 60 0 1474345696 340294960 8301856
08:55:30 37815 8192000000 10086629376 10525581312 1118407664 12.012 99 23 46 1 1158149776 34289472 5452640
08:57:14 124789 8192000000 10089910576 10544664576 1097188624 11.811 97 22 44 3 1135888192 34771744 3927824
09:00:25 79111 8192000000 10080646784 10527678464 1148030864 12.291 100 21 41 1 1190073200 38055216 3987120
09:02:46 52784 8192000000 10172144048 10608635904 1132306560 12.143 98 27 54 0 1248984816 110454832 6223424
09:04:40 53577 8192000000 10107411616 10585145344 1129588896 12.117 99 17 34 0 1214963680 81706304 3668480
09:07:08 43844 8192000000 10114108864 10617053184 1148567856 12.296 100 14 28 0 1225591712 74183456 2840400
09:08:52 53412 8192000000 10117441280 10604847104 1132480256 12.145 99 9 18 0 1201718336 67364576 1873504
09:10:46 54747 8192000000 10122029120 10635104256 1151210128 12.321 100 18 36 0 1252240432 97123472 3906832
09:12:48 60598 8192000000 10144736064 10595037184 1158229552 12.387 100 24 48 0 1229074592 66066496 4778544
09:14:50 77413 8192000000 10164976208 10618060800 1154215984 12.349 99 24 48 0 1246435104 87107920 5111200
09:17:18 69828 8192000000 10081726832 10548461568 1133085424 12.150 98 21 42 1 1200245680 62733280 4426976
09:19:29 82122 8192000000 10086512816 10515169280 1097639184 11.815 95 21 43 0 1159696976 57979360 4078432
09:21:54 83134 8192000000 10087113968 10547998720 1150312656 12.312 100 20 40 1 1195296224 41089408 3894160
09:24:46 36957 8192000000 10052015856 10520010752 1095543936 11.795 96 8 16 0 1140037808 42695552 1798320
09:26:23 47016 8192000000 10071703696 10556383232 1136922656 12.187 100 14 28 1 1209608176 69272352 3413168
09:28:17 58389 8192000000 10046718096 10510331904 1076825456 11.617 96 10 20 0 1128970448 50284048 1860944
09:30:49 47273 8192000000 10081227744 10530373632 1105503200 11.890 98 27 54 1 1176007728 64979168 5525360
09:32:43 50732 8192000000 10062853040 10494529536 1071903120 11.570 96 19 38 2 1131261040 55336048 4021872
09:34:34 50408 8192000000 10077863104 10565890048 1109364096 11.926 100 16 32 1 1195039520 82167360 3508064
09:36:47 13416 8192000000 10071089040 10488406016 1114110576 11.971 99 67 133 1 1150232208 21399424 14722208
09:38:07 57400 8192000000 10056538784 10507681792 1064201616 11.497 95 23 46 1 1105816448 37345504 4269328
09:40:08 108546 8192000000 10074737952 10537967616 1082796736 11.674 98 27 55 0 1141179904 53186480 5196688
09:43:24 78515 8192000000 10105557712 10589224960 1111639680 11.948 100 10 20 3 1201306048 87388688 2277680
09:45:59 64649 8192000000 10140518448 10577661952 1111252256 11.944 99 24 47 0 1225779008 109128368 5398384
09:48:04 69708 8192000000 10051112272 10532655104 1106151024 11.896 99 18 36 0 1166790960 57016880 3623056
09:50:24 10471 8192000000 10069396512 10497728512 1117693120 12.005 100 41 78 2 1171554528 45719840 8141568
09:51:35 44276 8192000000 10080584144 10548408320 1097382128 11.813 98 24 48 1 1168194960 65649424 5163408
09:53:28 33351 8192000000 10068434784 10493497344 1088759488 11.731 97 37 74 1 1146955072 49978096 8217488
09:55:08 109208 8192000000 10053821744 10529468416 1095764320 11.797 97 18 37 0 1149154496 49679584 3710592
09:57:58 96527 8192000000 10056832976 10527494144 1126508064 12.088 100 12 24 0 1166711472 37829936 2373472
10:00:45 85995 8192000000 10134787344 10590478336 1135321392 12.171 99 16 32 0 1223345312 84183776 3840144
10:03:23 54704 8192000000 10082687056 10566098944 1124365184 12.068 99 19 38 2 1189499872 60760496 4374192
10:05:18 53194 8192000000 10078170368 10533031936 1121625712 12.042 98 17 34 1 1158564336 33446144 3492480
10:07:15 75750 8192000000 10091236384 10606981120 1108306624 11.916 97 34 68 1 1217449392 102068624 7074144
10:09:32 63007 8192000000 10198275808 10678587392 1138877936 12.205 99 29 58 2 1260211504 115001904 6331664
10:11:56 104369 8192000000 10063492608 10517753856 1103993216 11.876 96 18 36 0 1135669328 28157200 3518912
10:14:44 121476 8192000000 10082451824 10519334912 1101275024 11.850 96 21 42 2 1151273584 45539552 4459008
10:17:53 59604 8192000000 10047172240 10510004224 1119641312 12.024 99 5 10 0 1142989264 22546912 801040
10:19:53 58558 8192000000 10055342624 10508025856 1117570528 12.004 98 14 28 2 1147840896 27568976 2701392
10:21:53 60622 8192000000 10089967712 10553217024 1120609280 12.033 99 28 56 0 1185712480 59337824 5765376
10:24:07 40570 8192000000 10114728096 10544656384 1114752944 11.977 98 30 60 1 1181093904 59872912 6468048
10:25:49 65157 8192000000 10086194576 10617008128 1099115408 11.829 98 11 22 1 1209256848 107963024 2178416

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 17-01-2021 10:28:07 to 17-01-2021 10:28:22.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 156 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 17-01-2021 10:28:23 to 17-01-2021 10:28: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          230911
MON$OLDEST_ACTIVE               234370
MON$OLDEST_SNAPSHOT             234370
MON$NEXT_TRANSACTION            234370
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                       2091776
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             799
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 ---
:192: 
:193: mscs-test-161.budzdorov.ru    Sun Jan 17 10:27:14 2021
:194:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:195: 
--- end of diff output ---

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

17.01.21 10:28: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-17 10:28:28 No severe PSQL-related problems occured
17.01.21 10:28:30. End of report.