Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.09.2021 13:39
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 58e1ddde69c44dad93d327777e5becec
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 2021-05-09 08:45
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 4793.92
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 15970672 kB
MemAvailable 30944900 kB
Buffers 0 kB
Cached 15752696 kB
SwapCached 336 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.1
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-09-02 13:38:07
DTS_END                         2021-09-02 13:37:10.1210
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/35458
TRN_ID                          242030
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: 9394.54 24817 1127345
customer order: creation 171.43 420 20572
customer order: refuse 14.70 318 1764
order to supplier: creation 47.48 1403 5698
order to supplier: removal 3.50 111832 420
invoice (draft): creation 46.89 3111 5627
invoice (draft): removal 53.94 847 6473
invoice accept: apply 41.48 19120 4977
invoice accept: cancel 49.66 880 5959
customer reserve: creation 3831.33 190 459760
customer reserve: removal 4825.58 65 579069
realization accept: apply 84.53 186 10144
realization accept: cancel 92.37 116 11084
payment from customer: creation 60.90 117 7308
payment from customer: removal 5.97 94 716
payment to supplier: creation 50.12 128 6014
payment to supplier: removal 3.51 77 421
service: total inventory turnovers 3.94 3649 473
service: total monetary turnovers 6.39 621 767
service: aggregate perf. data 0.79 20301 95
service: refresh index statistics 0.03 332855 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 02-09-2021 13:39:47 to 02-09-2021 13:39:49.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6396
TEST_TIME 2 6395
TEST_TIME 3 6582
TEST_TIME 4 6720
TEST_TIME 5 6079
TEST_TIME 6 5973
TEST_TIME 7 6000
TEST_TIME 8 6703
TEST_TIME 9 6774
TEST_TIME 10 6820
TEST_TIME 11 7272
TEST_TIME 12 7491
TEST_TIME 13 7780
TEST_TIME 14 7818
TEST_TIME 15 7883
TEST_TIME 16 7878
TEST_TIME 17 8600
TEST_TIME 18 8703
TEST_TIME 19 9002
TEST_TIME 20 9080
TEST_TIME 21 8975
TEST_TIME 22 8955
TEST_TIME 23 9132
TEST_TIME 24 9343
TEST_TIME 25 9896
TEST_TIME 26 9847
TEST_TIME 27 11136
TEST_TIME 28 11067
TEST_TIME 29 11061
TEST_TIME 30 11147
TEST_TIME 31 10930
TEST_TIME 32 10643
TEST_TIME 33 10575
TEST_TIME 34 10569
TEST_TIME 35 10422
TEST_TIME 36 10346
TEST_TIME 37 10241
TEST_TIME 38 10141
TEST_TIME 39 10278
TEST_TIME 40 10228
TEST_TIME 41 10088
TEST_TIME 42 9969
TEST_TIME 43 9976
TEST_TIME 44 9913
TEST_TIME 45 10068
TEST_TIME 46 9994
TEST_TIME 47 10013
TEST_TIME 48 9997
TEST_TIME 49 10016
TEST_TIME 50 10128
TEST_TIME 51 10023
TEST_TIME 52 10072
TEST_TIME 53 10195
TEST_TIME 54 10312
TEST_TIME 55 10233
TEST_TIME 56 10231
TEST_TIME 57 10114
TEST_TIME 58 9956
TEST_TIME 59 9900
TEST_TIME 60 9846
TEST_TIME 61 9942
TEST_TIME 62 9871
TEST_TIME 63 9801
TEST_TIME 64 9794
TEST_TIME 65 9692
TEST_TIME 66 9595
TEST_TIME 67 9564
TEST_TIME 68 9577
TEST_TIME 69 9593
TEST_TIME 70 9625
TEST_TIME 71 9643
TEST_TIME 72 9594
TEST_TIME 73 9567
TEST_TIME 74 9721
TEST_TIME 75 9784
TEST_TIME 76 9786
TEST_TIME 77 9759
TEST_TIME 78 9768
TEST_TIME 79 9839
TEST_TIME 80 9952
TEST_TIME 81 9849
TEST_TIME 82 9758
TEST_TIME 83 9692
TEST_TIME 84 9658
TEST_TIME 85 9698
TEST_TIME 86 9625
TEST_TIME 87 9589
TEST_TIME 88 9535
TEST_TIME 89 9473
TEST_TIME 90 9444
TEST_TIME 91 9448
TEST_TIME 92 9426
TEST_TIME 93 9399
TEST_TIME 94 9443
TEST_TIME 95 9419
TEST_TIME 96 9378
TEST_TIME 97 9323
TEST_TIME 98 9529
TEST_TIME 99 9536
TEST_TIME 100 9542
TEST_TIME 101 9535
TEST_TIME 102 9607
TEST_TIME 103 9538
TEST_TIME 104 9470
TEST_TIME 105 9437
TEST_TIME 106 9402
TEST_TIME 107 9384
TEST_TIME 108 9422
TEST_TIME 109 9384
TEST_TIME 110 9373
TEST_TIME 111 9339
TEST_TIME 112 9353
TEST_TIME 113 9392
TEST_TIME 114 9412
TEST_TIME 115 9407
TEST_TIME 116 9462
TEST_TIME 117 9416
TEST_TIME 118 9511
TEST_TIME 119 9475
TEST_TIME 120 9396

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 02-09-2021 13:39:50 to 02-09-2021 13:39:54.

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 1554414 1554414 0 0.00 0 16644 7 0 0
doc_list_biud 1554407 1554407 0 0.00 0 64223 30 0 0
sp_add_invoice_to_stock 5116 4977 139 2.71 29 206389 19120 0 139
sp_cancel_adding_invoice 5959 5959 0 0.00 4 64545 880 0 0
sp_cancel_client_order 1764 1764 0 0.00 4 12491 319 0 0
sp_cancel_customer_reserve 579069 579069 0 0.00 1 64224 65 0 0
sp_cancel_pay_from_customer 716 716 0 0.00 3 11253 95 0 0
sp_cancel_pay_to_supplier 421 421 0 0.00 3 2610 77 0 0
sp_cancel_supplier_invoice 6482 6473 9 0.13 2 18097 848 0 9
sp_cancel_supplier_order 420 420 0 0.00 5 1041653 111833 0 0
sp_cancel_write_off 11084 11084 0 0.00 1 18930 116 0 0
sp_client_order 20572 20572 0 0.00 4 56384 421 0 0
sp_customer_reserve 459760 459760 0 0.00 0 52568 190 0 0
sp_fill_shopping_cart 31886 31886 0 0.00 1 16594 42 0 0
sp_fill_shopping_cart_clo_res 459750 459750 0 0.00 0 20483 14 0 0
sp_get_clo_for_invoice 4967 4967 0 0.00 0 20167 311 0 0
sp_kill_cost_storno 18180 18180 0 0.00 0 7516 52 0 0
sp_kill_qstorno_ret_qs2qd 1176115 1176115 0 0.00 0 64221 35 0 0
sp_kill_qty_storno 619876 619876 0 0.00 0 64223 75 0 0
sp_lock_selected_doc 21051 21051 0 0.00 0 7267 5 0 0
sp_make_cost_storno 28432 28432 0 0.00 0 16586 51 0 0
sp_multiply_rows_for_pdistr 28429 28429 0 0.00 0 16282 33 0 0
sp_multiply_rows_for_qdistr 20572 20572 0 0.00 0 15576 203 0 0
sp_pay_from_customer 7308 7308 0 0.00 4 16649 118 0 0
sp_pay_to_supplier 6014 6014 0 0.00 5 17370 128 0 0
sp_payment_common 13320 13320 0 0.00 1 9808 20 0 0
sp_qd_handle_on_cancel_clo 1764 1764 0 0.00 0 12253 283 0 0
sp_qd_handle_on_invoice_upd_sts 10926 10926 0 0.00 0 18917 322 0 0
sp_qd_handle_on_reserve_upd_sts 10140 10140 0 0.00 0 18042 60 0 0
sp_reserve_write_off 10144 10144 0 0.00 7 18174 187 0 0
sp_supplier_invoice 5627 5627 0 0.00 7 66363 3111 0 0
sp_supplier_order 5698 5698 0 0.00 5 56743 1403 0 0
srv_aggregate_perf_data 95 95 0 0.00 3168 461809 20302 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1430 134001 43819 0 0
srv_find_qd_qs_mism 1079921 1079921 0 0.00 0 11201 6 0 0
srv_make_invnt_saldo 473 473 0 0.00 57 100501 3650 0 0
srv_make_money_saldo 767 767 0 0.00 1 17582 622 0 0
srv_recalc_idx_stat 4 4 0 0.00 176798 575972 332856 0 0
t$perf-abend:sp_add_invoice_to_stock 139 139 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 4977 4977 0 0.00 0 9780 167 0 0
t$perf-norm:sp_cancel_adding_invoice 457 457 0 0.00 1 5630 101 0 0
t$perf-norm:sp_cancel_client_order 1764 1764 0 0.00 0 5176 17 0 0
t$perf-norm:sp_cancel_customer_reserve 717 717 0 0.00 0 898 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 716 716 0 0.00 0 712 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 421 421 0 0.00 0 752 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 394 394 0 0.00 0 1377 23 0 0
t$perf-norm:sp_cancel_supplier_order 420 420 0 0.00 0 42195 1674 0 0
t$perf-norm:sp_cancel_write_off 1056 1056 0 0.00 0 3620 9 0 0
t$perf-norm:sp_client_order 20572 20572 0 0.00 0 8370 16 0 0
t$perf-norm:sp_customer_reserve 1038 1038 0 0.00 0 1533 6 0 0
t$perf-norm:sp_pay_from_customer 7308 7308 0 0.00 0 4941 11 0 0
t$perf-norm:sp_pay_to_supplier 6014 6014 0 0.00 0 6390 15 0 0
t$perf-norm:sp_reserve_write_off 10144 10144 0 0.00 0 4384 8 0 0
t$perf-norm:sp_supplier_invoice 5627 5627 0 0.00 0 5795 17 0 0
t$perf-norm:sp_supplier_order 5698 5698 0 0.00 0 5888 17 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 273 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 127 4 0 0
t$perf-norm:srv_make_invnt_saldo 473 473 0 0.00 0 2587 18 0 0
t$perf-norm:srv_make_money_saldo 767 767 0 0.00 0 3137 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 72 20 0 0
v_add_invoice_to_stock 10206 9928 278 2.72 0 8517 19 0 278
v_all_customers 37172 37172 0 0.00 0 61 1 0 0
v_all_suppliers 31494 31494 0 0.00 0 31 1 0 0
v_all_wares 41134 41134 0 0.00 0 23 1 0 0
v_cancel_adding_invoice 914 914 0 0.00 0 1261 9 0 0
v_cancel_client_order 3528 3528 0 0.00 0 3259 7 0 0
v_cancel_customer_prepayment 1432 1432 0 0.00 0 11058 18 0 0
v_cancel_customer_reserve 1434 1434 0 0.00 0 2421 11 0 0
v_cancel_payment_to_supplier 842 842 0 0.00 0 2401 10 0 0
v_cancel_supplier_invoice 806 788 18 2.23 0 1605 18 0 18
v_cancel_supplier_order 840 840 0 0.00 0 5890 15 0 0
v_cancel_write_off 2112 2112 0 0.00 0 2578 14 0 0
v_max_id_clo_ord 5695 5695 0 0.00 0 306 1 0 0
v_max_id_clo_res 1038 1038 0 0.00 0 3263 8 0 0
v_max_id_ord_sup 5623 5623 0 0.00 0 10081 6 0 0
v_max_non_paid_invoice 1585 1585 0 0.00 0 107 2 0 0
v_max_non_paid_realizn 3930 3930 0 0.00 0 4619 4 0 0
v_min_id_clo_ord 5695 5695 0 0.00 0 2513 6 0 0
v_min_id_clo_res 1038 1038 0 0.00 0 2559 6 0 0
v_min_id_ord_sup 5623 5623 0 0.00 0 4500 4 0 0
v_min_non_paid_invoice 6014 6014 0 0.00 0 9801 12 0 0
v_min_non_paid_realizn 7306 7306 0 0.00 0 6242 12 0 0
v_our_firm 10712 10712 0 0.00 0 21 1 0 0
v_reserve_write_off 20280 20280 0 0.00 0 11052 7 0 0
x_lock_depdocs_on_canc_invoice 12038 12038 0 0.00 0 16903 118 0 0
x_lock_depdocs_on_canc_sup_ord 420 420 0 0.00 0 5224 367 0 0
x_make_qstorno_1000_1200 5698 5698 0 0.00 2 56735 1320 0 0
x_make_qstorno_1200_2000 5627 5627 0 0.00 3 63130 2960 0 0
x_make_qstorno_2100_3300 459760 459760 0 0.00 0 52567 174 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-09-2021 13:39:55 to 02-09-2021 13:39:59.

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
11:07:13 27 8192000000 8813622336 8830775296 15220256 0.185 6 3 3 0 15395936 114304 61376
11:08:14 40689 8192000000 9722360352 10021015552 788913024 8.784 100 81 162 0 815981424 7385648 19682752
11:09:59 26030 8192000000 9765017904 10071396352 837790032 9.278 100 89 178 1 872258432 13117360 21351040
11:11:28 11988 8192000000 9804324688 10121732096 867022848 9.570 100 86 172 2 915197376 27856768 20317760
11:12:47 18942 8192000000 9875434624 10212032512 913945120 10.036 100 78 156 1 980511744 49379280 17187344
11:14:11 6168 8192000000 9929985072 10277425152 962882240 10.517 100 81 162 2 1042058816 62145904 17030672
11:15:20 6736 8192000000 9973494208 10330066944 1011455200 10.989 100 85 168 1 1083642656 55474416 16713040
11:16:29 2972 8192000000 10007057296 10369073152 1032208016 11.190 100 93 186 1 1121702688 70564016 18930656
11:17:33 1833 8192000000 10021311264 10390638592 1051823008 11.378 100 90 178 3 1135384368 65008240 18553120
11:18:36 33479 8192000000 10013522176 10408701952 1069899184 11.551 100 45 91 3 1143808896 64803632 9106080
11:20:10 5259 8192000000 10016974176 10417111040 1079024128 11.638 100 37 75 1 1124723424 38197472 7501824
11:21:17 6308 8192000000 10011939760 10418253824 1086864384 11.713 100 36 72 0 1128232928 33769632 7598912
11:22:25 26885 8192000000 10014876704 10409865216 1092104224 11.763 100 64 125 1 1118542368 11756032 14682112
11:23:52 4589 8192000000 10016105264 10418896896 1098858976 11.827 100 34 67 1 1135402224 28918976 7624272
11:24:57 7481 8192000000 10018672288 10424926208 1102995952 11.866 100 46 86 4 1131638368 19382432 9259984
11:26:08 5853 8192000000 10011298288 10420715520 1104753392 11.883 100 31 63 1 1135802112 23495472 7553248
11:27:16 17561 8192000000 10008997104 10394144768 1059707648 11.454 97 63 127 1 1085965728 12656320 13601760
11:28:36 3312 8192000000 9989715376 10374905856 1049052992 11.352 99 59 118 3 1092960480 31444528 12462960
11:29:42 4088 8192000000 9940902864 10325864448 1013498256 11.009 97 39 75 6 1031610176 9874944 8236976
11:30:47 4864 8192000000 9931465072 10315034624 1003472960 10.912 98 38 72 1 1028473920 16848656 8152304
11:31:55 33272 8192000000 9919246992 10317447168 984369920 10.727 96 13 26 0 1013711696 26931008 2410768
11:33:29 7392 8192000000 9971129584 10354962432 1039717008 11.262 100 56 110 0 1069452496 17573200 12162288
11:34:41 11876 8192000000 9986193568 10385387520 1059556480 11.452 100 38 77 2 1086612032 18169440 8886112
11:35:58 72717 8192000000 9980317872 10380533760 1066667296 11.520 100 32 64 2 1086229152 12507536 7054320
11:38:18 3851 8192000000 10040545168 10431586304 1085339216 11.698 100 59 115 1 1141807728 45264752 11203760
11:39:26 4220 8192000000 10059034064 10455830528 1093425872 11.775 100 65 130 1 1166249408 58913776 13909760
11:40:35 4718 8192000000 10084663296 10485870592 1110238320 11.935 100 58 116 1 1192634448 70285088 12111040
11:41:42 16223 8192000000 10112984656 10518097920 1122473184 12.050 100 58 114 2 1220741376 85454080 12814112
11:43:13 18205 8192000000 10137608752 10545324032 1129087376 12.113 100 44 88 2 1237767312 99337920 9342016
11:44:35 7545 8192000000 10141544496 10562527232 1119711744 12.024 99 51 100 1 1245371376 114175120 11484512
11:45:56 3832 8192000000 10142655104 10552963072 1140527680 12.220 100 68 136 0 1251626928 96344208 14755040
11:47:05 2167 8192000000 10207208016 10609537024 1149867104 12.308 100 82 163 1 1307603600 141004512 16731984
11:48:14 5079 8192000000 10250397552 10665906176 1148729760 12.298 100 70 141 2 1352840464 189024736 15085968
11:49:22 3415 8192000000 10255386800 10659385344 1147005888 12.281 100 80 160 0 1362888432 198407936 17474608
11:50:32 1950 8192000000 10278274720 10677755904 1154149088 12.348 100 91 180 0 1373924528 199915984 19859456
11:51:39 3187 8192000000 10269449456 10681618432 1146700192 12.279 100 75 149 1 1373296944 209398768 17197984
11:52:49 3753 8192000000 10302119712 10703425536 1148743072 12.298 100 81 162 2 1406032176 239029680 18259424
11:53:57 2988 8192000000 10326332096 10754650112 1154243168 12.349 100 75 146 2 1430652288 258620320 17788800
11:55:06 3094 8192000000 10351960832 10751082496 1170894480 12.505 100 93 186 0 1446089744 253639744 21555520
11:56:14 1428 8192000000 10340549568 10763784192 1165283696 12.453 100 85 168 0 1432739648 247931168 19524784
11:57:19 8555 8192000000 10359965712 10786144256 1171163744 12.508 100 45 89 0 1459006528 276558816 11283968
11:58:36 8079 8192000000 10412707648 10841133056 1178200144 12.573 100 42 84 1 1516788032 327316704 11271184
11:59:49 12313 8192000000 10423990480 10860060672 1158144320 12.386 100 37 73 1 1519568896 351011232 10413344
12:01:10 12599 8192000000 10385262784 10852540416 1148443536 12.295 99 32 64 0 1479552240 322370800 8737904
12:02:27 20844 8192000000 10311932752 10794446848 1140229856 12.218 100 18 36 2 1423931760 278847088 4854816
12:04:29 37742 8192000000 10091134352 10564325376 1128905184 12.111 99 16 32 0 1241989664 109749296 3335184
12:06:14 74028 8192000000 10062739360 10505936896 1103576688 11.872 97 36 72 1 1152413776 41358304 7478784
12:08:28 74701 8192000000 10051041216 10492788736 1114256384 11.973 99 25 48 0 1143222096 24115280 4850432
12:10:46 70944 8192000000 10067938768 10502610944 1109128992 11.924 98 34 68 0 1176211728 59104832 7977904
12:13:00 38965 8192000000 10112257392 10561843200 1145328624 12.266 100 21 42 0 1210558576 60418160 4811792
12:14:40 42390 8192000000 10135804416 10586669056 1143733344 12.251 100 20 40 0 1241143408 92953488 4456576
12:16:31 23612 8192000000 10149088064 10603634688 1143637920 12.250 99 34 66 0 1241698416 91101136 6959360
12:17:56 32704 8192000000 10099250896 10550050816 1151576480 12.324 100 17 35 2 1203151136 48236224 3338432
12:19:33 41054 8192000000 10134889904 10618040320 1151308784 12.322 100 17 34 1 1256676384 101444592 3923008
12:22:14 72585 8192000000 10098544080 10562281472 1156204592 12.368 100 15 29 0 1219990512 60782848 3003072
12:24:27 61870 8192000000 10107337952 10551209984 1125745424 12.081 97 26 51 2 1180068112 48715856 5606832
12:26:34 44983 8192000000 10092627616 10563342336 1154872224 12.355 100 30 60 3 1221577424 60306576 6398624
12:28:21 49564 8192000000 10126944864 10590334976 1113869728 11.969 96 33 66 2 1197874928 77167232 6837968
12:30:25 98264 8192000000 10067926160 10554236928 1089127376 11.734 96 14 28 1 1170048336 78051264 2869696
12:33:04 52155 8192000000 10074179888 10513895424 1080569392 11.653 94 19 38 0 1111250816 26966416 3715008
12:34:59 76896 8192000000 10126360752 10561007616 1135661152 12.175 100 54 108 0 1212306848 65553312 11092384
12:37:17 39058 8192000000 10118502736 10586198016 1131906416 12.139 100 23 46 0 1227669312 91480064 4282832
12:38:56 47017 8192000000 10069670096 10530447360 1102155200 11.858 97 25 46 3 1145146608 39154096 3837312
12:40:44 49757 8192000000 10083895248 10528448512 1136258960 12.180 100 14 28 0 1188888144 49766576 2862608
12:42:39 63028 8192000000 10063651088 10507911168 1106925040 11.903 97 14 28 0 1147678624 37632192 3121392
12:44:45 65167 8192000000 10054123072 10560905216 1089755248 11.740 97 8 16 1 1155995936 64353328 1887360
12:46:51 59431 8192000000 10079160128 10547245056 1083895184 11.685 97 17 34 2 1146527072 59186784 3445104
12:48:53 84809 8192000000 10178093872 10624434176 1127548672 12.098 99 20 40 0 1219398048 87484656 4364720
12:51:29 96998 8192000000 10086599216 10599702528 1122033936 12.046 99 16 31 2 1205511216 80179600 3297680
12:54:45 25300 8192000000 10121549984 10548801536 1123333968 12.058 99 56 111 1 1196627008 62104000 11189040
12:56:12 124080 8192000000 10076163712 10588200960 1101130352 11.848 97 19 38 0 1166002912 60909856 3962704
12:59:16 73024 8192000000 10076330576 10513104896 1121462752 12.041 98 24 48 0 1149275280 22959168 4853360
13:01:29 61275 8192000000 10077627696 10574864384 1110947952 11.941 98 16 32 1 1198211984 83859504 3404528
13:03:41 109840 8192000000 10052292416 10496884736 1082542496 11.672 96 16 31 1 1124499424 38287344 3669584
13:06:31 37713 8192000000 10067969936 10514685952 1117455120 12.003 99 15 30 0 1174668224 53899856 3313248
13:08:17 51249 8192000000 10061105632 10508087296 1126029072 12.084 99 15 29 0 1164195888 34802704 3364112
13:10:10 45313 8192000000 10109924032 10568015872 1146228800 12.274 100 16 32 0 1217006160 67325456 3451904
13:11:56 49067 8192000000 10096797312 10534653952 1131715040 12.138 99 21 43 2 1176129264 39388224 5026000
13:13:46 39760 8192000000 10203452304 10651652096 1145044672 12.263 99 29 58 0 1282265584 130724272 6496640
13:15:26 67700 8192000000 10069869696 10537914368 1136508448 12.183 99 12 24 1 1233660752 94313584 2838720
13:17:36 53245 8192000000 10091677312 10527244288 1081922432 11.666 95 32 64 0 1138121712 50007504 6191776
13:19:31 134000 8192000000 10052279920 10489122816 1100458592 11.842 97 27 54 1 1147506640 41637280 5410768
13:22:46 35445 8192000000 10106079792 10578108416 1130143088 12.123 99 19 37 1 1212377728 78418800 3815840
13:24:27 40007 8192000000 10116578720 10628452352 1119276080 12.020 98 7 14 0 1189786528 69009376 1501072
13:26:07 54553 8192000000 10080290656 10519969792 1113586336 11.966 99 15 30 0 1159909936 43168592 3155008
13:28:14 77853 8192000000 10139563088 10600402944 1125603328 12.080 99 23 46 0 1226239344 95270848 5365168
13:30:33 78400 8192000000 10090513696 10583904256 1119526880 12.023 100 13 26 1 1154938288 33031456 2379952
13:33:05 62009 8192000000 10077971648 10593259520 1114667488 11.977 98 10 20 0 1182331632 65783968 1880176
13:35:29 53617 8192000000 10082423104 10601689088 1137903904 12.196 100 12 24 0 1227961760 87693888 2363968

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 02-09-2021 13:40:01 to 02-09-2021 13:40:16.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 139 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 02-09-2021 13:40:17 to 02-09-2021 13:40:19.

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          241754
MON$OLDEST_ACTIVE               245321
MON$OLDEST_SNAPSHOT             245321
MON$NEXT_TRANSACTION            245321
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                       2061216
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             811
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2579 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2579 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2579 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2579 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.0

Database statistics

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

Database validation

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

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

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

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

02.09.21 13:40:20. 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-09-02 13:40:22 No severe PSQL-related problems occured
02.09.21 13:40:24. End of report.