Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.02.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 16538640 kB
MemAvailable 31182420 kB
Buffers 0 kB
Cached 15167276 kB
SwapCached 4132 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-02-17 13:27:17
DTS_END                         2021-02-17 13:26:57.7770
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/39168
TRN_ID                          225720
ATT_ID                          259
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 8525.31 24322 1023037
customer order: creation 160.28 370 19233
customer order: refuse 13.76 365 1651
order to supplier: creation 42.80 1480 5136
order to supplier: removal 3.05 129942 366
invoice (draft): creation 43.68 3740 5242
invoice (draft): removal 46.72 1059 5606
invoice accept: apply 39.84 19342 4781
invoice accept: cancel 42.31 1013 5077
customer reserve: creation 3739.25 186 448710
customer reserve: removal 4117.25 76 494070
realization accept: apply 78.54 198 9425
realization accept: cancel 76.93 132 9232
payment from customer: creation 55.50 118 6660
payment from customer: removal 5.68 110 682
payment to supplier: creation 46.28 129 5554
payment to supplier: removal 3.49 81 419
service: total inventory turnovers 3.55 4063 426
service: total monetary turnovers 5.47 636 656
service: aggregate perf. data 0.89 15675 107
service: refresh index statistics 0.03 307731 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-02-2021 13:29:13 to 17-02-2021 13:29:15.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1485
TEST_TIME 2 2377
TEST_TIME 3 3223
TEST_TIME 4 4217
TEST_TIME 5 4811
TEST_TIME 6 4775
TEST_TIME 7 5142
TEST_TIME 8 5268
TEST_TIME 9 5888
TEST_TIME 10 5667
TEST_TIME 11 5483
TEST_TIME 12 5630
TEST_TIME 13 5562
TEST_TIME 14 5858
TEST_TIME 15 5751
TEST_TIME 16 5975
TEST_TIME 17 6272
TEST_TIME 18 6427
TEST_TIME 19 6582
TEST_TIME 20 6766
TEST_TIME 21 6870
TEST_TIME 22 7318
TEST_TIME 23 7632
TEST_TIME 24 7936
TEST_TIME 25 7851
TEST_TIME 26 8151
TEST_TIME 27 8450
TEST_TIME 28 8703
TEST_TIME 29 8727
TEST_TIME 30 9290
TEST_TIME 31 10401
TEST_TIME 32 10318
TEST_TIME 33 10030
TEST_TIME 34 9921
TEST_TIME 35 9745
TEST_TIME 36 9754
TEST_TIME 37 9584
TEST_TIME 38 9527
TEST_TIME 39 9407
TEST_TIME 40 9315
TEST_TIME 41 9174
TEST_TIME 42 9179
TEST_TIME 43 9315
TEST_TIME 44 9484
TEST_TIME 45 9447
TEST_TIME 46 9527
TEST_TIME 47 9472
TEST_TIME 48 9402
TEST_TIME 49 9485
TEST_TIME 50 9455
TEST_TIME 51 9448
TEST_TIME 52 9316
TEST_TIME 53 9224
TEST_TIME 54 9148
TEST_TIME 55 9102
TEST_TIME 56 9173
TEST_TIME 57 9096
TEST_TIME 58 9065
TEST_TIME 59 9069
TEST_TIME 60 8995
TEST_TIME 61 8930
TEST_TIME 62 8850
TEST_TIME 63 8768
TEST_TIME 64 8725
TEST_TIME 65 8747
TEST_TIME 66 8850
TEST_TIME 67 8827
TEST_TIME 68 8851
TEST_TIME 69 8922
TEST_TIME 70 8913
TEST_TIME 71 8950
TEST_TIME 72 9027
TEST_TIME 73 9018
TEST_TIME 74 9084
TEST_TIME 75 9015
TEST_TIME 76 9003
TEST_TIME 77 9124
TEST_TIME 78 9051
TEST_TIME 79 8992
TEST_TIME 80 8924
TEST_TIME 81 8863
TEST_TIME 82 8788
TEST_TIME 83 8739
TEST_TIME 84 8691
TEST_TIME 85 8719
TEST_TIME 86 8699
TEST_TIME 87 8685
TEST_TIME 88 8696
TEST_TIME 89 8699
TEST_TIME 90 8704
TEST_TIME 91 8750
TEST_TIME 92 8721
TEST_TIME 93 8784
TEST_TIME 94 8841
TEST_TIME 95 8814
TEST_TIME 96 8813
TEST_TIME 97 8794
TEST_TIME 98 8799
TEST_TIME 99 8768
TEST_TIME 100 8730
TEST_TIME 101 8695
TEST_TIME 102 8666
TEST_TIME 103 8622
TEST_TIME 104 8609
TEST_TIME 105 8641
TEST_TIME 106 8620
TEST_TIME 107 8610
TEST_TIME 108 8607
TEST_TIME 109 8620
TEST_TIME 110 8661
TEST_TIME 111 8670
TEST_TIME 112 8661
TEST_TIME 113 8734
TEST_TIME 114 8781
TEST_TIME 115 8772
TEST_TIME 116 8698
TEST_TIME 117 8668
TEST_TIME 118 8636
TEST_TIME 119 8583
TEST_TIME 120 8529

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 17-02-2021 13:29:16 to 17-02-2021 13:29:20.

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 1436011 1436011 0 0.00 0 24520 7 0 0
doc_list_biud 1435991 1435991 0 0.00 0 50685 33 0 0
sp_add_invoice_to_stock 4880 4781 99 2.02 35 234422 19343 0 99
sp_cancel_adding_invoice 5077 5077 0 0.00 5 75111 1013 0 0
sp_cancel_client_order 1651 1651 0 0.00 4 13450 366 0 0
sp_cancel_customer_reserve 494070 494070 0 0.00 1 50686 76 0 0
sp_cancel_pay_from_customer 682 682 0 0.00 3 11476 110 0 0
sp_cancel_pay_to_supplier 419 419 0 0.00 3 1620 81 0 0
sp_cancel_supplier_invoice 5615 5606 9 0.16 2 23831 1059 0 9
sp_cancel_supplier_order 366 366 0 0.00 9 1131112 129942 0 0
sp_cancel_write_off 9232 9232 0 0.00 1 17620 132 0 0
sp_client_order 19233 19233 0 0.00 4 22600 371 0 0
sp_customer_reserve 448710 448710 0 0.00 0 78428 187 0 0
sp_fill_shopping_cart 29603 29603 0 0.00 1 22573 49 0 0
sp_fill_shopping_cart_clo_res 448704 448704 0 0.00 0 25751 18 0 0
sp_get_clo_for_invoice 4775 4775 0 0.00 0 40096 420 0 0
sp_kill_cost_storno 15410 15410 0 0.00 0 9301 67 0 0
sp_kill_qstorno_ret_qs2qd 1003344 1003344 0 0.00 0 50682 41 0 0
sp_kill_qty_storno 530191 530191 0 0.00 0 50685 89 0 0
sp_lock_selected_doc 19679 19679 0 0.00 0 9032 6 0 0
sp_make_cost_storno 26414 26414 0 0.00 0 10337 59 0 0
sp_multiply_rows_for_pdistr 26397 26397 0 0.00 0 7666 35 0 0
sp_multiply_rows_for_qdistr 19233 19233 0 0.00 0 22540 194 0 0
sp_pay_from_customer 6660 6660 0 0.00 4 10690 119 0 0
sp_pay_to_supplier 5554 5554 0 0.00 5 15422 130 0 0
sp_payment_common 12208 12208 0 0.00 1 15408 27 0 0
sp_qd_handle_on_cancel_clo 1651 1651 0 0.00 0 13441 333 0 0
sp_qd_handle_on_invoice_upd_sts 9846 9846 0 0.00 0 28345 426 0 0
sp_qd_handle_on_reserve_upd_sts 9420 9420 0 0.00 0 14420 60 0 0
sp_reserve_write_off 9425 9425 0 0.00 7 17402 199 0 0
sp_supplier_invoice 5242 5242 0 0.00 6 84816 3741 0 0
sp_supplier_order 5136 5136 0 0.00 5 50562 1481 0 0
srv_aggregate_perf_data 107 107 0 0.00 2714 250430 15675 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1289 151098 45232 0 0
srv_find_qd_qs_mism 976443 976443 0 0.00 0 24518 6 0 0
srv_make_invnt_saldo 426 426 0 0.00 42 144624 4063 0 0
srv_make_money_saldo 656 656 0 0.00 1 23852 637 0 0
srv_recalc_idx_stat 4 4 0 0.00 207358 417548 307732 0 0
t$perf-abend:sp_add_invoice_to_stock 99 99 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4781 4781 0 0.00 0 23340 195 0 0
t$perf-norm:sp_cancel_adding_invoice 417 417 0 0.00 0 8962 155 0 0
t$perf-norm:sp_cancel_client_order 1651 1651 0 0.00 0 4686 18 0 0
t$perf-norm:sp_cancel_customer_reserve 683 683 0 0.00 0 2362 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 682 682 0 0.00 0 1423 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 419 419 0 0.00 0 5559 29 0 0
t$perf-norm:sp_cancel_supplier_invoice 365 365 0 0.00 0 294 8 0 0
t$perf-norm:sp_cancel_supplier_order 366 366 0 0.00 0 18231 1716 0 0
t$perf-norm:sp_cancel_write_off 907 907 0 0.00 0 2769 11 0 0
t$perf-norm:sp_client_order 19233 19233 0 0.00 0 12573 21 0 0
t$perf-norm:sp_customer_reserve 860 860 0 0.00 0 518 3 0 0
t$perf-norm:sp_pay_from_customer 6660 6660 0 0.00 0 12775 10 0 0
t$perf-norm:sp_pay_to_supplier 5554 5554 0 0.00 0 6786 11 0 0
t$perf-norm:sp_reserve_write_off 9425 9425 0 0.00 0 6862 13 0 0
t$perf-norm:sp_supplier_invoice 5244 5244 0 0.00 0 6133 20 0 0
t$perf-norm:sp_supplier_order 5136 5136 0 0.00 0 7026 19 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 323 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 5914 97 0 0
t$perf-norm:srv_make_invnt_saldo 426 426 0 0.00 0 2960 17 0 0
t$perf-norm:srv_make_money_saldo 656 656 0 0.00 0 3743 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 9736 9538 198 2.03 0 6929 18 0 198
v_all_customers 35020 35020 0 0.00 0 25 1 0 0
v_all_suppliers 28894 28894 0 0.00 0 20 1 0 0
v_all_wares 38462 38462 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 834 834 0 0.00 0 2792 9 0 0
v_cancel_client_order 3302 3302 0 0.00 0 2125 5 0 0
v_cancel_customer_prepayment 1364 1364 0 0.00 0 2586 5 0 0
v_cancel_customer_reserve 1366 1366 0 0.00 0 5232 8 0 0
v_cancel_payment_to_supplier 838 838 0 0.00 0 1138 6 0 0
v_cancel_supplier_invoice 748 730 18 2.40 0 347 11 0 18
v_cancel_supplier_order 732 732 0 0.00 0 2609 9 0 0
v_cancel_write_off 1814 1814 0 0.00 0 2297 16 0 0
v_max_id_clo_ord 5132 5132 0 0.00 0 155 1 0 0
v_max_id_clo_res 860 860 0 0.00 0 2556 20 0 0
v_max_id_ord_sup 5240 5240 0 0.00 0 5136 4 0 0
v_max_non_paid_invoice 1475 1475 0 0.00 0 35 2 0 0
v_max_non_paid_realizn 3601 3601 0 0.00 0 1872 3 0 0
v_min_id_clo_ord 5132 5132 0 0.00 0 4430 8 0 0
v_min_id_clo_res 860 860 0 0.00 0 8154 28 0 0
v_min_id_ord_sup 5240 5240 0 0.00 0 4236 8 0 0
v_min_non_paid_invoice 5550 5550 0 0.00 0 15405 23 0 0
v_min_non_paid_realizn 6658 6658 0 0.00 0 8812 19 0 0
v_our_firm 9556 9556 0 0.00 0 13 1 0 0
v_reserve_write_off 18840 18840 0 0.00 0 4587 6 0 0
x_lock_depdocs_on_canc_invoice 10318 10318 0 0.00 0 22805 140 0 0
x_lock_depdocs_on_canc_sup_ord 366 366 0 0.00 0 7920 379 0 0
x_make_qstorno_1000_1200 5136 5136 0 0.00 2 50551 1396 0 0
x_make_qstorno_1200_2000 5242 5242 0 0.00 4 84784 3552 0 0
x_make_qstorno_2100_3300 448710 448710 0 0.00 0 78426 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-02-2021 13:29:21 to 17-02-2021 13:29:25.

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:57:01 2 8192000000 8798580832 8809799680 4478880 0.054 2 1 2 0 4578272 63616 35776
10:58:02 43938 8192000000 9737154048 10038845440 812824016 9.026 100 91 183 0 842355856 7931152 21600688
10:59:53 25260 8192000000 9783409824 10095931392 855107776 9.451 100 90 180 0 890899776 14841984 20950016
11:01:27 16037 8192000000 9840049328 10160754688 892156560 9.821 100 88 176 2 946150192 34402704 19590928
11:02:47 15023 8192000000 9879068656 10216140800 927703328 10.172 100 76 152 1 992741920 48319968 16718624
11:04:04 9739 8192000000 9932636688 10276163584 963070272 10.519 100 83 166 1 1045416128 65163872 17181984
11:05:17 5716 8192000000 9973842000 10326757376 990324176 10.785 100 84 166 1 1088575088 81135984 17114928
11:06:25 2817 8192000000 10006719504 10362888192 1017820416 11.051 100 90 173 0 1120976400 86572624 16583360
11:07:29 2599 8192000000 10011770592 10377285632 1036349872 11.230 100 80 160 3 1125064208 72813120 15901216
11:08:34 1020 8192000000 10048541136 10414768128 1058199008 11.439 100 95 191 0 1153575456 75452016 19924432
11:09:36 48530 8192000000 10008555808 10387054592 1066348880 11.517 100 64 129 0 1132957520 51420496 15188144
11:11:25 9117 8192000000 9994721056 10399293440 1074308608 11.593 100 41 82 1 1113365664 30750208 8306848
11:12:34 7037 8192000000 9976630160 10374053888 1078139712 11.630 100 38 79 2 1094365904 7263312 8962880
11:13:46 8689 8192000000 10009146624 10420150272 1088109456 11.725 100 34 68 1 1122916560 26718336 8088768
11:14:57 12190 8192000000 10009690272 10417287168 1097416320 11.813 100 19 37 2 1134806976 33627296 3763360
11:16:10 55028 8192000000 10009609776 10412802048 1088652096 11.730 99 40 80 1 1111072400 13296752 9123552
11:18:06 5735 8192000000 10013463728 10413326336 1074311312 11.593 99 46 89 3 1120447120 37164288 8971520
11:19:13 10203 8192000000 9978043536 10374496256 1029383376 11.163 95 35 70 1 1063818416 26846144 7588896
11:20:27 14251 8192000000 9932280544 10328018944 984102096 10.724 94 23 46 1 1014225264 24821328 5301840
11:21:44 53979 8192000000 9888487632 10271821824 918480624 10.081 90 18 35 0 936428464 14547216 3400624
11:23:42 8402 8192000000 9937799728 10335649792 1030377952 11.172 100 36 71 1 1057696112 19057168 8260992
11:24:56 7405 8192000000 9959754544 10352345088 1051039280 11.371 100 33 66 1 1066835488 9207488 6588720
11:26:04 8681 8192000000 9973270000 10379112448 1064613376 11.501 100 33 66 0 1095812736 23519760 7679600
11:27:13 109630 8192000000 10018851840 10429288448 1082042880 11.667 100 30 58 2 1113624800 25383392 6198528
11:30:10 6717 8192000000 10068020736 10468626432 1114696624 11.977 100 55 107 0 1171566496 45797920 11071952
11:31:22 4384 8192000000 10099127440 10500804608 1127251248 12.095 100 62 123 1 1212745760 72260736 13233776
11:32:31 10116 8192000000 10123079280 10523430912 1137417168 12.191 100 61 122 1 1230349776 79995584 12937024
11:33:49 2038 8192000000 10186774512 10584682496 1146942864 12.281 100 65 130 0 1289740320 128633904 14163552
11:35:00 22328 8192000000 10182601648 10606784512 1157839136 12.383 100 57 112 1 1294148528 124347744 11961648
11:36:36 8553 8192000000 10163424208 10582650880 1163390400 12.435 100 48 95 1 1271059568 97518016 10151152
11:37:49 11383 8192000000 10210547680 10630733824 1164483296 12.445 99 53 105 0 1305600624 129437744 11679584
11:39:09 5755 8192000000 10276430544 10684936192 1180959904 12.599 100 77 152 1 1380614880 182290208 17364768
11:40:23 6324 8192000000 10291541072 10705797120 1180228368 12.592 99 67 130 1 1384566800 188300352 16038080
11:41:34 5044 8192000000 10320355536 10729021440 1173282816 12.528 99 68 134 1 1414297136 225313376 15700944
11:42:43 10144 8192000000 10339078560 10783322112 1186692800 12.653 100 61 116 0 1435653200 234361984 14598416
11:44:01 3345 8192000000 10392120432 10798678016 1194843408 12.728 100 83 166 0 1480195296 266487456 18864432
11:45:10 1776 8192000000 10386641552 10800824320 1176591312 12.558 100 84 167 2 1479808320 284232688 18984320
11:46:21 1288 8192000000 10427135648 10829033472 1171815248 12.514 100 93 185 1 1524295472 331788944 20691280
11:47:29 1957 8192000000 10425738624 10835890176 1182263104 12.611 100 85 168 0 1524413504 321631376 20519024
11:48:37 5435 8192000000 10388763424 10809606144 1167375984 12.472 100 61 121 2 1493149360 310342144 15431232
11:49:48 7510 8192000000 10393467776 10811437056 1132010880 12.140 99 62 123 1 1477911712 330378688 15522144
11:51:09 4275 8192000000 10394783040 10812301312 1137790080 12.195 99 58 116 0 1494179312 342035264 14353968
11:52:32 16527 8192000000 10384069920 10835603456 1124746368 12.072 99 28 56 0 1489905936 357175840 7983728
11:54:14 14158 8192000000 10385103680 10848354304 1131173216 12.132 99 29 58 0 1472052480 333817056 7062208
11:55:40 22852 8192000000 10382810624 10824966144 1122749440 12.053 100 28 56 1 1473973552 343570368 7653744
11:57:09 38674 8192000000 10084850768 10584432640 1114064608 11.971 99 16 32 1 1307694944 189601232 4029104
11:58:48 84058 8192000000 10049429808 10496311296 1132390112 12.144 100 31 62 2 1154654192 15700464 6563616
12:01:22 45771 8192000000 10053597408 10509082624 1099723360 11.835 98 16 32 0 1140691296 37596608 3371328
12:03:13 56154 8192000000 10022550336 10472259584 1084506640 11.690 98 23 43 2 1127320848 37060880 5753328
12:05:17 98578 8192000000 10106332800 10549452800 1083087776 11.677 96 30 60 3 1164914320 74926880 6899664
12:08:04 63347 8192000000 10157995216 10603995136 1117144608 12.000 100 20 38 2 1238605248 116924032 4536608
12:10:20 54362 8192000000 10082522336 10559848448 1094003216 11.781 98 7 14 0 1172538864 77161568 1374080
12:12:14 151098 8192000000 10113943136 10589777920 1118190544 12.010 99 16 32 0 1176870816 55739232 2941040
12:16:13 79483 8192000000 10079518352 10557726720 1100397664 11.841 97 12 24 2 1174183984 70917568 2868752
12:18:38 91541 8192000000 10087665664 10538479616 1133531344 12.155 100 28 55 0 1187993440 48287344 6174752
12:21:45 13034 8192000000 10089158416 10541797376 1138261984 12.199 100 7 14 0 1200185792 60463136 1460672
12:23:05 64254 8192000000 10046466288 10508550144 1115187952 11.982 98 19 38 0 1165212192 46158640 3865600
12:25:18 32667 8192000000 10084272576 10559496192 1139344320 12.209 100 21 42 0 1191886416 48583376 3958720
12:26:52 101388 8192000000 10106059888 10556407808 1149374720 12.304 100 19 38 1 1196001888 42426768 4200400
12:29:36 90083 8192000000 10130287568 10626830336 1140020064 12.216 99 14 28 0 1238714192 95546480 3147648
12:32:07 55830 8192000000 10081087008 10549862400 1145493344 12.267 100 19 38 3 1199223136 49535568 4194224
12:34:08 33065 8192000000 10090939776 10561658880 1125320240 12.077 99 19 38 0 1186767712 57213200 4234272
12:35:48 25220 8192000000 10090306192 10527735808 1118695888 12.015 98 49 97 1 1172163712 42578352 10889472
12:37:14 42174 8192000000 10119105648 10582679552 1133106704 12.151 99 22 44 2 1235347696 96533776 5707216
12:38:57 49248 8192000000 10079971072 10533851136 1073955616 11.590 94 25 50 0 1131761504 52587440 5218448
12:40:46 63835 8192000000 10073199616 10490888192 1059210976 11.449 94 27 55 0 1108697040 44230704 5255360
12:42:53 45156 8192000000 10086852656 10584104960 1113151056 11.962 99 27 54 0 1190934608 72322176 5461376
12:44:43 127591 8192000000 10015545232 10472349696 1044321568 11.306 94 16 32 0 1098482928 50927008 3234352
12:47:50 61776 8192000000 10029244336 10483580928 1104647264 11.882 100 9 19 0 1136976976 30582976 1746736
12:50:03 57443 8192000000 10060265360 10526375936 1088615136 11.729 98 11 22 0 1149332880 58286016 2431728
12:52:03 36273 8192000000 10047884064 10490982400 1075171904 11.601 97 24 48 1 1114835760 34676112 4987744
12:53:44 31871 8192000000 10041965600 10495938560 1074535120 11.595 97 25 50 2 1135851440 55573008 5743312
12:55:26 40842 8192000000 10107455472 10556784640 1111855360 11.950 99 37 73 1 1181698576 62448912 7394304
12:57:10 52177 8192000000 10058167328 10489884672 1097016912 11.809 98 29 59 0 1135706176 33000656 5688608
12:59:02 73857 8192000000 10100619920 10553167872 1118744544 12.015 99 24 48 0 1187336560 63835632 4756384
13:02:09 20179 8192000000 10082402656 10510442496 1102055328 11.857 98 53 106 1 1162321472 49257136 11009008
13:03:31 16057 8192000000 10128461008 10557677568 1134026096 12.159 100 41 82 0 1217080608 74962304 8092208
13:04:47 91512 8192000000 10066304064 10520539136 1132110832 12.141 100 16 32 0 1184718176 49307824 3299520
13:07:25 58510 8192000000 10061762672 10513149952 1128527872 12.107 99 9 18 0 1148786736 18144976 2113888
13:09:40 65171 8192000000 10092271936 10542374912 1121914448 12.045 98 25 49 2 1182424192 54687616 5822128
13:11:46 91169 8192000000 10098261488 10541465600 1145090208 12.263 100 20 40 0 1186721392 36887616 4743568
13:14:18 78379 8192000000 10126398144 10593607680 1138453328 12.201 99 26 51 3 1217004560 72582192 5969040
13:16:36 49847 8192000000 10122302576 10557444096 1131021968 12.131 98 28 56 0 1197118880 59693248 6403664
13:18:29 77333 8192000000 10101239968 10584719360 1135991888 12.178 99 19 38 0 1207176944 67508848 3676208
13:20:48 126047 8192000000 10088624928 10588680192 1122223536 12.048 98 7 14 0 1175705184 51924144 1557504
13:24:11 31763 8192000000 10039601024 10489798656 1105835408 11.893 97 13 26 0 1134174080 25769712 2568960
13:25:50 46361 8192000000 10073419248 10520662016 1117419312 12.003 99 4 8 0 1172389248 54135008 834928

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-02-2021 13:29:27 to 17-02-2021 13:29:42.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 99 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-02-2021 13:29:43 to 17-02-2021 13:29:45.

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          225739
MON$OLDEST_ACTIVE               227799
MON$OLDEST_SNAPSHOT             227799
MON$NEXT_TRANSACTION            227799
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                       2100736
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                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             776
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

Database statistics

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

Database validation

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

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

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

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

17.02.21 13:29:46. 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-02-17 13:29:48 No severe PSQL-related problems occured
17.02.21 13:29:50. End of report.