Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.09.2021 16:38
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 16101816 kB
MemAvailable 30849236 kB
Buffers 0 kB
Cached 15547972 kB
SwapCached 340 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 3.0.8
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-06 16:37:51
DTS_END                         2021-09-06 16:37:51.1120
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/40214
TRN_ID                          236781
ATT_ID                          643
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: 9447.39 25892 1133687
customer order: creation 162.55 366 19506
customer order: refuse 13.74 349 1649
order to supplier: creation 43.61 1327 5233
order to supplier: removal 3.34 119909 401
invoice (draft): creation 43.46 3494 5215
invoice (draft): removal 55.28 857 6633
invoice accept: apply 40.13 19041 4816
invoice accept: cancel 50.08 803 6010
customer reserve: creation 3827.11 183 459253
customer reserve: removal 4912.18 63 589462
realization accept: apply 80.47 194 9657
realization accept: cancel 89.99 112 10799
payment from customer: creation 57.14 141 6857
payment from customer: removal 5.87 70 704
payment to supplier: creation 47.60 185 5712
payment to supplier: removal 3.07 78 368
service: total inventory turnovers 4.75 1237 570
service: total monetary turnovers 6.16 34 739
service: aggregate perf. data 0.83 22470 99
service: refresh index statistics 0.03 346930 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-09-2021 16:38:43 to 06-09-2021 16:38:45.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2406
TEST_TIME 2 1880
TEST_TIME 3 2697
TEST_TIME 4 3195
TEST_TIME 5 3492
TEST_TIME 6 3781
TEST_TIME 7 4537
TEST_TIME 8 4485
TEST_TIME 9 4704
TEST_TIME 10 4787
TEST_TIME 11 4715
TEST_TIME 12 4723
TEST_TIME 13 4900
TEST_TIME 14 5051
TEST_TIME 15 5138
TEST_TIME 16 5624
TEST_TIME 17 6083
TEST_TIME 18 6192
TEST_TIME 19 6206
TEST_TIME 20 6990
TEST_TIME 21 7983
TEST_TIME 22 8056
TEST_TIME 23 8530
TEST_TIME 24 8692
TEST_TIME 25 8561
TEST_TIME 26 8636
TEST_TIME 27 9022
TEST_TIME 28 9220
TEST_TIME 29 10752
TEST_TIME 30 10958
TEST_TIME 31 10932
TEST_TIME 32 10858
TEST_TIME 33 10616
TEST_TIME 34 10454
TEST_TIME 35 10567
TEST_TIME 36 10397
TEST_TIME 37 10317
TEST_TIME 38 10212
TEST_TIME 39 10223
TEST_TIME 40 10151
TEST_TIME 41 9981
TEST_TIME 42 9873
TEST_TIME 43 9929
TEST_TIME 44 9805
TEST_TIME 45 9671
TEST_TIME 46 9502
TEST_TIME 47 9668
TEST_TIME 48 9664
TEST_TIME 49 9528
TEST_TIME 50 9434
TEST_TIME 51 9483
TEST_TIME 52 9591
TEST_TIME 53 9486
TEST_TIME 54 9550
TEST_TIME 55 9474
TEST_TIME 56 9537
TEST_TIME 57 9489
TEST_TIME 58 9472
TEST_TIME 59 9552
TEST_TIME 60 9544
TEST_TIME 61 9564
TEST_TIME 62 9514
TEST_TIME 63 9549
TEST_TIME 64 9701
TEST_TIME 65 9793
TEST_TIME 66 9823
TEST_TIME 67 9870
TEST_TIME 68 9848
TEST_TIME 69 9885
TEST_TIME 70 9782
TEST_TIME 71 9683
TEST_TIME 72 9706
TEST_TIME 73 9674
TEST_TIME 74 9657
TEST_TIME 75 9612
TEST_TIME 76 9640
TEST_TIME 77 9600
TEST_TIME 78 9550
TEST_TIME 79 9575
TEST_TIME 80 9513
TEST_TIME 81 9487
TEST_TIME 82 9471
TEST_TIME 83 9449
TEST_TIME 84 9484
TEST_TIME 85 9559
TEST_TIME 86 9526
TEST_TIME 87 9524
TEST_TIME 88 9519
TEST_TIME 89 9530
TEST_TIME 90 9540
TEST_TIME 91 9585
TEST_TIME 92 9643
TEST_TIME 93 9717
TEST_TIME 94 9737
TEST_TIME 95 9710
TEST_TIME 96 9649
TEST_TIME 97 9625
TEST_TIME 98 9627
TEST_TIME 99 9609
TEST_TIME 100 9579
TEST_TIME 101 9557
TEST_TIME 102 9499
TEST_TIME 103 9484
TEST_TIME 104 9460
TEST_TIME 105 9420
TEST_TIME 106 9440
TEST_TIME 107 9412
TEST_TIME 108 9376
TEST_TIME 109 9383
TEST_TIME 110 9366
TEST_TIME 111 9348
TEST_TIME 112 9430
TEST_TIME 113 9399
TEST_TIME 114 9406
TEST_TIME 115 9380
TEST_TIME 116 9413
TEST_TIME 117 9447
TEST_TIME 118 9430
TEST_TIME 119 9423
TEST_TIME 120 9449

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 06-09-2021 16:38:47 to 06-09-2021 16:38:50.

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 1557671 1557671 0 0.00 0 24631 7 0 0
doc_list_biud 1557670 1557670 0 0.00 0 67293 30 0 0
sp_add_invoice_to_stock 4941 4816 125 2.52 41 180684 19042 0 125
sp_cancel_adding_invoice 6010 6010 0 0.00 5 83310 804 0 0
sp_cancel_client_order 1649 1649 0 0.00 4 14703 349 0 0
sp_cancel_customer_reserve 589462 589462 0 0.00 1 67298 63 0 0
sp_cancel_pay_from_customer 704 704 0 0.00 3 1898 70 0 0
sp_cancel_pay_to_supplier 368 368 0 0.00 3 3241 79 0 0
sp_cancel_supplier_invoice 6646 6633 13 0.19 2 31592 858 0 13
sp_cancel_supplier_order 401 401 0 0.00 5 1174084 119909 0 0
sp_cancel_write_off 10799 10799 0 0.00 1 8571 112 0 0
sp_client_order 19506 19506 0 0.00 4 29859 367 0 0
sp_customer_reserve 459253 459253 0 0.00 0 40123 183 0 0
sp_fill_shopping_cart 29949 29949 0 0.00 1 13387 36 0 0
sp_fill_shopping_cart_clo_res 459249 459249 0 0.00 0 23769 15 0 0
sp_get_clo_for_invoice 4812 4812 0 0.00 0 26028 283 0 0
sp_kill_cost_storno 17881 17881 0 0.00 0 8679 62 0 0
sp_kill_qstorno_ret_qs2qd 1196757 1196757 0 0.00 0 67280 34 0 0
sp_kill_qty_storno 629423 629423 0 0.00 0 67293 73 0 0
sp_lock_selected_doc 20001 20001 0 0.00 0 8419 7 0 0
sp_make_cost_storno 27038 27038 0 0.00 0 24627 63 0 0
sp_multiply_rows_for_pdistr 27038 27038 0 0.00 0 19790 33 0 0
sp_multiply_rows_for_qdistr 19506 19506 0 0.00 0 29850 154 0 0
sp_pay_from_customer 6857 6857 0 0.00 4 21274 142 0 0
sp_pay_to_supplier 5712 5712 0 0.00 5 24912 185 0 0
sp_payment_common 12569 12569 0 0.00 1 20992 52 0 0
sp_qd_handle_on_cancel_clo 1649 1649 0 0.00 0 14672 316 0 0
sp_qd_handle_on_invoice_upd_sts 10822 10822 0 0.00 0 25653 345 0 0
sp_qd_handle_on_reserve_upd_sts 9657 9657 0 0.00 0 10446 53 0 0
sp_reserve_write_off 9657 9657 0 0.00 6 24637 194 0 0
sp_supplier_invoice 5215 5215 0 0.00 7 93567 3495 0 0
sp_supplier_order 5233 5233 0 0.00 5 39500 1327 0 0
srv_aggregate_perf_data 99 99 0 0.00 3031 451784 22470 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1705 156787 47287 0 0
srv_find_qd_qs_mism 1086729 1086729 0 0.00 0 13928 5 0 0
srv_make_invnt_saldo 570 570 0 0.00 43 85079 1237 0 0
srv_make_money_saldo 739 739 0 0.00 1 2157 35 0 0
srv_recalc_idx_stat 4 4 0 0.00 111423 507331 346930 0 0
t$perf-abend:sp_add_invoice_to_stock 125 125 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4816 4816 0 0.00 0 40628 191 0 0
t$perf-norm:sp_cancel_adding_invoice 403 403 0 0.00 0 5236 120 0 0
t$perf-norm:sp_cancel_client_order 1649 1649 0 0.00 0 5006 16 0 0
t$perf-norm:sp_cancel_customer_reserve 671 671 0 0.00 0 34789 60 0 0
t$perf-norm:sp_cancel_pay_from_customer 704 704 0 0.00 0 1180 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 368 368 0 0.00 0 176 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 368 368 0 0.00 0 753 10 0 0
t$perf-norm:sp_cancel_supplier_order 401 401 0 0.00 0 39910 2017 0 0
t$perf-norm:sp_cancel_write_off 968 968 0 0.00 0 3413 13 0 0
t$perf-norm:sp_client_order 19506 19506 0 0.00 0 36523 23 0 0
t$perf-norm:sp_customer_reserve 968 968 0 0.00 0 4707 18 0 0
t$perf-norm:sp_pay_from_customer 6857 6857 0 0.00 0 8148 13 0 0
t$perf-norm:sp_pay_to_supplier 5712 5712 0 0.00 0 11480 15 0 0
t$perf-norm:sp_reserve_write_off 9657 9657 0 0.00 0 5205 14 0 0
t$perf-norm:sp_supplier_invoice 5215 5215 0 0.00 0 11594 23 0 0
t$perf-norm:sp_supplier_order 5233 5233 0 0.00 0 4999 18 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 702 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 1657 28 0 0
t$perf-norm:srv_make_invnt_saldo 570 570 0 0.00 0 4543 24 0 0
t$perf-norm:srv_make_money_saldo 739 739 0 0.00 0 4805 24 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 9874 9624 250 2.53 0 7972 22 0 250
v_all_customers 35918 35918 0 0.00 0 20 1 0 0
v_all_suppliers 29576 29576 0 0.00 0 71 1 0 0
v_all_wares 39012 39012 0 0.00 0 26 1 0 0
v_cancel_adding_invoice 806 806 0 0.00 0 537 8 0 0
v_cancel_client_order 3298 3298 0 0.00 0 2450 5 0 0
v_cancel_customer_prepayment 1408 1408 0 0.00 0 1876 6 0 0
v_cancel_customer_reserve 1342 1342 0 0.00 0 2792 9 0 0
v_cancel_payment_to_supplier 736 736 0 0.00 0 925 6 0 0
v_cancel_supplier_invoice 762 736 26 3.41 0 2761 18 0 26
v_cancel_supplier_order 802 802 0 0.00 0 676 4 0 0
v_cancel_write_off 1936 1936 0 0.00 0 2571 15 0 0
v_max_id_clo_ord 5230 5230 0 0.00 0 2883 3 0 0
v_max_id_clo_res 968 968 0 0.00 0 1627 11 0 0
v_max_id_ord_sup 5213 5213 0 0.00 0 2393 3 0 0
v_max_non_paid_invoice 1367 1367 0 0.00 0 15626 21 0 0
v_max_non_paid_realizn 3595 3595 0 0.00 0 4605 6 0 0
v_min_id_clo_ord 5230 5230 0 0.00 0 9866 8 0 0
v_min_id_clo_res 968 968 0 0.00 0 1684 6 0 0
v_min_id_ord_sup 5213 5213 0 0.00 0 13381 6 0 0
v_min_non_paid_invoice 5712 5712 0 0.00 0 15165 35 0 0
v_min_non_paid_realizn 6857 6857 0 0.00 0 15057 29 0 0
v_our_firm 9618 9618 0 0.00 0 18 1 0 0
v_reserve_write_off 19314 19314 0 0.00 0 11190 8 0 0
x_lock_depdocs_on_canc_invoice 12275 12275 0 0.00 0 14348 119 0 0
x_lock_depdocs_on_canc_sup_ord 401 401 0 0.00 0 13647 429 0 0
x_make_qstorno_1000_1200 5233 5233 0 0.00 1 39492 1246 0 0
x_make_qstorno_1200_2000 5215 5215 0 0.00 3 93553 3377 0 0
x_make_qstorno_2100_3300 459253 459253 0 0.00 0 39773 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-09-2021 16:38:51 to 06-09-2021 16:38:55.

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
14:07:54 14 8192000000 8804071536 8816136192 8614704 0.105 3 1 2 0 8735408 58144 62560
14:08:55 11022 8192000000 10341726176 10652401664 1396624288 14.565 100 87 174 0 1430394688 5494576 28275824
14:10:15 25772 8192000000 10433259984 10757808128 1479881472 15.300 100 89 178 0 1515730832 7889760 27959600
14:11:47 18124 8192000000 10481293808 10820665344 1531508480 15.750 100 84 168 2 1567540752 9456752 26575520
14:13:08 14066 8192000000 10539930320 10894417920 1602520128 16.361 100 77 154 0 1636356576 10642928 23193520
14:14:28 13284 8192000000 10624006192 10998190080 1695682160 17.149 100 84 166 4 1731453808 11461376 24310272
14:15:44 4886 8192000000 10704572144 11082387456 1765601008 17.731 100 84 168 2 1803764352 14147440 24015904
14:16:51 3246 8192000000 10739095360 11124768768 1805731872 18.061 100 83 166 1 1841135808 12083328 23320608
14:17:55 2098 8192000000 10787351344 11170197504 1845932224 18.389 100 95 192 1 1885922464 13312976 26677264
14:18:59 1312 8192000000 10817054304 11205488640 1877450832 18.645 100 94 185 1 1916927136 13120544 26355760
14:20:02 8885 8192000000 10787431776 11205246976 1872499056 18.604 100 39 78 0 1890898496 7037648 11361792
14:21:11 5889 8192000000 10794100208 11219865600 1881821856 18.680 100 26 52 0 1894852048 6155184 6875008
14:22:19 7627 8192000000 10807118800 11232378880 1891853504 18.761 100 17 34 0 1902903824 6010480 5039840
14:23:29 8572 8192000000 10810100560 11243216896 1904917536 18.866 100 20 40 2 1916488272 5670304 5900432
14:24:39 58411 8192000000 10851524352 11265253376 1911805888 18.921 100 40 80 0 1929627920 5496656 12325376
14:26:38 4815 8192000000 10831501744 11259416576 1907324784 18.885 100 30 60 1 1922858720 7583152 7950784
14:27:44 9742 8192000000 10821117632 11257536512 1893732848 18.776 99 19 39 0 1904923152 5175296 6015008
14:28:56 60325 8192000000 10730309136 11135139840 1676958160 16.992 90 28 56 0 1689654768 4639344 8057264
14:31:00 5789 8192000000 10706852000 11119857664 1718477200 17.340 95 22 43 1 1729926304 5738992 5710112
14:32:06 6610 8192000000 10721381712 11139612672 1804741824 18.053 100 22 44 1 1816724000 5397456 6584720
14:33:14 14752 8192000000 10743697680 11163901952 1828967424 18.251 100 17 32 0 1838532480 4931424 4633632
14:34:29 33771 8192000000 10761791056 11184599040 1844685120 18.379 100 23 46 0 1857556992 6111296 6760576
14:36:04 5186 8192000000 10771814032 11202445312 1861496928 18.515 100 30 60 1 1876224656 6080400 8647328
14:37:09 9735 8192000000 10808506896 11233841152 1891947072 18.761 100 22 44 1 1903169792 5109568 6113152
14:38:22 133068 8192000000 10861117120 11302080512 1932890112 19.090 100 52 104 1 1959325280 11393744 15041424
14:41:38 43057 8192000000 10905755456 11320172544 1948914224 19.218 100 34 68 2 1974456736 15297296 10245216
14:43:26 17258 8192000000 10907795584 11338301440 1965179120 19.347 100 42 84 1 1997119152 19904800 12035232
14:44:56 9977 8192000000 10934007264 11371634688 1986830416 19.519 100 48 96 0 2021940000 20090400 15019184
14:46:16 11604 8192000000 10956046048 11391815680 2007500288 19.682 100 51 101 0 2047071072 24024416 15546368
14:47:35 9444 8192000000 10978480112 11408539648 2018969104 19.772 100 57 114 0 2063745440 27762944 17013392
14:49:00 7428 8192000000 10969016272 11393658880 2006096496 19.671 100 67 131 3 2056153536 31177680 18879360
14:50:16 11496 8192000000 10988553056 11419222016 2009923776 19.701 99 58 116 1 2064500384 37300544 17276064
14:51:32 4657 8192000000 11003869456 11423043584 2022698944 19.801 100 71 142 2 2089281712 44737328 21845440
14:52:43 8357 8192000000 11025678784 11445301248 2031064832 19.867 100 81 162 0 2111581584 54868096 25648656
14:53:57 1703 8192000000 11031096192 11448127488 2027637920 19.840 100 87 168 3 2112913520 58587584 26688016
14:55:06 5121 8192000000 10997933072 11423096832 1999859584 19.622 100 65 129 1 2080567296 59469568 21238144
14:56:21 10181 8192000000 11007559472 11432562688 1993285872 19.570 100 78 156 0 2092633104 72891872 26455360
14:57:40 3463 8192000000 10997576096 11419525120 1971546128 19.398 99 73 146 1 2070100720 74346000 24208592
14:58:52 15431 8192000000 10973546224 11416600576 1974605280 19.422 99 45 91 0 2047429056 56702560 16121216
15:00:15 22909 8192000000 10963610368 11415785472 1963158000 19.331 99 39 78 0 2043188272 66457072 13573200
15:01:44 8957 8192000000 10960939408 11414745088 1958152112 19.291 99 35 70 0 2030253104 60490912 11610080
15:02:56 27564 8192000000 10937192656 11387224064 1951478944 19.238 100 30 60 1 2025863056 63869360 10514752
15:04:25 25611 8192000000 10883914320 11335118848 1883595280 18.694 99 25 50 2 1964500960 71045696 9859984
15:05:51 32752 8192000000 10902942128 11381669888 1924153360 19.020 100 23 46 0 2001627872 68257344 9217168
15:07:28 68237 8192000000 10881200816 11321622528 1908720800 18.896 99 7 14 0 1926464448 15893824 1849824
15:09:38 31716 8192000000 10894443824 11320328192 1885402112 18.709 97 55 107 4 1911343696 10433280 15508304
15:11:41 67720 8192000000 10847532736 11322048512 1876550032 18.637 97 18 37 1 1896744816 14701792 5492992
15:13:50 35687 8192000000 10858681344 11317096448 1928531296 19.055 100 15 30 0 1947044608 14701648 3811664
15:16:27 46653 8192000000 10871371520 11323002880 1900796016 18.833 97 22 42 2 1914696224 8023888 5876320
15:18:37 67295 8192000000 10899403536 11361869824 1939156496 19.140 98 19 38 2 1959535952 15162304 5217152
15:21:14 52845 8192000000 10905569456 11366408192 1958246640 19.292 99 15 30 0 1975280224 12827712 4205872
15:23:21 81832 8192000000 10906892592 11389718528 1983493728 19.492 100 19 38 0 2005517408 15942976 6080704
15:25:50 42078 8192000000 10901543184 11375935488 1984443904 19.500 100 16 32 0 2001083920 12079040 4560976
15:27:33 40946 8192000000 10936887728 11397861376 1982238608 19.482 99 26 52 1 2003770336 13233616 8298112
15:29:29 51525 8192000000 10921068320 11405303808 1993118800 19.568 100 9 18 0 2020262288 24294064 2849424
15:31:22 54604 8192000000 10917049792 11388063744 1982753056 19.486 99 27 54 3 2006562912 15888256 7921600
15:33:29 61088 8192000000 10909269200 11383635968 1987344032 19.523 100 7 14 1 1999661392 10085680 2231680
15:35:36 41231 8192000000 10913300656 11376623616 1950619488 19.231 98 43 86 2 1973052624 10927616 11505520
15:37:21 56796 8192000000 10886370736 11356360704 1915696688 18.952 96 11 22 1 1929174640 11019968 2457984
15:39:21 70980 8192000000 10903171232 11382267904 1908221888 18.892 96 15 30 1 1935280192 22955744 4102560
15:41:32 50402 8192000000 10915220800 11383590912 1964808720 19.344 99 15 30 2 1980522816 11720352 3993744
15:43:24 55215 8192000000 10896793568 11373187072 1949648512 19.224 99 29 58 2 1972112816 14406896 8057408
15:45:20 47916 8192000000 10897616992 11358560256 1933672944 19.096 98 21 41 0 1949676240 9958656 6044640
15:47:51 93975 8192000000 10861149296 11327164416 1899139216 18.819 97 23 46 0 1920972912 14744656 7089040
15:50:26 51946 8192000000 10870160976 11345739776 1932493552 19.087 99 22 44 0 1956396160 17609184 6293424
15:52:24 71053 8192000000 10859221744 11346149376 1881661072 18.679 96 6 12 0 1898745488 15473168 1611248
15:54:36 52608 8192000000 10872194336 11352023040 1940590544 19.151 99 12 24 1 1956908592 12910656 3407392
15:57:59 54580 8192000000 10856049520 11333816320 1910432496 18.910 98 22 44 1 1927529680 10928480 6168704
16:00:17 91670 8192000000 10860877376 11328442368 1835417184 18.303 94 24 49 1 1859336256 16776832 7142240
16:03:07 36123 8192000000 10873433008 11324588032 1901453104 18.838 98 31 62 0 1918809808 8340592 9016112
16:04:46 49437 8192000000 10853460208 11313180672 1897659104 18.807 98 30 59 4 1919593888 13492752 8442032
16:06:41 57814 8192000000 10879528464 11335544832 1903067248 18.851 98 39 78 2 1928087216 14348144 10671824
16:08:40 38464 8192000000 10889137424 11344654336 1935500528 19.111 99 30 60 1 1958837344 14693536 8643280
16:11:01 156787 8192000000 10928234976 11382607872 1953398864 19.254 98 22 44 3 1976977824 16805904 6773056
16:14:45 53591 8192000000 10902788112 11387572224 1967866032 19.369 99 11 22 0 1982329904 11337456 3126416
16:16:55 65614 8192000000 10898803840 11367575552 1933143728 19.092 97 17 34 0 1947477632 10117968 4215936
16:19:22 66708 8192000000 10900178336 11377795072 1955986896 19.274 98 22 44 0 1969479808 7410720 6082192
16:21:37 55031 8192000000 10913453616 11370106880 1924794816 19.025 97 14 28 1 1937285296 8957328 3533152
16:23:36 23066 8192000000 10917288160 11390853120 1985081536 19.505 100 16 31 0 2000798592 11481104 4235952
16:25:02 83864 8192000000 10914800784 11376271360 1936838784 19.122 97 22 44 0 1956785376 13732352 6214240
16:27:28 75795 8192000000 10915556208 11389075456 1977575072 19.445 100 14 28 1 2001816464 19573264 4668128
16:29:56 48278 8192000000 10884392736 11344023552 1901090832 18.835 97 6 12 0 1914963392 12615216 1257344
16:31:45 49954 8192000000 10903951936 11357163520 1949845536 19.225 99 35 69 0 1967757024 7941680 9969808
16:33:35 98157 8192000000 10878957072 11358232576 1872959312 18.608 95 17 34 0 1890850032 13202592 4688128
16:36:18 75066 8192000000 10854712416 11337158656 1831923136 18.275 94 21 42 1 1850841728 13166640 5751952

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 06-09-2021 16:38:57 to 06-09-2021 16:39:11.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 125 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-09-2021 16:39:12 to 06-09-2021 16:39:14.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          235888
MON$OLDEST_ACTIVE               236914
MON$OLDEST_SNAPSHOT             236914
MON$NEXT_TRANSACTION            236914
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-03 16:41:55.3470
MON$PAGES                       2036000
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33491 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33491 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33491 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33491 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:254: 
:255: mscs-test-161.budzdorov.ru    Mon Sep  6 16:37:56 2021
:256:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:257: 
:258: 
:259: mscs-test-161.budzdorov.ru    Mon Sep  6 16:37:59 2021
:260:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:261: 
--- end of diff output ---

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

06.09.21 16:39:15. There are 3 logs in /var/tmp/logs-oltp30 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-06 16:39:18 No severe PSQL-related problems occured
06.09.21 16:39:19. End of report.