Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.08.2022 22: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 15955700 kB
MemAvailable 30890540 kB
Buffers 0 kB
Cached 15745568 kB
SwapCached 2512 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-08-15 22:38:54
DTS_END                         2022-08-15 22:38:22.7300
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/54752
TRN_ID                          235110
ATT_ID                          274
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: 9103.41 17606 1092409
customer order: creation 170.28 399 20434
customer order: refuse 14.78 373 1774
order to supplier: creation 44.21 1328 5305
order to supplier: removal 3.46 91393 415
invoice (draft): creation 44.23 3484 5308
invoice (draft): removal 52.03 655 6243
invoice accept: apply 40.45 19140 4854
invoice accept: cancel 47.17 849 5660
customer reserve: creation 3736.89 189 448427
customer reserve: removal 4660.74 54 559289
realization accept: apply 82.75 184 9930
realization accept: cancel 79.54 105 9545
payment from customer: creation 58.91 147 7069
payment from customer: removal 5.58 84 670
payment to supplier: creation 46.86 136 5623
payment to supplier: removal 3.35 87 402
service: total inventory turnovers 4.72 1097 567
service: total monetary turnovers 6.60 65 792
service: aggregate perf. data 0.82 19739 98
service: refresh index statistics 0.03 212603 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-08-2022 22:39:42 to 15-08-2022 22:39:44.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4446
TEST_TIME 2 5791
TEST_TIME 3 5669
TEST_TIME 4 5809
TEST_TIME 5 5603
TEST_TIME 6 5494
TEST_TIME 7 5744
TEST_TIME 8 5826
TEST_TIME 9 6032
TEST_TIME 10 6078
TEST_TIME 11 6103
TEST_TIME 12 6469
TEST_TIME 13 6933
TEST_TIME 14 6885
TEST_TIME 15 7666
TEST_TIME 16 7726
TEST_TIME 17 8571
TEST_TIME 18 8534
TEST_TIME 19 8897
TEST_TIME 20 8821
TEST_TIME 21 9148
TEST_TIME 22 9189
TEST_TIME 23 9512
TEST_TIME 24 9636
TEST_TIME 25 9941
TEST_TIME 26 10369
TEST_TIME 27 10375
TEST_TIME 28 10122
TEST_TIME 29 10282
TEST_TIME 30 10215
TEST_TIME 31 10141
TEST_TIME 32 10014
TEST_TIME 33 9772
TEST_TIME 34 9739
TEST_TIME 35 9834
TEST_TIME 36 9791
TEST_TIME 37 9718
TEST_TIME 38 9603
TEST_TIME 39 9589
TEST_TIME 40 9431
TEST_TIME 41 9367
TEST_TIME 42 9311
TEST_TIME 43 9255
TEST_TIME 44 9357
TEST_TIME 45 9355
TEST_TIME 46 9258
TEST_TIME 47 9156
TEST_TIME 48 9010
TEST_TIME 49 9100
TEST_TIME 50 9051
TEST_TIME 51 9030
TEST_TIME 52 8998
TEST_TIME 53 9047
TEST_TIME 54 9004
TEST_TIME 55 9002
TEST_TIME 56 9070
TEST_TIME 57 9137
TEST_TIME 58 9280
TEST_TIME 59 9329
TEST_TIME 60 9263
TEST_TIME 61 9271
TEST_TIME 62 9277
TEST_TIME 63 9287
TEST_TIME 64 9360
TEST_TIME 65 9247
TEST_TIME 66 9219
TEST_TIME 67 9142
TEST_TIME 68 9123
TEST_TIME 69 9054
TEST_TIME 70 8983
TEST_TIME 71 8968
TEST_TIME 72 8980
TEST_TIME 73 8963
TEST_TIME 74 8993
TEST_TIME 75 9025
TEST_TIME 76 8976
TEST_TIME 77 9040
TEST_TIME 78 9099
TEST_TIME 79 9090
TEST_TIME 80 9117
TEST_TIME 81 9041
TEST_TIME 82 9209
TEST_TIME 83 9206
TEST_TIME 84 9242
TEST_TIME 85 9270
TEST_TIME 86 9278
TEST_TIME 87 9193
TEST_TIME 88 9222
TEST_TIME 89 9155
TEST_TIME 90 9106
TEST_TIME 91 9086
TEST_TIME 92 9064
TEST_TIME 93 9095
TEST_TIME 94 9043
TEST_TIME 95 9095
TEST_TIME 96 9118
TEST_TIME 97 9134
TEST_TIME 98 9157
TEST_TIME 99 9215
TEST_TIME 100 9208
TEST_TIME 101 9199
TEST_TIME 102 9308
TEST_TIME 103 9254
TEST_TIME 104 9339
TEST_TIME 105 9350
TEST_TIME 106 9363
TEST_TIME 107 9328
TEST_TIME 108 9277
TEST_TIME 109 9227
TEST_TIME 110 9295
TEST_TIME 111 9247
TEST_TIME 112 9249
TEST_TIME 113 9217
TEST_TIME 114 9198
TEST_TIME 115 9171
TEST_TIME 116 9172
TEST_TIME 117 9143
TEST_TIME 118 9167
TEST_TIME 119 9165
TEST_TIME 120 9106

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 15-08-2022 22:39:46 to 15-08-2022 22:39:49.

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 1510480 1510480 0 0.00 0 29780 6 0 0
doc_list_biud 1510478 1510478 0 0.00 0 37009 26 0 0
sp_add_invoice_to_stock 4973 4854 119 2.39 39 166666 19141 0 119
sp_cancel_adding_invoice 5660 5660 0 0.00 4 64006 850 0 0
sp_cancel_client_order 1774 1774 0 0.00 4 13133 374 0 0
sp_cancel_customer_reserve 559289 559289 0 0.00 0 37011 55 0 0
sp_cancel_pay_from_customer 670 670 0 0.00 2 6114 84 0 0
sp_cancel_pay_to_supplier 402 402 0 0.00 3 3350 87 0 0
sp_cancel_supplier_invoice 6257 6243 14 0.22 1 16489 656 0 14
sp_cancel_supplier_order 415 415 0 0.00 6 926893 91394 0 0
sp_cancel_write_off 9545 9545 0 0.00 1 8242 106 0 0
sp_client_order 20434 20434 0 0.00 3 26323 400 0 0
sp_customer_reserve 448427 448427 0 0.00 0 53029 189 0 0
sp_fill_shopping_cart 31041 31041 0 0.00 1 31235 44 0 0
sp_fill_shopping_cart_clo_res 448424 448424 0 0.00 0 19800 16 0 0
sp_get_clo_for_invoice 4850 4850 0 0.00 0 41823 350 0 0
sp_kill_cost_storno 16277 16277 0 0.00 0 14798 47 0 0
sp_kill_qstorno_ret_qs2qd 1134781 1134781 0 0.00 0 37008 29 0 0
sp_kill_qty_storno 597706 597706 0 0.00 0 37009 64 0 0
sp_lock_selected_doc 20553 20553 0 0.00 0 9409 8 0 0
sp_make_cost_storno 27472 27472 0 0.00 0 14776 52 0 0
sp_multiply_rows_for_pdistr 27472 27472 0 0.00 0 10714 30 0 0
sp_multiply_rows_for_qdistr 20434 20434 0 0.00 0 20332 173 0 0
sp_pay_from_customer 7069 7069 0 0.00 4 32147 147 0 0
sp_pay_to_supplier 5623 5623 0 0.00 4 11843 136 0 0
sp_payment_common 12692 12692 0 0.00 1 32138 31 0 0
sp_qd_handle_on_cancel_clo 1774 1774 0 0.00 0 13126 341 0 0
sp_qd_handle_on_invoice_upd_sts 10510 10510 0 0.00 0 29560 340 0 0
sp_qd_handle_on_reserve_upd_sts 9930 9930 0 0.00 0 13535 60 0 0
sp_reserve_write_off 9930 9930 0 0.00 6 13560 184 0 0
sp_supplier_invoice 5308 5308 0 0.00 6 104639 3485 0 0
sp_supplier_order 5305 5305 0 0.00 5 34583 1329 0 0
srv_aggregate_perf_data 98 98 0 0.00 2706 163861 19740 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1519 127866 41215 0 0
srv_find_qd_qs_mism 1047214 1047214 0 0.00 0 29778 5 0 0
srv_make_invnt_saldo 567 567 0 0.00 53 62617 1098 0 0
srv_make_money_saldo 792 792 0 0.00 1 23048 65 0 0
srv_recalc_idx_stat 4 4 0 0.00 103580 342814 212604 0 0
t$perf-abend:sp_add_invoice_to_stock 119 119 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4855 4855 0 0.00 0 13182 180 0 0
t$perf-norm:sp_cancel_adding_invoice 420 420 0 0.00 0 8107 136 0 0
t$perf-norm:sp_cancel_client_order 1774 1774 0 0.00 0 3326 17 0 0
t$perf-norm:sp_cancel_customer_reserve 655 655 0 0.00 0 1962 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 670 670 0 0.00 0 5067 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 402 402 0 0.00 0 888 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 400 400 0 0.00 0 7107 35 0 0
t$perf-norm:sp_cancel_supplier_order 415 415 0 0.00 0 22445 1402 0 0
t$perf-norm:sp_cancel_write_off 1038 1038 0 0.00 0 834 4 0 0
t$perf-norm:sp_client_order 20434 20434 0 0.00 0 6887 16 0 0
t$perf-norm:sp_customer_reserve 960 960 0 0.00 0 3303 14 0 0
t$perf-norm:sp_pay_from_customer 7069 7069 0 0.00 0 7750 12 0 0
t$perf-norm:sp_pay_to_supplier 5623 5623 0 0.00 0 6522 11 0 0
t$perf-norm:sp_reserve_write_off 9930 9930 0 0.00 0 7605 13 0 0
t$perf-norm:sp_supplier_invoice 5308 5308 0 0.00 0 4980 17 0 0
t$perf-norm:sp_supplier_order 5305 5305 0 0.00 0 4518 15 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 2659 38 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 41 1 0 0
t$perf-norm:srv_make_invnt_saldo 567 567 0 0.00 0 1003 24 0 0
t$perf-norm:srv_make_money_saldo 792 792 0 0.00 0 1521 21 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 4 0 0
v_add_invoice_to_stock 9938 9700 238 2.39 0 6997 22 0 238
v_all_customers 37446 37446 0 0.00 0 22 1 0 0
v_all_suppliers 29364 29364 0 0.00 0 23 1 0 0
v_all_wares 40868 40868 0 0.00 0 245 1 0 0
v_cancel_adding_invoice 840 840 0 0.00 0 817 8 0 0
v_cancel_client_order 3548 3548 0 0.00 0 5946 11 0 0
v_cancel_customer_prepayment 1340 1340 0 0.00 0 5889 16 0 0
v_cancel_customer_reserve 1310 1310 0 0.00 0 2837 6 0 0
v_cancel_payment_to_supplier 804 804 0 0.00 0 3344 19 0 0
v_cancel_supplier_invoice 828 800 28 3.38 0 1585 17 0 28
v_cancel_supplier_order 830 830 0 0.00 0 175 3 0 0
v_cancel_write_off 2076 2076 0 0.00 0 6787 26 0 0
v_max_id_clo_ord 5303 5303 0 0.00 0 1539 2 0 0
v_max_id_clo_res 960 960 0 0.00 0 7813 24 0 0
v_max_id_ord_sup 5304 5304 0 0.00 0 5458 6 0 0
v_max_non_paid_invoice 1548 1548 0 0.00 0 1176 3 0 0
v_max_non_paid_realizn 3719 3719 0 0.00 0 3526 4 0 0
v_min_id_clo_ord 5303 5303 0 0.00 0 3484 6 0 0
v_min_id_clo_res 960 960 0 0.00 0 4591 22 0 0
v_min_id_ord_sup 5304 5304 0 0.00 0 6006 11 0 0
v_min_non_paid_invoice 5623 5623 0 0.00 0 5305 18 0 0
v_min_non_paid_realizn 7069 7069 0 0.00 0 32133 28 0 0
v_our_firm 10122 10122 0 0.00 0 25 1 0 0
v_reserve_write_off 19858 19858 0 0.00 0 6694 10 0 0
x_lock_depdocs_on_canc_invoice 11503 11503 0 0.00 0 15657 117 0 0
x_lock_depdocs_on_canc_sup_ord 415 415 0 0.00 0 11771 369 0 0
x_make_qstorno_1000_1200 5305 5305 0 0.00 1 34571 1251 0 0
x_make_qstorno_1200_2000 5308 5308 0 0.00 3 104081 3310 0 0
x_make_qstorno_2100_3300 448427 448427 0 0.00 0 53028 171 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-08-2022 22:39:50 to 15-08-2022 22:39:54.

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
20:08:26 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:27 31793 8192000000 9902378032 10211131392 981851520 10.702 100 92 185 0 1012207280 6178608 24177152
20:11:03 24118 8192000000 9947577056 10269863936 1033041088 11.198 100 89 179 0 1063947072 8209648 22696336
20:12:36 14118 8192000000 9987026080 10321420288 1068714384 11.540 100 87 174 0 1101024528 10328224 21981920
20:13:57 9406 8192000000 10041179632 10390974464 1123039792 12.056 100 79 158 0 1152864800 11168576 18656432
20:15:11 4746 8192000000 10101012944 10457264128 1179272224 12.583 100 91 182 0 1213241904 13449984 20519696
20:16:20 2424 8192000000 10132023072 10498613248 1217148432 12.935 100 88 174 0 1250189216 13659472 19381312
20:17:25 3312 8192000000 10154905280 10533597184 1253989712 13.275 100 81 160 0 1283976112 12820560 17165840
20:18:31 937 8192000000 10201072688 10583351296 1282252368 13.534 100 88 170 1 1318021136 16554176 19214592
20:19:34 996 8192000000 10210761136 10600505344 1301076720 13.705 100 81 162 1 1330944128 11633856 18233552
20:20:35 31687 8192000000 10196817216 10605891584 1304448288 13.736 100 47 97 0 1321275360 5728800 11098272
20:22:11 8406 8192000000 10196809232 10611679232 1309308528 13.780 100 26 50 1 1320872832 5921296 5643008
20:23:21 5163 8192000000 10207275104 10628046848 1324392336 13.916 100 25 51 0 1336384272 6189376 5802560
20:24:26 13335 8192000000 10216751536 10635128832 1332112464 13.986 100 22 43 0 1342809696 5949184 4748048
20:25:40 13129 8192000000 10219223312 10642432000 1334961824 14.012 100 7 14 0 1342089040 5510752 1616464
20:26:55 47846 8192000000 10241450384 10654998528 1338767888 14.046 100 46 92 0 1355916128 5527520 11620720
20:28:43 5478 8192000000 10208976480 10635280384 1327495776 13.945 100 19 38 0 1337721472 5798176 4427520
20:29:50 12266 8192000000 10185948080 10601136128 1274920224 13.467 97 20 40 0 1285113104 5212400 4980480
20:31:03 59167 8192000000 10129540096 10532950016 1106534416 11.900 86 25 50 0 1117110416 5128496 5447504
20:33:03 6026 8192000000 10128614720 10520006656 1227885968 13.035 98 23 45 1 1238636656 5961136 4789552
20:34:10 8448 8192000000 10162921904 10566012928 1271074480 13.431 100 24 48 0 1283568368 5621056 6872832
20:35:21 63190 8192000000 10177127744 10590519296 1279971648 13.513 99 32 63 0 1292409280 5059888 7377744
20:37:25 5103 8192000000 10194483168 10609745920 1300816352 13.703 100 34 68 0 1318942176 10781472 7344352
20:38:32 14334 8192000000 10231471648 10640015360 1327944784 13.949 100 48 93 1 1344686336 6159024 10582528
20:39:53 8116 8192000000 10250247552 10661502976 1344029568 14.094 100 62 124 0 1369796624 12278352 13488704
20:41:03 38283 8192000000 10265393344 10685419520 1357238000 14.213 100 34 66 1 1380525408 15830736 7456672
20:42:45 4662 8192000000 10284451200 10704789504 1376702784 14.387 100 54 108 1 1401775376 12335664 12736928
20:43:53 8537 8192000000 10291254784 10718212096 1388358528 14.491 100 46 84 0 1410708528 12515952 9834048
20:45:06 14503 8192000000 10307399168 10730999808 1393370880 14.536 100 49 98 0 1420105760 15193632 11541248
20:46:23 18377 8192000000 10321666272 10747764736 1401983968 14.613 100 58 115 0 1433574304 17725584 13864752
20:47:50 8588 8192000000 10331381008 10757586944 1414004896 14.720 100 57 113 2 1445187232 18128672 13053664
20:49:01 3752 8192000000 10348600704 10771632128 1419575520 14.769 100 75 150 0 1458761952 20966432 18220000
20:50:10 3234 8192000000 10328902720 10744934400 1396195744 14.561 100 68 136 1 1438903984 25903392 16804848
20:51:16 8616 8192000000 10317926592 10731569152 1384380640 14.456 100 67 133 2 1426474032 26613440 15479952
20:52:30 6141 8192000000 10339275216 10762153984 1402881856 14.621 100 64 127 0 1450871360 32056656 15932848
20:53:43 1512 8192000000 10356913728 10767659008 1394586816 14.547 99 91 182 0 1449998304 32972400 22439088
20:54:49 6718 8192000000 10337898976 10755866624 1391508656 14.519 100 70 140 2 1444829712 36115808 17205248
20:56:04 11499 8192000000 10314062336 10741051392 1384715856 14.459 100 39 78 0 1420489392 25642160 10131376
20:57:20 1959 8192000000 10318060672 10726608896 1369487600 14.322 100 80 160 0 1420557520 31441072 19628848
20:58:27 1950 8192000000 10288430400 10698407936 1350379152 14.151 100 52 102 1 1387830416 23472352 13978912
20:59:33 18951 8192000000 10251941040 10677780480 1304397136 13.735 96 25 50 1 1338003472 26797024 6809312
21:01:06 11030 8192000000 10255810400 10679930880 1330638112 13.973 100 31 60 2 1364611280 25756784 8216384
21:02:30 7331 8192000000 10275058464 10700218368 1333971824 14.003 99 38 74 1 1372445808 28561536 9912448
21:03:43 23536 8192000000 10271500800 10701905920 1339996880 14.057 99 15 29 1 1373633152 29746752 3889520
21:05:11 68240 8192000000 10278583344 10707107840 1358648992 14.225 100 25 50 1 1380564912 15067696 6848224
21:07:20 31053 8192000000 10271183808 10713587712 1361293008 14.249 100 25 50 0 1379713952 12711744 5709200
21:08:53 57651 8192000000 10283442048 10726346752 1352726608 14.172 98 25 48 0 1371264352 13317296 5220448
21:10:55 39777 8192000000 10297532224 10749206528 1388131424 14.489 100 15 29 0 1412796816 21125824 3539568
21:12:46 42734 8192000000 10294387712 10759864320 1384459264 14.456 100 11 22 1 1410067552 22994016 2614272
21:14:45 79803 8192000000 10277911008 10736177152 1357761680 14.217 98 28 56 0 1374671616 10143232 6766704
21:17:10 59745 8192000000 10281377632 10742681600 1386502944 14.475 100 16 30 2 1399366880 9515440 3348496
21:19:18 51869 8192000000 10302203056 10751995904 1384840576 14.460 99 22 44 0 1402711808 12284640 5586592
21:21:37 46909 8192000000 10307337712 10767171584 1408312288 14.669 100 17 33 0 1425230688 13155840 3762560
21:23:32 34744 8192000000 10309370224 10765230080 1406551728 14.653 100 22 44 0 1421408832 9719360 5137744
21:25:09 73580 8192000000 10307380816 10772598784 1407521280 14.662 100 10 20 0 1423745904 14076208 2148416
21:27:27 42954 8192000000 10308042544 10779430912 1413646640 14.716 100 22 44 1 1434582176 15527600 5407936
21:29:44 37239 8192000000 10321164976 10774605824 1413933840 14.719 100 18 36 2 1429506912 10566704 5006368
21:31:26 44385 8192000000 10312131488 10778181632 1404363360 14.634 99 17 34 0 1420043104 11896224 3783520
21:33:14 21961 8192000000 10342716624 10799464448 1429587696 14.858 100 52 104 0 1456062144 14904080 11570368
21:34:36 35702 8192000000 10314406784 10778468352 1400605744 14.600 99 17 34 0 1424498608 19650112 4242752
21:36:12 101407 8192000000 10306852736 10774835200 1358948000 14.228 96 12 24 0 1375703280 13630688 3124592
21:38:54 16376 8192000000 10328602272 10772492288 1411193408 14.695 100 54 108 0 1432413328 9114480 12105440
21:40:12 51852 8192000000 10308660544 10755194880 1357048864 14.211 96 18 36 0 1369835040 9316432 3469744
21:42:06 36994 8192000000 10291624736 10734792704 1333445136 13.998 96 35 70 0 1357809648 16571872 7792640
21:43:44 115603 8192000000 10295786224 10751471616 1374420512 14.367 99 28 56 0 1393955520 13506064 6028944
21:46:49 20943 8192000000 10271358976 10731204608 1359931696 14.237 98 24 49 0 1377349744 11927744 5490304
21:48:49 50496 8192000000 10279290736 10732290048 1366997248 14.300 99 21 40 0 1382192096 10442704 4752144
21:50:48 55099 8192000000 10262341776 10727276544 1321858560 13.894 96 11 22 1 1343985568 19663824 2463184
21:52:43 38387 8192000000 10261050944 10698108928 1284901120 13.558 94 28 56 0 1307192800 15706864 6584816
21:54:33 66608 8192000000 10264853328 10707922944 1329180256 13.960 99 15 30 0 1351658368 18938032 3540080
21:56:40 73042 8192000000 10259460384 10707824640 1314575856 13.828 97 18 36 0 1328352112 9744016 4032240
21:58:54 44504 8192000000 10307942528 10734350336 1350252368 14.150 98 33 65 0 1379998320 22539856 7206096
22:00:42 78255 8192000000 10272604752 10707689472 1298755360 13.684 95 13 26 0 1317764768 16332576 2676832
22:03:09 48053 8192000000 10279308384 10705330176 1331200976 13.978 98 24 51 0 1348346560 11116272 6029312
22:05:01 127865 8192000000 10263164256 10717843456 1324488224 13.917 97 17 34 0 1339022528 10523664 4010640
22:08:17 32117 8192000000 10268654816 10718523392 1366367200 14.294 100 15 29 1 1384647856 15392144 2888512
22:10:04 68749 8192000000 10277449600 10735398912 1372417824 14.349 99 13 26 0 1391032800 15446032 3168944
22:12:29 69196 8192000000 10298040688 10754383872 1388915600 14.496 100 12 24 0 1405704592 14225408 2563584
22:14:52 35304 8192000000 10314305840 10766454784 1409967248 14.684 100 17 34 0 1425985760 12230576 3787936
22:16:28 49876 8192000000 10307715984 10770407424 1404343888 14.634 100 16 32 0 1420546224 12368480 3833856
22:18:18 56021 8192000000 10308599568 10759938048 1360843456 14.245 97 29 59 0 1381373568 13814480 6715632
22:20:18 66699 8192000000 10308874112 10756042752 1388924208 14.496 99 14 27 0 1411779360 19663648 3191504
22:22:28 76300 8192000000 10302329472 10762883072 1372578208 14.350 98 13 26 0 1395577536 20260224 2739104
22:24:47 50014 8192000000 10309519360 10754412544 1388063712 14.489 99 12 24 0 1400496672 10159456 2273504
22:26:42 93323 8192000000 10310509232 10760871936 1389850160 14.505 99 27 54 0 1407586720 12057760 5678800
22:29:16 36340 8192000000 10289718048 10756886528 1389630416 14.503 100 6 12 0 1403732352 12803792 1298144
22:30:57 47741 8192000000 10275448016 10736910336 1356913904 14.210 97 8 16 0 1369480464 10760128 1806432
22:32:54 77552 8192000000 10291040976 10752618496 1363675040 14.270 98 17 35 0 1381376176 13585584 4115552
22:35:12 42981 8192000000 10300980160 10750033920 1373948032 14.362 98 20 40 0 1389202016 10451552 4802432
22:37:07 43713 8192000000 10303304960 10758647808 1387436160 14.483 100 19 36 0 1401104848 9418608 4250080

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 15-08-2022 22:39:56 to 15-08-2022 22:40:11.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 119 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-08-2022 22:40:12 to 15-08-2022 22:40:14.

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          246987
MON$OLDEST_ACTIVE               246988
MON$OLDEST_SNAPSHOT             246988
MON$NEXT_TRANSACTION            246988
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                       2021312
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.33616 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33616 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33616 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33616 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 ---
--- end of diff output ---

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

15.08.22 22:40:15. 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-08-15 22:40:17 No severe PSQL-related problems occured
15.08.22 22:40:19. End of report.