Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.04.2022 16: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 16484328 kB
MemAvailable 31540580 kB
Buffers 0 kB
Cached 15257444 kB
SwapCached 2688 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-16 16:38:13
DTS_END                         2022-04-16 16:38:13.3670
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_FROM_CUSTOMER(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/55464
TRN_ID                          242530
ATT_ID                          724
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: 9440.04 17955 1132805
customer order: creation 177.00 343 21240
customer order: refuse 15.27 317 1832
order to supplier: creation 47.00 1266 5640
order to supplier: removal 3.28 97615 393
invoice (draft): creation 46.86 3416 5623
invoice (draft): removal 53.05 790 6366
invoice accept: apply 43.95 16693 5274
invoice accept: cancel 48.04 832 5765
customer reserve: creation 4008.79 167 481055
customer reserve: removal 4680.10 53 561612
realization accept: apply 85.68 177 10281
realization accept: cancel 97.91 105 11749
payment from customer: creation 61.27 103 7352
payment from customer: removal 6.01 86 721
payment to supplier: creation 49.62 121 5954
payment to supplier: removal 3.44 71 413
service: total inventory turnovers 4.92 1032 590
service: total monetary turnovers 7.10 29 852
service: aggregate perf. data 0.74 23003 89
service: refresh index statistics 0.03 212887 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-04-2022 16:40:08 to 16-04-2022 16:40:10.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6443
TEST_TIME 2 6394
TEST_TIME 3 5722
TEST_TIME 4 5753
TEST_TIME 5 6060
TEST_TIME 6 5686
TEST_TIME 7 5608
TEST_TIME 8 6093
TEST_TIME 9 6289
TEST_TIME 10 5988
TEST_TIME 11 6354
TEST_TIME 12 6532
TEST_TIME 13 6818
TEST_TIME 14 6835
TEST_TIME 15 7111
TEST_TIME 16 7154
TEST_TIME 17 7418
TEST_TIME 18 7489
TEST_TIME 19 7877
TEST_TIME 20 8534
TEST_TIME 21 8964
TEST_TIME 22 9738
TEST_TIME 23 9581
TEST_TIME 24 9833
TEST_TIME 25 9735
TEST_TIME 26 9794
TEST_TIME 27 10108
TEST_TIME 28 10914
TEST_TIME 29 10750
TEST_TIME 30 10570
TEST_TIME 31 10544
TEST_TIME 32 10404
TEST_TIME 33 10534
TEST_TIME 34 10503
TEST_TIME 35 10537
TEST_TIME 36 10448
TEST_TIME 37 10284
TEST_TIME 38 10073
TEST_TIME 39 10100
TEST_TIME 40 10274
TEST_TIME 41 10103
TEST_TIME 42 10093
TEST_TIME 43 9994
TEST_TIME 44 9915
TEST_TIME 45 10030
TEST_TIME 46 10038
TEST_TIME 47 9954
TEST_TIME 48 9835
TEST_TIME 49 9914
TEST_TIME 50 9854
TEST_TIME 51 9862
TEST_TIME 52 9781
TEST_TIME 53 9718
TEST_TIME 54 9609
TEST_TIME 55 9513
TEST_TIME 56 9482
TEST_TIME 57 9457
TEST_TIME 58 9444
TEST_TIME 59 9501
TEST_TIME 60 9461
TEST_TIME 61 9565
TEST_TIME 62 9693
TEST_TIME 63 9730
TEST_TIME 64 9750
TEST_TIME 65 9830
TEST_TIME 66 9772
TEST_TIME 67 9816
TEST_TIME 68 9805
TEST_TIME 69 9826
TEST_TIME 70 9975
TEST_TIME 71 9947
TEST_TIME 72 9832
TEST_TIME 73 9799
TEST_TIME 74 9843
TEST_TIME 75 9784
TEST_TIME 76 9754
TEST_TIME 77 9729
TEST_TIME 78 9713
TEST_TIME 79 9647
TEST_TIME 80 9571
TEST_TIME 81 9554
TEST_TIME 82 9571
TEST_TIME 83 9653
TEST_TIME 84 9667
TEST_TIME 85 9642
TEST_TIME 86 9731
TEST_TIME 87 9718
TEST_TIME 88 9734
TEST_TIME 89 9778
TEST_TIME 90 9746
TEST_TIME 91 9760
TEST_TIME 92 9843
TEST_TIME 93 9887
TEST_TIME 94 9870
TEST_TIME 95 9776
TEST_TIME 96 9759
TEST_TIME 97 9694
TEST_TIME 98 9642
TEST_TIME 99 9625
TEST_TIME 100 9572
TEST_TIME 101 9562
TEST_TIME 102 9536
TEST_TIME 103 9522
TEST_TIME 104 9570
TEST_TIME 105 9681
TEST_TIME 106 9652
TEST_TIME 107 9616
TEST_TIME 108 9678
TEST_TIME 109 9668
TEST_TIME 110 9684
TEST_TIME 111 9699
TEST_TIME 112 9688
TEST_TIME 113 9721
TEST_TIME 114 9656
TEST_TIME 115 9613
TEST_TIME 116 9575
TEST_TIME 117 9555
TEST_TIME 118 9553
TEST_TIME 119 9519
TEST_TIME 120 9447

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 16-04-2022 16:40:11 to 16-04-2022 16:40:15.

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 1577253 1577253 0 0.00 0 43737 6 0 0
doc_list_biud 1577251 1577251 0 0.00 0 40173 25 0 0
sp_add_invoice_to_stock 5349 5274 75 1.40 29 187511 16694 0 75
sp_cancel_adding_invoice 5765 5765 0 0.00 5 58321 832 0 0
sp_cancel_client_order 1832 1832 0 0.00 4 14640 317 0 0
sp_cancel_customer_reserve 561612 561612 0 0.00 1 40174 54 0 0
sp_cancel_pay_from_customer 721 721 0 0.00 3 10360 87 0 0
sp_cancel_pay_to_supplier 413 413 0 0.00 3 4117 72 0 0
sp_cancel_supplier_invoice 6373 6366 7 0.10 2 19852 790 0 7
sp_cancel_supplier_order 393 393 0 0.00 6 1003712 97616 0 0
sp_cancel_write_off 11749 11749 0 0.00 1 18800 106 0 0
sp_client_order 21240 21240 0 0.00 3 39634 344 0 0
sp_customer_reserve 481055 481055 0 0.00 0 96208 168 0 0
sp_fill_shopping_cart 32500 32500 0 0.00 1 16117 42 0 0
sp_fill_shopping_cart_clo_res 481047 481047 0 0.00 0 13413 13 0 0
sp_get_clo_for_invoice 5263 5263 0 0.00 0 24904 288 0 0
sp_kill_cost_storno 18648 18648 0 0.00 0 6808 51 0 0
sp_kill_qstorno_ret_qs2qd 1141732 1141732 0 0.00 0 40171 29 0 0
sp_kill_qty_storno 603260 603260 0 0.00 0 40173 64 0 0
sp_lock_selected_doc 21529 21529 0 0.00 0 10353 7 0 0
sp_make_cost_storno 28849 28849 0 0.00 0 43679 51 0 0
sp_multiply_rows_for_pdistr 28849 28849 0 0.00 0 8765 28 0 0
sp_multiply_rows_for_qdistr 21240 21240 0 0.00 0 13963 165 0 0
sp_pay_from_customer 7352 7352 0 0.00 4 10781 104 0 0
sp_pay_to_supplier 5954 5954 0 0.00 4 43747 121 0 0
sp_payment_common 13306 13306 0 0.00 1 9425 30 0 0
sp_qd_handle_on_cancel_clo 1832 1832 0 0.00 0 14632 293 0 0
sp_qd_handle_on_invoice_upd_sts 11027 11027 0 0.00 0 18533 306 0 0
sp_qd_handle_on_reserve_upd_sts 10281 10281 0 0.00 0 19003 56 0 0
sp_reserve_write_off 10281 10281 0 0.00 6 21756 178 0 0
sp_supplier_invoice 5623 5623 0 0.00 5 106975 3416 0 0
sp_supplier_order 5640 5640 0 0.00 5 28682 1267 0 0
srv_aggregate_perf_data 89 89 0 0.00 2694 210714 23003 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1882 141529 44540 0 0
srv_find_qd_qs_mism 1083040 1083040 0 0.00 0 22509 5 0 0
srv_make_invnt_saldo 590 590 0 0.00 22 53331 1033 0 0
srv_make_money_saldo 852 852 0 0.00 1 3685 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 158260 261863 212888 0 0
t$perf-abend:sp_add_invoice_to_stock 75 75 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5274 5274 0 0.00 0 24189 169 0 0
t$perf-norm:sp_cancel_adding_invoice 455 455 0 0.00 0 2223 92 0 0
t$perf-norm:sp_cancel_client_order 1832 1832 0 0.00 0 2718 13 0 0
t$perf-norm:sp_cancel_customer_reserve 687 687 0 0.00 0 3217 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 721 721 0 0.00 0 1913 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 413 413 0 0.00 0 388 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 406 406 0 0.00 0 5371 33 0 0
t$perf-norm:sp_cancel_supplier_order 393 393 0 0.00 0 22195 1677 0 0
t$perf-norm:sp_cancel_write_off 1079 1079 0 0.00 0 2715 9 0 0
t$perf-norm:sp_client_order 21240 21240 0 0.00 0 20611 19 0 0
t$perf-norm:sp_customer_reserve 1034 1034 0 0.00 0 5224 22 0 0
t$perf-norm:sp_pay_from_customer 7352 7352 0 0.00 0 6114 10 0 0
t$perf-norm:sp_pay_to_supplier 5954 5954 0 0.00 0 18910 17 0 0
t$perf-norm:sp_reserve_write_off 10281 10281 0 0.00 0 14138 13 0 0
t$perf-norm:sp_supplier_invoice 5623 5623 0 0.00 0 7882 18 0 0
t$perf-norm:sp_supplier_order 5640 5640 0 0.00 0 16594 17 0 0
t$perf-norm:srv_aggregate_perf_data 89 89 0 0.00 0 67 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 380 8 0 0
t$perf-norm:srv_make_invnt_saldo 590 590 0 0.00 0 2309 28 0 0
t$perf-norm:srv_make_money_saldo 852 852 0 0.00 0 596 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 108 29 0 0
v_add_invoice_to_stock 10674 10524 150 1.40 0 7092 16 0 150
v_all_customers 38856 38856 0 0.00 0 28 1 0 0
v_all_suppliers 30870 30870 0 0.00 0 154 1 0 0
v_all_wares 42480 42480 0 0.00 0 63 1 0 0
v_cancel_adding_invoice 910 910 0 0.00 0 6272 12 0 0
v_cancel_client_order 3664 3664 0 0.00 0 896 3 0 0
v_cancel_customer_prepayment 1442 1442 0 0.00 0 5656 8 0 0
v_cancel_customer_reserve 1374 1374 0 0.00 0 1435 4 0 0
v_cancel_payment_to_supplier 826 826 0 0.00 0 4110 13 0 0
v_cancel_supplier_invoice 826 812 14 1.69 0 1603 14 0 14
v_cancel_supplier_order 786 786 0 0.00 0 2555 7 0 0
v_cancel_write_off 2158 2158 0 0.00 0 6285 18 0 0
v_max_id_clo_ord 5640 5640 0 0.00 0 2233 2 0 0
v_max_id_clo_res 1034 1034 0 0.00 0 4129 11 0 0
v_max_id_ord_sup 5620 5620 0 0.00 0 2832 3 0 0
v_max_non_paid_invoice 1779 1779 0 0.00 0 117 2 0 0
v_max_non_paid_realizn 3938 3938 0 0.00 0 6112 6 0 0
v_min_id_clo_ord 5640 5640 0 0.00 0 9898 19 0 0
v_min_id_clo_res 1034 1034 0 0.00 0 4402 10 0 0
v_min_id_ord_sup 5620 5620 0 0.00 0 6919 17 0 0
v_min_non_paid_invoice 5954 5954 0 0.00 0 8499 17 0 0
v_min_non_paid_realizn 7352 7352 0 0.00 0 9139 26 0 0
v_our_firm 10452 10452 0 0.00 0 19 1 0 0
v_reserve_write_off 20562 20562 0 0.00 0 6565 7 0 0
x_lock_depdocs_on_canc_invoice 11725 11725 0 0.00 0 11096 115 0 0
x_lock_depdocs_on_canc_sup_ord 393 393 0 0.00 0 19271 381 0 0
x_make_qstorno_1000_1200 5640 5640 0 0.00 2 28667 1172 0 0
x_make_qstorno_1200_2000 5623 5623 0 0.00 2 106583 3270 0 0
x_make_qstorno_2100_3300 481055 481055 0 0.00 0 96207 153 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-04-2022 16:40:16 to 16-04-2022 16:40:20.

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:17 4 8192000000 8800694864 8812658688 6887168 0.084 2 1 2 0 7030896 67184 76544
14:09:17 24531 8192000000 9893931584 10207444992 978458704 10.669 100 86 172 0 1008167744 6334256 23374784
14:10:49 16342 8192000000 9940459520 10262728704 1021127200 11.083 100 86 172 0 1052003456 8688880 22187376
14:12:09 26087 8192000000 9991103648 10328129536 1075542720 11.605 100 82 163 0 1104827920 9548032 19737168
14:13:43 13520 8192000000 10038992432 10390224896 1130980640 12.131 100 82 165 0 1162035408 11237840 19816928
14:15:02 7313 8192000000 10085288288 10447761408 1173621104 12.531 100 77 154 2 1203454976 12183408 17650464
14:16:11 6793 8192000000 10126269344 10497097728 1210822736 12.877 100 78 157 0 1242134128 14074976 17236416
14:17:20 6107 8192000000 10159454112 10538037248 1251250880 13.250 100 72 144 1 1279083216 12027760 15804576
14:18:28 2701 8192000000 10196270992 10579181568 1287457488 13.581 100 91 182 0 1321185616 13268304 20459824
14:19:34 2011 8192000000 10219038720 10600562688 1298108752 13.678 100 92 185 2 1333158320 14220576 20828992
14:20:37 5234 8192000000 10213786704 10606940160 1306802288 13.757 100 65 130 0 1332172144 10529376 14840480
14:21:42 10782 8192000000 10196097296 10609008640 1310303984 13.789 100 24 48 2 1321074256 5393904 5376368
14:22:54 5824 8192000000 10221555184 10623746048 1314295056 13.825 100 51 103 0 1331813024 5258048 12259920
14:24:01 2856 8192000000 10221486064 10629959680 1321847200 13.893 100 36 70 2 1337856384 7609456 8399728
14:25:08 4817 8192000000 10217254000 10635300864 1326606544 13.936 100 21 41 1 1338043040 6243744 5192752
14:26:17 13042 8192000000 10212485104 10634383360 1318414352 13.862 99 25 50 1 1329965664 5714672 5836640
14:27:31 10797 8192000000 10212354464 10632949760 1304505216 13.736 98 19 38 1 1314135328 5572560 4057552
14:28:42 6565 8192000000 10197801152 10612645888 1276014848 13.477 97 21 42 1 1286481296 5661888 4804560
14:29:49 6731 8192000000 10163909760 10565427200 1232200240 13.074 96 18 36 1 1241507072 5099456 4207376
14:30:56 15853 8192000000 10158840480 10560585728 1237176992 13.120 97 22 42 2 1248171872 5930064 5064816
14:32:12 18196 8192000000 10114512496 10512764928 1185341104 12.640 94 30 60 2 1198499680 6047568 7111008
14:33:33 5385 8192000000 10168430528 10569502720 1269038672 13.413 100 24 46 1 1280529120 5429184 6061264
14:34:47 9284 8192000000 10193049760 10600116224 1295630688 13.655 100 34 67 1 1309605280 5684016 8290576
14:36:01 56201 8192000000 10192595200 10608680960 1302996448 13.722 100 44 88 0 1319533696 5898144 10639104
14:37:59 5209 8192000000 10206808080 10620190720 1313283824 13.816 100 33 66 0 1326506416 4847248 8375344
14:39:08 3804 8192000000 10253442800 10661396480 1340410480 14.061 100 51 102 1 1360245952 8191712 11643760
14:40:20 13305 8192000000 10254137008 10673004544 1351843936 14.164 100 50 99 3 1373385872 9328464 12213472
14:41:36 45817 8192000000 10272592176 10699399168 1364045376 14.274 100 41 82 0 1384712224 10509712 10157136
14:43:38 16668 8192000000 10283566528 10715754496 1380684608 14.423 100 27 53 0 1402123792 14769168 6670016
14:45:04 16545 8192000000 10309122736 10729275392 1389555728 14.502 100 53 106 1 1418945104 17064896 12324480
14:46:25 9197 8192000000 10326838848 10744381440 1398188288 14.579 100 68 134 3 1438222848 24261648 15772912
14:47:38 4759 8192000000 10336434960 10763112448 1414001168 14.719 100 59 118 1 1450489968 22480384 14008416
14:48:51 3953 8192000000 10350405520 10775674880 1419445872 14.768 100 57 112 3 1458292512 25380672 13465968
14:50:00 1881 8192000000 10355413168 10768408576 1412397184 14.705 100 93 184 0 1461799712 27594256 21808272
14:51:06 6191 8192000000 10348956976 10768732160 1402467568 14.617 99 72 143 0 1446500272 27141408 16891296
14:52:16 3971 8192000000 10356477104 10779869184 1423149952 14.801 100 66 131 0 1465333168 25780240 16402976
14:53:25 2919 8192000000 10368543616 10787147776 1423520464 14.804 100 88 176 0 1476275408 30809376 21945568
14:54:32 6391 8192000000 10366438560 10787479552 1415805664 14.735 99 70 139 1 1462841328 30281712 16753952
14:55:44 3150 8192000000 10336731936 10757050368 1394617840 14.547 100 65 129 1 1444971456 34165808 16187808
14:56:50 2681 8192000000 10330785792 10748092416 1371011248 14.336 99 73 146 1 1428104912 38367696 18725968
14:57:57 9973 8192000000 10327251376 10750877696 1375734752 14.378 100 49 98 0 1436765952 47254992 13776208
14:59:14 14753 8192000000 10304326000 10732130304 1362726640 14.262 100 31 62 1 1411523008 39307472 9488896
15:00:34 8618 8192000000 10304651696 10730225664 1373344064 14.357 100 32 64 1 1405574864 23638816 8591984
15:01:47 24677 8192000000 10285092880 10722758656 1361835680 14.254 100 28 56 1 1395017984 25810560 7371744
15:03:20 14034 8192000000 10286529056 10712514560 1346321232 14.114 99 34 68 1 1380334048 24690352 9322464
15:04:51 36136 8192000000 10279970144 10721284096 1356252640 14.204 100 20 40 1 1404014576 41636624 6125312
15:06:30 79708 8192000000 10270176176 10717241344 1342434048 14.079 99 20 41 1 1373672672 26321856 4916768
15:08:52 44399 8192000000 10282102176 10726744064 1361168448 14.248 99 27 50 2 1374375568 8319728 4887392
15:10:36 71555 8192000000 10292095184 10740736000 1350536224 14.152 98 26 51 0 1365043248 8386160 6120864
15:13:00 141529 8192000000 10292384528 10727481344 1338387344 14.043 97 20 40 1 1360257552 16841136 5029072
15:16:24 67398 8192000000 10301174400 10743128064 1364405680 14.277 99 40 80 2 1387440128 12936112 10098336
15:18:35 83272 8192000000 10275085728 10721038336 1347264096 14.123 98 23 46 1 1359217536 6744912 5208528
15:20:58 26885 8192000000 10286170192 10734333952 1377205344 14.392 100 21 39 1 1392167584 10051872 4910368
15:22:32 31149 8192000000 10297367744 10748194816 1390848768 14.513 100 37 74 1 1415811584 14754256 10208560
15:24:08 37741 8192000000 10285482416 10747318272 1380421408 14.420 100 12 24 1 1397909776 14541040 2947328
15:26:48 70790 8192000000 10282290048 10748588032 1376623712 14.386 99 25 50 0 1395903856 13254432 6025712
15:29:44 38275 8192000000 10292207040 10754072576 1373214176 14.356 99 12 23 2 1392189808 17073888 1901744
15:31:23 36591 8192000000 10277805088 10734346240 1371014368 14.336 99 14 28 0 1381079488 7039312 3025808
15:33:05 61300 8192000000 10274317392 10737459200 1368964848 14.318 99 14 28 0 1382278000 9994128 3319024
15:35:10 56424 8192000000 10278598208 10746056704 1383449648 14.447 100 8 16 0 1397384848 12024240 1910960
15:37:08 47970 8192000000 10288709872 10750001152 1357953184 14.219 97 16 32 0 1375008352 13412672 3642496
15:38:56 42648 8192000000 10282943744 10743754752 1363041456 14.265 98 22 44 1 1385912128 17685808 5184864
15:40:39 21294 8192000000 10264877728 10711601152 1325822720 13.929 97 23 44 0 1341281568 11074000 4384848
15:42:02 51797 8192000000 10281018288 10731024384 1312695440 13.811 96 20 40 1 1331764096 14557792 4510864
15:44:02 26101 8192000000 10292353792 10721042432 1344656016 14.099 98 56 110 2 1365596784 7980688 12960080
15:45:32 53333 8192000000 10271639072 10724577280 1342840080 14.083 98 13 26 0 1355423760 10115360 2468320
15:47:25 68933 8192000000 10281697824 10746863616 1332683760 13.991 97 14 28 1 1359671600 23734272 3253568
15:49:38 106877 8192000000 10235335968 10672582656 1310243632 13.788 96 14 26 0 1323523440 10540672 2739136
15:52:29 65015 8192000000 10255848224 10698878976 1333653472 14.000 98 31 61 1 1351356464 11410896 6292096
15:54:34 80837 8192000000 10238916112 10694885376 1300614736 13.701 96 8 16 0 1314518064 12088736 1814592
15:56:55 73692 8192000000 10278622112 10730401792 1366065360 14.292 100 15 30 2 1379543104 9597104 3880640
15:59:32 80041 8192000000 10274688464 10734960640 1371622000 14.342 100 10 20 0 1389675616 15617952 2435664
16:01:53 42297 8192000000 10268395552 10712600576 1331993664 13.985 97 25 50 1 1351334032 13794384 5545984
16:03:36 75498 8192000000 10276408912 10741133312 1368924576 14.317 100 15 30 0 1385296736 12735712 3636448
16:06:10 74532 8192000000 10271832368 10722660352 1330559760 13.972 97 12 24 1 1348321440 15316048 2445632
16:08:37 68489 8192000000 10314008768 10754097152 1386575312 14.475 100 32 64 0 1407144528 13815456 6753760
16:11:05 115423 8192000000 10293807600 10753560576 1354033360 14.184 97 8 16 0 1371347472 15531024 1783088
16:14:16 47451 8192000000 10283328048 10749808640 1381208656 14.427 99 19 38 0 1394496720 9138736 4149328
16:16:06 62854 8192000000 10282596944 10752937984 1376027088 14.381 99 3 6 0 1386630608 9793408 810112
16:19:04 46044 8192000000 10323150352 10783346688 1401192352 14.606 100 21 42 0 1421745952 15449024 5104576
16:20:53 55680 8192000000 10325175600 10783350784 1409979056 14.684 100 23 47 2 1436358912 21133120 5246736
16:22:51 68164 8192000000 10301696016 10776702976 1367575648 14.305 98 3 6 0 1390807232 22591680 639904
16:25:01 24228 8192000000 10313996832 10757935104 1374627776 14.368 98 43 86 2 1396430336 11580208 10222352
16:26:41 59521 8192000000 10293155152 10740645888 1336736672 14.028 97 23 46 0 1354265008 12454160 5074176
16:28:41 57449 8192000000 10275616256 10736824320 1313458960 13.817 96 15 30 0 1334759120 17995920 3304240
16:30:40 78873 8192000000 10275859408 10724474880 1342745760 14.082 98 16 32 1 1359189264 13107232 3336272
16:34:14 61732 8192000000 10271129424 10719752192 1331635136 13.982 97 29 58 0 1349941936 11509776 6797024
16:36:34 52777 8192000000 10231835984 10682576896 1288733936 13.593 95 13 26 0 1298458704 6969040 2755728

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 16-04-2022 16:40:21 to 16-04-2022 16:40:36.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-04-2022 16:40:37 to 16-04-2022 16:40:39.

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          240347
MON$OLDEST_ACTIVE               242653
MON$OLDEST_SNAPSHOT             242653
MON$NEXT_TRANSACTION            242653
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                       2046880
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.33577 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33577 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33577 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33577 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 ---
:510: 
:511: mscs-test-161.budzdorov.ru    Sat Apr 16 16:38:17 2022
:512:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:513: 
:514: 
:515: mscs-test-161.budzdorov.ru    Sat Apr 16 16:38:24 2022
:516:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:517: 
:518: 
:519: mscs-test-161.budzdorov.ru    Sat Apr 16 16:38:24 2022
:520:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:521: 
:522: 
:523: mscs-test-161.budzdorov.ru    Sat Apr 16 16:38:31 2022
:524:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:525: 
:526: 
:527: mscs-test-161.budzdorov.ru    Sat Apr 16 16:38:31 2022
:528:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:529: 
--- end of diff output ---

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

16.04.22 16:40:40. 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-16 16:40:43 No severe PSQL-related problems occured
16.04.22 16:40:44. End of report.