Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.10.2020 16:27
Common Performance Final Results
  1. Hardware and OS info
  2. DB and 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, full
  3. Ratio "Versions / Records" for tables
  4. Database Validation Results
  5. New in firebird.log while test was run
  6. 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 e0e7b46250ba4f9795ceb55e0face73f
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 2020-09-25 13:42
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.00
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 17686680 kB
MemAvailable 31553268 kB
Buffers 0 kB
Cached 13979664 kB
SwapCached 5444 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.7
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 2020-10-16 16:26:59
DTS_END                         2020-10-16 16:26:59.8870
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(56:17)
IP                              ::1/49846
TRN_ID                          244331
ATT_ID                          632
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: 9535.17 18330 1144221
customer order: creation 176.52 326 21182
customer order: refuse 14.35 292 1722
order to supplier: creation 46.45 1216 5574
order to supplier: removal 3.62 80082 434
invoice (draft): creation 47.06 2787 5647
invoice (draft): removal 54.56 593 6547
invoice accept: apply 42.48 16955 5097
invoice accept: cancel 48.85 604 5862
customer reserve: creation 3961.42 167 475370
customer reserve: removal 4839.98 48 580797
realization accept: apply 85.00 146 10200
realization accept: cancel 84.33 96 10120
payment from customer: creation 60.13 85 7215
payment from customer: removal 5.76 59 691
payment to supplier: creation 49.61 107 5953
payment to supplier: removal 3.68 68 442
service: total inventory turnovers 4.53 1021 543
service: total monetary turnovers 6.04 24 725
service: aggregate perf. data 0.80 21157 96
service: refresh index statistics 0.03 240772 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-10-2020 16:28:14 to 16-10-2020 16:28:16.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3146
TEST_TIME 2 4846
TEST_TIME 3 4925
TEST_TIME 4 5376
TEST_TIME 5 5792
TEST_TIME 6 5600
TEST_TIME 7 6462
TEST_TIME 8 6454
TEST_TIME 9 6871
TEST_TIME 10 6887
TEST_TIME 11 7019
TEST_TIME 12 7210
TEST_TIME 13 8403
TEST_TIME 14 8515
TEST_TIME 15 9187
TEST_TIME 16 9373
TEST_TIME 17 10487
TEST_TIME 18 10850
TEST_TIME 19 11166
TEST_TIME 20 12046
TEST_TIME 21 12842
TEST_TIME 22 12347
TEST_TIME 23 12010
TEST_TIME 24 11689
TEST_TIME 25 11519
TEST_TIME 26 11492
TEST_TIME 27 11286
TEST_TIME 28 11127
TEST_TIME 29 10965
TEST_TIME 30 10774
TEST_TIME 31 10755
TEST_TIME 32 10444
TEST_TIME 33 10390
TEST_TIME 34 10249
TEST_TIME 35 10210
TEST_TIME 36 10075
TEST_TIME 37 10169
TEST_TIME 38 10308
TEST_TIME 39 10421
TEST_TIME 40 10355
TEST_TIME 41 10334
TEST_TIME 42 10511
TEST_TIME 43 10413
TEST_TIME 44 10444
TEST_TIME 45 10431
TEST_TIME 46 10451
TEST_TIME 47 10368
TEST_TIME 48 10240
TEST_TIME 49 10129
TEST_TIME 50 10077
TEST_TIME 51 10006
TEST_TIME 52 9897
TEST_TIME 53 9841
TEST_TIME 54 9834
TEST_TIME 55 9795
TEST_TIME 56 9778
TEST_TIME 57 9884
TEST_TIME 58 9838
TEST_TIME 59 9907
TEST_TIME 60 9863
TEST_TIME 61 9833
TEST_TIME 62 9799
TEST_TIME 63 9778
TEST_TIME 64 9928
TEST_TIME 65 9916
TEST_TIME 66 9902
TEST_TIME 67 9973
TEST_TIME 68 9834
TEST_TIME 69 9755
TEST_TIME 70 9715
TEST_TIME 71 9661
TEST_TIME 72 9579
TEST_TIME 73 9551
TEST_TIME 74 9589
TEST_TIME 75 9592
TEST_TIME 76 9562
TEST_TIME 77 9626
TEST_TIME 78 9603
TEST_TIME 79 9648
TEST_TIME 80 9613
TEST_TIME 81 9654
TEST_TIME 82 9623
TEST_TIME 83 9630
TEST_TIME 84 9674
TEST_TIME 85 9749
TEST_TIME 86 9793
TEST_TIME 87 9778
TEST_TIME 88 9696
TEST_TIME 89 9674
TEST_TIME 90 9651
TEST_TIME 91 9655
TEST_TIME 92 9599
TEST_TIME 93 9528
TEST_TIME 94 9554
TEST_TIME 95 9570
TEST_TIME 96 9546
TEST_TIME 97 9555
TEST_TIME 98 9533
TEST_TIME 99 9590
TEST_TIME 100 9659
TEST_TIME 101 9646
TEST_TIME 102 9634
TEST_TIME 103 9628
TEST_TIME 104 9680
TEST_TIME 105 9757
TEST_TIME 106 9731
TEST_TIME 107 9763
TEST_TIME 108 9781
TEST_TIME 109 9702
TEST_TIME 110 9670
TEST_TIME 111 9626
TEST_TIME 112 9638
TEST_TIME 113 9587
TEST_TIME 114 9574
TEST_TIME 115 9564
TEST_TIME 116 9512
TEST_TIME 117 9485
TEST_TIME 118 9487
TEST_TIME 119 9527
TEST_TIME 120 9538

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 16-10-2020 16:28:17 to 16-10-2020 16:28:21.

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 1584391 1584391 0 0.00 0 12104 5 0 0
doc_list_biud 1584389 1584389 0 0.00 0 28265 22 0 0
sp_add_invoice_to_stock 5193 5097 96 1.84 44 214103 16955 0 96
sp_cancel_adding_invoice 5862 5862 0 0.00 5 61505 604 0 0
sp_cancel_client_order 1722 1722 0 0.00 3 22374 292 0 0
sp_cancel_customer_reserve 580797 580797 0 0.00 1 28267 48 0 0
sp_cancel_pay_from_customer 691 691 0 0.00 3 5240 60 0 0
sp_cancel_pay_to_supplier 442 442 0 0.00 2 5613 69 0 0
sp_cancel_supplier_invoice 6550 6547 3 0.04 2 28188 594 0 3
sp_cancel_supplier_order 434 434 0 0.00 12 886202 80083 0 0
sp_cancel_write_off 10120 10120 0 0.00 1 21677 97 0 0
sp_client_order 21182 21182 0 0.00 3 52304 327 0 0
sp_customer_reserve 475370 475370 0 0.00 0 71302 168 0 0
sp_fill_shopping_cart 32376 32376 0 0.00 1 13539 35 0 0
sp_fill_shopping_cart_clo_res 475365 475365 0 0.00 0 19856 14 0 0
sp_get_clo_for_invoice 5091 5091 0 0.00 0 39683 331 0 0
sp_kill_cost_storno 17115 17115 0 0.00 0 6129 41 0 0
sp_kill_qstorno_ret_qs2qd 1178695 1178695 0 0.00 0 28253 26 0 0
sp_kill_qty_storno 620773 620773 0 0.00 0 28264 56 0 0
sp_lock_selected_doc 21052 21052 0 0.00 0 12125 6 0 0
sp_make_cost_storno 28459 28459 0 0.00 0 12100 43 0 0
sp_multiply_rows_for_pdistr 28459 28459 0 0.00 0 7910 19 0 0
sp_multiply_rows_for_qdistr 21182 21182 0 0.00 0 52296 169 0 0
sp_pay_from_customer 7215 7215 0 0.00 4 12514 86 0 0
sp_pay_to_supplier 5953 5953 0 0.00 4 10445 107 0 0
sp_payment_common 13168 13168 0 0.00 1 8634 24 0 0
sp_qd_handle_on_cancel_clo 1722 1722 0 0.00 0 22365 267 0 0
sp_qd_handle_on_invoice_upd_sts 10953 10953 0 0.00 0 17160 238 0 0
sp_qd_handle_on_reserve_upd_sts 10200 10200 0 0.00 0 11113 53 0 0
sp_reserve_write_off 10200 10200 0 0.00 6 13270 147 0 0
sp_supplier_invoice 5647 5647 0 0.00 6 96526 2788 0 0
sp_supplier_order 5574 5574 0 0.00 5 41729 1216 0 0
srv_aggregate_perf_data 96 96 0 0.00 2609 244885 21158 0 0
srv_fill_mon_cache_memory 58 58 0 0.00 938 156377 57523 0 0
srv_find_qd_qs_mism 1095446 1095446 0 0.00 0 7812 5 0 0
srv_make_invnt_saldo 543 543 0 0.00 43 37656 1021 0 0
srv_make_money_saldo 725 725 0 0.00 1 4967 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 131490 321223 240772 0 0
t$perf-abend:sp_add_invoice_to_stock 96 96 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5097 5097 0 0.00 0 13592 151 0 0
t$perf-norm:sp_cancel_adding_invoice 376 376 0 0.00 0 4770 93 0 0
t$perf-norm:sp_cancel_client_order 1722 1722 0 0.00 0 16779 22 0 0
t$perf-norm:sp_cancel_customer_reserve 691 691 0 0.00 0 11751 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 691 691 0 0.00 0 461 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 442 442 0 0.00 0 4706 24 0 0
t$perf-norm:sp_cancel_supplier_invoice 380 380 0 0.00 0 5938 31 0 0
t$perf-norm:sp_cancel_supplier_order 434 434 0 0.00 0 23253 1185 0 0
t$perf-norm:sp_cancel_write_off 1037 1037 0 0.00 0 4179 18 0 0
t$perf-norm:sp_client_order 21182 21182 0 0.00 0 7296 13 0 0
t$perf-norm:sp_customer_reserve 996 996 0 0.00 0 3530 9 0 0
t$perf-norm:sp_pay_from_customer 7215 7215 0 0.00 0 9808 9 0 0
t$perf-norm:sp_pay_to_supplier 5953 5953 0 0.00 0 8225 11 0 0
t$perf-norm:sp_reserve_write_off 10200 10200 0 0.00 0 22080 16 0 0
t$perf-norm:sp_supplier_invoice 5647 5647 0 0.00 0 9663 19 0 0
t$perf-norm:sp_supplier_order 5574 5574 0 0.00 0 11661 13 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 197 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 58 58 0 0.00 0 126 3 0 0
t$perf-norm:srv_make_invnt_saldo 543 543 0 0.00 0 683 9 0 0
t$perf-norm:srv_make_money_saldo 725 725 0 0.00 0 372 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 14 5 0 0
v_add_invoice_to_stock 10358 10166 192 1.85 0 6270 21 0 192
v_all_customers 38092 38092 0 0.00 0 64 0 0 0
v_all_suppliers 30951 30951 0 0.00 0 11 0 0 0
v_all_wares 42334 42334 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 752 752 0 0.00 0 1812 8 0 0
v_cancel_client_order 3444 3444 0 0.00 0 4994 6 0 0
v_cancel_customer_prepayment 1382 1382 0 0.00 0 5227 9 0 0
v_cancel_customer_reserve 1382 1382 0 0.00 0 537 3 0 0
v_cancel_payment_to_supplier 884 884 0 0.00 0 4804 10 0 0
v_cancel_supplier_invoice 766 760 6 0.78 0 1893 21 0 6
v_cancel_supplier_order 868 868 0 0.00 0 3056 6 0 0
v_cancel_write_off 2074 2074 0 0.00 0 2179 14 0 0
v_max_id_clo_ord 5572 5572 0 0.00 0 1196 1 0 0
v_max_id_clo_res 996 996 0 0.00 0 2746 13 0 0
v_max_id_ord_sup 5637 5637 0 0.00 0 5605 4 0 0
v_max_non_paid_invoice 1686 1686 0 0.00 0 3430 8 0 0
v_max_non_paid_realizn 3936 3936 0 0.00 0 1349 3 0 0
v_min_id_clo_ord 5572 5572 0 0.00 0 3855 5 0 0
v_min_id_clo_res 996 996 0 0.00 0 4347 14 0 0
v_min_id_ord_sup 5637 5637 0 0.00 0 5039 7 0 0
v_min_non_paid_invoice 5953 5953 0 0.00 0 4280 13 0 0
v_min_non_paid_realizn 7215 7215 0 0.00 0 5986 17 0 0
v_our_firm 10800 10800 0 0.00 0 16 1 0 0
v_reserve_write_off 20390 20390 0 0.00 0 4720 7 0 0
x_lock_depdocs_on_canc_invoice 12029 12029 0 0.00 0 25496 105 0 0
x_lock_depdocs_on_canc_sup_ord 434 434 0 0.00 0 5743 243 0 0
x_make_qstorno_1000_1200 5574 5574 0 0.00 2 41718 1157 0 0
x_make_qstorno_1200_2000 5647 5647 0 0.00 3 96508 2659 0 0
x_make_qstorno_2100_3300 475370 475370 0 0.00 0 71300 152 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 16-10-2020 16:28:21 to 16-10-2020 16:28:26.

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
13:57:03 1 8192000000 8798422528 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
13:58:04 38110 8192000000 10399928560 10724954112 1440298048 14.952 100 91 182 1 1476467584 6141344 30028192
13:59:47 34139 8192000000 10459151904 10795491328 1512769584 15.587 100 95 190 1 1552515248 9394208 30351456
14:01:28 12747 8192000000 10522744592 10873008128 1573119408 16.109 100 82 164 1 1608706592 11107168 24480016
14:02:44 9151 8192000000 10578398128 10943602688 1641730400 16.694 100 75 150 0 1676486160 11881200 22874560
14:03:55 6686 8192000000 10650990352 11026116608 1713355760 17.297 100 73 146 0 1747010144 12894016 20760368
14:05:03 4180 8192000000 10724351120 11106942976 1783521040 17.878 100 78 156 2 1817752848 12407136 21824672
14:06:09 2363 8192000000 10773348000 11166461952 1839722496 18.339 100 86 172 2 1876149168 12501184 23925488
14:07:13 2075 8192000000 10814661792 11203108864 1869450640 18.580 100 89 176 1 1908035680 13765696 24819344
14:08:16 2700 8192000000 10814800400 11218751488 1879683888 18.663 100 69 138 2 1909026192 10580880 18761424
14:09:21 9110 8192000000 10791119104 11224252416 1880935552 18.673 100 16 32 0 1891313792 6139600 4238640
14:10:31 12816 8192000000 10801614992 11234385920 1897428368 18.806 100 36 72 0 1917918688 10593136 9897184
14:11:45 23154 8192000000 10812334160 11247910912 1896725376 18.800 100 36 68 1 1913003824 6554384 9724064
14:13:08 8422 8192000000 10834380928 11262267392 1905543456 18.871 100 33 66 2 1925062032 10188576 9330000
14:14:17 13479 8192000000 10828919040 11266764800 1915553152 18.951 100 27 54 3 1929732688 5818720 8360816
14:15:34 55566 8192000000 10768835408 11193962496 1789045888 17.924 94 32 64 2 1803994784 5795616 9153280
14:17:31 3960 8192000000 10721575968 11141828608 1791136080 17.941 98 28 56 1 1805091184 6043776 7911328
14:18:37 7374 8192000000 10722437840 11146919936 1793022352 17.957 99 19 36 0 1804256400 6083280 5150768
14:19:46 10758 8192000000 10731017920 11146502144 1783070288 17.875 98 17 34 2 1793439344 5117696 5251360
14:21:00 33399 8192000000 10718774576 11148206080 1788948960 17.923 98 35 68 1 1804400176 5859248 9591968
14:22:36 8999 8192000000 10739065504 11166134272 1828256912 18.245 100 18 36 2 1838473040 4888048 5328080
14:23:46 5643 8192000000 10770190928 11206438912 1864205008 18.537 100 9 18 1 1872187648 5290080 2692560
14:24:54 8256 8192000000 10785057840 11218345984 1874015424 18.617 100 19 38 1 1885311312 5622160 5673728
14:26:06 48722 8192000000 10789994688 11229052928 1885709312 18.711 100 29 58 2 1900695888 6044416 8942160
14:27:59 3784 8192000000 10870493360 11287822336 1932259472 19.085 100 58 117 0 1960962976 12363920 16339584
14:29:07 13838 8192000000 10910624928 11340410880 1962636928 19.327 100 55 109 1 2002286736 23996768 15653040
14:30:23 15008 8192000000 10923638944 11353190400 1980141984 19.466 100 57 114 2 2020884672 23751472 16991216
14:31:43 19968 8192000000 10926758848 11368067072 1988610560 19.533 100 39 77 1 2023908512 23572544 11725408
14:33:07 8846 8192000000 10943478496 11386228736 1980302464 19.467 99 42 84 0 2023238288 30177072 12758752
14:34:20 4122 8192000000 10973309232 11407224832 2010111312 19.702 100 58 116 1 2057520400 30618704 16790384
14:35:27 5096 8192000000 10955932128 11385057280 1962035472 19.322 98 53 104 0 2011030192 33710208 15284512
14:36:35 2912 8192000000 10978873872 11403575296 2005320960 19.665 100 80 159 0 2068114528 38671712 24121856
14:37:41 2054 8192000000 10971892384 11390554112 1980666608 19.470 99 70 136 1 2043647776 41930848 21050320
14:38:46 2265 8192000000 10991229104 11412369408 1985374608 19.507 99 69 138 1 2064412848 57192336 21845904
14:39:51 938 8192000000 10978917808 11391967232 1984905888 19.504 100 92 184 0 2061823184 48835472 28081824
14:40:53 8373 8192000000 10933242832 11360456704 1937743728 19.129 98 49 96 2 1998191184 44280848 16166608
14:42:03 17213 8192000000 10947230832 11383902208 1971179024 19.395 100 44 87 2 2032840048 46643104 15017920
14:43:25 17243 8192000000 10929554768 11396055040 1971855616 19.400 100 34 67 2 2038033696 53802400 12375680
14:44:43 20653 8192000000 10895417184 11342020608 1883994960 18.697 96 24 48 1 1927628416 34416448 9217008
14:46:05 50077 8192000000 10885120880 11351445504 1915321744 18.949 99 19 38 4 1963238144 41428272 6488128
14:48:41 116075 8192000000 10872677376 11313709056 1897546128 18.807 99 34 68 2 1914716336 7625664 9544544
14:51:37 84715 8192000000 10849321120 11317796864 1908615664 18.896 99 17 34 0 1924528528 11513744 4399120
14:54:03 97677 8192000000 10870812992 11322855424 1919237632 18.981 99 18 34 1 1935013200 10664176 5111392
14:56:41 88513 8192000000 10854811104 11320836096 1912690560 18.928 98 21 42 1 1927721024 9351232 5679232
14:59:29 67318 8192000000 10851286816 11316850688 1884692256 18.703 97 15 31 0 1898152624 9681360 3779008
15:01:36 46778 8192000000 10903505632 11356798976 1932346144 19.086 98 33 66 1 1960958080 19154560 9457376
15:03:32 83113 8192000000 10900220160 11369635840 1955666848 19.272 100 15 30 2 1985455648 25301360 4487440
15:05:57 49041 8192000000 10919977760 11373760512 1977918432 19.448 100 35 69 1 2001733328 13302976 10511920
15:07:55 54803 8192000000 10905720784 11376427008 1977483344 19.445 100 34 68 2 2008888000 21742944 9661712
15:09:50 47333 8192000000 10889765696 11346874368 1925628224 19.032 97 32 64 1 1948014656 13580704 8805728
15:11:40 37214 8192000000 10924789984 11375603712 1949420528 19.222 98 42 84 2 1971189296 10317504 11451264
15:13:18 76149 8192000000 10941716288 11393060864 1999373760 19.618 100 46 92 1 2028790608 16367360 13049488
15:15:40 38705 8192000000 10922088224 11380641792 1982110896 19.481 99 42 84 1 2004955312 10802352 12042064
15:17:26 51729 8192000000 10892115552 11372720128 1948956688 19.218 98 3 6 0 1958084528 8404352 723488
15:19:23 57878 8192000000 10917812048 11385970688 1959838208 19.305 98 10 20 0 1970173536 7590736 2744592
15:21:21 85559 8192000000 10885170032 11356024832 1834358480 18.295 92 19 38 1 1847341440 7879776 5103184
15:23:47 65300 8192000000 10888247376 11351638016 1915695504 18.952 97 11 22 0 1931770784 12527232 3548048
15:26:02 102572 8192000000 10846944208 11304226816 1689388304 17.096 86 17 35 1 1701194656 7254864 4551488
15:28:44 57408 8192000000 10840034320 11287052288 1837810608 18.323 96 46 92 1 1863031056 12178864 13041584
15:30:42 145205 8192000000 10873680480 11350478848 1824953472 18.218 94 9 18 1 1850210432 22697152 2559808
15:34:08 107062 8192000000 10846398368 11294834688 1860542064 18.508 96 26 52 1 1881046848 12726032 7778752
15:36:57 39522 8192000000 10850642432 11311620096 1922856320 19.010 100 13 26 1 1933799328 6532336 4410672
15:38:38 51276 8192000000 10842605040 11313238016 1919012848 18.979 99 9 18 1 1929229856 7807056 2409952
15:41:32 47893 8192000000 10864318368 11332374528 1911009520 18.915 98 22 44 0 1926821920 9622368 6190032
15:43:27 109054 8192000000 10865133312 11327717376 1872305936 18.603 96 12 24 2 1884550576 8886912 3357728
15:46:27 88268 8192000000 10873807584 11342061568 1907637296 18.888 97 15 30 0 1924399168 12591888 4169984
15:48:55 40887 8192000000 10920381200 11384201216 1986807392 19.519 100 30 60 0 2008082960 12830432 8445136
15:50:48 156377 8192000000 10900016416 11382456320 1941985312 19.163 98 15 30 0 1961989344 15878160 4125872
15:54:25 141875 8192000000 10894902864 11361902592 1949456304 19.222 99 24 48 0 1968444576 12097584 6890688
15:57:47 33645 8192000000 10881653104 11357007872 1953312800 19.253 99 15 30 0 1965321104 8227040 3781264
15:59:23 81965 8192000000 10860661200 11350134784 1906282048 18.877 97 9 18 0 1924737360 15905984 2549328
16:01:48 80171 8192000000 10877576624 11350855680 1890320320 18.748 96 15 31 1 1905850928 11890496 3640112
16:04:10 36672 8192000000 10917993824 11380903936 1957679968 19.288 99 38 74 1 1983849952 15643040 10526944
16:05:56 90117 8192000000 10905391776 11364261888 1924243024 19.021 97 19 38 0 1946581872 17192528 5146320
16:08:27 60199 8192000000 10900288992 11346894848 1907283424 18.885 97 37 73 3 1928221104 10912256 10025424
16:10:59 79031 8192000000 10855098832 11326394368 1788535328 17.920 92 13 26 0 1811176304 18906192 3734784
16:13:19 56039 8192000000 10885009856 11364495360 1946134912 19.196 100 22 44 1 1964201728 12083792 5983024
16:15:28 81430 8192000000 10834738400 11298603008 1867054144 18.560 96 13 26 0 1882098704 11306112 3738448
16:18:24 97599 8192000000 10829070784 11304529920 1841450656 18.353 95 8 16 0 1857359568 13563904 2345008
16:21:02 75081 8192000000 10839589920 11310178304 1901404512 18.838 99 12 24 0 1912254064 7595472 3254080
16:23:32 91275 8192000000 10859270480 11340054528 1923432496 19.014 99 14 27 2 1944233936 16354976 4446464
16:26:24 45267 8192000000 9202792960 9446264832 360755248 4.218 19 2 4 0 364259664 3138832 365584

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 16-10-2020 16:28:27 to 16-10-2020 16:28:42.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 96 v_add_invoice_to_stock 335544517
user_exc 3 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 16-10-2020 16:28:43 to 16-10-2020 16:28:44.

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          244617
MON$OLDEST_ACTIVE               244618
MON$OLDEST_SNAPSHOT             244618
MON$NEXT_TRANSACTION            244618
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                       2006208
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.7.33374 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.7.33374 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.7.33374 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.7.33374 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 ---
:230: 
:231: mscs-test-161.budzdorov.ru    Fri Oct 16 16:27:12 2020
:232:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:233: 
:234: 
:235: mscs-test-161.budzdorov.ru    Fri Oct 16 16:27:13 2020
:236:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:237: 
--- end of diff output ---

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

16.10.20 16:28:45. 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
2020-10-16 16:28:48 No severe PSQL-related problems occured
16.10.20 16:28:49. End of report.