Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.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 16804864 kB
MemAvailable 31236788 kB
Buffers 0 kB
Cached 14861312 kB
SwapCached 4868 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-30 10:26:56
DTS_END                         2021-01-30 10:26:56.8250
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/41508
TRN_ID                          222289
ATT_ID                          610
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: 8437.27 18979 1012472
customer order: creation 160.75 426 19290
customer order: refuse 13.66 357 1639
order to supplier: creation 41.93 1479 5032
order to supplier: removal 3.09 133128 371
invoice (draft): creation 43.03 3791 5163
invoice (draft): removal 47.58 908 5710
invoice accept: apply 38.83 19305 4660
invoice accept: cancel 43.50 1028 5220
customer reserve: creation 3513.83 192 421660
customer reserve: removal 4263.30 80 511596
realization accept: apply 76.72 184 9207
realization accept: cancel 71.69 130 8603
payment from customer: creation 54.94 102 6593
payment from customer: removal 5.52 104 662
payment to supplier: creation 45.72 128 5486
payment to supplier: removal 3.33 92 400
service: total inventory turnovers 3.00 4488 360
service: total monetary turnovers 5.87 637 704
service: aggregate perf. data 0.93 14009 111
service: refresh index statistics 0.04 199012 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 30-01-2021 10:27:34 to 30-01-2021 10:27:36.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 850
TEST_TIME 2 3497
TEST_TIME 3 4818
TEST_TIME 4 5228
TEST_TIME 5 5306
TEST_TIME 6 4825
TEST_TIME 7 4682
TEST_TIME 8 5000
TEST_TIME 9 5175
TEST_TIME 10 5202
TEST_TIME 11 5691
TEST_TIME 12 5809
TEST_TIME 13 5837
TEST_TIME 14 5807
TEST_TIME 15 5800
TEST_TIME 16 5798
TEST_TIME 17 6169
TEST_TIME 18 6418
TEST_TIME 19 6477
TEST_TIME 20 6820
TEST_TIME 21 6877
TEST_TIME 22 7147
TEST_TIME 23 7292
TEST_TIME 24 7523
TEST_TIME 25 7928
TEST_TIME 26 8080
TEST_TIME 27 8151
TEST_TIME 28 8647
TEST_TIME 29 8738
TEST_TIME 30 9309
TEST_TIME 31 10347
TEST_TIME 32 10286
TEST_TIME 33 10116
TEST_TIME 34 9909
TEST_TIME 35 9918
TEST_TIME 36 9738
TEST_TIME 37 9625
TEST_TIME 38 9479
TEST_TIME 39 9387
TEST_TIME 40 9227
TEST_TIME 41 9130
TEST_TIME 42 9061
TEST_TIME 43 8997
TEST_TIME 44 9060
TEST_TIME 45 8931
TEST_TIME 46 8876
TEST_TIME 47 8836
TEST_TIME 48 8781
TEST_TIME 49 8750
TEST_TIME 50 8603
TEST_TIME 51 8588
TEST_TIME 52 8474
TEST_TIME 53 8707
TEST_TIME 54 8684
TEST_TIME 55 8645
TEST_TIME 56 8664
TEST_TIME 57 8670
TEST_TIME 58 8615
TEST_TIME 59 8605
TEST_TIME 60 8715
TEST_TIME 61 8639
TEST_TIME 62 8768
TEST_TIME 63 8801
TEST_TIME 64 8751
TEST_TIME 65 8863
TEST_TIME 66 8857
TEST_TIME 67 8730
TEST_TIME 68 8652
TEST_TIME 69 8616
TEST_TIME 70 8526
TEST_TIME 71 8463
TEST_TIME 72 8439
TEST_TIME 73 8405
TEST_TIME 74 8401
TEST_TIME 75 8507
TEST_TIME 76 8481
TEST_TIME 77 8503
TEST_TIME 78 8465
TEST_TIME 79 8506
TEST_TIME 80 8473
TEST_TIME 81 8509
TEST_TIME 82 8528
TEST_TIME 83 8538
TEST_TIME 84 8588
TEST_TIME 85 8644
TEST_TIME 86 8675
TEST_TIME 87 8649
TEST_TIME 88 8556
TEST_TIME 89 8500
TEST_TIME 90 8463
TEST_TIME 91 8405
TEST_TIME 92 8365
TEST_TIME 93 8394
TEST_TIME 94 8409
TEST_TIME 95 8416
TEST_TIME 96 8424
TEST_TIME 97 8396
TEST_TIME 98 8409
TEST_TIME 99 8441
TEST_TIME 100 8528
TEST_TIME 101 8507
TEST_TIME 102 8517
TEST_TIME 103 8510
TEST_TIME 104 8554
TEST_TIME 105 8561
TEST_TIME 106 8634
TEST_TIME 107 8561
TEST_TIME 108 8500
TEST_TIME 109 8477
TEST_TIME 110 8465
TEST_TIME 111 8461
TEST_TIME 112 8419
TEST_TIME 113 8419
TEST_TIME 114 8412
TEST_TIME 115 8460
TEST_TIME 116 8442
TEST_TIME 117 8421
TEST_TIME 118 8432
TEST_TIME 119 8417
TEST_TIME 120 8437

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 30-01-2021 10:27:37 to 30-01-2021 10:27:41.

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 1403905 1403905 0 0.00 0 25601 7 0 0
doc_list_biud 1403889 1403889 0 0.00 0 40020 35 0 0
sp_add_invoice_to_stock 4764 4660 104 2.18 18 203699 19306 0 104
sp_cancel_adding_invoice 5220 5220 0 0.00 6 91207 1029 0 0
sp_cancel_client_order 1639 1639 0 0.00 5 7519 358 0 0
sp_cancel_customer_reserve 511596 511596 0 0.00 1 40023 80 0 0
sp_cancel_pay_from_customer 662 662 0 0.00 3 21100 104 0 0
sp_cancel_pay_to_supplier 400 400 0 0.00 3 3616 93 0 0
sp_cancel_supplier_invoice 5720 5710 10 0.17 3 15043 909 0 10
sp_cancel_supplier_order 371 371 0 0.00 8 1287458 133129 0 0
sp_cancel_write_off 8603 8603 0 0.00 1 8711 130 0 0
sp_client_order 19290 19290 0 0.00 4 52454 427 0 0
sp_customer_reserve 421660 421660 0 0.00 0 88471 192 0 0
sp_fill_shopping_cart 29484 29484 0 0.00 1 23105 47 0 0
sp_fill_shopping_cart_clo_res 421660 421660 0 0.00 0 26954 19 0 0
sp_get_clo_for_invoice 4660 4660 0 0.00 0 37620 451 0 0
sp_kill_cost_storno 14885 14885 0 0.00 0 7014 62 0 0
sp_kill_qstorno_ret_qs2qd 1037876 1037876 0 0.00 0 40017 42 0 0
sp_kill_qty_storno 546993 546993 0 0.00 0 40020 90 0 0
sp_lock_selected_doc 19280 19280 0 0.00 0 9296 5 0 0
sp_make_cost_storno 25946 25946 0 0.00 0 11196 54 0 0
sp_multiply_rows_for_pdistr 25930 25930 0 0.00 0 11004 31 0 0
sp_multiply_rows_for_qdistr 19290 19290 0 0.00 0 52395 227 0 0
sp_pay_from_customer 6593 6593 0 0.00 4 8064 103 0 0
sp_pay_to_supplier 5486 5486 0 0.00 5 11034 129 0 0
sp_payment_common 12076 12076 0 0.00 1 5093 13 0 0
sp_qd_handle_on_cancel_clo 1639 1639 0 0.00 0 7510 330 0 0
sp_qd_handle_on_invoice_upd_sts 9872 9872 0 0.00 0 30961 391 0 0
sp_qd_handle_on_reserve_upd_sts 9202 9202 0 0.00 0 9963 56 0 0
sp_reserve_write_off 9207 9207 0 0.00 6 12831 184 0 0
sp_supplier_invoice 5163 5163 0 0.00 8 71237 3791 0 0
sp_supplier_order 5032 5032 0 0.00 5 44400 1480 0 0
srv_aggregate_perf_data 111 111 0 0.00 2365 137235 14009 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 836 128755 50436 0 0
srv_find_qd_qs_mism 968872 968872 0 0.00 0 25601 6 0 0
srv_make_invnt_saldo 360 360 0 0.00 68 106455 4488 0 0
srv_make_money_saldo 704 704 0 0.00 1 15207 637 0 0
srv_recalc_idx_stat 5 5 0 0.00 122111 321975 199013 0 0
t$perf-abend:sp_add_invoice_to_stock 104 104 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4660 4660 0 0.00 0 17440 183 0 0
t$perf-norm:sp_cancel_adding_invoice 430 430 0 0.00 1 5467 112 0 0
t$perf-norm:sp_cancel_client_order 1639 1639 0 0.00 0 19604 30 0 0
t$perf-norm:sp_cancel_customer_reserve 632 632 0 0.00 0 2937 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 662 662 0 0.00 0 7437 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 400 400 0 0.00 0 1231 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 403 403 0 0.00 0 808 14 0 0
t$perf-norm:sp_cancel_supplier_order 371 371 0 0.00 0 21890 1599 0 0
t$perf-norm:sp_cancel_write_off 889 889 0 0.00 0 4047 15 0 0
t$perf-norm:sp_client_order 19290 19290 0 0.00 0 9310 22 0 0
t$perf-norm:sp_customer_reserve 938 938 0 0.00 0 643 4 0 0
t$perf-norm:sp_pay_from_customer 6593 6593 0 0.00 0 27270 15 0 0
t$perf-norm:sp_pay_to_supplier 5486 5486 0 0.00 0 6106 11 0 0
t$perf-norm:sp_reserve_write_off 9207 9207 0 0.00 0 13321 11 0 0
t$perf-norm:sp_supplier_invoice 5163 5163 0 0.00 0 7831 22 0 0
t$perf-norm:sp_supplier_order 5032 5032 0 0.00 0 10945 20 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 273 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 95 2 0 0
t$perf-norm:srv_make_invnt_saldo 360 360 0 0.00 0 941 8 0 0
t$perf-norm:srv_make_money_saldo 704 704 0 0.00 0 1494 9 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 21 8 0 0
v_add_invoice_to_stock 9510 9302 208 2.18 0 7158 20 0 208
v_all_customers 35130 35130 0 0.00 0 72 1 0 0
v_all_suppliers 28310 28310 0 0.00 0 71 1 0 0
v_all_wares 38580 38580 0 0.00 0 69 1 0 0
v_cancel_adding_invoice 860 860 0 0.00 0 2299 10 0 0
v_cancel_client_order 3278 3278 0 0.00 0 3072 5 0 0
v_cancel_customer_prepayment 1324 1324 0 0.00 0 443 4 0 0
v_cancel_customer_reserve 1264 1264 0 0.00 0 3484 11 0 0
v_cancel_payment_to_supplier 800 800 0 0.00 0 3476 12 0 0
v_cancel_supplier_invoice 826 806 20 2.42 0 2139 17 0 20
v_cancel_supplier_order 742 742 0 0.00 0 341 4 0 0
v_cancel_write_off 1778 1778 0 0.00 0 4939 19 0 0
v_max_id_clo_ord 5031 5031 0 0.00 0 895 1 0 0
v_max_id_clo_res 938 938 0 0.00 0 26925 49 0 0
v_max_id_ord_sup 5163 5163 0 0.00 0 1669 3 0 0
v_max_non_paid_invoice 1520 1520 0 0.00 0 670 2 0 0
v_max_non_paid_realizn 3521 3521 0 0.00 0 5089 4 0 0
v_min_id_clo_ord 5031 5031 0 0.00 0 3056 6 0 0
v_min_id_clo_res 938 938 0 0.00 0 8062 38 0 0
v_min_id_ord_sup 5163 5163 0 0.00 0 7762 10 0 0
v_min_non_paid_invoice 5483 5483 0 0.00 0 3598 6 0 0
v_min_non_paid_realizn 6593 6593 0 0.00 0 3646 8 0 0
v_our_firm 9594 9594 0 0.00 0 17 1 0 0
v_reserve_write_off 18404 18404 0 0.00 0 6708 6 0 0
x_lock_depdocs_on_canc_invoice 10527 10527 0 0.00 0 13143 129 0 0
x_lock_depdocs_on_canc_sup_ord 371 371 0 0.00 0 11096 417 0 0
x_make_qstorno_1000_1200 5032 5032 0 0.00 2 44390 1392 0 0
x_make_qstorno_1200_2000 5163 5163 0 0.00 3 70303 3613 0 0
x_make_qstorno_2100_3300 421660 421660 0 0.00 0 88470 171 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-01-2021 10:27:42 to 30-01-2021 10:27:46.

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:57:00 2 8192000000 8796640128 8807108608 3168304 0.038 1 1 2 0 3265920 61840 35776
07:58:02 31924 8192000000 9730485344 10027077632 805062304 8.948 100 92 183 2 834741568 7662720 22016544
07:59:40 23276 8192000000 9770733920 10076880896 845040384 9.350 100 91 182 0 879897248 12890608 21966256
08:01:16 14077 8192000000 9822346448 10144002048 889359824 9.793 100 79 156 1 932654608 25594256 17700528
08:02:34 7200 8192000000 9866866096 10202275840 925294368 10.148 100 79 158 0 979891856 38279360 16318128
08:03:44 9763 8192000000 9925688960 10270093312 962790944 10.516 100 84 166 1 1039624256 59215600 17617712
08:04:56 5966 8192000000 9987035840 10338324480 993297120 10.813 100 87 173 0 1095083568 83948976 17837472
08:06:04 2851 8192000000 9987756560 10346926080 1025082688 11.121 100 83 166 0 1102095056 60184016 16828352
08:07:09 2184 8192000000 10041504320 10408742912 1047455520 11.336 100 91 182 1 1149384032 83104128 18824384
08:08:13 1502 8192000000 10028787632 10397229056 1064658896 11.501 100 97 195 0 1143320672 58008400 20653376
08:09:16 17201 8192000000 10001685040 10374512640 1068247424 11.535 100 67 133 0 1110658992 28110896 14300672
08:10:33 6432 8192000000 9998916112 10389618688 1080002864 11.647 100 33 66 0 1119466528 32720224 6743440
08:11:42 5977 8192000000 9990575248 10383998976 1084959360 11.695 100 22 44 1 1113894832 24186640 4748832
08:12:48 37997 8192000000 9993534752 10400251904 1087381072 11.718 100 24 48 2 1113184240 20910672 4892496
08:14:27 6377 8192000000 10005693424 10413174784 1096680976 11.806 100 33 66 1 1133047936 29562064 6804896
08:15:35 6724 8192000000 10029142208 10434793472 1098570512 11.824 100 42 84 1 1142462624 35619280 8272832
08:16:42 59171 8192000000 10021287568 10436677632 1103262432 11.869 100 39 79 2 1142098880 30418528 8417920
08:18:44 5976 8192000000 9972865456 10367508480 1025542416 11.125 95 28 56 1 1055748160 24070640 6135104
08:19:51 8479 8192000000 9945964992 10342207488 1032763200 11.195 98 42 84 3 1055208912 12152912 10292800
08:20:59 3321 8192000000 9958844192 10341064704 1014599936 11.020 98 42 84 0 1061177600 37531360 9046304
08:22:03 42919 8192000000 9903942000 10298150912 968666592 10.574 95 29 58 1 996147232 21227968 6252672
08:23:47 6934 8192000000 9960510672 10344652800 1042471216 11.288 100 49 98 1 1065318656 12007568 10839872
08:24:57 8296 8192000000 9981513696 10372526080 1045422896 11.317 100 35 70 2 1071227040 17806608 7997536
08:26:05 8825 8192000000 9966183696 10368389120 1059428704 11.451 100 31 62 1 1085803392 18990320 7384368
08:27:14 49707 8192000000 10008015120 10393231360 1079721008 11.645 100 50 98 3 1106992640 16573776 10697856
08:29:09 3461 8192000000 10070916912 10464464896 1099216880 11.830 100 64 130 2 1169563712 57198800 13148032
08:30:16 4449 8192000000 10073956320 10467516416 1116755584 11.996 100 68 136 1 1182043584 52078400 13209600
08:31:23 43271 8192000000 10077350592 10485325824 1127353824 12.096 100 43 85 0 1178290400 41122208 9814368
08:33:33 15315 8192000000 10131877936 10533928960 1147510624 12.286 100 53 106 1 1236955840 78681328 10763888
08:34:51 5874 8192000000 10187294736 10597769216 1153959104 12.347 100 56 110 0 1291652224 125561152 12131968
08:36:00 17970 8192000000 10210238784 10616000512 1162747936 12.429 100 67 134 3 1325257280 148046208 14463136
08:37:24 6066 8192000000 10210260432 10612256768 1160075728 12.404 99 67 131 2 1298831088 124474720 14280640
08:38:36 4834 8192000000 10252167248 10655289344 1165321232 12.453 100 73 147 0 1356264992 174711904 16231856
08:39:45 10215 8192000000 10264233888 10664226816 1158866944 12.393 99 53 106 0 1353380064 182418432 12094688
08:40:59 1723 8192000000 10301583840 10703921152 1171761232 12.513 100 79 157 1 1405247984 215978000 17508752
08:42:07 2191 8192000000 10325416176 10730803200 1165966448 12.459 98 67 134 1 1418503264 236870896 15665920
08:43:14 3026 8192000000 10351846192 10758737920 1133539360 12.155 97 78 153 2 1438216384 285816912 18860112
08:44:24 5422 8192000000 10362672496 10768654336 1156263824 12.368 100 68 135 2 1460950176 288425456 16260896
08:45:36 835 8192000000 10364923728 10762899456 1138420544 12.201 100 82 161 1 1464142944 307164512 18557888
08:46:42 6671 8192000000 10347925888 10757136384 1151887536 12.327 100 62 122 0 1457501376 289474880 16138960
08:47:58 2678 8192000000 10403260640 10798268416 1141586672 12.230 99 81 161 0 1497249184 336958496 18704016
08:49:07 1462 8192000000 10413172432 10811244544 1158131200 12.386 100 78 154 2 1509710688 332633632 18945856
08:50:13 3131 8192000000 10466698480 10866982912 1167137408 12.470 100 75 149 1 1561537744 376633728 17766608
08:51:20 7712 8192000000 10390771552 10811154432 1143640096 12.250 99 40 80 2 1502541696 348676416 10225184
08:52:33 16551 8192000000 10368602160 10803994624 1138780112 12.204 99 44 86 4 1462395824 313100528 10515184
08:53:53 17527 8192000000 10410877856 10874167296 1149545792 12.305 100 32 64 0 1527924784 369484464 8894528
08:55:26 22340 8192000000 10367094496 10846420992 1126591888 12.089 100 21 42 1 1504765520 372420096 5753536
08:57:53 43237 8192000000 10067863248 10503626752 1105690656 11.892 98 9 18 1 1151250560 43840704 1719200
08:59:42 58900 8192000000 10056075088 10485522432 1102127344 11.858 98 19 38 3 1150444432 44696320 3620768
09:01:47 47760 8192000000 10038736576 10497339392 1113529056 11.966 99 11 22 0 1157158896 41494192 2135648
09:03:39 47054 8192000000 10053364688 10487492608 1130124960 12.123 100 16 32 0 1150817040 18088576 2603504
09:05:27 58090 8192000000 10082489792 10512183296 1111724480 11.949 98 20 40 0 1181018912 64869856 4424576
09:07:26 115940 8192000000 10068624240 10516410368 1112279696 11.954 99 25 50 0 1220662128 102953328 5429104
09:10:26 57326 8192000000 10057286464 10546143232 1127242336 12.095 100 16 32 0 1172159776 41807600 3109840
09:12:29 50773 8192000000 10076445072 10499563520 1105620832 11.891 98 40 80 0 1150409200 36064976 8723392
09:14:24 98367 8192000000 10115872656 10577575936 1129710512 12.119 99 24 48 3 1209747408 74673312 5363584
09:17:03 98952 8192000000 10145877136 10625261568 1147628784 12.287 100 24 47 1 1253339168 99453632 6256752
09:20:06 96715 8192000000 10056142480 10505818112 1118893520 12.017 98 10 20 2 1145459792 24082528 2483744
09:22:43 102358 8192000000 10057833840 10499633152 1113555840 11.966 97 14 28 2 1154172896 37591024 3026032
09:25:31 107495 8192000000 10075409200 10531577856 1130973744 12.131 98 15 30 0 1208894224 74450944 3469536
09:28:19 104406 8192000000 10076989040 10557255680 1122565824 12.051 98 6 12 0 1177297728 53624832 1107072
09:31:03 101930 8192000000 10035739664 10543476736 1055655600 11.415 92 22 44 1 1127555520 67656768 4243152
09:33:49 71032 8192000000 10067904960 10504843264 1115527856 11.985 99 20 40 0 1149431232 29629200 4274176
09:36:12 62034 8192000000 10097630640 10567286784 1124456704 12.069 99 6 12 0 1190751552 64796160 1498688
09:38:14 49877 8192000000 10120570992 10583003136 1136431120 12.182 99 18 35 1 1208414992 68074000 3909872
09:40:05 110104 8192000000 10088067136 10613579776 1115537040 11.985 97 16 32 0 1199814560 80581120 3696400
09:43:20 44680 8192000000 10036681952 10465226752 1073050384 11.581 95 10 20 0 1110202432 35221968 1930080
09:45:06 46476 8192000000 10076785888 10540212224 1113968768 11.970 99 24 48 1 1165039712 46528560 4542384
09:46:53 77583 8192000000 10107527104 10552229888 1101372320 11.851 98 32 64 0 1172632208 64775264 6484624
09:49:21 79169 8192000000 10084927872 10537197568 1046638640 11.328 93 25 50 0 1120999840 68960432 5400768
09:52:00 69381 8192000000 10071154096 10525716480 1114189936 11.972 99 17 34 1 1156188240 38498720 3499584
09:54:10 128755 8192000000 10073922240 10513436672 1091748736 11.759 97 12 24 0 1127970048 33501056 2720256
09:57:35 33411 8192000000 10067469776 10530168832 1107551296 11.909 100 7 14 0 1176489968 67423584 1515088
09:59:14 55327 8192000000 10085912608 10571902976 1113340672 11.964 100 8 16 0 1190760928 75701168 1719088
10:01:15 79967 8192000000 10099840256 10531938304 1052479296 11.384 93 16 32 1 1103398656 47713600 3205760
10:03:36 62730 8192000000 10157620240 10594258944 1137026272 12.188 100 23 46 0 1241508480 99484992 4997216
10:05:48 115235 8192000000 10078767120 10501111808 1105212464 11.887 97 16 32 0 1152899200 44327696 3359040
10:08:44 28343 8192000000 10090119264 10521485312 1121981680 12.046 99 29 58 0 1171623008 43348800 6292528
10:10:12 64658 8192000000 10086640128 10574852096 1120535840 12.032 99 25 48 0 1180801328 55336000 4929488
10:12:19 125874 8192000000 10070859936 10547347456 1123790640 12.063 99 7 14 0 1179420864 54383664 1246560
10:15:27 55763 8192000000 10084964736 10543980544 1135083216 12.169 100 8 16 1 1182033696 45453696 1496784
10:17:28 61056 8192000000 10079653376 10554114048 1126778048 12.091 99 12 23 1 1176446656 47388528 2280080
10:19:29 66509 8192000000 10105098288 10532704256 1143847104 12.252 100 25 50 0 1206001424 56608512 5545808
10:21:42 90758 8192000000 10063396624 10512863232 1104864960 11.884 97 14 28 1 1144097968 36517840 2715168
10:24:13 38647 8192000000 10104707472 10564489216 1132511232 12.145 100 32 64 0 1207175312 68322656 6341424
10:25:52 67761 8192000000 9672993984 10035224576 758185280 8.471 67 21 39 2 776684768 14482976 4016512

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 30-01-2021 10:27:47 to 30-01-2021 10:28:02.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 104 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 30-01-2021 10:28:03 to 30-01-2021 10:28:05.

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          223032
MON$OLDEST_ACTIVE               223033
MON$OLDEST_SNAPSHOT             223033
MON$NEXT_TRANSACTION            223033
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                       2078496
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             775
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2353 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2353 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2353 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.2353 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'

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