Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.05.2022 16: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 16581124 kB
MemAvailable 31524644 kB
Buffers 0 kB
Cached 15164424 kB
SwapCached 2544 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-05-08 16:38:15
DTS_END                         2022-05-08 16:38:15.8030
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/49830
TRN_ID                          240417
ATT_ID                          719
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: 9337.33 21642 1120479
customer order: creation 176.28 384 21153
customer order: refuse 14.93 338 1791
order to supplier: creation 45.32 1339 5438
order to supplier: removal 3.73 95177 447
invoice (draft): creation 46.59 3356 5591
invoice (draft): removal 53.32 869 6398
invoice accept: apply 42.10 17681 5052
invoice accept: cancel 48.29 762 5795
customer reserve: creation 3847.63 177 461715
customer reserve: removal 4757.93 58 570952
realization accept: apply 85.98 173 10317
realization accept: cancel 82.99 97 9959
payment from customer: creation 60.03 112 7203
payment from customer: removal 5.88 73 706
payment to supplier: creation 50.35 115 6042
payment to supplier: removal 3.82 58 458
service: total inventory turnovers 4.89 1002 587
service: total monetary turnovers 6.48 28 778
service: aggregate perf. data 0.78 24780 93
service: refresh index statistics 0.03 286263 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-05-2022 16:40:07 to 08-05-2022 16:40:09.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5603
TEST_TIME 2 5969
TEST_TIME 3 5585
TEST_TIME 4 5536
TEST_TIME 5 5368
TEST_TIME 6 6133
TEST_TIME 7 6266
TEST_TIME 8 6004
TEST_TIME 9 5753
TEST_TIME 10 5798
TEST_TIME 11 5738
TEST_TIME 12 5828
TEST_TIME 13 6139
TEST_TIME 14 6258
TEST_TIME 15 6667
TEST_TIME 16 6959
TEST_TIME 17 7493
TEST_TIME 18 7770
TEST_TIME 19 7832
TEST_TIME 20 8068
TEST_TIME 21 8224
TEST_TIME 22 8383
TEST_TIME 23 8679
TEST_TIME 24 9207
TEST_TIME 25 9535
TEST_TIME 26 9559
TEST_TIME 27 9999
TEST_TIME 28 10292
TEST_TIME 29 10318
TEST_TIME 30 10679
TEST_TIME 31 10618
TEST_TIME 32 10308
TEST_TIME 33 10269
TEST_TIME 34 10271
TEST_TIME 35 10294
TEST_TIME 36 10216
TEST_TIME 37 10126
TEST_TIME 38 9960
TEST_TIME 39 10026
TEST_TIME 40 9891
TEST_TIME 41 9952
TEST_TIME 42 9907
TEST_TIME 43 9895
TEST_TIME 44 9883
TEST_TIME 45 9788
TEST_TIME 46 9668
TEST_TIME 47 9664
TEST_TIME 48 9810
TEST_TIME 49 9787
TEST_TIME 50 9860
TEST_TIME 51 9798
TEST_TIME 52 9807
TEST_TIME 53 9730
TEST_TIME 54 9724
TEST_TIME 55 9650
TEST_TIME 56 9633
TEST_TIME 57 9789
TEST_TIME 58 9886
TEST_TIME 59 9876
TEST_TIME 60 9802
TEST_TIME 61 9909
TEST_TIME 62 9863
TEST_TIME 63 9898
TEST_TIME 64 10035
TEST_TIME 65 10040
TEST_TIME 66 9997
TEST_TIME 67 9885
TEST_TIME 68 9807
TEST_TIME 69 9751
TEST_TIME 70 9723
TEST_TIME 71 9715
TEST_TIME 72 9661
TEST_TIME 73 9632
TEST_TIME 74 9563
TEST_TIME 75 9483
TEST_TIME 76 9434
TEST_TIME 77 9469
TEST_TIME 78 9423
TEST_TIME 79 9402
TEST_TIME 80 9500
TEST_TIME 81 9573
TEST_TIME 82 9544
TEST_TIME 83 9569
TEST_TIME 84 9602
TEST_TIME 85 9565
TEST_TIME 86 9563
TEST_TIME 87 9673
TEST_TIME 88 9655
TEST_TIME 89 9633
TEST_TIME 90 9603
TEST_TIME 91 9567
TEST_TIME 92 9580
TEST_TIME 93 9528
TEST_TIME 94 9502
TEST_TIME 95 9461
TEST_TIME 96 9478
TEST_TIME 97 9520
TEST_TIME 98 9507
TEST_TIME 99 9466
TEST_TIME 100 9538
TEST_TIME 101 9512
TEST_TIME 102 9502
TEST_TIME 103 9542
TEST_TIME 104 9560
TEST_TIME 105 9519
TEST_TIME 106 9576
TEST_TIME 107 9603
TEST_TIME 108 9628
TEST_TIME 109 9624
TEST_TIME 110 9579
TEST_TIME 111 9543
TEST_TIME 112 9518
TEST_TIME 113 9483
TEST_TIME 114 9528
TEST_TIME 115 9484
TEST_TIME 116 9470
TEST_TIME 117 9456
TEST_TIME 118 9415
TEST_TIME 119 9381
TEST_TIME 120 9342

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 08-05-2022 16:40:11 to 08-05-2022 16:40:14.

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 1548932 1548932 0 0.00 0 21653 6 0 0
doc_list_biud 1548932 1548932 0 0.00 0 62306 28 0 0
sp_add_invoice_to_stock 5132 5052 80 1.55 24 184281 17682 0 80
sp_cancel_adding_invoice 5795 5795 0 0.00 4 77779 763 0 0
sp_cancel_client_order 1791 1791 0 0.00 4 8241 339 0 0
sp_cancel_customer_reserve 570952 570952 0 0.00 1 32094 59 0 0
sp_cancel_pay_from_customer 706 706 0 0.00 3 5449 73 0 0
sp_cancel_pay_to_supplier 458 458 0 0.00 3 1469 59 0 0
sp_cancel_supplier_invoice 6407 6398 9 0.14 1 62309 869 0 9
sp_cancel_supplier_order 447 447 0 0.00 8 1076382 95178 0 0
sp_cancel_write_off 9959 9959 0 0.00 1 11025 98 0 0
sp_client_order 21153 21153 0 0.00 4 26305 384 0 0
sp_customer_reserve 461715 461715 0 0.00 0 78783 178 0 0
sp_fill_shopping_cart 32180 32180 0 0.00 1 20020 34 0 0
sp_fill_shopping_cart_clo_res 461709 461709 0 0.00 0 20013 15 0 0
sp_get_clo_for_invoice 5046 5046 0 0.00 0 28833 295 0 0
sp_kill_cost_storno 16918 16918 0 0.00 0 10998 52 0 0
sp_kill_qstorno_ret_qs2qd 1158708 1158708 0 0.00 0 62304 32 0 0
sp_kill_qty_storno 610704 610704 0 0.00 0 62305 69 0 0
sp_lock_selected_doc 21365 21365 0 0.00 0 9422 6 0 0
sp_make_cost_storno 28607 28607 0 0.00 0 21644 55 0 0
sp_multiply_rows_for_pdistr 28607 28607 0 0.00 0 11072 25 0 0
sp_multiply_rows_for_qdistr 21153 21153 0 0.00 0 26297 190 0 0
sp_pay_from_customer 7203 7203 0 0.00 4 17367 112 0 0
sp_pay_to_supplier 6042 6042 0 0.00 5 16838 116 0 0
sp_payment_common 13245 13245 0 0.00 1 14727 26 0 0
sp_qd_handle_on_cancel_clo 1791 1791 0 0.00 0 8229 313 0 0
sp_qd_handle_on_invoice_upd_sts 10840 10840 0 0.00 0 22944 323 0 0
sp_qd_handle_on_reserve_upd_sts 10317 10317 0 0.00 0 23819 57 0 0
sp_reserve_write_off 10317 10317 0 0.00 6 23842 173 0 0
sp_supplier_invoice 5591 5591 0 0.00 8 101777 3356 0 0
sp_supplier_order 5438 5438 0 0.00 5 60277 1339 0 0
srv_aggregate_perf_data 93 93 0 0.00 2704 648370 24780 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1886 139028 45504 0 0
srv_find_qd_qs_mism 1072616 1072616 0 0.00 0 11643 5 0 0
srv_make_invnt_saldo 587 587 0 0.00 39 55734 1003 0 0
srv_make_money_saldo 778 778 0 0.00 0 5428 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 204058 379496 286264 0 0
t$perf-abend:sp_add_invoice_to_stock 80 80 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5052 5052 0 0.00 0 18498 148 0 0
t$perf-norm:sp_cancel_adding_invoice 450 450 0 0.00 0 7084 90 0 0
t$perf-norm:sp_cancel_client_order 1791 1791 0 0.00 0 5419 16 0 0
t$perf-norm:sp_cancel_customer_reserve 713 713 0 0.00 0 309 4 0 0
t$perf-norm:sp_cancel_pay_from_customer 706 706 0 0.00 0 1917 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 458 458 0 0.00 0 287 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 423 423 0 0.00 0 703 11 0 0
t$perf-norm:sp_cancel_supplier_order 447 447 0 0.00 0 20907 1487 0 0
t$perf-norm:sp_cancel_write_off 1018 1018 0 0.00 0 1019 6 0 0
t$perf-norm:sp_client_order 21153 21153 0 0.00 0 7821 16 0 0
t$perf-norm:sp_customer_reserve 1101 1101 0 0.00 0 1815 5 0 0
t$perf-norm:sp_pay_from_customer 7203 7203 0 0.00 0 8053 10 0 0
t$perf-norm:sp_pay_to_supplier 6042 6042 0 0.00 0 18418 13 0 0
t$perf-norm:sp_reserve_write_off 10317 10317 0 0.00 0 6045 10 0 0
t$perf-norm:sp_supplier_invoice 5591 5591 0 0.00 0 5281 19 0 0
t$perf-norm:sp_supplier_order 5438 5438 0 0.00 0 6204 15 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 294 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 128 3 0 0
t$perf-norm:srv_make_invnt_saldo 587 587 0 0.00 0 811 18 0 0
t$perf-norm:srv_make_money_saldo 778 778 0 0.00 0 1051 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 3 0 0
v_add_invoice_to_stock 10244 10084 160 1.56 0 3509 15 0 160
v_all_customers 38346 38346 0 0.00 0 85 0 0 0
v_all_suppliers 30824 30824 0 0.00 0 26 1 0 0
v_all_wares 42306 42306 0 0.00 0 116 1 0 0
v_cancel_adding_invoice 900 900 0 0.00 0 446 5 0 0
v_cancel_client_order 3582 3582 0 0.00 0 2100 6 0 0
v_cancel_customer_prepayment 1412 1412 0 0.00 0 5113 12 0 0
v_cancel_customer_reserve 1426 1426 0 0.00 0 2445 8 0 0
v_cancel_payment_to_supplier 916 916 0 0.00 0 789 5 0 0
v_cancel_supplier_invoice 864 846 18 2.08 0 494 11 0 18
v_cancel_supplier_order 894 894 0 0.00 0 3658 7 0 0
v_cancel_write_off 2036 2036 0 0.00 0 6510 14 0 0
v_max_id_clo_ord 5437 5437 0 0.00 0 616 1 0 0
v_max_id_clo_res 1101 1101 0 0.00 0 6654 31 0 0
v_max_id_ord_sup 5589 5589 0 0.00 0 10092 7 0 0
v_max_non_paid_invoice 1656 1656 0 0.00 0 218 2 0 0
v_max_non_paid_realizn 3851 3851 0 0.00 0 2374 3 0 0
v_min_id_clo_ord 5437 5437 0 0.00 0 2384 5 0 0
v_min_id_clo_res 1101 1101 0 0.00 0 6586 18 0 0
v_min_id_ord_sup 5589 5589 0 0.00 0 4869 9 0 0
v_min_non_paid_invoice 6042 6042 0 0.00 0 11028 19 0 0
v_min_non_paid_realizn 7203 7203 0 0.00 0 14377 22 0 0
v_our_firm 10664 10664 0 0.00 0 10 1 0 0
v_reserve_write_off 20634 20634 0 0.00 0 3513 6 0 0
x_lock_depdocs_on_canc_invoice 11770 11770 0 0.00 0 27420 103 0 0
x_lock_depdocs_on_canc_sup_ord 447 447 0 0.00 0 31333 386 0 0
x_make_qstorno_1000_1200 5438 5438 0 0.00 2 60271 1271 0 0
x_make_qstorno_1200_2000 5591 5591 0 0.00 3 98461 3229 0 0
x_make_qstorno_2100_3300 461715 461715 0 0.00 0 78780 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-05-2022 16:40:15 to 08-05-2022 16:40:19.

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:19 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:19 21939 8192000000 9891779568 10197413888 976365504 10.649 100 88 176 0 1005624400 5987456 23271440
14:10:51 16620 8192000000 9940314144 10256883712 1018798496 11.060 100 88 176 0 1049723952 8426576 22498880
14:12:12 20601 8192000000 9974122480 10302853120 1054477248 11.404 100 86 172 0 1085981760 10402576 21101936
14:13:37 10953 8192000000 10034542816 10377019392 1111352544 11.945 100 78 156 0 1142107216 12497056 18257616
14:14:53 9052 8192000000 10078787040 10433519616 1163922448 12.440 100 85 170 0 1197594304 13810112 19861744
14:16:05 5757 8192000000 10114884592 10481897472 1203584544 12.810 100 75 151 1 1232537808 11966192 16987072
14:17:13 3481 8192000000 10159698224 10531397632 1243990928 13.183 100 86 168 3 1276032672 12702544 19339200
14:18:18 3013 8192000000 10189386288 10567766016 1272722624 13.447 100 86 172 0 1304666480 12956112 18987744
14:19:22 1800 8192000000 10219952064 10602831872 1299136688 13.687 100 91 183 1 1332629552 13149520 20343344
14:20:25 2541 8192000000 10205540688 10607185920 1307947408 13.767 100 43 83 3 1325918912 9112176 8859328
14:21:29 27082 8192000000 10235072016 10626732032 1316267792 13.843 100 62 125 0 1337219424 5317728 15633904
14:22:57 5304 8192000000 10221352800 10632056832 1322433056 13.899 100 40 80 1 1343284736 11787312 9064368
14:24:05 9160 8192000000 10217701536 10636685312 1329028224 13.958 100 36 73 1 1345309568 8294048 7987296
14:25:14 52532 8192000000 10252367088 10650521600 1338174720 14.041 100 58 115 0 1358413008 6646240 13592048
14:27:07 5261 8192000000 10226748272 10649952256 1339352768 14.052 100 26 52 2 1351768448 6355456 6060224
14:28:14 6788 8192000000 10225717968 10641707008 1328033904 13.949 100 30 60 1 1345657136 10691824 6931408
14:29:22 56232 8192000000 10195938976 10591117312 1260127408 13.331 97 53 106 0 1279639520 6891120 12620992
14:31:19 6943 8192000000 10156977200 10553372672 1247658656 13.217 99 20 40 2 1257536528 5087728 4790144
14:32:29 6712 8192000000 10121308624 10515795968 1218437568 12.947 99 24 48 2 1230464608 6587184 5439856
14:33:36 26298 8192000000 10151089440 10556764160 1261939776 13.348 100 39 78 1 1278396592 5794176 10662640
14:35:29 6814 8192000000 10177406528 10574979072 1275031072 13.468 100 26 51 1 1286681824 5416256 6234496
14:36:37 7312 8192000000 10183314960 10589532160 1289137840 13.596 100 22 44 2 1299782176 5350848 5293488
14:37:45 12630 8192000000 10191404656 10600472576 1297165968 13.669 100 19 36 1 1306917152 5536624 4214560
14:39:07 36146 8192000000 10241013792 10642026496 1329791744 13.965 100 52 104 0 1351177904 9630048 11756112
14:40:45 44759 8192000000 10253016304 10666901504 1342151440 14.077 100 40 80 2 1358572176 7508640 8912096
14:42:33 13226 8192000000 10265602960 10681106432 1361608656 14.252 100 55 109 3 1385467328 11121136 12737536
14:43:50 12007 8192000000 10293522624 10708996096 1374058304 14.363 100 60 120 1 1405344688 17158640 14127744
14:45:07 9511 8192000000 10297160288 10715455488 1383443344 14.447 100 44 88 0 1409820384 15809792 10567248
14:46:21 16073 8192000000 10318953888 10736025600 1397029280 14.569 100 54 108 0 1427303472 17597280 12676912
14:47:46 6928 8192000000 10325711600 10743410688 1401715696 14.610 100 57 114 1 1435505328 19620896 14168736
14:48:58 6203 8192000000 10340187792 10762760192 1413490304 14.715 100 51 103 1 1452525280 25918192 13116784
14:50:12 4294 8192000000 10364664016 10784362496 1427492496 14.839 100 60 119 1 1473906880 31546752 14867632
14:51:20 7462 8192000000 10380911952 10803994624 1436539952 14.919 100 66 130 2 1489006752 35651168 16815632
14:52:34 1872 8192000000 10370547872 10774589440 1403497408 14.626 99 92 183 1 1469110320 42087840 23525072
14:53:43 2457 8192000000 10313928160 10729865216 1361672544 14.252 97 48 97 0 1416585168 41572016 13340608
14:54:51 4938 8192000000 10332974144 10740584448 1378133696 14.400 100 71 139 2 1438674912 42582768 17958448
14:56:01 6031 8192000000 10328567360 10735611904 1368014928 14.309 99 83 166 0 1426523296 38250784 20257584
14:57:12 6645 8192000000 10316652592 10736267264 1363010160 14.264 99 44 88 1 1410958576 36452736 11495680
14:58:28 8841 8192000000 10322114736 10749124608 1383895952 14.451 100 40 80 1 1433798912 39045936 10857024
14:59:44 14116 8192000000 10291424880 10716258304 1327411280 13.944 97 41 82 1 1385083792 46309568 11362944
15:01:04 13364 8192000000 10280598528 10696790016 1289591664 13.601 96 35 68 3 1344781232 45113280 10076288
15:02:18 11504 8192000000 10275613840 10695557120 1339812528 14.056 100 40 82 0 1383100416 32931520 10356368
15:03:32 25374 8192000000 10281518112 10714624000 1344533168 14.098 99 22 42 2 1381911472 31844784 5533520
15:05:10 24924 8192000000 10294333728 10736603136 1352343248 14.169 99 21 42 1 1393098416 34838272 5916896
15:07:36 64334 8192000000 10283679008 10729943040 1346605040 14.117 99 14 28 2 1389254512 39189408 3460064
15:09:41 100966 8192000000 10289519760 10728861696 1367441024 14.304 100 27 54 0 1383080512 9782304 5857184
15:12:28 63851 8192000000 10265737856 10709008384 1331725632 13.983 97 16 32 1 1342644368 7453776 3464960
15:14:33 9494 8192000000 10294360880 10724892672 1373306096 14.357 100 58 113 2 1392235728 6073200 12856432
15:15:44 70289 8192000000 10279394512 10722959360 1350967840 14.156 98 25 50 0 1369092480 11634720 6489920
15:17:57 38386 8192000000 10284169232 10729422848 1367449280 14.304 99 11 22 0 1382222992 12275184 2498528
15:19:36 75188 8192000000 10289079104 10751307776 1387304112 14.482 100 27 54 1 1407594528 13723792 6566624
15:21:56 57412 8192000000 10297183904 10753363968 1393556272 14.538 100 14 28 0 1406988048 10176288 3255488
15:23:55 68702 8192000000 10315996448 10769416192 1391115952 14.516 99 21 42 0 1416410752 19327920 5966880
15:26:05 62258 8192000000 10316103952 10777509888 1394512992 14.546 100 25 50 0 1429466864 28198048 6755824
15:28:16 24336 8192000000 10312698256 10761814016 1405834608 14.647 100 38 76 0 1423265264 8774144 8656512
15:29:42 65421 8192000000 10302507568 10768257024 1398417744 14.581 100 16 32 1 1414340048 12201504 3720800
15:31:47 64644 8192000000 10307035440 10761740288 1401085632 14.605 100 31 62 1 1420709200 12820304 6803264
15:34:05 39203 8192000000 10323480320 10781786112 1377185904 14.391 97 28 56 2 1401485552 17680784 6618864
15:35:46 71204 8192000000 10288905296 10748928000 1364119728 14.274 98 7 14 1 1383682112 17871440 1690944
15:38:08 60252 8192000000 10278807648 10714337280 1298610320 13.683 93 38 76 0 1317526640 9930960 8985360
15:40:13 67326 8192000000 10290350992 10748329984 1351356416 14.160 98 8 16 1 1361817136 8555232 1905488
15:42:23 52988 8192000000 10284877280 10747920384 1342460128 14.080 97 10 20 1 1363141168 18388352 2292688
15:44:20 60416 8192000000 10268981936 10710142976 1308005728 13.768 95 29 58 2 1325363792 11153264 6204800
15:46:24 73665 8192000000 10263741552 10704728064 1309927024 13.785 96 33 66 1 1326566432 9268512 7370896
15:48:44 53975 8192000000 10251946896 10702766080 1321280208 13.888 97 24 47 1 1335010352 8270912 5459232
15:50:41 33837 8192000000 10211574976 10658533376 1278949824 13.503 95 9 18 1 1286698016 5990112 1758080
15:52:18 32212 8192000000 10233670400 10683748352 1326429904 13.935 99 10 20 0 1335494320 6672112 2392304
15:54:07 70159 8192000000 10242585568 10700447744 1334094448 14.004 99 15 29 3 1347986448 9973104 3918896
15:56:18 41273 8192000000 10261626496 10704510976 1351674912 14.163 100 21 42 0 1371424976 14585536 5164528
15:58:42 47880 8192000000 10273420176 10726498304 1352124016 14.167 99 27 54 1 1373677904 15197312 6356576
16:00:49 15443 8192000000 10294738336 10732916736 1378244064 14.401 100 44 88 0 1398943232 11011040 9688128
16:02:12 87584 8192000000 10300749136 10745040896 1376369088 14.384 99 26 53 0 1394135760 11846880 5919792
16:04:40 33080 8192000000 10302038624 10751193088 1370491184 14.331 99 33 66 0 1399380336 21518064 7371088
16:07:11 21125 8192000000 10298660000 10744340480 1375086272 14.373 99 31 62 1 1392081552 10322448 6672832
16:08:32 139028 8192000000 10288856624 10756263936 1376415888 14.384 99 24 47 4 1401791632 19958128 5417616
16:11:52 105578 8192000000 10315462128 10776838144 1404492704 14.635 100 6 12 0 1415489072 9804848 1191520
16:14:37 88299 8192000000 10304690848 10779881472 1376154336 14.382 97 14 28 0 1393542064 13774896 3612832
16:17:07 67537 8192000000 10321962448 10776981504 1385447600 14.465 98 21 42 0 1400866000 10517744 4900656
16:19:17 52864 8192000000 10308575008 10761011200 1359174224 14.230 97 15 30 0 1372176016 9634896 3366896
16:22:00 66676 8192000000 10293038144 10748305408 1364964240 14.282 98 13 26 0 1378080224 10359872 2756112
16:24:06 89293 8192000000 10280975072 10734755840 1333745408 14.001 97 15 30 0 1351270368 14366256 3158704
16:26:36 78909 8192000000 10285797952 10731876352 1333161200 13.996 97 17 32 1 1346516384 9670224 3684960
16:28:57 98870 8192000000 10281282160 10726428672 1324593264 13.918 96 33 63 3 1344091792 11325312 8173216
16:31:37 62615 8192000000 10267178688 10734358528 1357244384 14.213 99 11 22 1 1379344032 19432912 2666736
16:33:48 53822 8192000000 10257915248 10719936512 1359516336 14.233 100 7 14 0 1374473376 13362656 1594384
16:35:46 69005 8192000000 10233644944 10689798144 1336065088 14.022 99 14 28 1 1350046912 10834096 3147728
16:37:55 36049 8192000000 9444588000 9771925504 597541584 6.798 44 2 4 0 602719984 4839536 338864

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 08-05-2022 16:40:21 to 08-05-2022 16:40:36.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 80 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-05-2022 16:40:37 to 08-05-2022 16:40:39.

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          240691
MON$OLDEST_ACTIVE               240692
MON$OLDEST_SNAPSHOT             240692
MON$NEXT_TRANSACTION            240692
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                       2032800
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.33587 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33587 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33587 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33587 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'

08.05.22 16:40:40. 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-05-08 16:40:42 No severe PSQL-related problems occured
08.05.22 16:40:43. End of report.