Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.04.2022 19: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 16770188 kB
MemAvailable 31518732 kB
Buffers 0 kB
Cached 14935048 kB
SwapCached 2688 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-12 19:38:14
DTS_END                         2022-04-12 19:38:14.3540
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/52782
TRN_ID                          243477
ATT_ID                          725
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: 9021.43 21115 1082572
customer order: creation 180.57 363 21668
customer order: refuse 14.88 386 1786
order to supplier: creation 46.49 1333 5579
order to supplier: removal 3.37 89192 404
invoice (draft): creation 48.41 3393 5809
invoice (draft): removal 50.03 764 6004
invoice accept: apply 42.92 17085 5150
invoice accept: cancel 45.33 809 5439
customer reserve: creation 3867.13 174 464056
customer reserve: removal 4419.93 53 530391
realization accept: apply 86.82 160 10418
realization accept: cancel 81.45 95 9774
payment from customer: creation 60.70 104 7284
payment from customer: removal 5.59 77 671
payment to supplier: creation 51.69 123 6203
payment to supplier: removal 3.67 82 440
service: total inventory turnovers 4.98 990 598
service: total monetary turnovers 6.68 59 802
service: aggregate perf. data 0.77 20378 92
service: refresh index statistics 0.03 286677 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-04-2022 19:39:27 to 12-04-2022 19:39:29.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3029
TEST_TIME 2 3737
TEST_TIME 3 5007
TEST_TIME 4 5093
TEST_TIME 5 5040
TEST_TIME 6 5055
TEST_TIME 7 5038
TEST_TIME 8 5117
TEST_TIME 9 5241
TEST_TIME 10 5559
TEST_TIME 11 5799
TEST_TIME 12 6499
TEST_TIME 13 6697
TEST_TIME 14 7094
TEST_TIME 15 7346
TEST_TIME 16 7950
TEST_TIME 17 8416
TEST_TIME 18 8357
TEST_TIME 19 9379
TEST_TIME 20 9876
TEST_TIME 21 10318
TEST_TIME 22 10293
TEST_TIME 23 10354
TEST_TIME 24 10544
TEST_TIME 25 10552
TEST_TIME 26 10414
TEST_TIME 27 10346
TEST_TIME 28 10423
TEST_TIME 29 10794
TEST_TIME 30 10540
TEST_TIME 31 10530
TEST_TIME 32 10351
TEST_TIME 33 10275
TEST_TIME 34 10199
TEST_TIME 35 10324
TEST_TIME 36 10152
TEST_TIME 37 10081
TEST_TIME 38 9988
TEST_TIME 39 9835
TEST_TIME 40 9760
TEST_TIME 41 9671
TEST_TIME 42 9566
TEST_TIME 43 9411
TEST_TIME 44 9455
TEST_TIME 45 9408
TEST_TIME 46 9322
TEST_TIME 47 9435
TEST_TIME 48 9383
TEST_TIME 49 9321
TEST_TIME 50 9429
TEST_TIME 51 9381
TEST_TIME 52 9457
TEST_TIME 53 9433
TEST_TIME 54 9465
TEST_TIME 55 9516
TEST_TIME 56 9591
TEST_TIME 57 9620
TEST_TIME 58 9647
TEST_TIME 59 9597
TEST_TIME 60 9548
TEST_TIME 61 9416
TEST_TIME 62 9360
TEST_TIME 63 9317
TEST_TIME 64 9348
TEST_TIME 65 9306
TEST_TIME 66 9255
TEST_TIME 67 9203
TEST_TIME 68 9204
TEST_TIME 69 9272
TEST_TIME 70 9251
TEST_TIME 71 9215
TEST_TIME 72 9272
TEST_TIME 73 9228
TEST_TIME 74 9258
TEST_TIME 75 9270
TEST_TIME 76 9292
TEST_TIME 77 9316
TEST_TIME 78 9324
TEST_TIME 79 9343
TEST_TIME 80 9428
TEST_TIME 81 9507
TEST_TIME 82 9396
TEST_TIME 83 9355
TEST_TIME 84 9332
TEST_TIME 85 9312
TEST_TIME 86 9276
TEST_TIME 87 9239
TEST_TIME 88 9194
TEST_TIME 89 9179
TEST_TIME 90 9152
TEST_TIME 91 9128
TEST_TIME 92 9080
TEST_TIME 93 9148
TEST_TIME 94 9125
TEST_TIME 95 9167
TEST_TIME 96 9153
TEST_TIME 97 9218
TEST_TIME 98 9227
TEST_TIME 99 9203
TEST_TIME 100 9222
TEST_TIME 101 9231
TEST_TIME 102 9258
TEST_TIME 103 9257
TEST_TIME 104 9271
TEST_TIME 105 9269
TEST_TIME 106 9231
TEST_TIME 107 9177
TEST_TIME 108 9112
TEST_TIME 109 9186
TEST_TIME 110 9183
TEST_TIME 111 9141
TEST_TIME 112 9089
TEST_TIME 113 9093
TEST_TIME 114 9054
TEST_TIME 115 9077
TEST_TIME 116 9036
TEST_TIME 117 9027
TEST_TIME 118 9003
TEST_TIME 119 9072
TEST_TIME 120 9026

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 12-04-2022 19:39:30 to 12-04-2022 19:39:34.

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 1513611 1513611 0 0.00 0 27642 6 0 0
doc_list_biud 1513609 1513609 0 0.00 0 31348 24 0 0
sp_add_invoice_to_stock 5325 5150 175 3.28 28 201363 17085 0 175
sp_cancel_adding_invoice 5439 5439 0 0.00 5 100506 809 0 0
sp_cancel_client_order 1786 1786 0 0.00 4 22585 386 0 0
sp_cancel_customer_reserve 530391 530391 0 0.00 1 31349 54 0 0
sp_cancel_pay_from_customer 671 671 0 0.00 3 3727 77 0 0
sp_cancel_pay_to_supplier 440 440 0 0.00 3 4659 82 0 0
sp_cancel_supplier_invoice 6015 6004 11 0.18 2 28780 764 0 11
sp_cancel_supplier_order 404 404 0 0.00 5 1112095 89193 0 0
sp_cancel_write_off 9774 9774 0 0.00 1 11695 95 0 0
sp_client_order 21668 21668 0 0.00 3 22425 363 0 0
sp_customer_reserve 464056 464056 0 0.00 0 145583 174 0 0
sp_fill_shopping_cart 33052 33052 0 0.00 1 8567 37 0 0
sp_fill_shopping_cart_clo_res 464048 464048 0 0.00 0 14855 14 0 0
sp_get_clo_for_invoice 5141 5141 0 0.00 0 21850 299 0 0
sp_kill_cost_storno 16324 16324 0 0.00 0 27630 54 0 0
sp_kill_qstorno_ret_qs2qd 1076964 1076964 0 0.00 0 31345 29 0 0
sp_kill_qty_storno 569357 569357 0 0.00 0 31347 64 0 0
sp_lock_selected_doc 21481 21481 0 0.00 0 8290 5 0 0
sp_make_cost_storno 29046 29046 0 0.00 0 11975 47 0 0
sp_multiply_rows_for_pdistr 29046 29046 0 0.00 0 6839 28 0 0
sp_multiply_rows_for_qdistr 21668 21668 0 0.00 0 17197 168 0 0
sp_pay_from_customer 7284 7284 0 0.00 4 11370 104 0 0
sp_pay_to_supplier 6203 6203 0 0.00 4 12170 123 0 0
sp_payment_common 13487 13487 0 0.00 1 8521 23 0 0
sp_qd_handle_on_cancel_clo 1786 1786 0 0.00 0 19376 336 0 0
sp_qd_handle_on_invoice_upd_sts 10580 10580 0 0.00 0 27421 312 0 0
sp_qd_handle_on_reserve_upd_sts 10418 10418 0 0.00 0 9406 48 0 0
sp_reserve_write_off 10418 10418 0 0.00 6 17719 160 0 0
sp_supplier_invoice 5809 5809 0 0.00 5 93661 3393 0 0
sp_supplier_order 5579 5579 0 0.00 5 44204 1333 0 0
srv_aggregate_perf_data 92 92 0 0.00 2378 256849 20378 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1846 137473 45802 0 0
srv_find_qd_qs_mism 1034190 1034190 0 0.00 0 9472 5 0 0
srv_make_invnt_saldo 598 598 0 0.00 27 25110 991 0 0
srv_make_money_saldo 802 802 0 0.00 1 19042 60 0 0
srv_recalc_idx_stat 4 4 0 0.00 227114 373085 286677 0 0
t$perf-abend:sp_add_invoice_to_stock 175 175 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5150 5150 0 0.00 0 10341 171 0 0
t$perf-norm:sp_cancel_adding_invoice 434 434 0 0.00 0 5110 104 0 0
t$perf-norm:sp_cancel_client_order 1786 1786 0 0.00 0 5235 13 0 0
t$perf-norm:sp_cancel_customer_reserve 725 725 0 0.00 0 3018 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 671 671 0 0.00 0 5703 18 0 0
t$perf-norm:sp_cancel_pay_to_supplier 440 440 0 0.00 0 4495 25 0 0
t$perf-norm:sp_cancel_supplier_invoice 414 414 0 0.00 0 1091 19 0 0
t$perf-norm:sp_cancel_supplier_order 404 404 0 0.00 0 29722 1532 0 0
t$perf-norm:sp_cancel_write_off 1048 1048 0 0.00 0 6614 23 0 0
t$perf-norm:sp_client_order 21668 21668 0 0.00 0 9334 20 0 0
t$perf-norm:sp_customer_reserve 1053 1053 0 0.00 0 3522 9 0 0
t$perf-norm:sp_pay_from_customer 7284 7284 0 0.00 0 9960 14 0 0
t$perf-norm:sp_pay_to_supplier 6203 6203 0 0.00 0 5717 13 0 0
t$perf-norm:sp_reserve_write_off 10418 10418 0 0.00 0 8475 14 0 0
t$perf-norm:sp_supplier_invoice 5809 5809 0 0.00 0 6323 25 0 0
t$perf-norm:sp_supplier_order 5579 5579 0 0.00 0 2885 14 0 0
t$perf-norm:srv_aggregate_perf_data 92 92 0 0.00 0 48 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 2 0 0 0
t$perf-norm:srv_make_invnt_saldo 598 598 0 0.00 0 3341 33 0 0
t$perf-norm:srv_make_money_saldo 802 802 0 0.00 0 2833 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 10630 10280 350 3.29 0 10560 18 0 350
v_all_customers 39066 39066 0 0.00 0 95 1 0 0
v_all_suppliers 31662 31662 0 0.00 0 26 1 0 0
v_all_wares 43336 43336 0 0.00 0 59 1 0 0
v_cancel_adding_invoice 868 868 0 0.00 0 1275 7 0 0
v_cancel_client_order 3572 3572 0 0.00 0 20850 16 0 0
v_cancel_customer_prepayment 1342 1342 0 0.00 0 2369 6 0 0
v_cancel_customer_reserve 1450 1450 0 0.00 0 21469 27 0 0
v_cancel_payment_to_supplier 880 880 0 0.00 0 4639 17 0 0
v_cancel_supplier_invoice 850 828 22 2.58 0 4095 27 0 22
v_cancel_supplier_order 808 808 0 0.00 0 1087 6 0 0
v_cancel_write_off 2096 2096 0 0.00 0 9655 20 0 0
v_max_id_clo_ord 5578 5578 0 0.00 0 350 1 0 0
v_max_id_clo_res 1053 1053 0 0.00 0 2119 14 0 0
v_max_id_ord_sup 5803 5803 0 0.00 0 4235 5 0 0
v_max_non_paid_invoice 1753 1753 0 0.00 0 608 3 0 0
v_max_non_paid_realizn 3948 3948 0 0.00 0 3455 6 0 0
v_min_id_clo_ord 5578 5578 0 0.00 0 6221 7 0 0
v_min_id_clo_res 1053 1053 0 0.00 0 5901 13 0 0
v_min_id_ord_sup 5803 5803 0 0.00 0 7288 9 0 0
v_min_non_paid_invoice 6203 6203 0 0.00 0 5420 14 0 0
v_min_non_paid_realizn 7284 7284 0 0.00 1 8515 16 0 0
v_our_firm 10942 10942 0 0.00 0 15 1 0 0
v_reserve_write_off 20836 20836 0 0.00 0 15980 8 0 0
x_lock_depdocs_on_canc_invoice 11029 11029 0 0.00 0 18901 115 0 0
x_lock_depdocs_on_canc_sup_ord 404 404 0 0.00 0 7032 294 0 0
x_make_qstorno_1000_1200 5579 5579 0 0.00 2 44191 1254 0 0
x_make_qstorno_1200_2000 5809 5809 0 0.00 3 93471 3263 0 0
x_make_qstorno_2100_3300 464056 464056 0 0.00 0 145581 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-04-2022 19:39:35 to 12-04-2022 19:39:39.

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
17:08:18 17 8192000000 8816148832 8833441792 18280464 0.222 5 4 4 0 18476464 88864 107136
17:09:18 13369 8192000000 9890624688 10199756800 977202480 10.657 100 86 172 1 1005149776 5534160 22413136
17:10:35 27841 8192000000 9937021760 10254757888 1015820304 11.032 100 88 176 0 1047055904 8166704 23068896
17:12:05 12394 8192000000 9971881232 10300674048 1051543648 11.375 100 78 156 0 1080479248 9008144 19927456
17:13:25 13031 8192000000 10016296976 10357964800 1099110560 11.829 100 73 146 0 1126889040 10119504 17658976
17:14:46 8504 8192000000 10069098448 10423902208 1155489968 12.361 100 86 172 1 1188020176 12097120 20433088
17:15:58 6589 8192000000 10121422800 10488274944 1208993488 12.860 100 82 164 1 1240389152 12392064 19003600
17:17:07 3107 8192000000 10155982112 10527526912 1245505392 13.197 100 84 166 1 1276231008 12618832 18106784
17:18:12 2629 8192000000 10193516400 10570579968 1273791376 13.456 100 94 188 2 1309365232 14842464 20731392
17:19:16 2570 8192000000 10205046320 10585174016 1289104960 13.596 100 94 189 1 1321943808 11376416 21462432
17:20:21 1934 8192000000 10223816928 10606026752 1302161168 13.715 100 94 188 1 1337280592 13925488 21193936
17:21:23 5907 8192000000 10194141808 10603892736 1307418720 13.763 100 29 59 1 1321752688 8342032 5991936
17:22:30 17867 8192000000 10198146192 10611945472 1316099120 13.841 100 18 36 1 1326294304 5647024 4548160
17:23:49 37100 8192000000 10223387344 10636734464 1323303264 13.907 100 35 70 1 1337158512 6219776 7635472
17:25:27 8705 8192000000 10232241728 10649100288 1336282160 14.024 100 22 44 0 1347392272 6298864 4811248
17:26:36 7299 8192000000 10230198176 10648756224 1341786528 14.074 100 19 38 1 1351688800 5742080 4160192
17:27:44 54583 8192000000 10230274880 10637860864 1322903936 13.903 100 62 124 3 1344377776 7934096 13539744
17:29:44 6331 8192000000 10173940000 10588721152 1258401712 13.315 97 29 58 0 1271679744 6822000 6456032
17:30:56 7587 8192000000 10138810256 10537725952 1207769440 12.848 96 31 62 0 1221649952 6288160 7592352
17:32:05 12900 8192000000 10162562144 10557186048 1260214192 13.332 100 19 38 0 1270757312 5788256 4754864
17:33:19 13060 8192000000 10132222736 10541518848 1218321456 12.946 97 18 34 1 1227253328 4644592 4287280
17:34:34 6351 8192000000 10179088544 10583650304 1280509152 13.518 100 22 45 0 1292478064 5959376 6009536
17:35:44 11955 8192000000 10195602976 10606321664 1301393856 13.708 100 25 49 1 1313181280 5905104 5882320
17:37:00 56295 8192000000 10205297872 10619637760 1312165488 13.806 100 21 42 0 1321812112 5010496 4636128
17:39:08 20195 8192000000 10244222560 10659102720 1342272704 14.078 100 44 87 2 1363859216 11467360 10119152
17:40:37 11495 8192000000 10280421904 10692247552 1365346800 14.285 100 52 101 1 1392710480 16121936 11241744
17:41:52 24376 8192000000 10286044512 10710257664 1371063488 14.337 100 35 70 2 1402854496 23606608 8184400
17:43:22 6777 8192000000 10300919344 10722156544 1387337600 14.482 100 44 88 0 1415663600 17982336 10343664
17:44:39 22749 8192000000 10305970768 10731413504 1381095152 14.426 99 44 88 0 1411846320 20217408 10533760
17:46:08 5977 8192000000 10314477344 10731085824 1382443904 14.438 99 57 114 2 1419440032 22475120 14521008
17:47:21 6850 8192000000 10347946576 10771968000 1418925472 14.763 100 55 110 2 1458246624 26160560 13160592
17:48:36 2612 8192000000 10354440432 10779041792 1421695168 14.788 100 55 110 2 1465509072 30330480 13483424
17:49:45 2950 8192000000 10361031056 10777174016 1412532224 14.706 100 72 144 1 1465149584 34648640 17968720
17:50:53 2948 8192000000 10372535600 10781806592 1414970800 14.728 100 92 184 2 1476153760 37689184 23493776
17:52:00 1910 8192000000 10377083488 10793562112 1426125328 14.827 100 75 150 1 1483091808 37848864 19117616
17:53:08 3185 8192000000 10369618544 10788749312 1417639504 14.752 100 72 144 1 1474959424 39981728 17338192
17:54:19 1845 8192000000 10356934272 10768547840 1389327264 14.500 99 75 148 1 1452853408 44566544 18959600
17:55:24 16783 8192000000 10304521312 10741657600 1368501504 14.314 99 19 38 2 1409310960 35233280 5576176
17:56:43 16184 8192000000 10303619232 10730962944 1321345424 13.889 97 42 84 2 1374806880 41603872 11857584
17:58:02 35003 8192000000 10305739280 10745126912 1362524896 14.260 99 20 40 0 1397135120 29180544 5429680
17:59:39 29921 8192000000 10279269952 10718879744 1317437536 13.854 96 24 48 1 1347086448 23774816 5874096
18:01:14 7938 8192000000 10272849696 10700132352 1346472016 14.116 100 40 79 2 1373637248 18200832 8964400
18:02:26 24291 8192000000 10289521600 10729570304 1369027680 14.318 100 24 48 0 1401565984 26808032 5730272
18:04:05 43301 8192000000 10295319760 10741116928 1353093520 14.175 98 15 29 0 1380155920 23185248 3877152
18:05:50 19823 8192000000 10302777632 10724589568 1341685552 14.073 98 58 117 3 1382629376 26136880 14806944
18:07:11 108348 8192000000 10269222960 10704728064 1337885248 14.038 99 29 58 0 1370905216 26476848 6543120
18:10:05 137473 8192000000 10301399520 10737659904 1378629072 14.404 100 21 42 0 1391893232 8517008 4747152
18:13:25 35516 8192000000 10249511408 10703048704 1330825248 13.975 97 11 23 1 1343122064 9638752 2658064
18:15:02 114086 8192000000 10278622960 10733621248 1370572080 14.332 99 13 26 0 1384847696 11377136 2898480
18:18:13 56948 8192000000 10291907424 10748841984 1392808928 14.531 100 10 20 0 1408604784 13509344 2286512
18:20:10 100544 8192000000 10290427792 10740207616 1381270912 14.428 99 20 39 0 1397660640 12275440 4114288
18:22:52 32844 8192000000 10292735488 10750722048 1387276464 14.482 99 25 50 1 1403406048 10248176 5881408
18:24:26 80793 8192000000 10289115136 10769731584 1391483536 14.519 100 14 28 0 1411291728 16364496 3443696
18:26:47 39902 8192000000 10298934592 10759131136 1398069616 14.578 100 15 30 1 1410500208 8786848 3643744
18:28:30 48475 8192000000 10277198960 10732048384 1328663552 13.955 96 24 48 0 1342679392 8656288 5359552
18:30:19 60667 8192000000 10284457328 10746327040 1365275664 14.285 99 11 22 1 1378335744 10818768 2241312
18:32:20 23170 8192000000 10309423632 10756591616 1397043056 14.569 100 36 72 1 1419050512 14026368 7981088
18:33:46 82028 8192000000 10281706736 10741133312 1333040864 13.995 96 12 24 2 1348104416 12397008 2666544
18:36:09 43221 8192000000 10281568752 10731175936 1365680912 14.288 99 34 68 1 1383572208 10367520 7523776
18:37:52 48361 8192000000 10278120640 10718670848 1280507584 13.518 93 40 80 1 1300470384 11455792 8507008
18:39:42 63784 8192000000 10286390752 10723827712 1344072464 14.094 98 36 72 0 1364544240 11896960 8574816
18:41:48 11770 8192000000 10283534144 10724380672 1363855328 14.272 100 34 68 0 1390892912 19607664 7429920
18:43:04 57530 8192000000 10245094784 10702868480 1338834112 14.047 98 12 24 0 1350388240 8610608 2943520
18:45:02 52603 8192000000 10261456256 10708766720 1339184304 14.050 98 19 38 0 1352251824 8495456 4572064
18:47:06 44410 8192000000 10258318544 10712596480 1336420544 14.025 98 19 38 0 1350774416 10010032 4343840
18:48:59 30062 8192000000 10260416720 10695110656 1317870896 13.857 97 25 49 0 1335515424 12089440 5555088
18:50:30 84269 8192000000 10264349264 10707832832 1323702896 13.910 97 13 26 0 1342520864 15768992 3048976
18:52:58 70326 8192000000 10265916144 10715717632 1348561360 14.135 99 18 36 2 1362066784 9775920 3729504
18:55:09 102403 8192000000 10279669936 10731040768 1326033824 13.931 97 15 30 0 1347477568 18263056 3180688
18:57:55 84666 8192000000 10270377248 10731696128 1285285344 13.561 94 12 24 0 1307326256 19427648 2613264
19:00:23 61036 8192000000 10242276640 10684669952 1327252336 13.942 98 13 26 1 1350722000 20524560 2945104
19:02:35 43662 8192000000 10271116688 10724179968 1357339984 14.213 100 33 66 1 1378256384 13369792 7546608
19:04:21 34686 8192000000 10273119168 10734866432 1375345104 14.375 100 16 32 0 1388314624 9295088 3674432
19:05:56 32995 8192000000 10284201584 10743304192 1385415792 14.465 100 18 38 0 1400317984 10656640 4245552
19:07:34 50159 8192000000 10291709840 10752126976 1394598784 14.547 100 14 28 1 1410459744 12550736 3310224
19:09:25 72544 8192000000 10291088672 10751614976 1393356752 14.536 99 19 38 0 1408590320 10567664 4665904
19:11:52 71431 8192000000 10308937968 10767069184 1392226256 14.526 99 11 22 0 1404180256 9702928 2251072
19:14:08 17017 8192000000 10309455056 10747576320 1359288960 14.231 97 49 96 0 1376864304 6620096 10955248
19:15:32 93602 8192000000 10303050880 10763964416 1374747264 14.370 98 23 46 0 1389056704 9596880 4712560
19:18:11 56097 8192000000 10315763040 10770374656 1396219520 14.561 99 24 49 2 1413843488 12293184 5330784
19:20:08 83066 8192000000 10306346272 10772262912 1362582736 14.261 97 15 30 0 1381325568 14871536 3871296
19:22:31 62357 8192000000 10286164784 10736726016 1336384672 14.025 96 33 66 0 1355653584 11449552 7819360
19:24:34 17836 8192000000 10299691232 10730811392 1363915568 14.272 99 54 108 3 1390074640 13854048 12305024
19:25:55 95544 8192000000 10265822416 10729385984 1333220752 13.996 97 19 38 0 1363199904 25550432 4428720
19:28:48 47604 8192000000 10253568672 10707329024 1323781920 13.911 97 10 20 0 1332159632 6185536 2192176
19:31:16 69410 8192000000 10266361328 10733965312 1352178704 14.167 99 7 14 0 1368952592 14951216 1822672
19:33:35 65709 8192000000 10265419872 10712612864 1301643168 13.710 95 8 16 0 1317065824 13592560 1830096
19:35:46 42564 8192000000 10264878512 10708574208 1314414368 13.826 96 25 50 0 1337840048 17597408 5828272
19:37:45 38430 8192000000 9135006832 9386704896 275887744 3.258 20 3 6 0 286431440 9936752 606944

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 12-04-2022 19:39:40 to 12-04-2022 19:39:55.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 175 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-04-2022 19:39:56 to 12-04-2022 19:39:58.

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          243749
MON$OLDEST_ACTIVE               243750
MON$OLDEST_SNAPSHOT             243750
MON$NEXT_TRANSACTION            243750
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                       2014720
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.33577 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33577 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33577 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33577 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 ---
:267: 
:268: mscs-test-161.budzdorov.ru    Tue Apr 12 19:38:26 2022
:269:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:270: 
:271: 
:272: mscs-test-161.budzdorov.ru    Tue Apr 12 19:38:26 2022
:273:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:274: 
:275: 
:276: mscs-test-161.budzdorov.ru    Tue Apr 12 19:38:28 2022
:277:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:278: 
:279: 
:280: mscs-test-161.budzdorov.ru    Tue Apr 12 19:38:35 2022
:281:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:282: 
--- end of diff output ---

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

12.04.22 19:39:59. 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-12 19:40:02 No severe PSQL-related problems occured
12.04.22 19:40:03. End of report.