Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.02.2023 16: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 15229964 kB
MemAvailable 30472800 kB
Buffers 0 kB
Cached 16500468 kB
SwapCached 3308 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 2023-02-17 16:38:38
DTS_END                         2023-02-17 16:38:38.7930
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_MAKE_INVNT_SALDO(40:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/57866
TRN_ID                          242747
ATT_ID                          666
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: 9436.76 22724 1132411
customer order: creation 171.33 374 20560
customer order: refuse 15.10 368 1812
order to supplier: creation 46.28 1236 5553
order to supplier: removal 3.68 91999 441
invoice (draft): creation 45.26 3137 5431
invoice (draft): removal 55.36 658 6643
invoice accept: apply 41.47 16760 4976
invoice accept: cancel 49.83 726 5980
customer reserve: creation 3830.07 166 459608
customer reserve: removal 4880.93 54 585712
realization accept: apply 82.93 172 9952
realization accept: cancel 85.43 108 10252
payment from customer: creation 59.13 107 7095
payment from customer: removal 5.53 79 664
payment to supplier: creation 48.92 111 5870
payment to supplier: removal 3.63 62 435
service: total inventory turnovers 4.58 962 550
service: total monetary turnovers 6.48 50 778
service: aggregate perf. data 0.79 19554 95
service: refresh index statistics 0.03 317792 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-02-2023 16:40:31 to 17-02-2023 16:40:33.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2228
TEST_TIME 2 3203
TEST_TIME 3 4210
TEST_TIME 4 4369
TEST_TIME 5 4642
TEST_TIME 6 5103
TEST_TIME 7 4947
TEST_TIME 8 4844
TEST_TIME 9 4906
TEST_TIME 10 5370
TEST_TIME 11 5331
TEST_TIME 12 5372
TEST_TIME 13 6200
TEST_TIME 14 6425
TEST_TIME 15 6385
TEST_TIME 16 6640
TEST_TIME 17 7123
TEST_TIME 18 8166
TEST_TIME 19 8399
TEST_TIME 20 8520
TEST_TIME 21 8851
TEST_TIME 22 9136
TEST_TIME 23 9726
TEST_TIME 24 9994
TEST_TIME 25 10280
TEST_TIME 26 10677
TEST_TIME 27 10495
TEST_TIME 28 10392
TEST_TIME 29 10530
TEST_TIME 30 11065
TEST_TIME 31 10818
TEST_TIME 32 10626
TEST_TIME 33 10402
TEST_TIME 34 10674
TEST_TIME 35 10470
TEST_TIME 36 10354
TEST_TIME 37 10205
TEST_TIME 38 10051
TEST_TIME 39 10205
TEST_TIME 40 10180
TEST_TIME 41 10052
TEST_TIME 42 9919
TEST_TIME 43 10152
TEST_TIME 44 10079
TEST_TIME 45 10036
TEST_TIME 46 9887
TEST_TIME 47 9861
TEST_TIME 48 9781
TEST_TIME 49 9721
TEST_TIME 50 9627
TEST_TIME 51 9634
TEST_TIME 52 9499
TEST_TIME 53 9486
TEST_TIME 54 9494
TEST_TIME 55 9684
TEST_TIME 56 9623
TEST_TIME 57 9729
TEST_TIME 58 9763
TEST_TIME 59 9732
TEST_TIME 60 9746
TEST_TIME 61 9775
TEST_TIME 62 9879
TEST_TIME 63 9896
TEST_TIME 64 9907
TEST_TIME 65 9952
TEST_TIME 66 10013
TEST_TIME 67 9997
TEST_TIME 68 10058
TEST_TIME 69 9920
TEST_TIME 70 9850
TEST_TIME 71 9816
TEST_TIME 72 9777
TEST_TIME 73 9798
TEST_TIME 74 9727
TEST_TIME 75 9672
TEST_TIME 76 9601
TEST_TIME 77 9577
TEST_TIME 78 9564
TEST_TIME 79 9686
TEST_TIME 80 9636
TEST_TIME 81 9657
TEST_TIME 82 9653
TEST_TIME 83 9679
TEST_TIME 84 9693
TEST_TIME 85 9761
TEST_TIME 86 9713
TEST_TIME 87 9700
TEST_TIME 88 9744
TEST_TIME 89 9770
TEST_TIME 90 9827
TEST_TIME 91 9826
TEST_TIME 92 9748
TEST_TIME 93 9687
TEST_TIME 94 9681
TEST_TIME 95 9626
TEST_TIME 96 9630
TEST_TIME 97 9588
TEST_TIME 98 9602
TEST_TIME 99 9561
TEST_TIME 100 9596
TEST_TIME 101 9553
TEST_TIME 102 9599
TEST_TIME 103 9631
TEST_TIME 104 9610
TEST_TIME 105 9596
TEST_TIME 106 9577
TEST_TIME 107 9641
TEST_TIME 108 9653
TEST_TIME 109 9732
TEST_TIME 110 9703
TEST_TIME 111 9635
TEST_TIME 112 9618
TEST_TIME 113 9592
TEST_TIME 114 9572
TEST_TIME 115 9535
TEST_TIME 116 9522
TEST_TIME 117 9492
TEST_TIME 118 9451
TEST_TIME 119 9498
TEST_TIME 120 9439

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-02-2023 16:40:35 to 17-02-2023 16:40:38.

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 1559590 1559590 0 0.00 0 18607 5 0 0
doc_list_biud 1559574 1559574 0 0.00 0 51513 26 0 0
sp_add_invoice_to_stock 5139 4976 163 3.17 15 180042 16760 0 163
sp_cancel_adding_invoice 5980 5980 0 0.00 3 72099 727 0 0
sp_cancel_client_order 1812 1812 0 0.00 4 15439 369 0 0
sp_cancel_customer_reserve 585712 585712 0 0.00 0 36545 55 0 0
sp_cancel_pay_from_customer 664 664 0 0.00 3 4564 79 0 0
sp_cancel_pay_to_supplier 435 435 0 0.00 3 3905 62 0 0
sp_cancel_supplier_invoice 6658 6643 15 0.22 1 18654 658 0 15
sp_cancel_supplier_order 441 441 0 0.00 8 1051998 92000 0 0
sp_cancel_write_off 10252 10252 0 0.00 1 15277 108 0 0
sp_client_order 20560 20560 0 0.00 3 24378 375 0 0
sp_customer_reserve 459608 459608 0 0.00 0 45894 166 0 0
sp_fill_shopping_cart 31541 31541 0 0.00 1 21435 41 0 0
sp_fill_shopping_cart_clo_res 459602 459602 0 0.00 0 18353 14 0 0
sp_get_clo_for_invoice 4968 4968 0 0.00 0 30250 361 0 0
sp_kill_cost_storno 17331 17331 0 0.00 0 10827 53 0 0
sp_kill_qstorno_ret_qs2qd 1188760 1188760 0 0.00 0 36525 29 0 0
sp_kill_qty_storno 625751 625751 0 0.00 0 51512 64 0 0
sp_lock_selected_doc 20769 20769 0 0.00 0 8435 5 0 0
sp_make_cost_storno 27885 27885 0 0.00 0 18582 50 0 0
sp_multiply_rows_for_pdistr 27876 27876 0 0.00 0 13011 27 0 0
sp_multiply_rows_for_qdistr 20560 20560 0 0.00 0 18219 186 0 0
sp_pay_from_customer 7095 7095 0 0.00 4 14603 107 0 0
sp_pay_to_supplier 5870 5870 0 0.00 4 15775 111 0 0
sp_payment_common 12959 12959 0 0.00 1 10507 24 0 0
sp_qd_handle_on_cancel_clo 1812 1812 0 0.00 0 15431 343 0 0
sp_qd_handle_on_invoice_upd_sts 10944 10944 0 0.00 0 51512 322 0 0
sp_qd_handle_on_reserve_upd_sts 9947 9947 0 0.00 0 15274 62 0 0
sp_reserve_write_off 9952 9952 0 0.00 6 15498 173 0 0
sp_supplier_invoice 5431 5431 0 0.00 5 121031 3138 0 0
sp_supplier_order 5553 5553 0 0.00 5 50371 1236 0 0
srv_aggregate_perf_data 95 95 0 0.00 2604 341937 19555 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2127 138656 45659 0 0
srv_find_qd_qs_mism 1085638 1085638 0 0.00 0 13649 5 0 0
srv_make_invnt_saldo 550 550 0 0.00 41 21566 962 0 0
srv_make_money_saldo 778 778 0 0.00 1 18994 51 0 0
srv_recalc_idx_stat 4 4 0 0.00 171893 467880 317793 0 0
t$perf-abend:sp_add_invoice_to_stock 163 163 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4977 4977 0 0.00 0 25754 185 0 0
t$perf-norm:sp_cancel_adding_invoice 392 392 0 0.00 0 1855 62 0 0
t$perf-norm:sp_cancel_client_order 1812 1812 0 0.00 0 2071 12 0 0
t$perf-norm:sp_cancel_customer_reserve 678 678 0 0.00 0 4402 28 0 0
t$perf-norm:sp_cancel_pay_from_customer 664 664 0 0.00 0 6922 21 0 0
t$perf-norm:sp_cancel_pay_to_supplier 435 435 0 0.00 0 21707 84 0 0
t$perf-norm:sp_cancel_supplier_invoice 439 439 0 0.00 0 529 10 0 0
t$perf-norm:sp_cancel_supplier_order 441 441 0 0.00 0 20501 1454 0 0
t$perf-norm:sp_cancel_write_off 1003 1003 0 0.00 0 3237 11 0 0
t$perf-norm:sp_client_order 20560 20560 0 0.00 0 20495 16 0 0
t$perf-norm:sp_customer_reserve 975 975 0 0.00 0 3850 10 0 0
t$perf-norm:sp_pay_from_customer 7095 7095 0 0.00 0 7465 11 0 0
t$perf-norm:sp_pay_to_supplier 5870 5870 0 0.00 0 8479 11 0 0
t$perf-norm:sp_reserve_write_off 9953 9953 0 0.00 0 17251 13 0 0
t$perf-norm:sp_supplier_invoice 5431 5431 0 0.00 0 10822 20 0 0
t$perf-norm:sp_supplier_order 5553 5553 0 0.00 0 6293 15 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 206 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 276 5 0 0
t$perf-norm:srv_make_invnt_saldo 550 550 0 0.00 0 800 17 0 0
t$perf-norm:srv_make_money_saldo 778 778 0 0.00 0 2057 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 15 5 0 0
v_add_invoice_to_stock 10242 9916 326 3.18 0 6906 18 0 326
v_all_customers 37892 37892 0 0.00 0 64 0 0 0
v_all_suppliers 30470 30470 0 0.00 0 16 1 0 0
v_all_wares 41116 41116 0 0.00 0 44 1 0 0
v_cancel_adding_invoice 784 784 0 0.00 0 1417 8 0 0
v_cancel_client_order 3624 3624 0 0.00 0 2008 5 0 0
v_cancel_customer_prepayment 1328 1328 0 0.00 0 1723 10 0 0
v_cancel_customer_reserve 1356 1356 0 0.00 0 1763 6 0 0
v_cancel_payment_to_supplier 870 870 0 0.00 0 482 3 0 0
v_cancel_supplier_invoice 908 878 30 3.30 0 4432 24 0 30
v_cancel_supplier_order 882 882 0 0.00 0 4426 11 0 0
v_cancel_write_off 2006 2006 0 0.00 0 1867 12 0 0
v_max_id_clo_ord 5549 5549 0 0.00 0 4133 2 0 0
v_max_id_clo_res 975 975 0 0.00 0 4729 20 0 0
v_max_id_ord_sup 5431 5431 0 0.00 0 6647 7 0 0
v_max_non_paid_invoice 1614 1614 0 0.00 0 222 2 0 0
v_max_non_paid_realizn 3560 3560 0 0.00 0 3320 6 0 0
v_min_id_clo_ord 5549 5549 0 0.00 0 4313 6 0 0
v_min_id_clo_res 975 975 0 0.00 0 10948 18 0 0
v_min_id_ord_sup 5431 5431 0 0.00 0 8459 13 0 0
v_min_non_paid_invoice 5869 5869 0 0.00 0 8755 15 0 0
v_min_non_paid_realizn 7090 7090 0 0.00 0 7979 17 0 0
v_our_firm 10284 10284 0 0.00 0 14 1 0 0
v_reserve_write_off 19890 19890 0 0.00 0 4490 6 0 0
x_lock_depdocs_on_canc_invoice 12184 12184 0 0.00 0 15613 113 0 0
x_lock_depdocs_on_canc_sup_ord 441 441 0 0.00 0 15476 384 0 0
x_make_qstorno_1000_1200 5553 5553 0 0.00 1 50354 1163 0 0
x_make_qstorno_1200_2000 5431 5431 0 0.00 2 120892 2978 0 0
x_make_qstorno_2100_3300 459608 459608 0 0.00 0 45891 150 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-02-2023 16:40:39 to 17-02-2023 16:40:43.

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:08:42 4 8192000000 8801898928 8814301184 7744544 0.094 3 2 2 0 7847664 60336 42784
14:09:43 26499 8192000000 9903923376 10215317504 989370336 10.775 100 87 173 0 1017956448 6172432 22413680
14:11:19 17475 8192000000 9942547120 10263494656 1024880096 11.119 100 88 176 0 1056168640 8673744 22614800
14:12:48 11353 8192000000 9974875920 10306437120 1064101136 11.496 100 91 182 0 1097082720 10493664 22487920
14:14:09 8965 8192000000 10035326384 10381512704 1116910112 11.998 100 77 153 0 1146212240 11203392 18098736
14:15:20 3830 8192000000 10080488224 10438672384 1166847568 12.467 100 77 153 0 1196639152 12636288 17155296
14:16:27 3731 8192000000 10121860784 10493661184 1217167888 12.935 100 69 138 1 1243626352 11524640 14933824
14:17:34 2146 8192000000 10167099648 10543996928 1253741152 13.273 100 86 168 3 1287194144 13985120 19467872
14:18:38 3256 8192000000 10192130240 10572472320 1272082480 13.441 100 80 160 0 1303522160 13406624 18033056
14:19:42 3789 8192000000 10198327088 10597957632 1298554304 13.682 100 56 110 1 1322063472 11439040 12070128
14:20:47 10822 8192000000 10177579232 10596044800 1301163776 13.706 100 18 33 0 1310843664 5332016 4347872
14:22:00 7076 8192000000 10195714656 10605142016 1306196960 13.752 100 29 60 1 1318472864 5888048 6387856
14:23:09 11212 8192000000 10201163472 10619838464 1319739696 13.874 100 15 30 0 1328595840 5520496 3335648
14:24:20 3907 8192000000 10212264960 10633048064 1327698656 13.946 100 22 44 0 1338790112 5870960 5220496
14:25:24 25861 8192000000 10222303296 10645987328 1330751904 13.974 100 18 34 0 1340184592 5667872 3764816
14:26:53 7494 8192000000 10231491552 10651832320 1336540960 14.026 100 37 72 0 1350428896 4943520 8944416
14:28:00 9131 8192000000 10190827344 10607022080 1268778704 13.410 97 20 40 0 1279123904 6370736 3974464
14:29:10 67559 8192000000 10192423856 10598854656 1240156624 13.148 94 27 50 2 1251117328 5229856 5730848
14:31:18 7309 8192000000 10155909712 10560618496 1251406000 13.251 99 24 44 1 1261897552 5563360 4928192
14:32:26 5132 8192000000 10141428960 10540576768 1210320736 12.872 96 29 58 0 1223344896 6215344 6808816
14:33:32 7780 8192000000 10162457760 10570964992 1272731680 13.447 100 23 45 1 1283229392 5299328 5198384
14:34:42 37993 8192000000 10182676624 10594103296 1288360912 13.589 100 27 54 1 1299623184 5379392 5882880
14:36:24 8949 8192000000 10196751680 10610393088 1301928144 13.713 100 22 45 0 1313301536 6557248 4816144
14:37:34 9320 8192000000 10199984336 10619727872 1306213456 13.752 100 17 35 0 1319439856 9830144 3396256
14:38:47 106859 8192000000 10247420784 10664775680 1334625504 14.009 100 30 60 0 1349803872 8618224 6560144
14:41:38 12976 8192000000 10278025216 10693517312 1364658688 14.279 100 54 106 1 1391406704 14772864 11975152
14:42:55 17026 8192000000 10279779968 10706227200 1374010464 14.363 100 44 87 0 1399775392 15489024 10275904
14:44:15 18397 8192000000 10301595552 10726871040 1380226992 14.419 100 48 96 0 1410252160 19106320 10918848
14:45:37 14877 8192000000 10302607024 10725982208 1383952864 14.452 100 47 92 1 1414714320 19707360 11054096
14:47:02 7362 8192000000 10328223008 10752479232 1404458208 14.635 100 46 92 1 1438937344 23028080 11451056
14:48:14 7967 8192000000 10329972640 10748555264 1398345264 14.580 99 64 127 1 1443285648 29119088 15821296
14:49:32 4124 8192000000 10337932000 10759704576 1408978048 14.675 100 58 112 0 1454064640 30779808 14306784
14:50:41 8297 8192000000 10373796368 10792812544 1424580368 14.813 100 73 144 3 1481441920 39126592 17734960
14:52:00 5281 8192000000 10335157008 10755268608 1386855248 14.478 99 57 110 1 1437297216 36347024 14094944
14:53:10 3358 8192000000 10336160848 10745843712 1385655712 14.467 100 76 150 1 1442959200 38925184 18378304
14:54:24 2842 8192000000 10343677104 10746195968 1380526864 14.421 100 87 172 1 1451988672 49112464 22349344
14:55:31 2126 8192000000 10347520624 10757275648 1381992768 14.434 100 68 136 1 1451145424 50746512 18406144
14:56:39 8453 8192000000 10304246640 10728026112 1355009216 14.193 99 33 66 1 1411694624 47491584 9193824
14:58:03 16921 8192000000 10314890800 10738950144 1376880064 14.389 100 30 61 1 1420509600 35193552 8435984
14:59:28 20177 8192000000 10303031456 10739720192 1371849008 14.344 100 28 56 0 1418053280 38141888 8062384
15:00:54 28651 8192000000 10296292960 10730627072 1363180752 14.266 100 32 64 0 1404791520 33084208 8526560
15:02:25 34828 8192000000 10256740768 10692337664 1314091776 13.823 98 22 44 0 1349819152 30168864 5558512
15:04:06 28388 8192000000 10264272912 10704867328 1336678672 14.027 99 11 22 0 1351394112 11333264 3382176
15:05:40 58967 8192000000 10274498416 10708938752 1306908512 13.758 96 35 68 3 1333000304 18236944 7854848
15:07:49 87026 8192000000 10276324448 10707767296 1328246160 13.951 98 13 26 1 1359575696 28409024 2920512
15:10:20 138656 8192000000 10286413664 10731044864 1369305488 14.321 100 34 68 1 1393852832 16086960 8460384
15:13:44 31311 8192000000 10292631680 10722066432 1335549888 14.017 97 43 88 0 1356193856 10108576 10535392
15:15:16 52094 8192000000 10316245392 10750951424 1388198576 14.490 100 30 58 1 1407856624 12597472 7060576
15:17:13 67933 8192000000 10298645680 10765348864 1377093072 14.391 99 23 46 0 1404678768 21557888 6027808
15:20:11 50530 8192000000 10297010752 10754895872 1389814480 14.504 100 11 22 0 1410912240 17907888 3189872
15:22:03 41056 8192000000 10292841056 10752819200 1394915072 14.550 100 11 22 0 1405669952 8315216 2439664
15:23:46 61476 8192000000 10295995504 10753019904 1394166288 14.543 100 19 38 0 1405726048 6735808 4823952
15:25:48 56143 8192000000 10296319712 10758479872 1392228896 14.526 99 34 66 1 1407899648 7869856 7800896
15:27:44 3809 8192000000 10307766352 10765799424 1396699360 14.566 99 27 54 1 1414226928 11170704 6356864
15:28:49 50507 8192000000 10314642912 10776653824 1412125488 14.703 100 20 40 0 1430123968 13129264 4869216
15:30:40 52291 8192000000 10305866448 10761125888 1395513536 14.555 99 16 33 0 1414299776 14536464 4249776
15:32:34 81114 8192000000 10295050992 10770702336 1388421408 14.492 99 16 32 0 1405557664 13405344 3730912
15:34:56 75746 8192000000 10286399536 10731700224 1318828800 13.866 94 23 42 0 1333971776 10504752 4638224
15:37:12 50865 8192000000 10303884592 10747797504 1358834848 14.227 97 38 73 0 1375531904 8825536 7871520
15:39:03 56748 8192000000 10308598640 10766819328 1373889232 14.362 98 13 26 0 1389536432 12865824 2781376
15:41:00 50186 8192000000 10269757248 10724458496 1331552288 13.981 96 21 42 0 1345548528 10037488 3958752
15:43:06 84222 8192000000 10283129008 10730889216 1299272736 13.689 94 20 40 0 1315539792 12249648 4017408
15:45:33 46309 8192000000 10283711968 10728767488 1353177680 14.176 98 22 44 0 1373391536 15433216 4780640
15:48:50 43724 8192000000 10254003344 10688294912 1317090928 13.850 97 29 58 0 1332040992 8638880 6311184
15:50:44 64638 8192000000 10245053712 10702635008 1334453312 14.007 98 22 44 0 1348423728 8988048 4982368
15:53:07 63424 8192000000 10270103808 10720874496 1333334480 13.997 97 20 40 0 1355962144 18086864 4540800
15:55:11 58062 8192000000 10246180944 10693373952 1307706432 13.765 97 18 36 0 1334178432 22206560 4265440
15:57:09 54393 8192000000 10249295408 10706296832 1330932656 13.976 99 17 34 2 1346395888 11358288 4104944
15:59:04 45374 8192000000 10264678656 10699784192 1334219088 14.005 99 30 60 0 1354689824 13589920 6880816
16:00:49 44828 8192000000 10263695680 10712272896 1356572336 14.207 100 18 37 0 1370574320 9446080 4555904
16:02:39 70072 8192000000 10260797648 10711613440 1323438960 13.908 97 21 42 0 1339890480 11590512 4861008
16:04:53 26852 8192000000 10280999328 10717011968 1358748176 14.226 99 34 68 0 1379168592 13048704 7371712
16:06:23 34793 8192000000 10285374480 10721927168 1368696624 14.315 100 34 68 0 1387199440 11323776 7179040
16:08:03 73538 8192000000 10300593792 10755194880 1370612656 14.333 99 17 34 0 1392918528 18705552 3600320
16:10:16 84741 8192000000 10306627792 10759884800 1394937472 14.550 100 25 50 0 1412022144 11900240 5184432
16:12:42 49436 8192000000 10303546016 10766704640 1388673952 14.494 99 15 31 0 1407339264 15507920 3157392
16:14:31 64057 8192000000 10305904176 10771046400 1409022496 14.675 100 8 16 0 1421049648 10551664 1475488
16:16:36 98718 8192000000 10321611088 10796879872 1405820624 14.647 99 30 59 0 1423361008 10508992 7031392
16:19:14 54534 8192000000 10318485296 10777661440 1401008656 14.604 99 29 58 0 1417001792 9847056 6146080
16:21:09 49847 8192000000 10305728912 10770280448 1371057328 14.337 97 8 16 0 1382956416 10267728 1631360
16:23:03 16670 8192000000 10308850192 10758488064 1381560416 14.430 99 21 42 0 1394311664 8297360 4453888
16:24:21 20351 8192000000 10311266800 10751266816 1354555824 14.188 97 36 72 0 1374666096 12518608 7591664
16:25:44 59245 8192000000 10273440384 10720190464 1300076880 13.696 94 9 18 0 1313818432 11774208 1967344
16:27:47 40285 8192000000 10285556768 10736173056 1341916128 14.075 98 10 20 0 1364940016 21132736 1891152
16:29:30 93636 8192000000 10269457184 10719711232 1322493312 13.899 96 28 56 0 1337169744 8652064 6024368
16:32:06 9889 8192000000 10280201120 10729910272 1372278432 14.347 100 21 40 1 1389520368 12989120 4252816
16:33:17 84833 8192000000 10284926400 10745049088 1359982176 14.237 98 14 28 0 1376013952 12630448 3401328
16:36:00 70290 8192000000 10271685376 10725117952 1327979488 13.949 96 21 42 0 1352776544 19348832 5448224

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 17-02-2023 16:40:45 to 17-02-2023 16:41:00.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 163 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-02-2023 16:41:01 to 17-02-2023 16:41:03.

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          239845
MON$OLDEST_ACTIVE               242875
MON$OLDEST_SNAPSHOT             242875
MON$NEXT_TRANSACTION            242875
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                       2040352
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.33665 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33665 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33665 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33665 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 ---
:102: 
:103: mscs-test-161.budzdorov.ru    Fri Feb 17 16:38:50 2023
:104:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:105: 
--- end of diff output ---

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

17.02.23 16:41:04. 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
2023-02-17 16:41:07 No severe PSQL-related problems occured
17.02.23 16:41:08. End of report.