Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.04.2022 01:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16817960 kB
MemAvailable 31576532 kB
Buffers 0 kB
Cached 14929112 kB
SwapCached 2280 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-08 01:38:13
DTS_END                         2022-04-08 01:38:13.6100
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/36830
TRN_ID                          239666
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: 8914.86 30350 1069783
customer order: creation 166.70 371 20004
customer order: refuse 14.73 319 1768
order to supplier: creation 45.28 1220 5434
order to supplier: removal 3.24 110642 389
invoice (draft): creation 45.38 3718 5446
invoice (draft): removal 49.24 831 5909
invoice accept: apply 40.96 18280 4915
invoice accept: cancel 44.32 796 5318
customer reserve: creation 3820.07 178 458408
customer reserve: removal 4400.53 65 528064
realization accept: apply 81.73 167 9808
realization accept: cancel 73.47 120 8817
payment from customer: creation 60.17 122 7220
payment from customer: removal 5.41 70 649
payment to supplier: creation 48.21 136 5785
payment to supplier: removal 3.61 89 433
service: total inventory turnovers 4.82 1235 578
service: total monetary turnovers 6.14 25 737
service: aggregate perf. data 0.81 25393 97
service: refresh index statistics 0.03 443217 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 784
TEST_TIME 2 1927
TEST_TIME 3 3586
TEST_TIME 4 4760
TEST_TIME 5 5260
TEST_TIME 6 5895
TEST_TIME 7 6504
TEST_TIME 8 6336
TEST_TIME 9 5994
TEST_TIME 10 5865
TEST_TIME 11 5835
TEST_TIME 12 5993
TEST_TIME 13 5946
TEST_TIME 14 5879
TEST_TIME 15 6326
TEST_TIME 16 7124
TEST_TIME 17 7154
TEST_TIME 18 7438
TEST_TIME 19 7510
TEST_TIME 20 7825
TEST_TIME 21 8492
TEST_TIME 22 8324
TEST_TIME 23 8525
TEST_TIME 24 8606
TEST_TIME 25 8892
TEST_TIME 26 9195
TEST_TIME 27 9960
TEST_TIME 28 10477
TEST_TIME 29 10455
TEST_TIME 30 10312
TEST_TIME 31 10216
TEST_TIME 32 10071
TEST_TIME 33 9847
TEST_TIME 34 9952
TEST_TIME 35 9798
TEST_TIME 36 9654
TEST_TIME 37 9613
TEST_TIME 38 9527
TEST_TIME 39 9411
TEST_TIME 40 9318
TEST_TIME 41 9258
TEST_TIME 42 9296
TEST_TIME 43 9307
TEST_TIME 44 9285
TEST_TIME 45 9267
TEST_TIME 46 9187
TEST_TIME 47 9143
TEST_TIME 48 9267
TEST_TIME 49 9119
TEST_TIME 50 9117
TEST_TIME 51 9082
TEST_TIME 52 9061
TEST_TIME 53 8972
TEST_TIME 54 9038
TEST_TIME 55 9011
TEST_TIME 56 8958
TEST_TIME 57 8992
TEST_TIME 58 9112
TEST_TIME 59 9178
TEST_TIME 60 9137
TEST_TIME 61 9196
TEST_TIME 62 9190
TEST_TIME 63 9287
TEST_TIME 64 9250
TEST_TIME 65 9355
TEST_TIME 66 9395
TEST_TIME 67 9316
TEST_TIME 68 9204
TEST_TIME 69 9267
TEST_TIME 70 9203
TEST_TIME 71 9206
TEST_TIME 72 9178
TEST_TIME 73 9201
TEST_TIME 74 9184
TEST_TIME 75 9161
TEST_TIME 76 9109
TEST_TIME 77 9045
TEST_TIME 78 8996
TEST_TIME 79 9056
TEST_TIME 80 9030
TEST_TIME 81 9037
TEST_TIME 82 9045
TEST_TIME 83 9052
TEST_TIME 84 9075
TEST_TIME 85 9060
TEST_TIME 86 9059
TEST_TIME 87 9179
TEST_TIME 88 9160
TEST_TIME 89 9145
TEST_TIME 90 9203
TEST_TIME 91 9231
TEST_TIME 92 9245
TEST_TIME 93 9155
TEST_TIME 94 9087
TEST_TIME 95 9084
TEST_TIME 96 9047
TEST_TIME 97 9016
TEST_TIME 98 9042
TEST_TIME 99 9039
TEST_TIME 100 9004
TEST_TIME 101 9006
TEST_TIME 102 9046
TEST_TIME 103 9131
TEST_TIME 104 9092
TEST_TIME 105 9110
TEST_TIME 106 9098
TEST_TIME 107 9151
TEST_TIME 108 9177
TEST_TIME 109 9143
TEST_TIME 110 9126
TEST_TIME 111 9084
TEST_TIME 112 9048
TEST_TIME 113 9037
TEST_TIME 114 9011
TEST_TIME 115 9047
TEST_TIME 116 9012
TEST_TIME 117 8973
TEST_TIME 118 8956
TEST_TIME 119 8976
TEST_TIME 120 8915

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 08-04-2022 01:39:39 to 08-04-2022 01:39:43.

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 1493678 1493678 0 0.00 0 16307 6 0 0
doc_list_biud 1493678 1493678 0 0.00 0 49104 29 0 0
sp_add_invoice_to_stock 4996 4915 81 1.62 23 168332 18280 0 81
sp_cancel_adding_invoice 5318 5318 0 0.00 5 64200 796 0 0
sp_cancel_client_order 1768 1768 0 0.00 5 7471 319 0 0
sp_cancel_customer_reserve 528064 528064 0 0.00 1 49105 66 0 0
sp_cancel_pay_from_customer 649 649 0 0.00 3 3213 70 0 0
sp_cancel_pay_to_supplier 433 433 0 0.00 3 6861 90 0 0
sp_cancel_supplier_invoice 5915 5909 6 0.10 2 23518 832 0 6
sp_cancel_supplier_order 389 389 0 0.00 6 1090276 110643 0 0
sp_cancel_write_off 8817 8817 0 0.00 1 6653 120 0 0
sp_client_order 20004 20004 0 0.00 3 26725 372 0 0
sp_customer_reserve 458408 458408 0 0.00 0 32042 179 0 0
sp_fill_shopping_cart 30858 30858 0 0.00 1 22882 44 0 0
sp_fill_shopping_cart_clo_res 458407 458407 0 0.00 0 29991 15 0 0
sp_get_clo_for_invoice 4914 4914 0 0.00 0 50257 385 0 0
sp_kill_cost_storno 15217 15217 0 0.00 0 16299 60 0 0
sp_kill_qstorno_ret_qs2qd 1071243 1071243 0 0.00 0 49096 35 0 0
sp_kill_qty_storno 564987 564987 0 0.00 0 49103 75 0 0
sp_lock_selected_doc 20408 20408 0 0.00 0 7453 5 0 0
sp_make_cost_storno 27727 27727 0 0.00 0 16236 55 0 0
sp_multiply_rows_for_pdistr 27727 27727 0 0.00 0 8896 29 0 0
sp_multiply_rows_for_qdistr 20004 20004 0 0.00 0 12336 158 0 0
sp_pay_from_customer 7220 7220 0 0.00 4 11010 122 0 0
sp_pay_to_supplier 5785 5785 0 0.00 4 19619 136 0 0
sp_payment_common 13005 13005 0 0.00 1 8731 26 0 0
sp_qd_handle_on_cancel_clo 1768 1768 0 0.00 0 7464 289 0 0
sp_qd_handle_on_invoice_upd_sts 10232 10232 0 0.00 0 19645 318 0 0
sp_qd_handle_on_reserve_upd_sts 9808 9808 0 0.00 0 11177 55 0 0
sp_reserve_write_off 9808 9808 0 0.00 7 15015 168 0 0
sp_supplier_invoice 5446 5446 0 0.00 7 107709 3718 0 0
sp_supplier_order 5434 5434 0 0.00 5 28985 1221 0 0
srv_aggregate_perf_data 97 97 0 0.00 2210 711870 25393 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1851 133068 48011 0 0
srv_find_qd_qs_mism 1022302 1022302 0 0.00 0 11176 5 0 0
srv_make_invnt_saldo 578 578 0 0.00 42 158374 1235 0 0
srv_make_money_saldo 737 737 0 0.00 1 767 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 287747 626836 443217 0 0
t$perf-abend:sp_add_invoice_to_stock 81 81 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4915 4915 0 0.00 0 18999 186 0 0
t$perf-norm:sp_cancel_adding_invoice 381 381 0 0.00 0 3354 104 0 0
t$perf-norm:sp_cancel_client_order 1768 1768 0 0.00 0 9279 22 0 0
t$perf-norm:sp_cancel_customer_reserve 639 639 0 0.00 0 2971 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 649 649 0 0.00 0 2325 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 433 433 0 0.00 0 256 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 450 450 0 0.00 0 1669 16 0 0
t$perf-norm:sp_cancel_supplier_order 389 389 0 0.00 0 21742 1720 0 0
t$perf-norm:sp_cancel_write_off 977 977 0 0.00 0 4829 15 0 0
t$perf-norm:sp_client_order 20004 20004 0 0.00 0 7362 19 0 0
t$perf-norm:sp_customer_reserve 1058 1058 0 0.00 0 3318 9 0 0
t$perf-norm:sp_pay_from_customer 7220 7220 0 0.00 0 7405 9 0 0
t$perf-norm:sp_pay_to_supplier 5785 5785 0 0.00 0 10189 13 0 0
t$perf-norm:sp_reserve_write_off 9808 9808 0 0.00 0 5771 12 0 0
t$perf-norm:sp_supplier_invoice 5446 5446 0 0.00 0 8822 19 0 0
t$perf-norm:sp_supplier_order 5434 5434 0 0.00 0 5631 19 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 2152 26 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 166 4 0 0
t$perf-norm:srv_make_invnt_saldo 578 578 0 0.00 0 774 13 0 0
t$perf-norm:srv_make_money_saldo 737 737 0 0.00 0 10716 36 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 98 26 0 0
v_add_invoice_to_stock 9990 9828 162 1.62 0 6357 19 0 162
v_all_customers 36738 36738 0 0.00 0 155 1 0 0
v_all_suppliers 29934 29934 0 0.00 0 17 1 0 0
v_all_wares 40008 40008 0 0.00 0 26 1 0 0
v_cancel_adding_invoice 762 762 0 0.00 0 615 7 0 0
v_cancel_client_order 3536 3536 0 0.00 0 6814 8 0 0
v_cancel_customer_prepayment 1298 1298 0 0.00 0 962 4 0 0
v_cancel_customer_reserve 1278 1278 0 0.00 0 372 3 0 0
v_cancel_payment_to_supplier 866 866 0 0.00 0 6827 19 0 0
v_cancel_supplier_invoice 912 900 12 1.31 0 1638 16 0 12
v_cancel_supplier_order 778 778 0 0.00 0 2220 6 0 0
v_cancel_write_off 1954 1954 0 0.00 0 255 10 0 0
v_max_id_clo_ord 5434 5434 0 0.00 0 3147 2 0 0
v_max_id_clo_res 1058 1058 0 0.00 0 2437 9 0 0
v_max_id_ord_sup 5420 5420 0 0.00 0 8073 5 0 0
v_max_non_paid_invoice 1672 1672 0 0.00 0 1287 3 0 0
v_max_non_paid_realizn 3746 3746 0 0.00 0 901 3 0 0
v_min_id_clo_ord 5434 5434 0 0.00 0 4748 6 0 0
v_min_id_clo_res 1058 1058 0 0.00 0 2589 11 0 0
v_min_id_ord_sup 5420 5420 0 0.00 0 6125 7 0 0
v_min_non_paid_invoice 5785 5785 0 0.00 0 8264 16 0 0
v_min_non_paid_realizn 7220 7220 0 0.00 0 8728 22 0 0
v_our_firm 10218 10218 0 0.00 0 17 1 0 0
v_reserve_write_off 19616 19616 0 0.00 0 6806 7 0 0
x_lock_depdocs_on_canc_invoice 10777 10777 0 0.00 0 16657 124 0 0
x_lock_depdocs_on_canc_sup_ord 389 389 0 0.00 0 10102 416 0 0
x_make_qstorno_1000_1200 5434 5434 0 0.00 2 28968 1142 0 0
x_make_qstorno_1200_2000 5446 5446 0 0.00 3 107565 3548 0 0
x_make_qstorno_2100_3300 458408 458408 0 0.00 0 30309 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-04-2022 01:39:44 to 08-04-2022 01:39:48.

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:17 3 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:18 23005 8192000000 9891094368 10200698880 968528480 10.572 100 78 156 0 994718496 5458272 20731744
23:10:48 26555 8192000000 9957700208 10278215680 1035134784 11.218 100 89 178 0 1066338816 7974512 23229520
23:12:18 20334 8192000000 9993914048 10328965120 1066064736 11.514 100 79 157 0 1095103360 9396880 19641744
23:13:46 13693 8192000000 10053131824 10405847040 1136622576 12.184 100 78 156 2 1166134768 11215488 18296704
23:15:02 9889 8192000000 10107469728 10469351424 1192379616 12.706 100 75 145 2 1221080320 12594272 16106432
23:16:13 4074 8192000000 10147528816 10519515136 1237517680 13.123 100 82 165 0 1268515904 12650608 18347616
23:17:20 3297 8192000000 10181370704 10558353408 1263355264 13.361 100 89 178 0 1297201664 13691056 20155344
23:18:25 2015 8192000000 10209237600 10589814784 1289845952 13.603 100 93 184 1 1323985088 12734000 21405136
23:19:29 1943 8192000000 10219141152 10606387200 1304022720 13.732 100 85 169 3 1335904944 13392144 18490080
23:20:32 4365 8192000000 10196637552 10603966464 1306052032 13.750 100 39 78 0 1323935520 9575584 8307904
23:21:38 4906 8192000000 10198956688 10611302400 1311239440 13.797 100 32 65 0 1325669184 7393952 7035792
23:22:48 7364 8192000000 10212774592 10626150400 1323127376 13.905 100 28 56 0 1336082128 6669136 6285616
23:24:00 19722 8192000000 10216013600 10636156928 1326666224 13.937 100 33 65 1 1340596000 6706736 7223040
23:25:21 7003 8192000000 10221077520 10639507456 1328498752 13.954 100 37 76 0 1343997056 6519696 8978608
23:26:29 7129 8192000000 10231999440 10650185728 1332293888 13.988 100 19 38 0 1342163728 5508256 4361584
23:27:38 24883 8192000000 10210659120 10631188480 1302931664 13.722 98 39 80 2 1319230720 5959040 10340016
23:29:34 5789 8192000000 10164508800 10571448320 1264936896 13.375 99 30 60 1 1278419312 6951056 6531360
23:30:40 4502 8192000000 10153416528 10548027392 1252961024 13.265 100 34 66 1 1267866032 7043488 7861520
23:31:45 7694 8192000000 10153976864 10551836672 1234006208 13.091 98 23 45 0 1244588016 5380304 5201504
23:32:59 51689 8192000000 10138039488 10538430464 1210415920 12.873 96 38 74 2 1223922800 4693584 8813296
23:34:55 7178 8192000000 10168582592 10573459456 1263154720 13.359 99 28 56 0 1274798064 4965632 6677712
23:36:03 8566 8192000000 10183206800 10592997376 1288689504 13.592 100 30 60 1 1302013632 5322464 8001664
23:37:12 11952 8192000000 10191008192 10610167808 1304819184 13.739 100 18 33 1 1314405072 5774720 3811168
23:38:27 122260 8192000000 10241707824 10656075776 1331265968 13.979 100 34 65 0 1345927072 7137728 7523376
23:41:31 12890 8192000000 10269839536 10679504896 1352302608 14.168 100 54 108 0 1379937952 14901904 12733440
23:42:51 13147 8192000000 10268107648 10684932096 1357873504 14.218 100 46 93 1 1383923472 14984272 11065696
23:44:10 13048 8192000000 10287589920 10701631488 1357570624 14.216 99 49 98 0 1387380128 17451152 12358352
23:45:25 6100 8192000000 10310008704 10725367808 1384946272 14.461 100 61 122 4 1416368656 16974272 14448112
23:46:38 10692 8192000000 10324974736 10747936768 1402815104 14.620 100 37 74 0 1428933600 17558064 8560432
23:47:52 13324 8192000000 10347731968 10769915904 1419146464 14.765 100 67 133 1 1459913952 24939984 15827504
23:49:08 4151 8192000000 10363506720 10784997376 1424912816 14.816 100 58 116 2 1467646736 28681872 14052048
23:50:17 2300 8192000000 10369458640 10780680192 1422100832 14.791 100 78 154 1 1473350048 32472560 18776656
23:51:22 1850 8192000000 10371904208 10785570816 1427018464 14.835 100 85 169 2 1484574672 36493248 21062960
23:52:26 5729 8192000000 10369652768 10783797248 1412941968 14.710 100 77 153 1 1474109664 41499680 19668016
23:53:36 2784 8192000000 10348775536 10761261056 1394024064 14.542 100 77 152 2 1460250256 46300288 19925904
23:54:47 5050 8192000000 10328394752 10737061888 1375721680 14.378 100 71 141 1 1430695984 37325408 17648896
23:55:57 3742 8192000000 10351228976 10754240512 1380884656 14.424 99 76 152 2 1442416656 42122416 19409584
23:57:07 4288 8192000000 10348765888 10761175040 1382466544 14.439 99 75 149 1 1449384784 48009520 18908720
23:58:15 7807 8192000000 10372848944 10790088704 1408402960 14.670 100 64 128 2 1477857216 52588256 16866000
23:59:29 5827 8192000000 10346487056 10764722176 1378669648 14.405 99 69 138 0 1452673904 56078208 17926048
00:00:45 10916 8192000000 10339383408 10766573568 1390428144 14.510 100 40 80 0 1444798256 43791392 10578720
00:02:05 13082 8192000000 10335282976 10771582976 1393918560 14.541 100 38 76 1 1445957072 42006544 10031968
00:03:26 21885 8192000000 10355057472 10793312256 1397472176 14.572 100 29 58 2 1460649536 55101568 8075792
00:04:50 39578 8192000000 10301217328 10759843840 1369394704 14.322 100 19 37 1 1425561552 50208848 5958000
00:06:33 42346 8192000000 10274716768 10738524160 1340666608 14.063 98 10 19 1 1369233536 26057408 2509520
00:08:23 51829 8192000000 10274648032 10715676672 1348090048 14.130 98 24 48 0 1364547776 11429744 5027984
00:10:16 60801 8192000000 10259974080 10705055744 1343894000 14.093 99 12 22 0 1356500880 10688512 1918368
00:12:53 85413 8192000000 10275844640 10721046528 1350192464 14.149 99 15 30 0 1368124704 14863904 3068336
00:15:25 80143 8192000000 10274828944 10729594880 1361354576 14.250 99 13 26 0 1377271056 13207728 2708752
00:17:46 14445 8192000000 10281720832 10728747008 1373216352 14.356 100 25 50 2 1391978512 13453824 5308336
00:19:05 53499 8192000000 10291448352 10738311168 1374372336 14.366 99 21 42 0 1391517856 12128864 5016656
00:21:03 64503 8192000000 10292100960 10749222912 1380457776 14.421 100 19 37 2 1398932160 13881072 4593312
00:23:25 78545 8192000000 10303883056 10760843264 1382663168 14.440 99 17 34 0 1402586288 15829424 4093696
00:25:45 84869 8192000000 10299576288 10767237120 1396273136 14.562 100 23 47 1 1421874480 19493136 6108208
00:28:21 69081 8192000000 10291171216 10753314816 1379925136 14.416 99 7 14 0 1393242064 10944816 2372112
00:30:30 50550 8192000000 10304207696 10764058624 1383700704 14.450 99 6 12 1 1394847920 9944992 1202224
00:32:22 63313 8192000000 10299531488 10755170304 1387511440 14.484 99 12 24 0 1399997056 9804960 2680656
00:34:29 40221 8192000000 10307425440 10753441792 1369309424 14.321 98 33 66 0 1392122144 15516800 7295920
00:36:38 99450 8192000000 10300169536 10740412416 1308599520 13.773 94 7 14 0 1324127472 14178352 1349600
00:39:18 115042 8192000000 10287466400 10744217600 1321121440 13.887 95 19 38 1 1335929344 11025664 3782240
00:42:20 32743 8192000000 10299344608 10744336384 1345876352 14.110 97 29 58 0 1365210432 12575856 6758224
00:43:53 90695 8192000000 10290843072 10737508352 1301447424 13.708 94 7 13 1 1319661184 16935024 1278736
00:46:30 78787 8192000000 10272906672 10718216192 1342181824 14.077 98 24 49 0 1360324208 12507760 5634624
00:48:49 15728 8192000000 10287337136 10730745856 1372005680 14.345 100 44 87 2 1393919664 11875472 10038512
00:50:06 56359 8192000000 10255266096 10701774848 1282361728 13.535 93 20 40 0 1297569600 10767568 4440304
00:52:12 35035 8192000000 10255557536 10699206656 1346513456 14.116 100 17 34 2 1360271968 9486192 4272320
00:53:48 50255 8192000000 10258025664 10712580096 1351173072 14.158 99 14 28 0 1361541376 7386016 2982288
00:55:43 79701 8192000000 10265181968 10722570240 1345902304 14.111 98 11 22 0 1359361040 10495488 2963248
00:58:14 45438 8192000000 10240307600 10682781696 1277209280 13.488 94 10 20 0 1286375440 7301504 1864656
00:59:59 17204 8192000000 10264359952 10708144128 1350884496 14.155 100 37 73 0 1368117008 8864960 8367552
01:01:20 31795 8192000000 10266058800 10712510464 1332237760 13.987 98 25 50 0 1347917584 9719424 5960400
01:02:56 46693 8192000000 10266442064 10711154688 1345356960 14.106 99 27 54 0 1360056784 8510384 6189440
01:04:48 111629 8192000000 10266152784 10723676160 1310445488 13.790 96 28 56 1 1333433104 15993376 6994240
01:07:39 87567 8192000000 10277278752 10710360064 1294695952 13.647 95 20 38 2 1316690640 17585120 4409568
01:10:10 115867 8192000000 10274443760 10728292352 1367553104 14.305 100 14 28 0 1385669600 15361312 2755184
01:13:06 42517 8192000000 10264245184 10725232640 1371917872 14.344 100 19 38 1 1384697232 8835984 3943376
01:16:21 85181 8192000000 10290632880 10745163776 1338334176 14.042 97 21 43 1 1353886352 10892896 4659280
01:18:46 53724 8192000000 10295600640 10741772288 1342558608 14.080 97 23 46 0 1360958720 12744432 5655680
01:20:59 133066 8192000000 10295438208 10735759360 1337820080 14.038 97 14 28 0 1353639056 12486192 3332784
01:24:20 126395 8192000000 10299690480 10772160512 1336333664 14.024 97 17 34 1 1364772016 24605856 3832496
01:28:07 15823 8192000000 10273987440 10706485248 1338575456 14.045 99 44 88 0 1356385664 8547632 9262576
01:29:27 92722 8192000000 10269179888 10712641536 1337516192 14.035 98 23 46 2 1350815760 8034320 5265248
01:32:11 35628 8192000000 10271704736 10725617664 1358531504 14.224 99 37 72 2 1377405024 10840192 8033328
01:33:55 61682 8192000000 10260335392 10722910208 1348917120 14.138 98 8 16 1 1360527856 9774608 1836128
01:36:23 59871 8192000000 10258391248 10727440384 1347456064 14.125 99 17 32 1 1361992576 10475440 4061072

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 81 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

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

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          239100
MON$OLDEST_ACTIVE               239807
MON$OLDEST_SNAPSHOT             239807
MON$NEXT_TRANSACTION            239807
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                       2017024
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.33576 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33576 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33576 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33576 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 ---
:133: 
:134: mscs-test-161.budzdorov.ru    Fri Apr  8 01:38:17 2022
:135:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:136: 
--- end of diff output ---

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

08.04.22 01:40:08. 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-08 01:40:10 No severe PSQL-related problems occured
08.04.22 01:40:12. End of report.