Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.01.2022 16:38
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 16445872 kB
MemAvailable 31288284 kB
Buffers 0 kB
Cached 15203460 kB
SwapCached 1200 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.9
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-01-05 16:37:59
DTS_END                         2022-01-05 16:37:59.9040
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/53720
TRN_ID                          242019
ATT_ID                          675
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: 9203.70 17715 1104444
customer order: creation 176.93 339 21231
customer order: refuse 14.64 331 1757
order to supplier: creation 46.18 1317 5542
order to supplier: removal 3.58 96995 430
invoice (draft): creation 47.15 3366 5658
invoice (draft): removal 51.98 780 6237
invoice accept: apply 42.43 18600 5091
invoice accept: cancel 46.88 810 5625
customer reserve: creation 3896.35 186 467562
customer reserve: removal 4580.94 58 549713
realization accept: apply 85.64 171 10277
realization accept: cancel 79.64 106 9557
payment from customer: creation 59.94 113 7193
payment from customer: removal 5.53 79 664
payment to supplier: creation 49.73 113 5968
payment to supplier: removal 3.57 107 428
service: total inventory turnovers 4.95 1041 594
service: total monetary turnovers 6.65 53 798
service: aggregate perf. data 0.96 13132 115
service: refresh index statistics 0.03 216593 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 05-01-2022 16:39:16 to 05-01-2022 16:39:18.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4167
TEST_TIME 2 4568
TEST_TIME 3 3611
TEST_TIME 4 3834
TEST_TIME 5 4924
TEST_TIME 6 5209
TEST_TIME 7 5307
TEST_TIME 8 5290
TEST_TIME 9 5643
TEST_TIME 10 5696
TEST_TIME 11 5895
TEST_TIME 12 6062
TEST_TIME 13 6074
TEST_TIME 14 5980
TEST_TIME 15 5971
TEST_TIME 16 5982
TEST_TIME 17 6170
TEST_TIME 18 6406
TEST_TIME 19 6571
TEST_TIME 20 6806
TEST_TIME 21 7111
TEST_TIME 22 7539
TEST_TIME 23 8532
TEST_TIME 24 8716
TEST_TIME 25 8940
TEST_TIME 26 9077
TEST_TIME 27 9303
TEST_TIME 28 9381
TEST_TIME 29 10232
TEST_TIME 30 10194
TEST_TIME 31 10254
TEST_TIME 32 10333
TEST_TIME 33 10140
TEST_TIME 34 9982
TEST_TIME 35 9981
TEST_TIME 36 9840
TEST_TIME 37 10061
TEST_TIME 38 9929
TEST_TIME 39 9797
TEST_TIME 40 9800
TEST_TIME 41 9708
TEST_TIME 42 9710
TEST_TIME 43 9726
TEST_TIME 44 9715
TEST_TIME 45 9602
TEST_TIME 46 9595
TEST_TIME 47 9611
TEST_TIME 48 9567
TEST_TIME 49 9479
TEST_TIME 50 9407
TEST_TIME 51 9370
TEST_TIME 52 9341
TEST_TIME 53 9340
TEST_TIME 54 9357
TEST_TIME 55 9343
TEST_TIME 56 9385
TEST_TIME 57 9360
TEST_TIME 58 9453
TEST_TIME 59 9456
TEST_TIME 60 9495
TEST_TIME 61 9548
TEST_TIME 62 9495
TEST_TIME 63 9573
TEST_TIME 64 9597
TEST_TIME 65 9653
TEST_TIME 66 9773
TEST_TIME 67 9710
TEST_TIME 68 9634
TEST_TIME 69 9597
TEST_TIME 70 9523
TEST_TIME 71 9498
TEST_TIME 72 9463
TEST_TIME 73 9456
TEST_TIME 74 9426
TEST_TIME 75 9379
TEST_TIME 76 9372
TEST_TIME 77 9329
TEST_TIME 78 9321
TEST_TIME 79 9316
TEST_TIME 80 9367
TEST_TIME 81 9411
TEST_TIME 82 9389
TEST_TIME 83 9377
TEST_TIME 84 9348
TEST_TIME 85 9389
TEST_TIME 86 9510
TEST_TIME 87 9489
TEST_TIME 88 9525
TEST_TIME 89 9492
TEST_TIME 90 9580
TEST_TIME 91 9494
TEST_TIME 92 9461
TEST_TIME 93 9405
TEST_TIME 94 9404
TEST_TIME 95 9364
TEST_TIME 96 9351
TEST_TIME 97 9323
TEST_TIME 98 9310
TEST_TIME 99 9338
TEST_TIME 100 9371
TEST_TIME 101 9337
TEST_TIME 102 9362
TEST_TIME 103 9404
TEST_TIME 104 9381
TEST_TIME 105 9395
TEST_TIME 106 9414
TEST_TIME 107 9431
TEST_TIME 108 9454
TEST_TIME 109 9448
TEST_TIME 110 9485
TEST_TIME 111 9424
TEST_TIME 112 9390
TEST_TIME 113 9358
TEST_TIME 114 9324
TEST_TIME 115 9294
TEST_TIME 116 9249
TEST_TIME 117 9233
TEST_TIME 118 9218
TEST_TIME 119 9199
TEST_TIME 120 9205

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 05-01-2022 16:39:19 to 05-01-2022 16:39:23.

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 1536412 1536412 0 0.00 0 26530 6 0 0
doc_list_biud 1536411 1536411 0 0.00 0 63932 27 0 0
sp_add_invoice_to_stock 5210 5091 119 2.28 53 193765 18601 0 119
sp_cancel_adding_invoice 5625 5625 0 0.00 5 73379 810 0 0
sp_cancel_client_order 1757 1757 0 0.00 4 8854 331 0 0
sp_cancel_customer_reserve 549713 549713 0 0.00 1 63935 59 0 0
sp_cancel_pay_from_customer 664 664 0 0.00 3 3581 79 0 0
sp_cancel_pay_to_supplier 428 428 0 0.00 2 9357 107 0 0
sp_cancel_supplier_invoice 6249 6237 12 0.19 1 15961 780 0 12
sp_cancel_supplier_order 430 430 0 0.00 7 1094532 96995 0 0
sp_cancel_write_off 9557 9557 0 0.00 1 14025 106 0 0
sp_client_order 21231 21231 0 0.00 3 23207 339 0 0
sp_customer_reserve 467562 467562 0 0.00 0 54086 186 0 0
sp_fill_shopping_cart 32415 32415 0 0.00 1 20428 35 0 0
sp_fill_shopping_cart_clo_res 467559 467559 0 0.00 0 16888 14 0 0
sp_get_clo_for_invoice 5088 5088 0 0.00 0 35999 295 0 0
sp_kill_cost_storno 16274 16274 0 0.00 0 8094 59 0 0
sp_kill_qstorno_ret_qs2qd 1115650 1115650 0 0.00 0 63877 32 0 0
sp_kill_qty_storno 588684 588684 0 0.00 0 63931 69 0 0
sp_lock_selected_doc 21147 21147 0 0.00 0 7811 5 0 0
sp_make_cost_storno 28526 28526 0 0.00 0 21577 48 0 0
sp_multiply_rows_for_pdistr 28526 28526 0 0.00 0 7589 27 0 0
sp_multiply_rows_for_qdistr 21231 21231 0 0.00 0 17396 170 0 0
sp_pay_from_customer 7193 7193 0 0.00 4 16740 113 0 0
sp_pay_to_supplier 5968 5968 0 0.00 4 21592 114 0 0
sp_payment_common 13161 13161 0 0.00 2 16699 30 0 0
sp_qd_handle_on_cancel_clo 1757 1757 0 0.00 0 8846 305 0 0
sp_qd_handle_on_invoice_upd_sts 10713 10713 0 0.00 0 38375 355 0 0
sp_qd_handle_on_reserve_upd_sts 10277 10277 0 0.00 0 19964 58 0 0
sp_reserve_write_off 10277 10277 0 0.00 5 19977 172 0 0
sp_supplier_invoice 5658 5658 0 0.00 7 98365 3367 0 0
sp_supplier_order 5542 5542 0 0.00 5 29520 1318 0 0
srv_aggregate_perf_data 115 115 0 0.00 2988 103649 13133 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1207 160139 46025 0 0
srv_find_qd_qs_mism 1055429 1055429 0 0.00 0 26529 6 0 0
srv_make_invnt_saldo 594 594 0 0.00 42 100627 1042 0 0
srv_make_money_saldo 798 798 0 0.00 1 22243 53 0 0
srv_recalc_idx_stat 4 4 0 0.00 147751 336073 216593 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 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5091 5091 0 0.00 0 9563 163 0 0
t$perf-norm:sp_cancel_adding_invoice 420 420 0 0.00 1 1962 78 0 0
t$perf-norm:sp_cancel_client_order 1757 1757 0 0.00 0 9089 20 0 0
t$perf-norm:sp_cancel_customer_reserve 624 624 0 0.00 0 1126 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 664 664 0 0.00 0 1271 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 428 428 0 0.00 0 603 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 387 387 0 0.00 0 3080 25 0 0
t$perf-norm:sp_cancel_supplier_order 430 430 0 0.00 0 33536 1706 0 0
t$perf-norm:sp_cancel_write_off 1072 1072 0 0.00 0 1270 9 0 0
t$perf-norm:sp_client_order 21231 21231 0 0.00 0 12952 19 0 0
t$perf-norm:sp_customer_reserve 1015 1015 0 0.00 0 1629 7 0 0
t$perf-norm:sp_pay_from_customer 7193 7193 0 0.00 0 5354 11 0 0
t$perf-norm:sp_pay_to_supplier 5968 5968 0 0.00 0 4793 11 0 0
t$perf-norm:sp_reserve_write_off 10277 10277 0 0.00 0 5296 10 0 0
t$perf-norm:sp_supplier_invoice 5658 5658 0 0.00 0 5771 20 0 0
t$perf-norm:sp_supplier_order 5542 5542 0 0.00 0 7558 17 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 685 20 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 209 5 0 0
t$perf-norm:srv_make_invnt_saldo 594 594 0 0.00 0 752 16 0 0
t$perf-norm:srv_make_money_saldo 798 798 0 0.00 0 767 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 10414 10176 238 2.28 0 19088 21 0 238
v_all_customers 38840 38840 0 0.00 0 15 1 0 0
v_all_suppliers 31096 31096 0 0.00 0 76 1 0 0
v_all_wares 42460 42460 0 0.00 0 33 1 0 0
v_cancel_adding_invoice 840 840 0 0.00 0 1190 8 0 0
v_cancel_client_order 3514 3514 0 0.00 0 2757 6 0 0
v_cancel_customer_prepayment 1328 1328 0 0.00 0 1678 5 0 0
v_cancel_customer_reserve 1248 1248 0 0.00 0 2520 5 0 0
v_cancel_payment_to_supplier 856 856 0 0.00 0 9325 14 0 0
v_cancel_supplier_invoice 798 774 24 3.00 0 1605 22 0 24
v_cancel_supplier_order 860 860 0 0.00 0 377 3 0 0
v_cancel_write_off 2144 2144 0 0.00 0 13981 19 0 0
v_max_id_clo_ord 5542 5542 0 0.00 0 235 1 0 0
v_max_id_clo_res 1015 1015 0 0.00 0 5482 24 0 0
v_max_id_ord_sup 5642 5642 0 0.00 0 4332 3 0 0
v_max_non_paid_invoice 1604 1604 0 0.00 0 1176 3 0 0
v_max_non_paid_realizn 3844 3844 0 0.00 0 2402 5 0 0
v_min_id_clo_ord 5542 5542 0 0.00 0 2625 7 0 0
v_min_id_clo_res 1015 1015 0 0.00 0 11757 33 0 0
v_min_id_ord_sup 5642 5642 0 0.00 0 6530 9 0 0
v_min_non_paid_invoice 5968 5968 0 0.00 0 11970 19 0 0
v_min_non_paid_realizn 7193 7193 0 0.00 0 16695 25 0 0
v_our_firm 10316 10316 0 0.00 0 19 1 0 0
v_reserve_write_off 20554 20554 0 0.00 0 16058 7 0 0
x_lock_depdocs_on_canc_invoice 11475 11475 0 0.00 0 17054 116 0 0
x_lock_depdocs_on_canc_sup_ord 430 430 0 0.00 0 16816 398 0 0
x_make_qstorno_1000_1200 5542 5542 0 0.00 1 29509 1236 0 0
x_make_qstorno_1200_2000 5658 5658 0 0.00 3 98242 3243 0 0
x_make_qstorno_2100_3300 467562 467562 0 0.00 0 54085 169 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-01-2022 16:39:24 to 05-01-2022 16:39:28.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
14:08:03 7 8192000000 8799915648 8811413504 6309952 0.076 2 1 2 0 6409584 56848 42784
14:09:04 34911 8192000000 9913825520 10228350976 983933840 10.722 100 88 176 0 1013441088 6528160 22979088
14:10:42 18774 8192000000 9946222816 10270752768 1028531936 11.154 100 88 176 0 1060066928 9228464 22306528
14:12:09 19016 8192000000 9979671616 10317946880 1067868656 11.532 100 77 154 0 1097405792 10470144 19066992
14:13:34 9950 8192000000 10032774384 10381692928 1116246480 11.992 100 81 158 3 1147562544 11712720 19603344
14:14:46 6113 8192000000 10082755568 10445459456 1174209696 12.536 100 76 152 1 1202959104 10079008 18670400
14:15:53 4152 8192000000 10128722896 10500726784 1219527552 12.957 100 82 160 1 1248721568 11977840 17216176
14:17:00 4585 8192000000 10154269968 10533588992 1247202144 13.213 100 79 157 2 1277411184 12847840 17361200
14:18:07 2385 8192000000 10187490016 10572918784 1277790496 13.493 100 86 174 2 1310836944 14149248 18897200
14:19:12 1959 8192000000 10218353616 10603741184 1296149088 13.660 100 96 192 1 1332810160 14842192 21818880
14:20:14 9904 8192000000 10201170848 10609373184 1305810880 13.748 100 33 66 0 1320542928 7202576 7529472
14:21:27 9819 8192000000 10203708848 10619047936 1313014288 13.813 100 31 60 0 1326984080 7436768 6533024
14:22:39 5460 8192000000 10216164640 10630995968 1323725040 13.910 100 27 55 0 1336679184 6469936 6484208
14:23:48 53684 8192000000 10228584560 10645643264 1330125312 13.968 100 24 48 1 1341020704 5144080 5751312
14:25:42 6425 8192000000 10225660256 10650177536 1336176304 14.023 100 30 60 0 1348394128 5640448 6577376
14:26:49 6161 8192000000 10220313792 10638737408 1326992640 13.940 100 40 81 3 1341509856 5655456 8861760
14:27:57 9917 8192000000 10198300960 10613817344 1275976000 13.476 97 20 38 3 1285428512 4984288 4468224
14:29:09 26224 8192000000 10172328112 10581766144 1232807104 13.080 95 38 76 0 1247725264 6209552 8708608
14:30:37 3842 8192000000 10162617008 10563227648 1253923280 13.274 99 41 82 2 1269091840 5547376 9621184
14:31:44 11340 8192000000 10157602128 10556403712 1230620688 13.060 97 17 34 0 1244125392 9790784 3713920
14:32:57 8434 8192000000 10158822816 10567118848 1270356624 13.425 100 19 38 2 1280316912 5329568 4630720
14:34:07 47143 8192000000 10171886256 10582863872 1281619248 13.528 100 48 95 0 1298713504 5622464 11471792
14:35:57 6547 8192000000 10194911264 10602512384 1294711696 13.647 100 34 68 1 1309130576 5798736 8620144
14:37:06 7852 8192000000 10202099168 10616684544 1306506464 13.754 100 21 42 1 1316923216 5502176 4914576
14:38:15 30309 8192000000 10237202496 10649415680 1332020048 13.985 100 42 83 1 1347175920 5737344 9418528
14:39:48 70120 8192000000 10265392224 10684293120 1360187440 14.239 100 55 110 4 1384268208 11736896 12343872
14:42:07 13483 8192000000 10273341024 10701307904 1373876288 14.362 100 57 115 1 1401521680 14097088 13548304
14:43:25 25434 8192000000 10299587616 10722852864 1384557584 14.457 100 45 90 1 1414232576 18818560 10856432
14:44:52 13209 8192000000 10307183024 10729844736 1386164448 14.472 100 54 106 0 1420017504 20491312 13361744
14:46:13 6864 8192000000 10309818192 10734682112 1392406384 14.527 100 46 92 1 1421683840 18828848 10448608
14:47:25 14659 8192000000 10323944688 10743259136 1393957888 14.541 100 60 120 0 1429947120 21353472 14635760
14:48:44 6731 8192000000 10334163824 10756587520 1409344944 14.678 100 50 100 1 1443142672 21211328 12586400
14:49:54 4354 8192000000 10338179232 10753716224 1399105968 14.587 99 73 146 3 1440908544 23334960 18467616
14:51:01 8846 8192000000 10323706448 10741600256 1397931712 14.577 100 42 84 2 1433438736 24446608 11060416
14:52:16 6749 8192000000 10343743280 10760933376 1408813312 14.673 100 61 123 0 1452128208 27961168 15353728
14:53:27 2029 8192000000 10352250512 10757742592 1398412784 14.581 100 84 164 0 1454116608 35134784 20569040
14:54:34 12881 8192000000 10356690848 10765242368 1405106944 14.640 100 77 151 1 1462956112 38330384 19518784
14:55:53 4875 8192000000 10362405568 10773839872 1406072112 14.649 100 70 139 2 1465845680 40581744 19191824
14:57:04 3726 8192000000 10349509456 10756313088 1374965216 14.372 100 79 158 2 1448730816 52267632 21497968
14:58:18 2228 8192000000 10350804688 10753146880 1372253504 14.347 100 86 171 3 1449313152 55227488 21832160
14:59:28 1207 8192000000 10370269600 10773954560 1388001152 14.488 100 94 187 0 1469321216 56451776 24868288
15:00:33 7325 8192000000 10313373328 10751717376 1378600992 14.404 100 30 60 1 1429079856 42226464 8252400
15:01:59 7986 8192000000 10326479584 10757623808 1387117184 14.480 100 44 87 0 1433913232 35361216 11434832
15:03:12 22063 8192000000 10317668096 10755727360 1383314544 14.446 100 25 50 1 1423758208 33826544 6617120
15:04:34 32597 8192000000 10302222528 10745364480 1369742000 14.325 100 19 38 0 1410319264 34953440 5623824
15:07:00 58649 8192000000 10286799248 10734321664 1362390768 14.259 99 16 31 1 1379311136 12879776 4040592
15:09:10 75341 8192000000 10300819152 10739486720 1366918336 14.299 99 14 28 0 1381868992 11515552 3435104
15:12:01 113442 8192000000 10316033248 10762063872 1374260368 14.365 98 30 60 1 1407039216 25368576 7410272
15:15:01 96975 8192000000 10299449024 10754637824 1350413728 14.151 97 15 30 2 1376904256 23327360 3163168
15:17:39 47482 8192000000 10302495232 10748100608 1367022608 14.300 98 22 44 0 1385791360 13792848 4975904
15:19:37 97861 8192000000 10273565200 10733285376 1322310512 13.898 95 14 28 1 1343089872 17399808 3379552
15:22:15 24761 8192000000 10292418560 10749296640 1393290688 14.535 100 16 32 1 1405819024 8743328 3785008
15:23:42 46320 8192000000 10291417056 10761625600 1393301904 14.535 100 10 20 0 1406717840 10541136 2874800
15:25:29 54438 8192000000 10303707760 10763882496 1400034288 14.595 100 26 52 0 1414287792 8473872 5779632
15:27:25 44623 8192000000 10303115072 10767544320 1398925968 14.585 100 15 30 1 1414050464 11408912 3715584
15:30:22 61609 8192000000 10286320736 10748981248 1330650752 13.973 95 14 28 0 1341669408 7447744 3570912
15:32:45 31840 8192000000 10311089280 10762027008 1388702176 14.494 99 31 62 0 1404804656 8372224 7730256
15:34:20 63391 8192000000 10299486848 10769772544 1374254208 14.365 98 23 46 1 1397357968 18195664 4908096
15:36:29 42835 8192000000 10287045888 10741993472 1333245968 13.996 96 32 64 0 1354000096 13644112 7110016
15:38:17 70362 8192000000 10288492560 10732937216 1336796800 14.029 97 19 38 0 1350985440 10392064 3796576
15:40:30 69695 8192000000 10278853968 10727583744 1328063952 13.950 96 15 30 0 1342566544 11486416 3016176
15:42:41 67619 8192000000 10266381120 10723004416 1319963792 13.876 96 6 12 0 1348390160 27024176 1402192
15:44:49 27989 8192000000 10274520176 10716827648 1349865968 14.146 99 55 110 1 1375259760 13046272 12347520
15:47:00 43973 8192000000 10273337312 10712297472 1330091024 13.968 97 30 60 1 1346659568 9617200 6951344
15:49:03 80842 8192000000 10255394496 10717446144 1359795920 14.236 100 9 18 0 1373601136 11979712 1825504
15:51:24 85682 8192000000 10259324192 10710507520 1326662352 13.937 97 12 24 0 1338202592 8622224 2918016
15:55:40 67594 8192000000 10261877376 10712244224 1337038064 14.031 98 13 26 1 1349744208 9551520 3154624
15:57:48 58296 8192000000 10290537632 10736717824 1373106128 14.355 100 19 38 1 1387031120 9268528 4656464
15:59:52 33314 8192000000 10287846288 10728833024 1352587632 14.171 98 43 86 1 1372261600 10155424 9518544
16:01:30 46337 8192000000 10279192288 10723221504 1333046816 13.995 97 41 82 2 1358459744 15916224 9496704
16:03:23 115373 8192000000 10282986400 10734227456 1318311360 13.861 96 22 44 1 1337474576 14552656 4610560
16:06:23 49881 8192000000 10279382064 10722955264 1318095424 13.859 96 20 40 0 1338690384 15866080 4728880
16:08:17 112939 8192000000 10286698256 10731294720 1357118192 14.211 99 24 48 0 1378101472 15523472 5459808
16:11:16 70220 8192000000 10287033392 10748841984 1391810944 14.522 100 15 30 0 1404530688 9435712 3284032
16:14:23 27758 8192000000 10304161840 10757648384 1396939216 14.568 100 22 44 0 1411087040 8803328 5344496
16:16:09 84272 8192000000 10308633504 10769895424 1376284480 14.383 98 15 30 0 1399102704 19042080 3776144
16:18:34 35756 8192000000 10318396560 10769199104 1399171744 14.588 100 17 34 0 1420247648 16644880 4431024
16:20:20 82133 8192000000 10291183520 10738622464 1329774640 13.965 95 16 32 0 1347532064 14596112 3161312
16:22:52 23994 8192000000 10292592432 10728599552 1326090896 13.932 96 35 70 0 1346174848 12636240 7447712
16:24:19 47387 8192000000 10286936896 10724954112 1345445248 14.106 99 29 58 0 1363163856 11270432 6448176
16:26:07 38491 8192000000 10279152192 10725036032 1346308880 14.114 98 33 66 2 1363581536 9929568 7343088
16:27:48 160138 8192000000 10285440320 10750767104 1354644000 14.189 98 15 30 0 1370821952 12577440 3600512
16:31:34 67453 8192000000 10275194032 10733445120 1378626544 14.404 100 6 12 0 1387141120 7250976 1263600
16:33:46 72232 8192000000 10276002432 10741825536 1378302064 14.401 100 12 24 1 1391801280 10403520 3095696
16:36:02 48148 8192000000 10279319184 10734968832 1341605344 14.072 97 21 42 0 1355741776 9188416 4948016
16:37:57 8379 8192000000 9175479136 9439821824 334695312 3.925 25 2 3 0 339519744 4482560 341872

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 05-01-2022 16:39:29 to 05-01-2022 16:39:44.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 05-01-2022 16:39:45 to 05-01-2022 16:39:47.

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          242313
MON$OLDEST_ACTIVE               242314
MON$OLDEST_SNAPSHOT             242314
MON$NEXT_TRANSACTION            242314
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                       2032544
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33548 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33548 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33548 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33548 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 ---
:474: 
:475: mscs-test-161.budzdorov.ru    Wed Jan  5 16:38:08 2022
:476:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:477: 
--- end of diff output ---

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

05.01.22 16:39:48. 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-01-05 16:39:50 No severe PSQL-related problems occured
05.01.22 16:39:52. End of report.