Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.02.2023 01: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 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 15059740 kB
MemAvailable 30398512 kB
Buffers 0 kB
Cached 16611120 kB
SwapCached 5060 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-11 01:38:38
DTS_END                         2023-02-11 01:38:38.3360
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/40364
TRN_ID                          249589
ATT_ID                          649
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: 9744.08 18818 1169290
customer order: creation 183.52 351 22022
customer order: refuse 14.88 286 1785
order to supplier: creation 48.57 1226 5828
order to supplier: removal 3.65 115535 438
invoice (draft): creation 48.79 3409 5855
invoice (draft): removal 55.85 829 6702
invoice accept: apply 43.75 15929 5250
invoice accept: cancel 50.36 856 6043
customer reserve: creation 4049.23 157 485907
customer reserve: removal 4927.12 67 591254
realization accept: apply 88.89 150 10667
realization accept: cancel 92.13 112 11055
payment from customer: creation 61.70 102 7404
payment from customer: removal 6.09 65 731
payment to supplier: creation 52.90 123 6348
payment to supplier: removal 3.70 39 444
service: total inventory turnovers 5.03 992 604
service: total monetary turnovers 6.95 30 834
service: aggregate perf. data 0.96 13999 115
service: refresh index statistics 0.03 222104 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-02-2023 01:39:16 to 11-02-2023 01:39:18.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2352
TEST_TIME 2 2779
TEST_TIME 3 3721
TEST_TIME 4 4167
TEST_TIME 5 4060
TEST_TIME 6 4685
TEST_TIME 7 4761
TEST_TIME 8 4713
TEST_TIME 9 4911
TEST_TIME 10 5279
TEST_TIME 11 5605
TEST_TIME 12 5620
TEST_TIME 13 5901
TEST_TIME 14 5945
TEST_TIME 15 6246
TEST_TIME 16 6502
TEST_TIME 17 7279
TEST_TIME 18 7121
TEST_TIME 19 7470
TEST_TIME 20 7492
TEST_TIME 21 7897
TEST_TIME 22 7763
TEST_TIME 23 7745
TEST_TIME 24 8225
TEST_TIME 25 8456
TEST_TIME 26 9245
TEST_TIME 27 9315
TEST_TIME 28 9574
TEST_TIME 29 9819
TEST_TIME 30 9859
TEST_TIME 31 10581
TEST_TIME 32 11148
TEST_TIME 33 10959
TEST_TIME 34 10827
TEST_TIME 35 10752
TEST_TIME 36 10784
TEST_TIME 37 10638
TEST_TIME 38 10533
TEST_TIME 39 10355
TEST_TIME 40 10440
TEST_TIME 41 10356
TEST_TIME 42 10373
TEST_TIME 43 10323
TEST_TIME 44 10309
TEST_TIME 45 10363
TEST_TIME 46 10190
TEST_TIME 47 10068
TEST_TIME 48 10026
TEST_TIME 49 10032
TEST_TIME 50 9971
TEST_TIME 51 9965
TEST_TIME 52 9895
TEST_TIME 53 9951
TEST_TIME 54 9957
TEST_TIME 55 10016
TEST_TIME 56 9930
TEST_TIME 57 9947
TEST_TIME 58 9879
TEST_TIME 59 9775
TEST_TIME 60 9763
TEST_TIME 61 9731
TEST_TIME 62 9741
TEST_TIME 63 9737
TEST_TIME 64 9807
TEST_TIME 65 9767
TEST_TIME 66 9889
TEST_TIME 67 9960
TEST_TIME 68 9912
TEST_TIME 69 9947
TEST_TIME 70 9963
TEST_TIME 71 9994
TEST_TIME 72 10061
TEST_TIME 73 10028
TEST_TIME 74 9998
TEST_TIME 75 9911
TEST_TIME 76 9908
TEST_TIME 77 9848
TEST_TIME 78 9776
TEST_TIME 79 9784
TEST_TIME 80 9741
TEST_TIME 81 9674
TEST_TIME 82 9711
TEST_TIME 83 9608
TEST_TIME 84 9634
TEST_TIME 85 9645
TEST_TIME 86 9627
TEST_TIME 87 9676
TEST_TIME 88 9773
TEST_TIME 89 9798
TEST_TIME 90 9802
TEST_TIME 91 9779
TEST_TIME 92 9834
TEST_TIME 93 9847
TEST_TIME 94 9910
TEST_TIME 95 9907
TEST_TIME 96 9984
TEST_TIME 97 9984
TEST_TIME 98 9982
TEST_TIME 99 9900
TEST_TIME 100 9881
TEST_TIME 101 9850
TEST_TIME 102 9821
TEST_TIME 103 9841
TEST_TIME 104 9785
TEST_TIME 105 9749
TEST_TIME 106 9776
TEST_TIME 107 9729
TEST_TIME 108 9727
TEST_TIME 109 9714
TEST_TIME 110 9694
TEST_TIME 111 9714
TEST_TIME 112 9743
TEST_TIME 113 9745
TEST_TIME 114 9750
TEST_TIME 115 9727
TEST_TIME 116 9770
TEST_TIME 117 9821
TEST_TIME 118 9813
TEST_TIME 119 9790
TEST_TIME 120 9744

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-02-2023 01:39:20 to 11-02-2023 01:39:23.

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 1621314 1621314 0 0.00 0 17633 6 0 0
doc_list_biud 1621314 1621314 0 0.00 0 35204 30 0 0
sp_add_invoice_to_stock 5363 5250 113 2.10 39 144609 15929 0 113
sp_cancel_adding_invoice 6043 6043 0 0.00 4 72533 856 0 0
sp_cancel_client_order 1785 1785 0 0.00 4 15542 286 0 0
sp_cancel_customer_reserve 591254 591254 0 0.00 0 35215 67 0 0
sp_cancel_pay_from_customer 731 731 0 0.00 2 2564 66 0 0
sp_cancel_pay_to_supplier 444 444 0 0.00 3 2089 40 0 0
sp_cancel_supplier_invoice 6715 6702 13 0.19 1 20847 829 0 13
sp_cancel_supplier_order 438 438 0 0.00 14 1008475 115536 0 0
sp_cancel_write_off 11055 11055 0 0.00 1 10650 112 0 0
sp_client_order 22022 22022 0 0.00 3 32661 351 0 0
sp_customer_reserve 485907 485907 0 0.00 0 69454 158 0 0
sp_fill_shopping_cart 33672 33672 0 0.00 1 19718 38 0 0
sp_fill_shopping_cart_clo_res 485906 485906 0 0.00 0 12867 12 0 0
sp_get_clo_for_invoice 5249 5249 0 0.00 0 29062 269 0 0
sp_kill_cost_storno 18273 18273 0 0.00 0 17553 62 0 0
sp_kill_qstorno_ret_qs2qd 1200703 1200703 0 0.00 0 35200 36 0 0
sp_kill_qty_storno 633193 633193 0 0.00 0 35202 76 0 0
sp_lock_selected_doc 21918 21918 0 0.00 0 7157 5 0 0
sp_make_cost_storno 29668 29668 0 0.00 0 12694 49 0 0
sp_multiply_rows_for_pdistr 29668 29668 0 0.00 0 7699 26 0 0
sp_multiply_rows_for_qdistr 22022 22022 0 0.00 0 13668 122 0 0
sp_pay_from_customer 7404 7404 0 0.00 4 11774 102 0 0
sp_pay_to_supplier 6348 6348 0 0.00 4 13206 124 0 0
sp_payment_common 13752 13752 0 0.00 2 6491 23 0 0
sp_qd_handle_on_cancel_clo 1785 1785 0 0.00 0 15536 260 0 0
sp_qd_handle_on_invoice_upd_sts 11292 11292 0 0.00 0 20834 330 0 0
sp_qd_handle_on_reserve_upd_sts 10667 10667 0 0.00 0 17684 47 0 0
sp_reserve_write_off 10667 10667 0 0.00 6 17780 151 0 0
sp_supplier_invoice 5855 5855 0 0.00 5 85443 3410 0 0
sp_supplier_order 5828 5828 0 0.00 4 85751 1226 0 0
srv_aggregate_perf_data 115 115 0 0.00 2820 90866 14000 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 984 134739 38418 0 0
srv_find_qd_qs_mism 1119803 1119803 0 0.00 0 12467 5 0 0
srv_make_invnt_saldo 604 604 0 0.00 22 47489 993 0 0
srv_make_money_saldo 834 834 0 0.00 1 3932 31 0 0
srv_recalc_idx_stat 4 4 0 0.00 137186 391022 222105 0 0
t$perf-abend:sp_add_invoice_to_stock 113 113 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 5251 5251 0 0.00 0 11706 168 0 0
t$perf-norm:sp_cancel_adding_invoice 440 440 0 0.00 0 3793 97 0 0
t$perf-norm:sp_cancel_client_order 1785 1785 0 0.00 0 4142 16 0 0
t$perf-norm:sp_cancel_customer_reserve 754 754 0 0.00 0 10095 35 0 0
t$perf-norm:sp_cancel_pay_from_customer 731 731 0 0.00 0 5573 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 444 444 0 0.00 0 2152 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 400 400 0 0.00 0 3769 30 0 0
t$perf-norm:sp_cancel_supplier_order 438 438 0 0.00 0 23462 1807 0 0
t$perf-norm:sp_cancel_write_off 1010 1010 0 0.00 0 7166 16 0 0
t$perf-norm:sp_client_order 22022 22022 0 0.00 0 7312 17 0 0
t$perf-norm:sp_customer_reserve 1014 1014 0 0.00 0 4725 16 0 0
t$perf-norm:sp_pay_from_customer 7404 7404 0 0.00 0 9321 11 0 0
t$perf-norm:sp_pay_to_supplier 6348 6348 0 0.00 0 7068 11 0 0
t$perf-norm:sp_reserve_write_off 10667 10667 0 0.00 0 8583 16 0 0
t$perf-norm:sp_supplier_invoice 5855 5855 0 0.00 0 7713 22 0 0
t$perf-norm:sp_supplier_order 5828 5828 0 0.00 0 5667 21 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 2688 30 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 187 4 0 0
t$perf-norm:srv_make_invnt_saldo 604 604 0 0.00 0 2010 17 0 0
t$perf-norm:srv_make_money_saldo 834 834 0 0.00 0 3030 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 73 19 0 0
v_add_invoice_to_stock 10724 10498 226 2.10 0 7953 18 0 226
v_all_customers 40300 40300 0 0.00 0 32 1 0 0
v_all_suppliers 32916 32916 0 0.00 0 23 1 0 0
v_all_wares 44044 44044 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 880 880 0 0.00 0 1114 8 0 0
v_cancel_client_order 3570 3570 0 0.00 0 1905 5 0 0
v_cancel_customer_prepayment 1462 1462 0 0.00 0 1458 7 0 0
v_cancel_customer_reserve 1508 1508 0 0.00 0 2468 7 0 0
v_cancel_payment_to_supplier 888 888 0 0.00 0 209 3 0 0
v_cancel_supplier_invoice 826 800 26 3.14 0 1681 15 0 26
v_cancel_supplier_order 876 876 0 0.00 0 5830 12 0 0
v_cancel_write_off 2020 2020 0 0.00 0 1111 12 0 0
v_max_id_clo_ord 5828 5828 0 0.00 0 1172 2 0 0
v_max_id_clo_res 1014 1014 0 0.00 0 3552 18 0 0
v_max_id_ord_sup 5822 5822 0 0.00 0 2845 4 0 0
v_max_non_paid_invoice 1540 1540 0 0.00 0 3283 4 0 0
v_max_non_paid_realizn 3809 3809 0 0.00 0 4912 4 0 0
v_min_id_clo_ord 5828 5828 0 0.00 0 2809 6 0 0
v_min_id_clo_res 1014 1014 0 0.00 0 2071 5 0 0
v_min_id_ord_sup 5822 5822 0 0.00 0 6144 8 0 0
v_min_non_paid_invoice 6348 6348 0 0.00 0 6424 16 0 0
v_min_non_paid_realizn 7404 7404 0 0.00 0 4845 16 0 0
v_our_firm 10934 10934 0 0.00 0 16 1 0 0
v_reserve_write_off 21334 21334 0 0.00 0 5828 6 0 0
x_lock_depdocs_on_canc_invoice 12345 12345 0 0.00 0 37304 130 0 0
x_lock_depdocs_on_canc_sup_ord 438 438 0 0.00 0 8644 377 0 0
x_make_qstorno_1000_1200 5828 5828 0 0.00 1 84737 1153 0 0
x_make_qstorno_1200_2000 5855 5855 0 0.00 2 85431 3252 0 0
x_make_qstorno_2100_3300 485907 485907 0 0.00 0 69453 143 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-02-2023 01:39:24 to 11-02-2023 01:39:28.

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:42 12 8192000000 8811449936 8827473920 14482480 0.176 8 2 2 0 14592240 66976 42784
23:09:42 25200 8192000000 9901968720 10213253120 982406464 10.708 100 89 178 0 1012873296 6623328 23843504
23:11:12 22030 8192000000 9949541120 10273812480 1028424384 11.153 100 86 172 0 1059767040 9111696 22230960
23:12:43 8692 8192000000 9981901472 10318073856 1066662512 11.520 100 80 158 0 1096266080 9864864 19738704
23:13:55 12381 8192000000 10038712432 10385956864 1121697888 12.043 100 79 158 0 1151128416 10645088 18785440
23:15:12 6739 8192000000 10078988112 10438791168 1163218288 12.433 100 77 154 0 1193514576 12533664 17762624
23:16:23 2198 8192000000 10127119776 10492506112 1212370144 12.891 100 86 172 0 1244646752 13002592 19274016
23:17:28 1748 8192000000 10162926880 10537086976 1250627248 13.244 100 94 187 0 1284610976 13439824 20543904
23:18:32 1203 8192000000 10188803296 10566979584 1271820160 13.438 100 92 184 0 1305941376 13989008 20132208
23:19:35 830 8192000000 10212940688 10597060608 1298058752 13.678 100 88 173 1 1330971616 13597296 19315568
23:20:37 1419 8192000000 10212845664 10604630016 1305216288 13.743 100 82 168 2 1334636608 9390736 20029584
23:21:38 5949 8192000000 10202215520 10613075968 1312890288 13.812 100 30 59 0 1326502944 7022800 6589856
23:22:45 20718 8192000000 10201219328 10618638336 1317123856 13.851 100 13 27 1 1325772032 5782000 2866176
23:24:06 11778 8192000000 10210665376 10626711552 1322685440 13.901 100 14 28 0 1331318288 5808720 2824128
23:25:18 39918 8192000000 10230761808 10640412672 1327490144 13.944 100 49 98 0 1345380000 6055936 11833920
23:26:59 4564 8192000000 10210670688 10627088384 1317697968 13.856 100 26 51 0 1329166896 5709712 5759216
23:28:04 8020 8192000000 10217969776 10640175104 1321310608 13.889 99 16 32 0 1330381344 5636496 3434240
23:29:12 58129 8192000000 10196757200 10615873536 1263018752 13.358 96 20 39 0 1272651360 5096768 4535840
23:31:12 7869 8192000000 10147060880 10549399552 1237033744 13.119 97 28 54 1 1248369936 5473616 5862576
23:32:21 13877 8192000000 10138394576 10534445056 1179951088 12.590 94 15 29 0 1192798560 9423216 3424256
23:33:38 47093 8192000000 10149419536 10557431808 1261476224 13.344 100 18 32 1 1270101040 5200688 3424128
23:35:29 5581 8192000000 10176287184 10583896064 1285314992 13.562 100 16 32 0 1294583936 5708784 3560160
23:36:35 12369 8192000000 10189928208 10603589632 1301485072 13.709 100 14 28 0 1310346384 5471488 3389824
23:37:49 12575 8192000000 10208985472 10615160832 1313675376 13.819 100 25 44 1 1324207696 4912352 5619968
23:39:02 13270 8192000000 10238080176 10648854528 1331914352 13.984 100 57 114 1 1351338496 5252352 14171792
23:40:23 18186 8192000000 10263956144 10677968896 1350890448 14.155 100 54 108 1 1378363712 15107296 12365968
23:41:47 11868 8192000000 10273587264 10690949120 1364702768 14.280 100 42 84 2 1386825920 11746992 10376160
23:43:09 9090 8192000000 10285255168 10705960960 1378192976 14.400 100 36 71 0 1398167488 11501904 8472608
23:44:22 2955 8192000000 10309361104 10728648704 1394349120 14.545 100 59 116 1 1423290512 14505696 14435696
23:45:30 13805 8192000000 10328297280 10751479808 1409029584 14.675 100 53 106 1 1441040176 18516224 13494368
23:46:49 11408 8192000000 10317446928 10740072448 1399155664 14.587 100 38 76 2 1429903040 20905296 9842080
23:48:04 13966 8192000000 10340987184 10765484032 1411804048 14.700 100 45 90 0 1449134528 26035312 11295168
23:49:24 22659 8192000000 10334078464 10756411392 1402247888 14.615 100 52 104 1 1441313728 25872112 13193728
23:50:51 1294 8192000000 10351540128 10763206656 1409220736 14.677 100 83 163 1 1458752224 29183184 20348304
23:51:58 2297 8192000000 10353369664 10766450688 1409651872 14.681 100 76 148 0 1458756192 30346736 18757584
23:53:13 4580 8192000000 10365928800 10784186368 1401650016 14.610 99 64 124 0 1459088848 41262736 16176096
23:54:22 2426 8192000000 10380792304 10795884544 1420957024 14.781 100 67 132 2 1485351552 46401024 17993504
23:55:30 2234 8192000000 10366447792 10784575488 1413436560 14.714 100 71 142 0 1469958688 38309216 18212912
23:56:36 8123 8192000000 10389499904 10806702080 1431225712 14.872 100 71 141 0 1488567280 38707888 18633680
23:57:51 12238 8192000000 10363292032 10791624704 1421310016 14.784 100 56 110 2 1476745296 41069216 14366064
23:59:09 983 8192000000 10362555328 10773839872 1398871936 14.585 99 74 146 0 1463585680 45468592 19245152
00:00:13 9770 8192000000 10351351968 10776969216 1396873168 14.567 99 43 83 0 1450642384 42368784 11400432
00:01:40 5259 8192000000 10368917056 10781990912 1398783136 14.584 100 64 128 3 1468969872 53023680 17163056
00:02:47 8047 8192000000 10366071040 10790965248 1400528016 14.600 100 45 88 0 1468354576 55181024 12645536
00:03:59 10767 8192000000 10365589408 10809651200 1398248864 14.579 100 34 68 0 1471678752 63061264 10368624
00:05:17 21947 8192000000 10336445088 10763702272 1377828720 14.397 99 31 62 0 1430995056 43985200 9181136
00:06:45 39660 8192000000 10316397328 10748997632 1354080464 14.184 98 31 62 1 1411751088 48731248 8939376
00:09:26 22489 8192000000 10293328768 10755276800 1358941232 14.228 100 17 35 1 1400787856 37549296 4297328
00:10:56 102926 8192000000 10267117824 10704879616 1276332448 13.480 94 10 20 0 1291859824 13769792 1757584
00:13:40 79148 8192000000 10272338208 10717368320 1334989552 14.012 98 10 20 0 1353674432 16461968 2222912
00:16:05 27391 8192000000 10271218144 10704822272 1360456192 14.241 100 37 76 0 1375682400 6594752 8631456
00:17:34 67937 8192000000 10287199088 10722639872 1362698768 14.262 100 30 55 0 1380504016 11792352 6012896
00:19:48 8882 8192000000 10297698336 10734911488 1381784736 14.433 100 31 60 0 1397091360 8764816 6541808
00:21:02 69981 8192000000 10300862208 10745004032 1387628960 14.485 100 8 14 0 1397169408 8170320 1370128
00:23:18 64764 8192000000 10303135696 10746396672 1363591232 14.270 98 9 18 0 1379425696 12992480 2841984
00:25:25 24972 8192000000 10311017312 10757664768 1399452896 14.590 100 53 103 1 1420673040 8321904 12898240
00:27:07 51639 8192000000 10295123024 10751991808 1388164480 14.489 99 18 36 0 1400300832 8151872 3984480
00:29:01 31307 8192000000 10296604832 10754048000 1383316576 14.446 99 16 31 0 1394599280 7444112 3838592
00:30:33 28605 8192000000 10326476736 10767601664 1399360496 14.589 100 47 90 2 1420780032 9751808 11667728
00:32:05 12656 8192000000 10327247856 10767294464 1402118064 14.614 100 62 116 3 1425187280 10150128 12919088
00:33:24 43190 8192000000 10305567216 10764509184 1389817184 14.504 99 9 18 0 1400623088 8784208 2021696
00:35:20 33637 8192000000 10299043168 10766712832 1400615216 14.600 100 10 18 0 1412062512 9374976 2072320
00:36:54 51741 8192000000 10302106176 10764177408 1387862976 14.487 99 18 34 0 1400165392 8181296 4121120
00:39:08 35193 8192000000 10286644384 10753892352 1362970592 14.264 97 10 20 0 1371587920 6508064 2109264
00:40:45 90782 8192000000 10290892352 10751729664 1332621632 13.991 95 13 26 0 1347192016 11587760 2982624
00:43:21 30397 8192000000 10303851824 10758279168 1394348736 14.545 100 22 44 0 1411063024 11501888 5212400
00:44:54 69153 8192000000 10286944256 10745929728 1349028240 14.139 97 16 32 1 1365337504 12759696 3549568
00:47:03 45325 8192000000 10268282112 10721640448 1327563488 13.945 97 14 28 0 1343872448 12970080 3338880
00:48:52 48998 8192000000 10267985408 10710290432 1308875392 13.776 96 20 40 2 1327342720 13851552 4615776
00:50:42 54350 8192000000 10282852192 10724151296 1339214944 14.050 98 33 66 0 1355015584 7921568 7879072
00:52:42 127214 8192000000 10249623968 10708717568 1311795040 13.802 96 30 61 0 1327341200 8621648 6924512
00:55:51 13985 8192000000 10244019408 10700562432 1343004160 14.084 100 14 28 0 1355573440 9390624 3178656
00:57:10 74564 8192000000 10235344944 10696036352 1315220784 13.833 97 14 26 1 1326764656 8555104 2988768
00:59:25 64476 8192000000 10241359504 10697748480 1289695936 13.601 95 14 28 0 1306065648 12898336 3471376
01:01:31 47973 8192000000 10252473312 10704482304 1334428736 14.007 99 17 33 0 1351427520 13134032 3864752
01:03:23 70618 8192000000 10279579776 10733608960 1357545536 14.215 100 15 30 0 1384295408 23181120 3568752
01:05:40 84369 8192000000 10261418384 10717839360 1353532752 14.179 100 17 35 0 1373684256 15795280 4356224
01:08:05 20539 8192000000 10297784784 10736685056 1372876112 14.353 100 31 59 0 1387561424 8548416 6136896
01:09:26 63629 8192000000 10302858832 10753871872 1379056992 14.408 100 30 60 0 1398293776 13020784 6216000
01:11:32 77677 8192000000 10305664832 10770931712 1396696944 14.566 100 8 16 0 1411976240 13547696 1731600
01:14:12 29922 8192000000 10328816560 10779811840 1390317712 14.509 99 28 56 0 1411365792 14936032 6112048
01:15:47 134739 8192000000 10322670512 10767048704 1389837568 14.504 99 16 33 1 1403025120 9255952 3931600
01:19:11 48390 8192000000 10306891808 10765373440 1385063648 14.462 99 25 50 1 1399075712 8935728 5076336
01:21:00 46977 8192000000 10295094560 10771030016 1391457696 14.519 99 17 35 0 1405845600 10236880 4151024
01:23:20 31405 8192000000 10265327776 10720247808 1327231328 13.942 95 26 51 1 1347162000 14230320 5700352
01:24:54 53786 8192000000 10269113376 10736582656 1352675200 14.172 98 21 40 0 1366518224 9227632 4615392
01:26:56 53596 8192000000 10262274656 10728304640 1326993904 13.940 96 6 11 0 1335639616 7496144 1149568
01:28:50 70002 8192000000 10287193376 10752851968 1370228576 14.329 99 19 38 0 1387869712 13246080 4395056
01:31:00 24525 8192000000 10267066320 10720382976 1323201952 13.906 96 25 51 0 1336360384 7341472 5816960
01:32:28 29183 8192000000 10260431136 10699669504 1310007024 13.786 97 33 66 0 1325824416 8077744 7739648
01:33:59 56480 8192000000 10269580848 10723758080 1350673984 14.154 100 29 58 0 1378627984 21341776 6612224
01:36:03 64213 8192000000 10239627104 10685415424 1296489008 13.663 96 10 20 0 1307496864 8844880 2162976
01:38:08 20201 8192000000 10263960208 10694438912 1327993488 13.949 99 54 108 0 1351281552 11864800 11423264

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 11-02-2023 01:39:30 to 11-02-2023 01:39:46.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 113 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 11-02-2023 01:39:47 to 11-02-2023 01:39:49.

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          246652
MON$OLDEST_ACTIVE               249731
MON$OLDEST_SNAPSHOT             249731
MON$NEXT_TRANSACTION            249731
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                       2055072
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.33662 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33662 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33662 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33662 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 ---
:141: 
:142: mscs-test-161.budzdorov.ru    Sat Feb 11 01:38:42 2023
:143:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:144: 
--- end of diff output ---

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

11.02.23 01:39:50. 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-11 01:39:53 No severe PSQL-related problems occured
11.02.23 01:39:54. End of report.