Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.07.2022 01:40
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 2a7b53d1c5ed44b9bb2712fffac5d8c6
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-11-11 18:28
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.33
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 16040060 kB
MemAvailable 31063452 kB
Buffers 0 kB
Cached 15702924 kB
SwapCached 2552 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.11
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 2022-07-25 01:38:24
DTS_END                         2022-07-25 01:38:24.3600
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/46578
TRN_ID                          241632
ATT_ID                          698
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: 9274.69 28325 1112963
customer order: creation 170.13 378 20416
customer order: refuse 14.48 397 1738
order to supplier: creation 45.66 1242 5479
order to supplier: removal 3.19 115858 383
invoice (draft): creation 46.21 3219 5545
invoice (draft): removal 51.68 862 6201
invoice accept: apply 42.04 17550 5045
invoice accept: cancel 46.83 840 5619
customer reserve: creation 3888.31 172 466597
customer reserve: removal 4674.73 63 560967
realization accept: apply 82.65 176 9918
realization accept: cancel 79.81 115 9577
payment from customer: creation 59.12 102 7094
payment from customer: removal 5.51 75 661
payment to supplier: creation 48.66 134 5839
payment to supplier: removal 3.35 99 402
service: total inventory turnovers 4.46 1415 535
service: total monetary turnovers 7.01 40 841
service: aggregate perf. data 0.85 19224 102
service: refresh index statistics 0.03 404537 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 25-07-2022 01:40:45 to 25-07-2022 01:40:48.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3044
TEST_TIME 2 4479
TEST_TIME 3 4969
TEST_TIME 4 5810
TEST_TIME 5 5697
TEST_TIME 6 5677
TEST_TIME 7 5410
TEST_TIME 8 5689
TEST_TIME 9 5617
TEST_TIME 10 5666
TEST_TIME 11 6169
TEST_TIME 12 6349
TEST_TIME 13 6793
TEST_TIME 14 6978
TEST_TIME 15 6910
TEST_TIME 16 6963
TEST_TIME 17 8053
TEST_TIME 18 8260
TEST_TIME 19 8709
TEST_TIME 20 9079
TEST_TIME 21 9146
TEST_TIME 22 9228
TEST_TIME 23 9060
TEST_TIME 24 9781
TEST_TIME 25 10364
TEST_TIME 26 10284
TEST_TIME 27 10984
TEST_TIME 28 11684
TEST_TIME 29 11299
TEST_TIME 30 11103
TEST_TIME 31 11015
TEST_TIME 32 10920
TEST_TIME 33 10902
TEST_TIME 34 10736
TEST_TIME 35 10596
TEST_TIME 36 10502
TEST_TIME 37 10423
TEST_TIME 38 10341
TEST_TIME 39 10287
TEST_TIME 40 10219
TEST_TIME 41 10136
TEST_TIME 42 10028
TEST_TIME 43 9996
TEST_TIME 44 9982
TEST_TIME 45 9971
TEST_TIME 46 9777
TEST_TIME 47 9712
TEST_TIME 48 9723
TEST_TIME 49 9633
TEST_TIME 50 9613
TEST_TIME 51 9552
TEST_TIME 52 9543
TEST_TIME 53 9592
TEST_TIME 54 9565
TEST_TIME 55 9572
TEST_TIME 56 9531
TEST_TIME 57 9570
TEST_TIME 58 9688
TEST_TIME 59 9738
TEST_TIME 60 9708
TEST_TIME 61 9787
TEST_TIME 62 9785
TEST_TIME 63 9834
TEST_TIME 64 9810
TEST_TIME 65 9815
TEST_TIME 66 9798
TEST_TIME 67 9839
TEST_TIME 68 9761
TEST_TIME 69 9737
TEST_TIME 70 9713
TEST_TIME 71 9617
TEST_TIME 72 9630
TEST_TIME 73 9625
TEST_TIME 74 9563
TEST_TIME 75 9527
TEST_TIME 76 9467
TEST_TIME 77 9431
TEST_TIME 78 9383
TEST_TIME 79 9441
TEST_TIME 80 9416
TEST_TIME 81 9476
TEST_TIME 82 9529
TEST_TIME 83 9533
TEST_TIME 84 9526
TEST_TIME 85 9570
TEST_TIME 86 9527
TEST_TIME 87 9514
TEST_TIME 88 9517
TEST_TIME 89 9466
TEST_TIME 90 9566
TEST_TIME 91 9659
TEST_TIME 92 9653
TEST_TIME 93 9587
TEST_TIME 94 9550
TEST_TIME 95 9521
TEST_TIME 96 9474
TEST_TIME 97 9428
TEST_TIME 98 9487
TEST_TIME 99 9442
TEST_TIME 100 9439
TEST_TIME 101 9436
TEST_TIME 102 9417
TEST_TIME 103 9454
TEST_TIME 104 9448
TEST_TIME 105 9434
TEST_TIME 106 9564
TEST_TIME 107 9540
TEST_TIME 108 9531
TEST_TIME 109 9526
TEST_TIME 110 9559
TEST_TIME 111 9516
TEST_TIME 112 9455
TEST_TIME 113 9451
TEST_TIME 114 9442
TEST_TIME 115 9407
TEST_TIME 116 9374
TEST_TIME 117 9365
TEST_TIME 118 9362
TEST_TIME 119 9346
TEST_TIME 120 9276

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 25-07-2022 01:40:49 to 25-07-2022 01:40:52.

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 1544387 1544387 0 0.00 0 38045 6 0 0
doc_list_biud 1544386 1544386 0 0.00 0 65392 29 0 0
sp_add_invoice_to_stock 5126 5045 81 1.58 33 204005 17550 0 81
sp_cancel_adding_invoice 5619 5619 0 0.00 3 85354 841 0 0
sp_cancel_client_order 1738 1738 0 0.00 5 31635 397 0 0
sp_cancel_customer_reserve 560967 560967 0 0.00 0 65394 63 0 0
sp_cancel_pay_from_customer 661 661 0 0.00 2 7118 75 0 0
sp_cancel_pay_to_supplier 402 402 0 0.00 2 4585 99 0 0
sp_cancel_supplier_invoice 6208 6201 7 0.11 1 29549 862 0 7
sp_cancel_supplier_order 383 383 0 0.00 11 1064204 115858 0 0
sp_cancel_write_off 9577 9577 0 0.00 1 7123 116 0 0
sp_client_order 20416 20416 0 0.00 3 44536 379 0 0
sp_customer_reserve 466597 466597 0 0.00 0 106982 173 0 0
sp_fill_shopping_cart 31435 31435 0 0.00 1 16611 39 0 0
sp_fill_shopping_cart_clo_res 466589 466589 0 0.00 0 21421 15 0 0
sp_get_clo_for_invoice 5032 5032 0 0.00 0 25564 363 0 0
sp_kill_cost_storno 16259 16259 0 0.00 0 6850 60 0 0
sp_kill_qstorno_ret_qs2qd 1138095 1138095 0 0.00 0 65390 34 0 0
sp_kill_qty_storno 599433 599433 0 0.00 0 65392 74 0 0
sp_lock_selected_doc 20695 20695 0 0.00 0 7046 4 0 0
sp_make_cost_storno 27881 27881 0 0.00 0 38039 51 0 0
sp_multiply_rows_for_pdistr 27881 27881 0 0.00 0 6895 26 0 0
sp_multiply_rows_for_qdistr 20416 20416 0 0.00 0 40043 180 0 0
sp_pay_from_customer 7094 7094 0 0.00 4 10368 103 0 0
sp_pay_to_supplier 5839 5839 0 0.00 4 13486 135 0 0
sp_payment_common 12933 12933 0 0.00 1 13469 26 0 0
sp_qd_handle_on_cancel_clo 1738 1738 0 0.00 1 31625 364 0 0
sp_qd_handle_on_invoice_upd_sts 10649 10649 0 0.00 0 20663 340 0 0
sp_qd_handle_on_reserve_upd_sts 9918 9918 0 0.00 0 18727 58 0 0
sp_reserve_write_off 9918 9918 0 0.00 6 18775 177 0 0
sp_supplier_invoice 5545 5545 0 0.00 7 61895 3219 0 0
sp_supplier_order 5479 5479 0 0.00 5 58252 1243 0 0
srv_aggregate_perf_data 102 102 0 0.00 3323 210220 19225 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1336 169940 47826 0 0
srv_find_qd_qs_mism 1064915 1064915 0 0.00 0 12020 5 0 0
srv_make_invnt_saldo 535 535 0 0.00 35 115654 1415 0 0
srv_make_money_saldo 841 841 0 0.00 1 13477 41 0 0
srv_recalc_idx_stat 4 4 0 0.00 238668 636188 404537 0 0
t$perf-abend:sp_add_invoice_to_stock 81 81 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5045 5045 0 0.00 0 25298 189 0 0
t$perf-norm:sp_cancel_adding_invoice 405 405 0 0.00 0 3773 122 0 0
t$perf-norm:sp_cancel_client_order 1738 1738 0 0.00 0 1484 12 0 0
t$perf-norm:sp_cancel_customer_reserve 742 742 0 0.00 0 9139 28 0 0
t$perf-norm:sp_cancel_pay_from_customer 661 661 0 0.00 0 1286 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 402 402 0 0.00 0 9632 33 0 0
t$perf-norm:sp_cancel_supplier_invoice 394 394 0 0.00 0 1117 11 0 0
t$perf-norm:sp_cancel_supplier_order 383 383 0 0.00 0 32531 1875 0 0
t$perf-norm:sp_cancel_write_off 1022 1022 0 0.00 0 3993 20 0 0
t$perf-norm:sp_client_order 20416 20416 0 0.00 0 12710 18 0 0
t$perf-norm:sp_customer_reserve 990 990 0 0.00 0 3414 10 0 0
t$perf-norm:sp_pay_from_customer 7094 7094 0 0.00 0 12306 13 0 0
t$perf-norm:sp_pay_to_supplier 5839 5839 0 0.00 0 3897 11 0 0
t$perf-norm:sp_reserve_write_off 9918 9918 0 0.00 0 4798 12 0 0
t$perf-norm:sp_supplier_invoice 5545 5545 0 0.00 0 7325 22 0 0
t$perf-norm:sp_supplier_order 5479 5479 0 0.00 0 6633 18 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 1851 23 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 1572 26 0 0
t$perf-norm:srv_make_invnt_saldo 535 535 0 0.00 0 1887 18 0 0
t$perf-norm:srv_make_money_saldo 841 841 0 0.00 0 6503 26 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10222 10060 162 1.58 0 3051 16 0 162
v_all_customers 37520 37520 0 0.00 0 22 1 0 0
v_all_suppliers 30562 30562 0 0.00 0 19 1 0 0
v_all_wares 40828 40828 0 0.00 0 23 1 0 0
v_cancel_adding_invoice 810 810 0 0.00 0 2932 11 0 0
v_cancel_client_order 3476 3476 0 0.00 0 5534 9 0 0
v_cancel_customer_prepayment 1322 1322 0 0.00 0 1517 6 0 0
v_cancel_customer_reserve 1484 1484 0 0.00 0 323 3 0 0
v_cancel_payment_to_supplier 804 804 0 0.00 0 1787 9 0 0
v_cancel_supplier_invoice 802 788 14 1.74 0 3055 19 0 14
v_cancel_supplier_order 766 766 0 0.00 0 855 5 0 0
v_cancel_write_off 2044 2044 0 0.00 0 3899 17 0 0
v_max_id_clo_ord 5479 5479 0 0.00 0 1331 2 0 0
v_max_id_clo_res 990 990 0 0.00 0 1196 7 0 0
v_max_id_ord_sup 5540 5540 0 0.00 0 3889 3 0 0
v_max_non_paid_invoice 1577 1577 0 0.00 0 1480 4 0 0
v_max_non_paid_realizn 3788 3788 0 0.00 0 1334 3 0 0
v_min_id_clo_ord 5479 5479 0 0.00 0 3857 7 0 0
v_min_id_clo_res 990 990 0 0.00 0 660 3 0 0
v_min_id_ord_sup 5540 5540 0 0.00 0 5426 6 0 0
v_min_non_paid_invoice 5839 5839 0 0.00 0 13463 22 0 0
v_min_non_paid_realizn 7094 7094 0 0.00 0 7620 17 0 0
v_our_firm 9918 9918 0 0.00 0 26 1 0 0
v_reserve_write_off 19832 19832 0 0.00 0 5537 8 0 0
x_lock_depdocs_on_canc_invoice 11426 11426 0 0.00 0 10721 118 0 0
x_lock_depdocs_on_canc_sup_ord 383 383 0 0.00 0 8275 389 0 0
x_make_qstorno_1000_1200 5479 5479 0 0.00 1 58239 1165 0 0
x_make_qstorno_1200_2000 5545 5545 0 0.00 3 61888 3083 0 0
x_make_qstorno_2100_3300 466597 466597 0 0.00 0 106976 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-07-2022 01:40:53 to 25-07-2022 01:40:57.

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
23:08:28 3 8192000000 8800866368 8812728320 6924112 0.084 2 2 2 0 7033280 66384 42784
23:09:29 34171 8192000000 9921690944 10234044416 996857616 10.848 100 93 186 0 1027621232 6680256 24083360
23:11:08 18739 8192000000 9949255488 10272829440 1034252000 11.209 100 87 174 0 1064836800 8920224 21664576
23:12:30 20321 8192000000 9979293776 10314317824 1062294976 11.478 100 79 158 0 1091827904 10140032 19392896
23:14:01 8765 8192000000 10038522512 10386448384 1122141632 12.047 100 81 162 0 1153125328 11784544 19199152
23:15:18 3801 8192000000 10090464416 10451132416 1177504288 12.567 100 87 174 1 1210874656 13272288 20098080
23:16:25 3605 8192000000 10137859920 10508115968 1225481408 13.012 100 89 174 2 1257774704 12418528 19874768
23:17:29 1970 8192000000 10168201328 10545872896 1255237696 13.286 100 82 162 1 1287438864 14294752 17906416
23:18:32 1263 8192000000 10201373376 10582921216 1288234464 13.588 100 89 176 1 1321003664 13355984 19413216
23:19:35 15342 8192000000 10193901248 10606473216 1297903616 13.676 100 44 88 0 1318415696 11051824 9460256
23:21:04 5698 8192000000 10198427424 10607247360 1304245984 13.734 100 35 73 2 1318641264 5979808 8415472
23:22:10 8790 8192000000 10202931344 10618028032 1315032352 13.832 100 18 36 0 1325324480 6303936 3988192
23:23:21 7426 8192000000 10207549184 10621796352 1319368000 13.871 100 17 34 0 1328744016 5431968 3944048
23:24:31 67577 8192000000 10216774144 10638958592 1327043552 13.940 100 29 58 0 1338757312 5212912 6500848
23:26:40 4339 8192000000 10228554576 10641801216 1326902832 13.939 100 37 74 0 1342526944 7324176 8299936
23:27:45 10003 8192000000 10190290048 10606489600 1284210320 13.551 97 18 35 0 1293197936 4930368 4057248
23:28:56 52987 8192000000 10165733392 10585579520 1264716592 13.373 97 37 76 0 1279397760 5979024 8702144
23:30:51 5838 8192000000 10140807072 10532769792 1221269456 12.973 97 32 63 0 1235015024 6124512 7621056
23:31:58 4371 8192000000 10154453984 10557947904 1253891296 13.274 99 29 58 0 1266660624 6046224 6723104
23:33:04 62190 8192000000 10157807408 10563596288 1243692368 13.180 97 33 66 0 1256843024 5249440 7901216
23:35:12 6946 8192000000 10189421600 10602577920 1295728784 13.656 100 25 50 0 1307520736 5465760 6326192
23:36:19 11185 8192000000 10205034720 10617868288 1309823088 13.784 100 16 32 1 1319155216 5587216 3744912
23:37:33 70051 8192000000 10226122016 10639257600 1319121504 13.869 100 32 64 0 1331834816 5016864 7696448
23:40:00 15034 8192000000 10265524416 10679513088 1361104704 14.247 100 57 113 0 1385521328 11977712 12438912
23:41:20 18906 8192000000 10291886976 10707410944 1379234704 14.410 100 39 78 1 1402288624 13862672 9191248
23:42:46 15557 8192000000 10290706432 10714202112 1382151408 14.436 100 36 71 0 1405098032 14386144 8560480
23:44:03 15114 8192000000 10309367680 10737639424 1399495776 14.591 100 46 92 0 1428148672 17046864 11606032
23:45:33 19508 8192000000 10338164912 10761908224 1417602768 14.751 100 54 107 0 1451852384 20748720 13500896
23:47:01 13043 8192000000 10326080064 10753888256 1403410592 14.625 99 43 84 0 1436110448 22120960 10578896
23:48:22 3064 8192000000 10364452352 10783059968 1425123744 14.818 100 68 134 1 1471889776 30828624 15937408
23:49:31 1335 8192000000 10354483408 10764222464 1403718592 14.628 99 83 166 0 1460867424 36079536 21069296
23:50:37 5464 8192000000 10354360112 10771341312 1405809104 14.647 100 57 111 1 1459580048 39617600 14153344
23:51:49 1965 8192000000 10361214864 10778062848 1410717072 14.690 100 76 149 1 1472354880 43082704 18555104
23:52:55 1539 8192000000 10367710304 10778783744 1410880128 14.692 100 77 152 0 1475843776 45384352 19579296
23:54:03 3186 8192000000 10356984480 10772443136 1392918048 14.532 99 67 134 2 1461746848 51742208 17086592
23:55:12 2980 8192000000 10362335568 10775027712 1373499136 14.358 99 76 151 0 1456063360 62483680 20080544
23:56:24 2852 8192000000 10342738192 10767216640 1388887664 14.496 100 46 91 1 1450421296 48911552 12622080
23:57:36 24182 8192000000 10325276704 10750951424 1344331968 14.096 97 39 78 1 1406669808 51482864 10854976
23:59:04 12787 8192000000 10325713600 10758049792 1365720432 14.289 99 30 60 0 1422164912 47866688 8577792
00:00:22 15246 8192000000 10318620448 10747969536 1365717632 14.289 100 28 56 0 1424985440 51845376 7422432
00:01:39 15677 8192000000 10333273728 10754351104 1359819200 14.236 100 46 90 0 1430850880 58155888 12875792
00:02:57 28090 8192000000 10308386416 10746957824 1366117392 14.292 100 28 56 0 1419248752 45606656 7524704
00:04:26 20483 8192000000 10286091952 10722762752 1331717136 13.983 98 22 44 0 1383403104 45423696 6262272
00:05:48 78115 8192000000 10271667232 10698878976 1296190848 13.661 95 30 59 2 1314594976 10995120 7409008
00:08:44 54332 8192000000 10267619648 10718466048 1360566320 14.242 100 16 32 0 1379509200 15053632 3889248
00:10:41 49168 8192000000 10265789584 10714451968 1359495744 14.233 99 28 57 1 1375686592 10751280 5439568
00:12:31 119639 8192000000 10284399008 10732814336 1375974672 14.381 99 19 38 0 1389651568 9346752 4330144
00:16:40 72214 8192000000 10299109152 10752577536 1384764368 14.459 99 19 38 0 1402740592 13509328 4466896
00:19:25 79032 8192000000 10297580592 10765512704 1391251664 14.517 99 11 22 0 1407966832 13379216 3335952
00:21:48 90745 8192000000 10310372896 10764009472 1403426896 14.625 100 22 44 0 1415819216 7240496 5151824
00:24:29 28942 8192000000 10306773520 10770771968 1408422032 14.670 100 16 32 0 1420984624 8946736 3615856
00:26:50 17518 8192000000 10309431760 10777767936 1403167392 14.623 99 37 73 3 1423375840 11764272 8444176
00:28:08 75317 8192000000 10303796640 10771714048 1378632928 14.404 97 12 24 0 1396828768 14775152 3420688
00:30:24 51607 8192000000 10306318384 10783981568 1401334352 14.607 100 12 25 0 1421027056 16940688 2752016
00:32:16 69499 8192000000 10314743344 10775662592 1417256560 14.748 100 18 34 1 1429316736 8420960 3639216
00:34:26 18762 8192000000 10344316736 10784645120 1414312384 14.722 100 60 119 1 1444019344 15646976 14059984
00:35:48 97224 8192000000 10322166928 10789859328 1417164304 14.748 100 14 28 0 1430129856 9954496 3011056
00:38:29 71946 8192000000 10308183552 10779635712 1380754800 14.423 98 11 20 0 1397884608 14964864 2164944
00:40:50 31779 8192000000 10289800144 10730086400 1314563824 13.827 94 38 76 1 1331371744 8595456 8212464
00:42:27 63301 8192000000 10290602896 10735022080 1315311616 13.834 95 28 56 0 1333019616 11595824 6112176
00:44:38 4113 8192000000 10293092048 10724392960 1361316256 14.249 99 47 94 0 1383479616 11813088 10350272
00:45:43 99683 8192000000 10261749312 10684710912 1312236624 13.806 97 39 78 0 1334217072 13241184 8739264
00:48:26 73892 8192000000 10247512336 10688700416 1324444320 13.917 98 25 50 0 1338959840 9271712 5243808
00:50:52 39801 8192000000 10223664976 10672627712 1304455312 13.736 97 16 32 0 1316144288 8136096 3552880
00:52:32 38114 8192000000 10225147264 10668986368 1297149040 13.669 97 16 32 0 1307911792 7132304 3630448
00:54:11 59775 8192000000 10230001872 10680680448 1330140816 13.968 99 14 28 0 1341310400 7747664 3421920
00:56:28 47137 8192000000 10238600816 10694447104 1331650784 13.982 99 10 20 0 1349937872 15473424 2813664
00:58:17 65186 8192000000 10266254688 10712125440 1352778336 14.172 99 29 59 1 1370761008 10736800 7245872
01:00:26 42386 8192000000 10251256608 10707648512 1331928640 13.985 98 18 36 0 1346949824 10922064 4099120
01:02:16 52300 8192000000 10267758352 10728525824 1369751344 14.325 100 33 66 1 1387979552 10383728 7844480
01:04:09 44659 8192000000 10299177456 10737750016 1379858112 14.415 100 40 80 0 1400707152 12018928 8830112
01:05:54 64839 8192000000 10300956928 10755846144 1378928880 14.407 99 24 48 0 1400562288 15943648 5689760
01:08:06 80135 8192000000 10294449888 10769534976 1364540736 14.278 98 16 32 1 1392908784 24997888 3370160
01:10:37 117652 8192000000 10290383648 10743951360 1383881520 14.451 99 23 46 0 1399368448 10657552 4829376
01:13:41 91153 8192000000 10282232736 10742894592 1341003808 14.066 96 8 16 0 1351375696 8919664 1452224
01:16:12 52483 8192000000 10303684144 10769772544 1394983760 14.550 100 10 20 0 1412604608 15371568 2249280
01:18:17 63532 8192000000 10304280608 10761236480 1366969856 14.300 97 16 32 0 1382566240 11894464 3701920
01:20:22 61267 8192000000 10318006832 10780004352 1400065680 14.596 99 19 38 0 1421893264 17101296 4726288
01:22:30 41428 8192000000 10314657008 10759487488 1357373872 14.214 97 34 69 0 1383032064 17848160 7810032
01:24:13 119168 8192000000 10300554928 10751385600 1354612160 14.189 97 12 24 0 1371734016 14091456 3030400
01:27:13 45066 8192000000 10291462096 10735403008 1353535392 14.179 98 39 78 0 1374964960 12655520 8774048
01:29:04 169940 8192000000 10276784656 10731880448 1351253376 14.159 97 13 26 0 1366682304 12707120 2721808
01:33:00 54830 8192000000 10248512304 10698432512 1302364288 13.717 94 11 22 0 1315453152 10466752 2622112
01:35:03 51301 8192000000 10254215712 10707820544 1330330768 13.970 97 14 28 0 1350971280 17264656 3375856
01:36:58 57936 8192000000 10254623408 10709258240 1333327984 13.997 98 24 46 2 1345484784 6509312 5647488

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 25-07-2022 01:40:59 to 25-07-2022 01:41:14.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 81 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 25-07-2022 01:41:15 to 25-07-2022 01:41:17.

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          241631
MON$OLDEST_ACTIVE               241803
MON$OLDEST_SNAPSHOT             241803
MON$NEXT_TRANSACTION            241803
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                       2032800
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33613 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33613 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33613 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33613 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 ---
--- end of diff output ---

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

25.07.22 01:41:18. 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
2022-07-25 01:41:20 No severe PSQL-related problems occured
25.07.22 01:41:21. End of report.