Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.04.2022 01:39
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 16501608 kB
MemAvailable 31609544 kB
Buffers 0 kB
Cached 15225148 kB
SwapCached 2220 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.10
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-04-03 01:38:08
DTS_END                         2022-04-03 01:38:08.6060
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/57778
TRN_ID                          242217
ATT_ID                          677
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: 9412.73 21923 1129527
customer order: creation 173.81 376 20857
customer order: refuse 14.68 337 1761
order to supplier: creation 45.85 1370 5502
order to supplier: removal 3.43 112171 412
invoice (draft): creation 46.25 3346 5550
invoice (draft): removal 54.47 884 6536
invoice accept: apply 41.43 18024 4972
invoice accept: cancel 49.75 903 5970
customer reserve: creation 3826.17 178 459140
customer reserve: removal 4855.75 62 582690
realization accept: apply 82.96 189 9955
realization accept: cancel 88.08 118 10570
payment from customer: creation 59.32 132 7118
payment from customer: removal 5.58 104 670
payment to supplier: creation 49.99 158 5999
payment to supplier: removal 3.42 93 410
service: total inventory turnovers 4.75 1309 570
service: total monetary turnovers 6.15 38 738
service: aggregate perf. data 0.86 17489 103
service: refresh index statistics 0.03 281185 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-04-2022 01:39:34 to 03-04-2022 01:39:36.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5071
TEST_TIME 2 5106
TEST_TIME 3 4872
TEST_TIME 4 4972
TEST_TIME 5 4801
TEST_TIME 6 5083
TEST_TIME 7 4868
TEST_TIME 8 5123
TEST_TIME 9 5034
TEST_TIME 10 5132
TEST_TIME 11 5335
TEST_TIME 12 5640
TEST_TIME 13 5878
TEST_TIME 14 7052
TEST_TIME 15 7665
TEST_TIME 16 7627
TEST_TIME 17 7845
TEST_TIME 18 7814
TEST_TIME 19 8243
TEST_TIME 20 8216
TEST_TIME 21 8504
TEST_TIME 22 8916
TEST_TIME 23 9010
TEST_TIME 24 9071
TEST_TIME 25 9272
TEST_TIME 26 9528
TEST_TIME 27 9500
TEST_TIME 28 9574
TEST_TIME 29 9798
TEST_TIME 30 10743
TEST_TIME 31 11118
TEST_TIME 32 11079
TEST_TIME 33 10971
TEST_TIME 34 10863
TEST_TIME 35 10731
TEST_TIME 36 10498
TEST_TIME 37 10432
TEST_TIME 38 10457
TEST_TIME 39 10366
TEST_TIME 40 10317
TEST_TIME 41 10233
TEST_TIME 42 10215
TEST_TIME 43 10117
TEST_TIME 44 9980
TEST_TIME 45 9987
TEST_TIME 46 10005
TEST_TIME 47 10135
TEST_TIME 48 9996
TEST_TIME 49 9918
TEST_TIME 50 10047
TEST_TIME 51 9958
TEST_TIME 52 10073
TEST_TIME 53 10047
TEST_TIME 54 9956
TEST_TIME 55 10019
TEST_TIME 56 10038
TEST_TIME 57 10124
TEST_TIME 58 10026
TEST_TIME 59 10022
TEST_TIME 60 10130
TEST_TIME 61 10050
TEST_TIME 62 10210
TEST_TIME 63 10242
TEST_TIME 64 10141
TEST_TIME 65 10048
TEST_TIME 66 9967
TEST_TIME 67 9991
TEST_TIME 68 9941
TEST_TIME 69 9870
TEST_TIME 70 9836
TEST_TIME 71 9803
TEST_TIME 72 9762
TEST_TIME 73 9700
TEST_TIME 74 9616
TEST_TIME 75 9622
TEST_TIME 76 9645
TEST_TIME 77 9672
TEST_TIME 78 9639
TEST_TIME 79 9575
TEST_TIME 80 9677
TEST_TIME 81 9664
TEST_TIME 82 9611
TEST_TIME 83 9622
TEST_TIME 84 9711
TEST_TIME 85 9776
TEST_TIME 86 9811
TEST_TIME 87 9817
TEST_TIME 88 9786
TEST_TIME 89 9850
TEST_TIME 90 9755
TEST_TIME 91 9734
TEST_TIME 92 9714
TEST_TIME 93 9686
TEST_TIME 94 9625
TEST_TIME 95 9641
TEST_TIME 96 9629
TEST_TIME 97 9673
TEST_TIME 98 9638
TEST_TIME 99 9621
TEST_TIME 100 9695
TEST_TIME 101 9675
TEST_TIME 102 9647
TEST_TIME 103 9640
TEST_TIME 104 9660
TEST_TIME 105 9702
TEST_TIME 106 9737
TEST_TIME 107 9754
TEST_TIME 108 9734
TEST_TIME 109 9717
TEST_TIME 110 9648
TEST_TIME 111 9622
TEST_TIME 112 9596
TEST_TIME 113 9606
TEST_TIME 114 9545
TEST_TIME 115 9492
TEST_TIME 116 9486
TEST_TIME 117 9530
TEST_TIME 118 9467
TEST_TIME 119 9488
TEST_TIME 120 9415

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 03-04-2022 01:39:37 to 03-04-2022 01:39:41.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1555617 1555617 0 0.00 0 15474 6 0 0
doc_list_biud 1555615 1555615 0 0.00 0 23638 30 0 0
sp_add_invoice_to_stock 5136 4972 164 3.19 38 193835 18025 0 164
sp_cancel_adding_invoice 5970 5970 0 0.00 4 80569 903 0 0
sp_cancel_client_order 1761 1761 0 0.00 4 7845 338 0 0
sp_cancel_customer_reserve 582690 582690 0 0.00 1 22220 63 0 0
sp_cancel_pay_from_customer 670 670 0 0.00 3 11031 104 0 0
sp_cancel_pay_to_supplier 410 410 0 0.00 3 6404 93 0 0
sp_cancel_supplier_invoice 6547 6536 11 0.16 2 22746 884 0 11
sp_cancel_supplier_order 412 412 0 0.00 6 1165534 112172 0 0
sp_cancel_write_off 10570 10570 0 0.00 1 12331 119 0 0
sp_client_order 20857 20857 0 0.00 3 48001 377 0 0
sp_customer_reserve 459140 459140 0 0.00 0 71787 179 0 0
sp_fill_shopping_cart 31902 31902 0 0.00 1 11506 35 0 0
sp_fill_shopping_cart_clo_res 459133 459133 0 0.00 0 17773 13 0 0
sp_get_clo_for_invoice 4964 4964 0 0.00 0 10434 254 0 0
sp_kill_cost_storno 17620 17620 0 0.00 0 12290 60 0 0
sp_kill_qstorno_ret_qs2qd 1182898 1182898 0 0.00 0 22626 34 0 0
sp_kill_qty_storno 622856 622856 0 0.00 0 23638 74 0 0
sp_lock_selected_doc 20666 20666 0 0.00 0 11023 6 0 0
sp_make_cost_storno 28036 28036 0 0.00 0 15008 56 0 0
sp_multiply_rows_for_pdistr 28034 28034 0 0.00 0 13186 30 0 0
sp_multiply_rows_for_qdistr 20857 20857 0 0.00 0 47986 171 0 0
sp_pay_from_customer 7118 7118 0 0.00 4 17431 132 0 0
sp_pay_to_supplier 5999 5999 0 0.00 5 15034 158 0 0
sp_payment_common 13117 13117 0 0.00 2 17421 45 0 0
sp_qd_handle_on_cancel_clo 1761 1761 0 0.00 0 7840 318 0 0
sp_qd_handle_on_invoice_upd_sts 10933 10933 0 0.00 0 23637 339 0 0
sp_qd_handle_on_reserve_upd_sts 9954 9954 0 0.00 0 22308 64 0 0
sp_reserve_write_off 9955 9955 0 0.00 6 22331 190 0 0
sp_supplier_invoice 5550 5550 0 0.00 6 74062 3347 0 0
sp_supplier_order 5502 5502 0 0.00 5 38436 1371 0 0
srv_aggregate_perf_data 103 103 0 0.00 2565 120283 17490 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1782 127164 42181 0 0
srv_find_qd_qs_mism 1082142 1082142 0 0.00 0 13766 5 0 0
srv_make_invnt_saldo 570 570 0 0.00 62 118532 1310 0 0
srv_make_money_saldo 738 738 0 0.00 1 5216 38 0 0
srv_recalc_idx_stat 4 4 0 0.00 140439 399059 281186 0 0
t$perf-abend:sp_add_invoice_to_stock 164 164 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4973 4973 0 0.00 0 10956 181 0 0
t$perf-norm:sp_cancel_adding_invoice 451 451 0 0.00 0 12631 141 0 0
t$perf-norm:sp_cancel_client_order 1761 1761 0 0.00 0 2563 13 0 0
t$perf-norm:sp_cancel_customer_reserve 682 682 0 0.00 0 7949 31 0 0
t$perf-norm:sp_cancel_pay_from_customer 670 670 0 0.00 0 1939 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 410 410 0 0.00 0 1434 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 373 373 0 0.00 0 2914 27 0 0
t$perf-norm:sp_cancel_supplier_order 412 412 0 0.00 0 29477 1840 0 0
t$perf-norm:sp_cancel_write_off 991 991 0 0.00 0 12243 42 0 0
t$perf-norm:sp_client_order 20857 20857 0 0.00 0 12178 23 0 0
t$perf-norm:sp_customer_reserve 995 995 0 0.00 0 4151 25 0 0
t$perf-norm:sp_pay_from_customer 7118 7118 0 0.00 0 12293 26 0 0
t$perf-norm:sp_pay_to_supplier 5999 5999 0 0.00 0 7546 17 0 0
t$perf-norm:sp_reserve_write_off 9955 9955 0 0.00 0 12242 17 0 0
t$perf-norm:sp_supplier_invoice 5550 5550 0 0.00 0 5762 21 0 0
t$perf-norm:sp_supplier_order 5502 5502 0 0.00 0 6968 19 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 376 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 115 2 0 0
t$perf-norm:srv_make_invnt_saldo 570 570 0 0.00 0 14252 52 0 0
t$perf-norm:srv_make_money_saldo 738 738 0 0.00 0 2899 21 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 7 4 0 0
v_add_invoice_to_stock 10252 9924 328 3.19 0 5095 21 0 328
v_all_customers 38268 38268 0 0.00 0 15 1 0 0
v_all_suppliers 30968 30968 0 0.00 0 188 1 0 0
v_all_wares 41714 41714 0 0.00 0 48 1 0 0
v_cancel_adding_invoice 902 902 0 0.00 0 1291 9 0 0
v_cancel_client_order 3522 3522 0 0.00 0 2534 4 0 0
v_cancel_customer_prepayment 1340 1340 0 0.00 0 883 5 0 0
v_cancel_customer_reserve 1364 1364 0 0.00 0 1298 6 0 0
v_cancel_payment_to_supplier 820 820 0 0.00 0 1781 8 0 0
v_cancel_supplier_invoice 768 746 22 2.86 0 3831 24 0 22
v_cancel_supplier_order 824 824 0 0.00 0 1877 7 0 0
v_cancel_write_off 1982 1982 0 0.00 0 2363 13 0 0
v_max_id_clo_ord 5502 5502 0 0.00 0 2138 2 0 0
v_max_id_clo_res 995 995 0 0.00 0 1863 13 0 0
v_max_id_ord_sup 5543 5543 0 0.00 0 5067 4 0 0
v_max_non_paid_invoice 1560 1560 0 0.00 0 1150 3 0 0
v_max_non_paid_realizn 3676 3676 0 0.00 0 2945 5 0 0
v_min_id_clo_ord 5502 5502 0 0.00 0 4368 7 0 0
v_min_id_clo_res 995 995 0 0.00 0 1484 5 0 0
v_min_id_ord_sup 5543 5543 0 0.00 0 4024 5 0 0
v_min_non_paid_invoice 5999 5999 0 0.00 0 14596 36 0 0
v_min_non_paid_realizn 7118 7118 0 0.00 0 17415 33 0 0
v_our_firm 10330 10330 0 0.00 0 36 1 0 0
v_reserve_write_off 19908 19908 0 0.00 0 4767 8 0 0
x_lock_depdocs_on_canc_invoice 12133 12133 0 0.00 0 15576 127 0 0
x_lock_depdocs_on_canc_sup_ord 412 412 0 0.00 0 15514 417 0 0
x_make_qstorno_1000_1200 5502 5502 0 0.00 1 38340 1292 0 0
x_make_qstorno_1200_2000 5550 5550 0 0.00 3 74053 3228 0 0
x_make_qstorno_2100_3300 459139 459139 0 0.00 0 71785 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-04-2022 01:39:42 to 03-04-2022 01:39:46.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
23:08:12 1 8192000000 8797672096 8808464384 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:13 29514 8192000000 9893613952 10199613440 972507440 10.611 100 91 182 0 1002878736 5867360 24503936
23:10:50 26378 8192000000 9943141744 10262384640 1024268832 11.113 100 92 184 0 1056618960 8375504 23974624
23:12:24 14289 8192000000 9997787680 10331025408 1077922928 11.628 100 82 164 0 1107731152 9597968 20210256
23:13:43 13774 8192000000 10051270928 10400436224 1127405264 12.097 100 79 156 0 1156467744 10912800 18149680
23:15:04 8634 8192000000 10090964624 10453102592 1183694320 12.625 100 79 158 0 1214841872 13096576 18050976
23:16:15 4691 8192000000 10149204784 10517934080 1232512384 13.077 100 80 160 0 1262952016 12927344 17512288
23:17:21 3151 8192000000 10163364336 10550755328 1263244224 13.360 100 69 138 1 1291383184 13129520 15009440
23:18:26 1989 8192000000 10212113264 10595160064 1289964768 13.604 100 93 185 0 1324315232 14354368 19996096
23:19:29 22956 8192000000 10197720224 10600255488 1302179936 13.715 100 47 95 1 1321258096 8615184 10462976
23:20:55 6486 8192000000 10195467696 10611236864 1313888800 13.821 100 31 63 2 1327384000 6368736 7126464
23:22:02 13517 8192000000 10208496640 10627604480 1327250384 13.942 100 20 38 2 1337215136 5448576 4516176
23:23:16 53928 8192000000 10251862096 10651357184 1334690656 14.010 100 76 152 2 1359048992 6439200 17919136
23:25:10 2613 8192000000 10223516544 10642694144 1321008864 13.886 99 44 86 2 1338300176 7666144 9625168
23:26:14 10774 8192000000 10216287456 10639536128 1318317504 13.861 99 23 46 0 1329169152 5182032 5669616
23:27:26 10773 8192000000 10238294320 10636054528 1315935792 13.840 99 83 165 3 1341859840 5692208 20231840
23:28:37 6403 8192000000 10190711264 10608422912 1293940192 13.640 99 25 49 1 1305543168 5867856 5735120
23:29:49 6717 8192000000 10161525424 10570240000 1251241264 13.250 98 20 40 0 1262198384 6282800 4674320
23:30:59 56187 8192000000 10107500048 10506792960 1143563952 12.249 92 19 37 1 1152846352 5473040 3809360
23:32:57 8980 8192000000 10155831440 10565599232 1250191008 13.240 99 13 26 0 1266718784 13843488 2684288
23:34:07 8779 8192000000 10175378048 10580680704 1280590784 13.518 100 36 70 2 1294610560 5535664 8484112
23:35:17 50686 8192000000 10195550576 10604376064 1295134016 13.651 100 46 92 2 1316086304 9766928 11185360
23:37:11 8886 8192000000 10207956464 10618621952 1309185088 13.779 100 20 41 1 1319555568 5806576 4563904
23:38:24 3027 8192000000 10241626048 10642997248 1320095824 13.878 99 67 135 1 1346699040 11457488 15145728
23:39:33 45853 8192000000 10263272208 10675965952 1349501888 14.143 100 47 94 0 1375063712 15160912 10400912
23:41:22 30066 8192000000 10283166240 10700001280 1363006144 14.264 100 52 104 3 1391761920 16680912 12074864
23:42:57 13734 8192000000 10301583184 10721742848 1386108784 14.471 100 53 106 2 1416860480 18846544 11905152
23:44:14 6757 8192000000 10309246416 10729054208 1386753728 14.477 100 50 98 2 1422965776 24511376 11700672
23:45:25 8278 8192000000 10311747840 10732601344 1392146880 14.525 100 51 101 0 1425171072 20871136 12153056
23:46:36 5810 8192000000 10328639232 10748055552 1405561600 14.644 100 55 109 0 1440409616 21845472 13002544
23:47:47 5334 8192000000 10320092384 10734694400 1382757200 14.441 98 60 120 0 1423087712 25647376 14683136
23:49:02 6742 8192000000 10343923360 10753949696 1402568672 14.618 100 75 150 0 1452809024 32280400 17959952
23:50:15 13070 8192000000 10334727056 10757427200 1388959136 14.497 99 58 116 1 1440098208 35329920 15809152
23:51:35 6301 8192000000 10366385024 10779443200 1404689344 14.637 100 86 171 3 1473941648 47621120 21631184
23:52:46 5687 8192000000 10346277120 10766811136 1400581728 14.600 100 63 126 2 1454843520 38491344 15770448
23:53:57 3473 8192000000 10336754048 10747777024 1388444064 14.492 99 77 148 1 1436698560 30935120 17319376
23:55:07 1781 8192000000 10325470048 10729025536 1357205024 14.212 98 80 161 0 1410666384 33612640 19848720
23:56:11 5686 8192000000 10340638240 10750562304 1380988208 14.425 100 76 149 1 1440945520 41546320 18410992
23:57:22 18936 8192000000 10317330096 10751844352 1375358208 14.375 99 33 67 3 1426084720 41495776 9230736
23:58:46 5732 8192000000 10335635184 10756235264 1365476576 14.286 99 43 86 0 1425084448 47686800 11921072
00:00:00 12728 8192000000 10315507376 10741415936 1352799488 14.173 99 36 72 2 1412650144 50247856 9602800
00:01:28 13602 8192000000 10322995456 10750877696 1370822048 14.334 100 38 76 1 1427827568 46641792 10363728
00:02:49 5949 8192000000 10342233728 10762891264 1386365040 14.473 100 51 103 1 1444566944 44884800 13317104
00:04:05 19597 8192000000 10346504000 10784993280 1382937328 14.443 99 31 62 0 1442123984 49957632 9229024
00:05:37 33276 8192000000 10341593472 10778308608 1389674560 14.503 100 30 60 1 1443578400 45798032 8105808
00:07:13 32521 8192000000 10344874736 10785718272 1371140672 14.337 100 25 50 1 1448194000 69175504 7877824
00:08:50 51689 8192000000 10293735648 10731188224 1324744960 13.920 96 21 43 1 1362391216 32679664 4966592
00:10:45 47889 8192000000 10301241344 10728869888 1351587840 14.162 98 20 41 2 1362789712 6808144 4393728
00:12:34 93827 8192000000 10287544256 10736390144 1341023712 14.067 97 34 69 1 1358108864 8995936 8089216
00:16:05 29990 8192000000 10298832928 10741145600 1371739840 14.343 99 44 86 3 1388652528 6955584 9957104
00:17:42 65811 8192000000 10287544688 10749689856 1383936128 14.452 100 22 44 1 1399605920 10596512 5073280
00:19:58 10781 8192000000 10307449200 10755207168 1388118176 14.489 100 38 76 0 1415645488 18892544 8634768
00:21:10 27126 8192000000 10316552368 10760024064 1381580128 14.431 99 23 45 0 1402569792 15601248 5388416
00:22:38 43893 8192000000 10318926960 10774667264 1402488400 14.617 100 43 86 2 1434078336 21049536 10540400
00:24:25 34829 8192000000 10299763456 10764550144 1375311936 14.375 99 17 34 1 1404400192 24344336 4743920
00:26:01 37352 8192000000 10300880064 10759086080 1394594560 14.547 100 10 20 0 1410853792 13961392 2297840
00:27:44 50015 8192000000 10280632000 10754150400 1375780640 14.379 100 14 28 1 1401829152 22569264 3479248
00:29:49 63406 8192000000 10274883792 10744553472 1354723888 14.190 99 12 24 1 1373888288 16712512 2451888
00:31:53 19343 8192000000 10272926800 10709180416 1318280272 13.861 96 39 78 0 1334109584 7235328 8593984
00:33:15 56397 8192000000 10283653792 10742829056 1349834352 14.146 98 22 44 2 1373630560 18533216 5262992
00:35:13 59109 8192000000 10267482512 10711896064 1286441024 13.572 94 15 30 0 1298931424 9114560 3375840
00:37:38 23505 8192000000 10284248800 10726297600 1342813184 14.083 98 43 86 1 1365040576 12402512 9824880
00:39:43 59511 8192000000 10274324368 10728525824 1336339248 14.024 98 25 51 2 1364726144 22497216 5889680
00:41:43 90180 8192000000 10261740112 10714877952 1313228560 13.815 96 14 28 1 1331450976 15156752 3065664
00:44:14 58430 8192000000 10252140432 10705416192 1337495664 14.035 98 38 76 0 1356878672 10832432 8550576
00:46:19 57448 8192000000 10245741248 10696712192 1314592912 13.828 96 19 38 2 1326419216 7173520 4652784
00:48:18 44463 8192000000 10258312832 10705563648 1339986960 14.057 99 19 38 1 1360146432 16269328 3890144
00:50:04 63984 8192000000 10254121200 10708840448 1309136896 13.778 96 16 32 1 1322245888 8872400 4236592
00:52:08 84093 8192000000 10249240640 10712670208 1343549280 14.089 99 12 22 1 1357935552 12072816 2313456
00:54:33 52184 8192000000 10250437984 10717761536 1334551520 14.008 98 22 44 0 1358144992 18115984 5477488
00:56:38 34434 8192000000 10276542448 10728611840 1362802864 14.263 100 32 60 2 1377783104 7982800 6997440
00:58:21 60206 8192000000 10257191824 10729381888 1346098704 14.112 99 21 42 0 1364876656 14238688 4539264
01:00:41 72101 8192000000 10295478608 10743259136 1363923504 14.273 99 21 42 1 1386194496 17431472 4839520
01:02:54 77471 8192000000 10291720256 10747912192 1353582016 14.180 98 8 16 0 1379687632 24662752 1442864
01:05:12 65376 8192000000 10294290000 10729693184 1327448736 13.944 96 15 29 1 1340520512 9216912 3854864
01:07:19 127163 8192000000 10285998544 10745307136 1350739104 14.154 98 15 30 1 1366793376 12917856 3136416
01:11:00 53594 8192000000 10293923248 10750275584 1393643392 14.538 100 20 40 1 1410036528 11694576 4698560
01:13:00 86824 8192000000 10299782096 10772586496 1396573088 14.564 100 12 24 2 1414645040 15234736 2837216
01:15:29 74126 8192000000 10283412080 10737471488 1344915408 14.102 97 13 26 0 1356990304 9211744 2863152
01:18:00 75896 8192000000 10282771680 10754928640 1364729872 14.280 98 16 32 0 1384169984 15406640 4033472
01:20:31 18376 8192000000 10299892528 10742702080 1379500800 14.412 100 54 107 4 1399945856 7431328 13013728
01:21:50 49898 8192000000 10273813776 10732412928 1340842688 14.065 97 14 27 2 1355854416 11879600 3132128
01:23:40 115357 8192000000 10300796480 10762027008 1334961808 14.012 96 15 30 0 1355895056 17723712 3209536
01:26:38 44677 8192000000 10289751584 10738630656 1342553232 14.080 98 32 64 2 1365293008 15745040 6994736
01:28:35 79014 8192000000 10269082320 10720468992 1344509552 14.098 98 23 46 0 1356509488 6546832 5453104
01:30:56 46722 8192000000 10259532096 10716860416 1355668752 14.198 100 17 31 0 1369469056 9709440 4090864
01:32:48 59291 8192000000 10273379104 10733113344 1365129888 14.283 100 12 24 0 1385509104 17630416 2748800
01:34:53 71926 8192000000 10263892528 10724134912 1339878304 14.056 98 18 37 1 1356844416 12610128 4355984

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 03-04-2022 01:39:48 to 03-04-2022 01:40:03.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 164 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 03-04-2022 01:40:04 to 03-04-2022 01:40:05.

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          241643
MON$OLDEST_ACTIVE               242466
MON$OLDEST_SNAPSHOT             242466
MON$NEXT_TRANSACTION            242466
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                       2052768
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33574 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33574 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33574 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33574 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 ---
:949: 
:950: mscs-test-161.budzdorov.ru    Sun Apr  3 01:38:18 2022
:951:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:952: 
--- end of diff output ---

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

03.04.22 01:40:06. 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-04-03 01:40:09 No severe PSQL-related problems occured
03.04.22 01:40:10. End of report.