Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.11.2021 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 16925972 kB
MemAvailable 31576240 kB
Buffers 0 kB
Cached 14781384 kB
SwapCached 0 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.8
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 2021-11-26 01:37:57
DTS_END                         2021-11-26 01:37:57.1330
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/39516
TRN_ID                          239533
ATT_ID                          693
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: 8861.51 21723 1063381
customer order: creation 171.22 364 20546
customer order: refuse 14.75 385 1770
order to supplier: creation 44.63 1429 5356
order to supplier: removal 3.14 93369 377
invoice (draft): creation 44.56 3971 5347
invoice (draft): removal 49.02 709 5882
invoice accept: apply 41.59 18962 4991
invoice accept: cancel 44.32 907 5318
customer reserve: creation 3758.88 191 451066
customer reserve: removal 4404.02 53 528483
realization accept: apply 82.57 195 9908
realization accept: cancel 73.63 110 8836
payment from customer: creation 59.48 109 7138
payment from customer: removal 5.66 96 679
payment to supplier: creation 48.58 121 5830
payment to supplier: removal 3.68 96 441
service: total inventory turnovers 4.55 1239 546
service: total monetary turnovers 6.39 32 767
service: aggregate perf. data 0.80 17677 96
service: refresh index statistics 0.03 294451 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 26-11-2021 01:40:11 to 26-11-2021 01:40:14.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4743
TEST_TIME 2 2808
TEST_TIME 3 2903
TEST_TIME 4 4046
TEST_TIME 5 4439
TEST_TIME 6 5003
TEST_TIME 7 4936
TEST_TIME 8 4773
TEST_TIME 9 4898
TEST_TIME 10 5197
TEST_TIME 11 5491
TEST_TIME 12 5944
TEST_TIME 13 5950
TEST_TIME 14 6172
TEST_TIME 15 6716
TEST_TIME 16 7415
TEST_TIME 17 7982
TEST_TIME 18 8336
TEST_TIME 19 8644
TEST_TIME 20 8632
TEST_TIME 21 8653
TEST_TIME 22 8990
TEST_TIME 23 9065
TEST_TIME 24 9343
TEST_TIME 25 9882
TEST_TIME 26 9782
TEST_TIME 27 9531
TEST_TIME 28 9562
TEST_TIME 29 9696
TEST_TIME 30 9549
TEST_TIME 31 9484
TEST_TIME 32 9553
TEST_TIME 33 9518
TEST_TIME 34 9420
TEST_TIME 35 9264
TEST_TIME 36 9149
TEST_TIME 37 9070
TEST_TIME 38 9125
TEST_TIME 39 8983
TEST_TIME 40 8933
TEST_TIME 41 9163
TEST_TIME 42 9069
TEST_TIME 43 8960
TEST_TIME 44 8935
TEST_TIME 45 8902
TEST_TIME 46 8796
TEST_TIME 47 8715
TEST_TIME 48 8715
TEST_TIME 49 8624
TEST_TIME 50 8617
TEST_TIME 51 8501
TEST_TIME 52 8536
TEST_TIME 53 8562
TEST_TIME 54 8683
TEST_TIME 55 8637
TEST_TIME 56 8663
TEST_TIME 57 8743
TEST_TIME 58 8771
TEST_TIME 59 8715
TEST_TIME 60 8768
TEST_TIME 61 8914
TEST_TIME 62 8948
TEST_TIME 63 8971
TEST_TIME 64 9096
TEST_TIME 65 9062
TEST_TIME 66 9045
TEST_TIME 67 9003
TEST_TIME 68 8945
TEST_TIME 69 8976
TEST_TIME 70 8925
TEST_TIME 71 8883
TEST_TIME 72 8876
TEST_TIME 73 8819
TEST_TIME 74 8809
TEST_TIME 75 8854
TEST_TIME 76 8769
TEST_TIME 77 8728
TEST_TIME 78 8920
TEST_TIME 79 8965
TEST_TIME 80 8959
TEST_TIME 81 8918
TEST_TIME 82 8913
TEST_TIME 83 9018
TEST_TIME 84 9057
TEST_TIME 85 9095
TEST_TIME 86 9178
TEST_TIME 87 9152
TEST_TIME 88 9202
TEST_TIME 89 9147
TEST_TIME 90 9152
TEST_TIME 91 9107
TEST_TIME 92 9069
TEST_TIME 93 9049
TEST_TIME 94 8985
TEST_TIME 95 8909
TEST_TIME 96 8916
TEST_TIME 97 8938
TEST_TIME 98 8945
TEST_TIME 99 8956
TEST_TIME 100 8972
TEST_TIME 101 8952
TEST_TIME 102 8973
TEST_TIME 103 8969
TEST_TIME 104 8962
TEST_TIME 105 8949
TEST_TIME 106 9018
TEST_TIME 107 9072
TEST_TIME 108 9042
TEST_TIME 109 9054
TEST_TIME 110 9072
TEST_TIME 111 9037
TEST_TIME 112 8984
TEST_TIME 113 8990
TEST_TIME 114 8956
TEST_TIME 115 8911
TEST_TIME 116 8918
TEST_TIME 117 8911
TEST_TIME 118 8874
TEST_TIME 119 8889
TEST_TIME 120 8863

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 26-11-2021 01:40:15 to 26-11-2021 01:40:18.

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 1481471 1481471 0 0.00 0 25583 7 0 0
doc_list_biud 1481460 1481460 0 0.00 0 42811 25 0 0
sp_add_invoice_to_stock 5108 4991 117 2.29 38 156087 18962 0 117
sp_cancel_adding_invoice 5318 5318 0 0.00 5 57579 908 0 0
sp_cancel_client_order 1770 1770 0 0.00 4 20535 385 0 0
sp_cancel_customer_reserve 528483 528483 0 0.00 1 42813 54 0 0
sp_cancel_pay_from_customer 679 679 0 0.00 2 11204 97 0 0
sp_cancel_pay_to_supplier 441 441 0 0.00 3 5333 97 0 0
sp_cancel_supplier_invoice 5889 5882 7 0.11 2 19991 710 0 7
sp_cancel_supplier_order 377 377 0 0.00 7 789204 93370 0 0
sp_cancel_write_off 8836 8836 0 0.00 1 9722 110 0 0
sp_client_order 20546 20546 0 0.00 3 15910 364 0 0
sp_customer_reserve 451066 451066 0 0.00 0 40491 192 0 0
sp_fill_shopping_cart 31242 31242 0 0.00 1 18524 42 0 0
sp_fill_shopping_cart_clo_res 451065 451065 0 0.00 0 26223 15 0 0
sp_get_clo_for_invoice 4989 4989 0 0.00 0 27420 342 0 0
sp_kill_cost_storno 15274 15274 0 0.00 0 9718 56 0 0
sp_kill_qstorno_ret_qs2qd 1072061 1072061 0 0.00 0 42791 29 0 0
sp_kill_qty_storno 565552 565552 0 0.00 0 42811 64 0 0
sp_lock_selected_doc 20681 20681 0 0.00 0 8145 6 0 0
sp_make_cost_storno 27865 27865 0 0.00 0 14314 56 0 0
sp_multiply_rows_for_pdistr 27855 27855 0 0.00 0 9015 28 0 0
sp_multiply_rows_for_qdistr 20546 20546 0 0.00 0 12394 160 0 0
sp_pay_from_customer 7138 7138 0 0.00 4 15163 109 0 0
sp_pay_to_supplier 5830 5830 0 0.00 4 15158 121 0 0
sp_payment_common 12968 12968 0 0.00 1 8400 25 0 0
sp_qd_handle_on_cancel_clo 1770 1770 0 0.00 0 20527 349 0 0
sp_qd_handle_on_invoice_upd_sts 10302 10302 0 0.00 0 19986 342 0 0
sp_qd_handle_on_reserve_upd_sts 9902 9902 0 0.00 0 15617 67 0 0
sp_reserve_write_off 9908 9908 0 0.00 6 15669 195 0 0
sp_supplier_invoice 5347 5347 0 0.00 5 95336 3972 0 0
sp_supplier_order 5356 5356 0 0.00 5 58722 1430 0 0
srv_aggregate_perf_data 96 96 0 0.00 2760 215950 17677 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1396 151249 48104 0 0
srv_find_qd_qs_mism 1016466 1016466 0 0.00 0 25582 6 0 0
srv_make_invnt_saldo 546 546 0 0.00 20 52303 1240 0 0
srv_make_money_saldo 767 767 0 0.00 1 3391 32 0 0
srv_recalc_idx_stat 4 4 0 0.00 229812 408051 294451 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 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4991 4991 0 0.00 0 29973 175 0 0
t$perf-norm:sp_cancel_adding_invoice 410 410 0 0.00 1 2537 94 0 0
t$perf-norm:sp_cancel_client_order 1770 1770 0 0.00 0 10406 17 0 0
t$perf-norm:sp_cancel_customer_reserve 686 686 0 0.00 0 366 4 0 0
t$perf-norm:sp_cancel_pay_from_customer 679 679 0 0.00 0 4503 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 441 441 0 0.00 0 2263 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 425 425 0 0.00 0 867 11 0 0
t$perf-norm:sp_cancel_supplier_order 377 377 0 0.00 0 20200 1511 0 0
t$perf-norm:sp_cancel_write_off 1007 1007 0 0.00 0 2818 7 0 0
t$perf-norm:sp_client_order 20546 20546 0 0.00 0 24469 21 0 0
t$perf-norm:sp_customer_reserve 1027 1027 0 0.00 0 7826 19 0 0
t$perf-norm:sp_pay_from_customer 7138 7138 0 0.00 0 7864 11 0 0
t$perf-norm:sp_pay_to_supplier 5830 5830 0 0.00 0 4708 10 0 0
t$perf-norm:sp_reserve_write_off 9908 9908 0 0.00 0 11230 14 0 0
t$perf-norm:sp_supplier_invoice 5347 5347 0 0.00 0 11640 26 0 0
t$perf-norm:sp_supplier_order 5356 5356 0 0.00 0 9716 20 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 400 20 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 2002 33 0 0
t$perf-norm:srv_make_invnt_saldo 546 546 0 0.00 0 2040 26 0 0
t$perf-norm:srv_make_money_saldo 767 767 0 0.00 0 1435 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10202 9968 234 2.29 0 11712 21 0 234
v_all_customers 37198 37198 0 0.00 0 81 1 0 0
v_all_suppliers 29466 29466 0 0.00 0 17 1 0 0
v_all_wares 41088 41088 0 0.00 0 138 1 0 0
v_cancel_adding_invoice 820 820 0 0.00 0 1402 9 0 0
v_cancel_client_order 3540 3540 0 0.00 0 11796 9 0 0
v_cancel_customer_prepayment 1358 1358 0 0.00 0 4384 11 0 0
v_cancel_customer_reserve 1372 1372 0 0.00 0 3088 8 0 0
v_cancel_payment_to_supplier 882 882 0 0.00 0 5315 17 0 0
v_cancel_supplier_invoice 864 850 14 1.62 0 5663 20 0 14
v_cancel_supplier_order 754 754 0 0.00 0 3719 14 0 0
v_cancel_write_off 2014 2014 0 0.00 0 4585 15 0 0
v_max_id_clo_ord 5353 5353 0 0.00 0 2487 2 0 0
v_max_id_clo_res 1027 1027 0 0.00 0 7375 19 0 0
v_max_id_ord_sup 5345 5345 0 0.00 0 3475 4 0 0
v_max_non_paid_invoice 1795 1795 0 0.00 0 3435 6 0 0
v_max_non_paid_realizn 3979 3979 0 0.00 0 7263 7 0 0
v_min_id_clo_ord 5353 5353 0 0.00 0 10586 13 0 0
v_min_id_clo_res 1027 1027 0 0.00 0 5405 14 0 0
v_min_id_ord_sup 5345 5345 0 0.00 0 3751 5 0 0
v_min_non_paid_invoice 5830 5830 0 0.00 0 6026 16 0 0
v_min_non_paid_realizn 7136 7136 0 0.00 0 6990 15 0 0
v_our_firm 10208 10208 0 0.00 0 21 1 0 0
v_reserve_write_off 19804 19804 0 0.00 0 11850 7 0 0
x_lock_depdocs_on_canc_invoice 10775 10775 0 0.00 0 22749 121 0 0
x_lock_depdocs_on_canc_sup_ord 377 377 0 0.00 0 3309 296 0 0
x_make_qstorno_1000_1200 5356 5356 0 0.00 2 58713 1333 0 0
x_make_qstorno_1200_2000 5347 5347 0 0.00 3 95314 3827 0 0
x_make_qstorno_2100_3300 451066 451066 0 0.00 0 36949 174 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-11-2021 01:40:19 to 26-11-2021 01:40:23.

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:00 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:01 21629 8192000000 9907695296 10220613632 988063248 10.763 100 91 183 0 1018401456 6676992 23661216
23:10:29 27789 8192000000 9945695168 10269749248 1026640480 11.136 100 90 180 1 1059480576 9228656 23611440
23:12:07 19154 8192000000 9978976720 10315808768 1064246720 11.497 100 81 163 0 1095406048 10862736 20296592
23:13:32 11138 8192000000 10034928000 10384891904 1118916992 12.017 100 80 159 1 1149463296 11533440 19012864
23:14:46 7751 8192000000 10069621696 10429026304 1158420656 12.388 100 78 155 0 1188701376 12260544 18020176
23:15:57 5110 8192000000 10123193136 10492166144 1204640368 12.819 100 74 149 0 1234628048 13610672 16377008
23:17:06 3428 8192000000 10164525968 10540830720 1248200592 13.222 100 81 162 1 1280076400 14204048 17671760
23:18:11 1544 8192000000 10212531088 10600550400 1300309360 13.698 100 90 179 0 1333196528 11982528 20904640
23:19:14 35407 8192000000 10221109072 10615771136 1311094224 13.796 100 66 131 3 1336281152 9278160 15908768
23:20:50 6165 8192000000 10210698400 10625773568 1322067056 13.895 100 31 62 0 1337077232 7960720 7049456
23:21:59 13629 8192000000 10215247552 10633314304 1329454736 13.962 100 19 38 1 1339274976 5256000 4564240
23:23:14 54556 8192000000 10230762800 10651316224 1338723088 14.046 100 24 48 1 1349169104 4972208 5473808
23:25:10 4963 8192000000 10230255344 10654003200 1326406192 13.935 99 19 38 1 1336790016 6304560 4079264
23:26:15 7947 8192000000 10228197296 10651156480 1337542288 14.035 100 16 32 1 1346339120 4998336 3798496
23:27:27 12566 8192000000 10217068688 10642767872 1319406592 13.871 99 13 25 0 1327410640 5362624 2641424
23:28:42 25544 8192000000 10192445168 10614992896 1268391040 13.407 96 27 53 1 1279366864 4622512 6353312
23:30:09 7674 8192000000 10151095488 10551844864 1215819920 12.923 96 28 56 1 1227595600 4623056 7152624
23:31:20 5933 8192000000 10131330416 10524323840 1228656368 13.042 100 31 63 1 1241489600 5413648 7419584
23:32:28 12611 8192000000 10151591120 10558382080 1260845952 13.338 100 14 28 2 1269856912 5674480 3336480
23:33:41 26447 8192000000 10172177328 10581323776 1263884096 13.366 99 43 85 1 1279619360 5554976 10180288
23:35:10 6920 8192000000 10196848320 10612305920 1304688688 13.738 100 25 50 1 1316696432 6717024 5290720
23:36:17 9595 8192000000 10213536368 10625511424 1314900592 13.831 100 26 53 0 1327766576 6563488 6302496
23:37:27 13358 8192000000 10205691520 10629910528 1321069024 13.886 100 22 44 3 1332904736 6194752 5640960
23:38:44 114961 8192000000 10261734880 10677587968 1341087936 14.067 99 35 71 1 1356176496 6924576 8163984
23:41:43 14196 8192000000 10294100240 10712842240 1378345920 14.402 100 42 80 0 1400176112 12721984 9108208
23:43:07 11965 8192000000 10313515072 10735296512 1389984560 14.506 100 50 101 0 1422689888 21155328 11550000
23:44:26 9050 8192000000 10313562640 10740396032 1400402992 14.599 100 43 86 0 1427173152 16888608 9881552
23:45:39 20784 8192000000 10323576976 10749714432 1405376304 14.643 100 49 98 2 1437710864 20782544 11552016
23:47:07 2647 8192000000 10336328592 10764963840 1415281872 14.731 100 55 110 1 1450079184 21487584 13309728
23:48:14 6912 8192000000 10346153680 10770927616 1407812016 14.664 99 61 122 0 1447373024 25323120 14237888
23:49:27 6878 8192000000 10336449952 10757832704 1383724352 14.450 98 58 113 3 1424886144 26823296 14338496
23:50:38 3943 8192000000 10344627536 10765787136 1405512656 14.644 100 66 132 0 1452327280 30642592 16172032
23:51:45 4118 8192000000 10330899024 10737659904 1377233024 14.392 99 67 131 0 1428071920 35244080 15594816
23:52:54 1395 8192000000 10334551440 10734477312 1374455408 14.367 100 94 189 1 1433341088 35727760 23157920
23:54:01 12041 8192000000 10293177104 10718912512 1353922752 14.183 99 38 75 0 1395537488 31975424 9639312
23:55:28 2661 8192000000 10291514592 10698330112 1347415472 14.124 100 81 162 0 1394023840 26394864 20213504
23:56:37 11104 8192000000 10252247632 10678218752 1323670320 13.910 99 32 63 2 1356931168 25260368 8000480
23:58:02 7085 8192000000 10275659776 10689216512 1341686320 14.073 100 51 102 1 1376947712 22360000 12901392
23:59:13 12501 8192000000 10251624832 10673471488 1317823856 13.857 99 34 68 0 1348157680 21483392 8850432
00:00:27 20529 8192000000 10259936928 10683760640 1329415936 13.962 99 29 58 0 1357725440 20507424 7802080
00:01:55 12275 8192000000 10266125472 10696585216 1336536176 14.026 99 27 54 0 1365531392 21865456 7129760
00:03:10 29164 8192000000 10286495664 10707161088 1359066032 14.229 100 44 84 3 1378176528 8652640 10457856
00:04:39 27201 8192000000 10268945472 10697334784 1341180368 14.068 99 39 78 2 1370047680 19058208 9809104
00:06:08 29857 8192000000 10272426752 10719211520 1362560224 14.260 100 17 34 3 1385104560 18135568 4408768
00:07:45 16979 8192000000 10285219968 10720829440 1369608160 14.324 100 44 81 2 1395945472 14918496 11418816
00:09:04 35879 8192000000 10271816272 10714918912 1351716656 14.163 99 11 22 1 1371835136 17525984 2592496
00:10:44 61410 8192000000 10275789072 10722099200 1368191888 14.311 100 13 26 0 1384143744 12428992 3522864
00:13:13 52385 8192000000 10301576784 10739109888 1387464064 14.483 100 33 67 2 1407065168 12023152 7577952
00:15:06 34440 8192000000 10284274944 10748149760 1383521872 14.448 100 10 21 0 1400133600 14349536 2262192
00:16:43 44884 8192000000 10313136736 10748035072 1392812528 14.531 100 41 82 0 1416387952 14238368 9337056
00:19:09 44373 8192000000 10282595712 10730950656 1380794608 14.424 100 13 26 0 1392929360 9220928 2913824
00:21:02 68542 8192000000 10282311232 10740035584 1383717424 14.450 100 9 18 1 1398552928 12881584 1953920
00:23:11 42814 8192000000 10277482016 10727956480 1385849440 14.469 100 21 42 0 1398109824 7598832 4661552
00:25:00 71061 8192000000 10286422848 10747637760 1379694896 14.414 99 24 48 0 1399552464 14568624 5288944
00:27:11 32747 8192000000 10280707824 10742706176 1379381968 14.411 99 13 26 2 1396061392 13757424 2922000
00:28:51 80470 8192000000 10298385440 10766454784 1379856592 14.415 99 9 17 0 1398208752 16037616 2314544
00:31:12 69567 8192000000 10272521264 10722979840 1335280240 14.015 96 10 20 0 1355229120 17727088 2221792
00:33:27 39697 8192000000 10284204048 10742120448 1369687680 14.324 99 13 26 1 1385998352 13208000 3102672
00:35:10 57209 8192000000 10263094880 10705108992 1303482448 13.727 95 15 29 0 1317155008 10763120 2909440
00:37:09 36454 8192000000 10280369696 10720829440 1329914176 13.966 97 42 84 1 1353416368 14578928 8923264
00:38:50 89858 8192000000 10257218592 10714742784 1303468720 13.727 95 11 22 1 1317118912 11506128 2144064
00:41:28 92449 8192000000 10273817600 10718449664 1320113312 13.878 96 12 24 0 1337069808 13940608 3015888
00:44:04 55362 8192000000 10253625312 10691465216 1327840496 13.948 97 35 70 1 1344340064 8913696 7585872
00:46:19 84562 8192000000 10258664960 10721529856 1348028720 14.130 99 6 12 0 1365709200 16865120 815360
00:48:45 70078 8192000000 10271150080 10727878656 1368323712 14.312 100 21 42 1 1386205184 13024240 4857232
00:51:36 80528 8192000000 10284199120 10748465152 1341547616 14.071 97 21 42 0 1366114640 19623088 4943936
00:54:00 68053 8192000000 10268616544 10713935872 1314754400 13.829 96 18 36 2 1336426000 17479136 4192464
00:56:24 49561 8192000000 10261292240 10701205504 1316375216 13.844 97 22 44 0 1329794464 8072336 5346912
00:58:18 40187 8192000000 10301675216 10727276544 1362306048 14.258 99 45 90 4 1382072032 9720384 10045600
01:00:05 77179 8192000000 10298118864 10755895296 1365985680 14.291 99 10 19 1 1383540176 15284192 2270304
01:02:25 114225 8192000000 10294781216 10751344640 1360364368 14.241 98 13 26 2 1389164848 25884096 2916384
01:05:24 85652 8192000000 10316112688 10775216128 1393630896 14.538 100 17 34 0 1423118752 25643312 3844544
01:08:10 40701 8192000000 10292091504 10740142080 1375702816 14.378 99 14 28 0 1387836048 8801328 3331904
01:09:52 85282 8192000000 10292432944 10751533056 1377940416 14.398 98 19 38 0 1390290272 8340192 4009664
01:12:17 46681 8192000000 10307812928 10766479360 1408834016 14.674 100 9 18 0 1422329632 11345744 2149872
01:14:05 103085 8192000000 10318390704 10780323840 1394879376 14.549 99 21 42 0 1407876000 8530400 4466224
01:16:53 50951 8192000000 10310581872 10769526784 1394106640 14.542 99 16 32 0 1408803888 10894304 3802944
01:18:44 38453 8192000000 10309664048 10765737984 1408834304 14.674 100 37 74 1 1426521856 8504448 9183104
01:20:24 58456 8192000000 10315408992 10779435008 1402153856 14.614 99 11 22 1 1415219488 10695456 2370176
01:22:28 23205 8192000000 10333446960 10777067520 1400767952 14.602 99 38 76 1 1419685136 10670208 8246976
01:23:54 125741 8192000000 10306572928 10759933952 1324556016 13.918 94 15 30 1 1350642608 23164176 2922416
01:27:11 78989 8192000000 10273213632 10705403904 1263048432 13.358 91 15 29 1 1276397936 10136128 3213376
01:29:31 151249 8192000000 10257970320 10708213760 1306010880 13.750 95 18 36 0 1322688032 12617504 4059648
01:33:09 91177 8192000000 10265665040 10729783296 1341636896 14.072 97 13 26 0 1358256112 13086608 3532608
01:35:40 42890 8192000000 10265722880 10720907264 1345116960 14.104 98 10 20 1 1357092112 9743776 2231376
01:37:40 35681 8192000000 9387263344 9695293440 542109120 6.206 39 3 6 0 550845664 8327856 408688

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 26-11-2021 01:40:25 to 26-11-2021 01:40:40.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 117 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 26-11-2021 01:40:41 to 26-11-2021 01:40:43.

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          239532
MON$OLDEST_ACTIVE               239826
MON$OLDEST_SNAPSHOT             239826
MON$NEXT_TRANSACTION            239826
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                       2016256
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33540 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33540 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33540 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33540 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 ---
:373: 
:374: mscs-test-161.budzdorov.ru    Fri Nov 26 01:38:32 2021
:375:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:376: 
--- end of diff output ---

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

26.11.21 01:40:44. 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
2021-11-26 01:40:46 No severe PSQL-related problems occured
26.11.21 01:40:47. End of report.