Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.12.2022 16:42
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 15291004 kB
MemAvailable 30124092 kB
Buffers 0 kB
Cached 16448504 kB
SwapCached 3432 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.11
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-12-10 16:41:28
DTS_END                         2022-12-10 16:41:28.7730
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/46650
TRN_ID                          237075
ATT_ID                          717
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: 8917.65 23014 1070118
customer order: creation 165.86 336 19903
customer order: refuse 14.17 363 1700
order to supplier: creation 44.52 1218 5342
order to supplier: removal 3.20 99734 384
invoice (draft): creation 43.95 3266 5274
invoice (draft): removal 48.83 785 5859
invoice accept: apply 41.23 19382 4948
invoice accept: cancel 43.80 829 5256
customer reserve: creation 3884.19 189 466103
customer reserve: removal 4345.03 58 521404
realization accept: apply 80.87 178 9704
realization accept: cancel 77.18 106 9262
payment from customer: creation 56.60 115 6792
payment from customer: removal 5.53 108 664
payment to supplier: creation 47.85 128 5742
payment to supplier: removal 3.26 99 391
service: total inventory turnovers 4.26 1337 511
service: total monetary turnovers 6.50 29 780
service: aggregate perf. data 0.79 21951 95
service: refresh index statistics 0.03 310078 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 10-12-2022 16:43:12 to 10-12-2022 16:43:15.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3751
TEST_TIME 2 3816
TEST_TIME 3 3704
TEST_TIME 4 4565
TEST_TIME 5 4275
TEST_TIME 6 4824
TEST_TIME 7 4885
TEST_TIME 8 5276
TEST_TIME 9 5432
TEST_TIME 10 5664
TEST_TIME 11 5923
TEST_TIME 12 6203
TEST_TIME 13 6050
TEST_TIME 14 6425
TEST_TIME 15 6453
TEST_TIME 16 6490
TEST_TIME 17 6584
TEST_TIME 18 6757
TEST_TIME 19 7401
TEST_TIME 20 7428
TEST_TIME 21 7563
TEST_TIME 22 7923
TEST_TIME 23 8462
TEST_TIME 24 8678
TEST_TIME 25 9015
TEST_TIME 26 9099
TEST_TIME 27 9287
TEST_TIME 28 9784
TEST_TIME 29 9761
TEST_TIME 30 10121
TEST_TIME 31 9872
TEST_TIME 32 9704
TEST_TIME 33 9658
TEST_TIME 34 9486
TEST_TIME 35 9395
TEST_TIME 36 9271
TEST_TIME 37 9156
TEST_TIME 38 9046
TEST_TIME 39 8998
TEST_TIME 40 9090
TEST_TIME 41 9111
TEST_TIME 42 8943
TEST_TIME 43 8860
TEST_TIME 44 9040
TEST_TIME 45 8891
TEST_TIME 46 8813
TEST_TIME 47 8791
TEST_TIME 48 8872
TEST_TIME 49 8792
TEST_TIME 50 8677
TEST_TIME 51 8714
TEST_TIME 52 8647
TEST_TIME 53 8580
TEST_TIME 54 8555
TEST_TIME 55 8545
TEST_TIME 56 8623
TEST_TIME 57 8608
TEST_TIME 58 8625
TEST_TIME 59 8662
TEST_TIME 60 8721
TEST_TIME 61 8731
TEST_TIME 62 8716
TEST_TIME 63 8757
TEST_TIME 64 8907
TEST_TIME 65 8944
TEST_TIME 66 8930
TEST_TIME 67 8857
TEST_TIME 68 9004
TEST_TIME 69 9063
TEST_TIME 70 9090
TEST_TIME 71 8973
TEST_TIME 72 8983
TEST_TIME 73 8945
TEST_TIME 74 8950
TEST_TIME 75 8915
TEST_TIME 76 8905
TEST_TIME 77 8886
TEST_TIME 78 8807
TEST_TIME 79 8802
TEST_TIME 80 8763
TEST_TIME 81 8773
TEST_TIME 82 8802
TEST_TIME 83 8775
TEST_TIME 84 8790
TEST_TIME 85 8801
TEST_TIME 86 8818
TEST_TIME 87 8803
TEST_TIME 88 8901
TEST_TIME 89 8894
TEST_TIME 90 8968
TEST_TIME 91 8967
TEST_TIME 92 9051
TEST_TIME 93 9091
TEST_TIME 94 9082
TEST_TIME 95 9058
TEST_TIME 96 8973
TEST_TIME 97 8921
TEST_TIME 98 8945
TEST_TIME 99 8910
TEST_TIME 100 8935
TEST_TIME 101 8935
TEST_TIME 102 8909
TEST_TIME 103 8894
TEST_TIME 104 8917
TEST_TIME 105 8966
TEST_TIME 106 8975
TEST_TIME 107 8998
TEST_TIME 108 8966
TEST_TIME 109 8997
TEST_TIME 110 9062
TEST_TIME 111 9057
TEST_TIME 112 9034
TEST_TIME 113 9051
TEST_TIME 114 9023
TEST_TIME 115 9000
TEST_TIME 116 9006
TEST_TIME 117 8979
TEST_TIME 118 8987
TEST_TIME 119 8933
TEST_TIME 120 8920

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 10-12-2022 16:43:16 to 10-12-2022 16:43:19.

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 1497977 1497977 0 0.00 0 42489 6 0 0
doc_list_biud 1497971 1497971 0 0.00 0 38910 26 0 0
sp_add_invoice_to_stock 5065 4948 117 2.30 21 205626 19382 0 117
sp_cancel_adding_invoice 5256 5256 0 0.00 4 63135 829 0 0
sp_cancel_client_order 1700 1700 0 0.00 4 13543 364 0 0
sp_cancel_customer_reserve 521404 521404 0 0.00 0 31508 58 0 0
sp_cancel_pay_from_customer 664 664 0 0.00 3 15012 109 0 0
sp_cancel_pay_to_supplier 391 391 0 0.00 3 5391 99 0 0
sp_cancel_supplier_invoice 5875 5859 16 0.27 1 19276 785 0 16
sp_cancel_supplier_order 384 384 0 0.00 13 1006042 99734 0 0
sp_cancel_write_off 9262 9262 0 0.00 1 9513 106 0 0
sp_client_order 19903 19903 0 0.00 3 23983 337 0 0
sp_customer_reserve 466103 466103 0 0.00 0 51252 190 0 0
sp_fill_shopping_cart 30503 30503 0 0.00 1 21602 39 0 0
sp_fill_shopping_cart_clo_res 466093 466093 0 0.00 0 19139 15 0 0
sp_get_clo_for_invoice 4938 4938 0 0.00 0 22276 368 0 0
sp_kill_cost_storno 15573 15573 0 0.00 0 14999 57 0 0
sp_kill_qstorno_ret_qs2qd 1058313 1058313 0 0.00 0 31503 32 0 0
sp_kill_qty_storno 558506 558506 0 0.00 0 38910 69 0 0
sp_lock_selected_doc 20272 20272 0 0.00 0 7542 6 0 0
sp_make_cost_storno 27176 27176 0 0.00 0 42487 56 0 0
sp_multiply_rows_for_pdistr 27175 27175 0 0.00 0 14978 27 0 0
sp_multiply_rows_for_qdistr 19900 19900 0 0.00 0 23974 142 0 0
sp_pay_from_customer 6792 6792 0 0.00 4 19065 116 0 0
sp_pay_to_supplier 5742 5742 0 0.00 4 42495 129 0 0
sp_payment_common 12534 12534 0 0.00 1 19047 34 0 0
sp_qd_handle_on_cancel_clo 1700 1700 0 0.00 0 13536 335 0 0
sp_qd_handle_on_invoice_upd_sts 10193 10193 0 0.00 0 38908 328 0 0
sp_qd_handle_on_reserve_upd_sts 9704 9704 0 0.00 0 18727 60 0 0
sp_reserve_write_off 9704 9704 0 0.00 6 18740 179 0 0
sp_supplier_invoice 5274 5274 0 0.00 5 78340 3267 0 0
sp_supplier_order 5342 5342 0 0.00 4 27604 1219 0 0
srv_aggregate_perf_data 95 95 0 0.00 2607 207946 21951 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 945 138233 44137 0 0
srv_find_qd_qs_mism 1021459 1021459 0 0.00 0 17298 6 0 0
srv_make_invnt_saldo 511 511 0 0.00 38 121095 1338 0 0
srv_make_money_saldo 780 780 0 0.00 1 3026 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 195895 501206 310079 0 0
t$perf-abend:sp_add_invoice_to_stock 117 117 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4948 4948 0 0.00 0 13622 164 0 0
t$perf-norm:sp_cancel_adding_invoice 421 421 0 0.00 0 5883 103 0 0
t$perf-norm:sp_cancel_client_order 1700 1700 0 0.00 0 2750 12 0 0
t$perf-norm:sp_cancel_customer_reserve 700 700 0 0.00 0 557 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 664 664 0 0.00 0 595 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 391 391 0 0.00 0 21747 61 0 0
t$perf-norm:sp_cancel_supplier_invoice 411 411 0 0.00 0 4562 23 0 0
t$perf-norm:sp_cancel_supplier_order 384 384 0 0.00 0 52034 1707 0 0
t$perf-norm:sp_cancel_write_off 962 962 0 0.00 0 1456 6 0 0
t$perf-norm:sp_client_order 19903 19903 0 0.00 0 10565 16 0 0
t$perf-norm:sp_customer_reserve 955 955 0 0.00 0 3285 11 0 0
t$perf-norm:sp_pay_from_customer 6792 6792 0 0.00 0 4017 10 0 0
t$perf-norm:sp_pay_to_supplier 5742 5742 0 0.00 0 2556 6 0 0
t$perf-norm:sp_reserve_write_off 9704 9704 0 0.00 0 4830 10 0 0
t$perf-norm:sp_supplier_invoice 5274 5274 0 0.00 0 5639 16 0 0
t$perf-norm:sp_supplier_order 5342 5342 0 0.00 0 2641 17 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 1407 27 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 933 34 0 0
t$perf-norm:srv_make_invnt_saldo 511 511 0 0.00 0 4698 35 0 0
t$perf-norm:srv_make_money_saldo 780 780 0 0.00 0 841 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10108 9874 234 2.31 0 4804 17 0 234
v_all_customers 36208 36208 0 0.00 0 18 1 0 0
v_all_suppliers 29588 29588 0 0.00 0 31 1 0 0
v_all_wares 39800 39800 0 0.00 0 161 1 0 0
v_cancel_adding_invoice 842 842 0 0.00 0 2016 8 0 0
v_cancel_client_order 3400 3400 0 0.00 0 4819 7 0 0
v_cancel_customer_prepayment 1328 1328 0 0.00 0 2875 7 0 0
v_cancel_customer_reserve 1400 1400 0 0.00 0 86 3 0 0
v_cancel_payment_to_supplier 782 782 0 0.00 0 4515 9 0 0
v_cancel_supplier_invoice 854 822 32 3.74 0 5995 23 0 32
v_cancel_supplier_order 768 768 0 0.00 0 379 4 0 0
v_cancel_write_off 1924 1924 0 0.00 0 1344 14 0 0
v_max_id_clo_ord 5335 5335 0 0.00 0 158 1 0 0
v_max_id_clo_res 955 955 0 0.00 0 5135 22 0 0
v_max_id_ord_sup 5268 5268 0 0.00 0 6487 7 0 0
v_max_non_paid_invoice 1551 1551 0 0.00 0 3794 7 0 0
v_max_non_paid_realizn 3664 3664 0 0.00 0 18944 9 0 0
v_min_id_clo_ord 5335 5335 0 0.00 0 5678 9 0 0
v_min_id_clo_res 955 955 0 0.00 0 5722 20 0 0
v_min_id_ord_sup 5268 5268 0 0.00 0 3492 7 0 0
v_min_non_paid_invoice 5742 5742 0 0.00 0 13004 23 0 0
v_min_non_paid_realizn 6792 6792 0 0.00 0 13929 28 0 0
v_our_firm 9848 9848 0 0.00 0 15 1 0 0
v_reserve_write_off 19404 19404 0 0.00 0 8699 6 0 0
x_lock_depdocs_on_canc_invoice 10704 10704 0 0.00 0 17519 113 0 0
x_lock_depdocs_on_canc_sup_ord 384 384 0 0.00 0 6630 330 0 0
x_make_qstorno_1000_1200 5342 5342 0 0.00 1 27585 1143 0 0
x_make_qstorno_1200_2000 5274 5274 0 0.00 2 78326 3119 0 0
x_make_qstorno_2100_3300 466103 466103 0 0.00 0 43000 172 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 10-12-2022 16:43:20 to 10-12-2022 16:43:25.

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:11:32 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:12:33 20685 8192000000 9894121280 10203041792 976808016 10.653 100 90 181 0 1007413136 5827696 24777424
14:13:56 17126 8192000000 9941730544 10261020672 1023982640 11.110 100 92 184 0 1055910528 7770096 24157792
14:15:21 9913 8192000000 9969115808 10296602624 1056310288 11.421 100 84 168 0 1087188032 9645600 21232144
14:16:34 8909 8192000000 10017197280 10365620224 1102835280 11.865 100 77 154 0 1132036352 10781728 18419344
14:17:47 7208 8192000000 10068330672 10421137408 1152975888 12.337 100 86 172 1 1186148672 12957184 20215600
14:18:59 4247 8192000000 10109810912 10472988672 1193470784 12.716 100 80 161 1 1224474880 12235792 18768304
14:20:05 1965 8192000000 10140426640 10511581184 1228649424 13.042 100 87 171 0 1261250464 13960176 18640864
14:21:09 5166 8192000000 10166624976 10549649408 1258169328 13.313 100 67 132 0 1284075776 11496112 14410336
14:22:16 1116 8192000000 10216908704 10599940096 1298870080 13.685 100 93 187 1 1333989344 14516304 20602960
14:23:21 4849 8192000000 10226836000 10618232832 1314466000 13.827 100 59 119 0 1338988400 11527104 12995296
14:24:28 18023 8192000000 10205720416 10620633088 1322882288 13.903 100 40 78 0 1338856384 6675376 9298720
14:25:51 8714 8192000000 10210643296 10631438336 1329396976 13.962 100 15 31 0 1338129504 5932416 2800112
14:27:01 9082 8192000000 10228442304 10643431424 1338939872 14.048 100 23 46 0 1350690976 6037232 5713872
14:28:12 77746 8192000000 10240891904 10660519936 1342081168 14.076 100 23 46 0 1352912368 5540688 5290512
14:30:31 4892 8192000000 10240065552 10664886272 1346567552 14.117 100 23 45 1 1357365920 5751296 5047072
14:31:38 12283 8192000000 10203753792 10619924480 1276940704 13.485 96 18 36 0 1287039680 5703680 4395296
14:32:52 62413 8192000000 10170866672 10576408576 1226469120 13.021 94 19 38 0 1235934160 4436336 5028704
14:34:55 6528 8192000000 10124752080 10519097344 1208625296 12.856 98 32 65 0 1221723424 5906720 7191408
14:36:01 9248 8192000000 10119591632 10517176320 1199199808 12.769 96 21 42 0 1209374688 5528784 4646096
14:37:14 26834 8192000000 10170893168 10581807104 1277112368 13.487 100 36 70 0 1290392240 4839888 8439984
14:39:06 7614 8192000000 10189563728 10600247296 1297629936 13.674 100 31 63 0 1310397888 6136464 6631488
14:40:13 6505 8192000000 10200171888 10610806784 1301180512 13.706 100 21 42 0 1311155840 5030736 4944592
14:41:20 9772 8192000000 10216846560 10631213056 1321517920 13.890 100 15 30 0 1329326480 4838624 2969936
14:42:39 23487 8192000000 10256770128 10667655168 1342022848 14.076 100 47 93 1 1362304784 10057376 10224560
14:44:05 56357 8192000000 10265295936 10681122816 1350613088 14.153 100 46 92 0 1371918880 10718976 10586816
14:46:07 58702 8192000000 10276592656 10702884864 1374671056 14.369 100 32 62 0 1391792208 9966112 7155040
14:48:13 3474 8192000000 10292625216 10709032960 1377658720 14.396 100 57 114 0 1403768000 12489920 13619360
14:49:21 5372 8192000000 10304279344 10722435072 1386162320 14.472 100 58 115 0 1414035936 14824496 13049120
14:50:34 7891 8192000000 10310021024 10730491904 1387337264 14.482 100 51 102 1 1418008128 17891104 12779760
14:51:47 2345 8192000000 10330601648 10752663552 1408330848 14.669 100 63 125 2 1441015136 18507936 14176352
14:52:54 962 8192000000 10344000112 10762571776 1410574288 14.689 100 70 136 0 1453712688 27584976 15553424
14:53:58 12848 8192000000 10321921792 10745237504 1396676928 14.565 100 49 98 1 1430073440 21378176 12018336
14:55:19 7232 8192000000 10338481152 10760634368 1406562544 14.653 100 53 106 0 1449224688 28845824 13816320
14:56:31 1509 8192000000 10361193728 10782801920 1422477536 14.795 100 57 114 0 1467803504 31514080 13811888
14:57:47 1485 8192000000 10373336544 10785345536 1423065264 14.800 100 73 144 0 1474412672 34056384 17291024
14:58:57 4303 8192000000 10369288912 10785398784 1418297328 14.758 100 69 138 1 1471476368 35484176 17694864
15:00:07 7099 8192000000 10357691824 10778640384 1407344208 14.660 99 58 116 2 1455258688 33770304 14144176
15:01:31 6621 8192000000 10330808688 10748194816 1383633552 14.449 100 54 109 0 1436236528 38508080 14094896
15:02:44 945 8192000000 10316304416 10731085824 1362087776 14.256 100 63 124 0 1418663104 40569952 16005376
15:04:06 6476 8192000000 10323874576 10740129792 1368277552 14.312 100 51 102 2 1428015872 45288224 14450096
15:05:20 5516 8192000000 10298237152 10714161152 1348145504 14.131 99 49 99 1 1394313424 34051632 12116288
15:06:32 10108 8192000000 10302117952 10734346240 1366516144 14.296 100 30 60 0 1410789280 35871520 8401616
15:07:47 21706 8192000000 10292516304 10727768064 1358360240 14.223 100 26 52 0 1395960816 30506608 7093968
15:09:11 61538 8192000000 10246621904 10679341056 1299421888 13.690 97 19 36 2 1329872432 25457728 4992816
15:11:39 38349 8192000000 10255410640 10691604480 1346899968 14.120 100 17 33 0 1361841776 10852576 4089232
15:13:19 79034 8192000000 10250733168 10698022912 1354755888 14.190 100 21 42 0 1368635120 9130128 4749104
15:16:47 39260 8192000000 10284413936 10727677952 1378574992 14.404 100 29 55 0 1395968224 11460480 5932752
15:18:32 80597 8192000000 10283864064 10726383616 1330953456 13.976 96 21 43 0 1353285616 17737168 4594992
15:20:58 59256 8192000000 10296095776 10734206976 1370360976 14.330 100 43 83 1 1396108896 15746400 10001520
15:22:57 65745 8192000000 10296307776 10728325120 1365674800 14.288 99 20 41 0 1381044128 10687824 4681504
15:25:07 55340 8192000000 10288300848 10731462656 1380311344 14.419 100 11 22 1 1390743280 7708272 2723664
15:27:41 40681 8192000000 10303246768 10744266752 1377515728 14.394 99 33 65 0 1398175312 13198384 7461200
15:29:33 45653 8192000000 10282075392 10746757120 1386441376 14.474 100 11 22 0 1399446640 10450192 2555072
15:31:20 93530 8192000000 10301327648 10765443072 1398114320 14.578 100 24 48 0 1413417552 9565648 5737584
15:33:54 87622 8192000000 10307918768 10770436096 1402967792 14.621 100 11 22 0 1413859936 9125040 1767104
15:36:34 58083 8192000000 10305198656 10776195072 1410008416 14.684 100 10 20 0 1427653088 15761728 1882944
15:38:32 46915 8192000000 10300311040 10756567040 1375254032 14.374 98 26 53 0 1389076720 7885104 5937584
15:40:28 53082 8192000000 10295861152 10753200128 1384038624 14.453 99 23 46 0 1402150384 12988496 5123264
15:42:22 68949 8192000000 10288047952 10746462208 1340852384 14.065 96 13 26 1 1355771360 12088304 2830672
15:44:32 54634 8192000000 10293120016 10730721280 1338543488 14.044 96 45 88 0 1367566784 19109216 9914080
15:46:27 70433 8192000000 10280016480 10732646400 1319887328 13.876 95 13 26 0 1335188176 12667616 2633232
15:48:45 18813 8192000000 10301964016 10733453312 1335823184 14.020 97 39 78 0 1364726288 20248464 8654640
15:50:11 121515 8192000000 10279804928 10737651712 1310585072 13.791 96 10 20 0 1338557552 25546112 2426368
15:54:19 10907 8192000000 10296907040 10736738304 1376303616 14.383 100 47 94 1 1398958400 12293600 10361184
15:55:30 63070 8192000000 10255551856 10721247232 1364153280 14.275 100 10 20 0 1374685632 8030608 2501744
15:57:34 58506 8192000000 10247959824 10705981440 1348503008 14.134 99 15 29 0 1359469200 8412176 2554016
15:59:33 72691 8192000000 10272247344 10728034304 1356124896 14.203 99 20 40 0 1373326864 12819728 4382240
16:01:46 63090 8192000000 10256356848 10713653248 1314934480 13.831 96 11 22 0 1330849952 13425792 2489680
16:03:50 31276 8192000000 10252513520 10701967360 1335140336 14.014 98 13 26 0 1343847408 6214016 2493056
16:05:23 31526 8192000000 10265135456 10718330880 1366168064 14.293 100 43 83 0 1385281904 9812560 9301280
16:06:57 28745 8192000000 10244287872 10683916288 1317967456 13.858 97 35 71 2 1335709488 10068592 7673440
16:08:31 47960 8192000000 10280952096 10719854592 1350032656 14.148 99 32 64 0 1372411296 15390672 6987968
16:10:20 78546 8192000000 10284488752 10745012224 1370851888 14.335 100 30 60 0 1391829856 13945296 7032672
16:12:44 59572 8192000000 10273172528 10721714176 1312038000 13.805 96 18 36 0 1326789600 11549968 3201632
16:15:16 89980 8192000000 10292876656 10743181312 1367289152 14.303 99 31 63 0 1390068032 16243840 6535040
16:17:57 30892 8192000000 10298341760 10748395520 1384311872 14.455 100 39 79 0 1406779664 14054688 8413104
16:19:34 45414 8192000000 10297414816 10763337728 1389815184 14.504 100 16 32 0 1409211056 15736832 3659040
16:21:21 90351 8192000000 10295501904 10756304896 1372740064 14.352 99 13 26 0 1386688464 11461696 2486704
16:24:01 69428 8192000000 10291418800 10752024576 1366679312 14.297 99 15 30 0 1385881072 15649488 3552272
16:26:11 64433 8192000000 10307775024 10765262848 1373410512 14.358 99 13 26 0 1391548208 15069040 3068656
16:28:22 47842 8192000000 10281235488 10733477888 1343573760 14.090 97 19 38 0 1360058208 12422128 4062320
16:30:24 71572 8192000000 10295231168 10744459264 1319639408 13.873 95 23 46 1 1341236096 16222656 5374032
16:32:36 46036 8192000000 10287936032 10729951232 1350505888 14.152 98 23 44 1 1366440768 11269504 4665376
16:34:24 27492 8192000000 10267611600 10704138240 1329788688 13.965 97 23 46 1 1343561520 8700368 5072464
16:35:57 138233 8192000000 10250074912 10699751424 1279708224 13.510 94 19 38 0 1293495504 9710208 4077072
16:39:31 63886 8192000000 10234652544 10696302592 1338877072 14.047 99 5 10 0 1349844736 10014976 952688

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 10-12-2022 16:43:27 to 10-12-2022 16:43:42.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 117 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-12-2022 16:43:43 to 10-12-2022 16:43:45.

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          236474
MON$OLDEST_ACTIVE               237191
MON$OLDEST_SNAPSHOT             237191
MON$NEXT_TRANSACTION            237191
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                       2010816
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.33647 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33647 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33647 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33647 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Sat Dec 10 16:41:38 2022
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Sat Dec 10 16:41:39 2022
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:13: 
--- end of diff output ---

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

10.12.22 16:43:46. 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-12-10 16:43:48 No severe PSQL-related problems occured
10.12.22 16:43:50. End of report.