Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.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 17544160 kB
MemAvailable 31487472 kB
Buffers 0 kB
Cached 14111720 kB
SwapCached 3064 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-27 16:26:57
DTS_END                         2020-10-27 16:26:57.0070
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(56:17)
IP                              ::1/50944
TRN_ID                          239269
ATT_ID                          661
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: 9298.05 19950 1115766
customer order: creation 172.94 381 20753
customer order: refuse 14.34 324 1721
order to supplier: creation 45.12 1264 5414
order to supplier: removal 3.66 94411 439
invoice (draft): creation 46.54 3387 5585
invoice (draft): removal 54.08 777 6489
invoice accept: apply 41.26 19027 4951
invoice accept: cancel 48.17 902 5780
customer reserve: creation 3849.80 187 461976
customer reserve: removal 4721.40 56 566568
realization accept: apply 84.40 196 10128
realization accept: cancel 87.97 111 10556
payment from customer: creation 58.87 121 7064
payment from customer: removal 5.58 115 669
payment to supplier: creation 47.55 148 5706
payment to supplier: removal 3.75 100 450
service: total inventory turnovers 4.99 1147 599
service: total monetary turnovers 6.76 27 811
service: aggregate perf. data 0.86 18550 103
service: refresh index statistics 0.03 257778 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 27-10-2020 16:27:51 to 27-10-2020 16:27:54.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4171
TEST_TIME 2 4232
TEST_TIME 3 4388
TEST_TIME 4 4377
TEST_TIME 5 5121
TEST_TIME 6 5361
TEST_TIME 7 5540
TEST_TIME 8 5669
TEST_TIME 9 5728
TEST_TIME 10 5862
TEST_TIME 11 6058
TEST_TIME 12 6071
TEST_TIME 13 6628
TEST_TIME 14 6965
TEST_TIME 15 7473
TEST_TIME 16 7726
TEST_TIME 17 8009
TEST_TIME 18 7957
TEST_TIME 19 8024
TEST_TIME 20 8658
TEST_TIME 21 8721
TEST_TIME 22 8724
TEST_TIME 23 9220
TEST_TIME 24 9338
TEST_TIME 25 9091
TEST_TIME 26 9179
TEST_TIME 27 9642
TEST_TIME 28 9629
TEST_TIME 29 9749
TEST_TIME 30 10097
TEST_TIME 31 10330
TEST_TIME 32 10163
TEST_TIME 33 10459
TEST_TIME 34 10534
TEST_TIME 35 10387
TEST_TIME 36 10249
TEST_TIME 37 10157
TEST_TIME 38 9994
TEST_TIME 39 9864
TEST_TIME 40 9870
TEST_TIME 41 9770
TEST_TIME 42 9618
TEST_TIME 43 9645
TEST_TIME 44 9597
TEST_TIME 45 9513
TEST_TIME 46 9508
TEST_TIME 47 9408
TEST_TIME 48 9277
TEST_TIME 49 9203
TEST_TIME 50 9072
TEST_TIME 51 9285
TEST_TIME 52 9219
TEST_TIME 53 9189
TEST_TIME 54 9410
TEST_TIME 55 9372
TEST_TIME 56 9403
TEST_TIME 57 9420
TEST_TIME 58 9389
TEST_TIME 59 9334
TEST_TIME 60 9310
TEST_TIME 61 9529
TEST_TIME 62 9469
TEST_TIME 63 9487
TEST_TIME 64 9556
TEST_TIME 65 9514
TEST_TIME 66 9478
TEST_TIME 67 9606
TEST_TIME 68 9591
TEST_TIME 69 9636
TEST_TIME 70 9608
TEST_TIME 71 9547
TEST_TIME 72 9471
TEST_TIME 73 9469
TEST_TIME 74 9455
TEST_TIME 75 9448
TEST_TIME 76 9448
TEST_TIME 77 9396
TEST_TIME 78 9433
TEST_TIME 79 9406
TEST_TIME 80 9334
TEST_TIME 81 9365
TEST_TIME 82 9304
TEST_TIME 83 9268
TEST_TIME 84 9265
TEST_TIME 85 9199
TEST_TIME 86 9211
TEST_TIME 87 9223
TEST_TIME 88 9216
TEST_TIME 89 9261
TEST_TIME 90 9278
TEST_TIME 91 9337
TEST_TIME 92 9318
TEST_TIME 93 9362
TEST_TIME 94 9423
TEST_TIME 95 9405
TEST_TIME 96 9459
TEST_TIME 97 9490
TEST_TIME 98 9470
TEST_TIME 99 9462
TEST_TIME 100 9528
TEST_TIME 101 9550
TEST_TIME 102 9465
TEST_TIME 103 9430
TEST_TIME 104 9388
TEST_TIME 105 9373
TEST_TIME 106 9368
TEST_TIME 107 9331
TEST_TIME 108 9312
TEST_TIME 109 9288
TEST_TIME 110 9297
TEST_TIME 111 9232
TEST_TIME 112 9263
TEST_TIME 113 9245
TEST_TIME 114 9279
TEST_TIME 115 9245
TEST_TIME 116 9278
TEST_TIME 117 9276
TEST_TIME 118 9326
TEST_TIME 119 9306
TEST_TIME 120 9300

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 27-10-2020 16:27:55 to 27-10-2020 16:27:58.

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 1543035 1543035 0 0.00 0 16398 6 0 0
doc_list_biud 1543032 1543032 0 0.00 0 45501 27 0 0
sp_add_invoice_to_stock 5097 4951 146 2.86 41 149975 19028 0 146
sp_cancel_adding_invoice 5780 5780 0 0.00 4 60654 902 0 0
sp_cancel_client_order 1721 1721 0 0.00 4 11866 325 0 0
sp_cancel_customer_reserve 566568 566568 0 0.00 1 45502 57 0 0
sp_cancel_pay_from_customer 669 669 0 0.00 3 12405 115 0 0
sp_cancel_pay_to_supplier 450 450 0 0.00 2 13546 100 0 0
sp_cancel_supplier_invoice 6498 6489 9 0.13 2 23837 777 0 9
sp_cancel_supplier_order 439 439 0 0.00 6 713649 94411 0 0
sp_cancel_write_off 10556 10556 0 0.00 1 14137 112 0 0
sp_client_order 20753 20753 0 0.00 3 22387 382 0 0
sp_customer_reserve 461976 461976 0 0.00 0 116261 188 0 0
sp_fill_shopping_cart 31740 31740 0 0.00 1 28208 40 0 0
sp_fill_shopping_cart_clo_res 461972 461972 0 0.00 0 13000 14 0 0
sp_get_clo_for_invoice 4946 4946 0 0.00 0 23800 334 0 0
sp_kill_cost_storno 17455 17455 0 0.00 0 12397 62 0 0
sp_kill_qstorno_ret_qs2qd 1150620 1150620 0 0.00 0 45496 31 0 0
sp_kill_qty_storno 606626 606626 0 0.00 0 45501 68 0 0
sp_lock_selected_doc 20949 20949 0 0.00 0 9143 5 0 0
sp_make_cost_storno 27844 27844 0 0.00 0 12238 51 0 0
sp_multiply_rows_for_pdistr 27842 27842 0 0.00 0 15570 37 0 0
sp_multiply_rows_for_qdistr 20753 20753 0 0.00 0 13721 158 0 0
sp_pay_from_customer 7064 7064 0 0.00 4 13879 122 0 0
sp_pay_to_supplier 5706 5706 0 0.00 5 16402 148 0 0
sp_payment_common 12769 12769 0 0.00 1 15579 30 0 0
sp_qd_handle_on_cancel_clo 1721 1721 0 0.00 0 11859 297 0 0
sp_qd_handle_on_invoice_upd_sts 10726 10726 0 0.00 0 21635 364 0 0
sp_qd_handle_on_reserve_upd_sts 10127 10127 0 0.00 0 15427 61 0 0
sp_reserve_write_off 10128 10128 0 0.00 6 15619 196 0 0
sp_supplier_invoice 5585 5585 0 0.00 5 66969 3387 0 0
sp_supplier_order 5414 5414 0 0.00 5 23509 1265 0 0
srv_aggregate_perf_data 103 103 0 0.00 2658 218730 18550 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2292 148699 46545 0 0
srv_find_qd_qs_mism 1067891 1067891 0 0.00 0 13171 5 0 0
srv_make_invnt_saldo 599 599 0 0.00 28 54551 1147 0 0
srv_make_money_saldo 811 811 0 0.00 1 4230 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 144491 470210 257779 0 0
t$perf-abend:sp_add_invoice_to_stock 146 146 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 4951 4951 0 0.00 0 11248 162 0 0
t$perf-norm:sp_cancel_adding_invoice 409 409 0 0.00 0 5038 112 0 0
t$perf-norm:sp_cancel_client_order 1721 1721 0 0.00 0 3609 16 0 0
t$perf-norm:sp_cancel_customer_reserve 667 667 0 0.00 0 2184 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 669 669 0 0.00 0 5632 27 0 0
t$perf-norm:sp_cancel_pay_to_supplier 450 450 0 0.00 0 5490 22 0 0
t$perf-norm:sp_cancel_supplier_invoice 441 441 0 0.00 0 543 10 0 0
t$perf-norm:sp_cancel_supplier_order 439 439 0 0.00 0 21422 1682 0 0
t$perf-norm:sp_cancel_write_off 1081 1081 0 0.00 0 4654 11 0 0
t$perf-norm:sp_client_order 20753 20753 0 0.00 0 11043 18 0 0
t$perf-norm:sp_customer_reserve 974 974 0 0.00 0 1738 4 0 0
t$perf-norm:sp_pay_from_customer 7064 7064 0 0.00 0 8477 11 0 0
t$perf-norm:sp_pay_to_supplier 5706 5706 0 0.00 0 8945 11 0 0
t$perf-norm:sp_reserve_write_off 10128 10128 0 0.00 0 7299 14 0 0
t$perf-norm:sp_supplier_invoice 5585 5585 0 0.00 0 8851 21 0 0
t$perf-norm:sp_supplier_order 5414 5414 0 0.00 0 11592 16 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 315 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 45 1 0 0
t$perf-norm:srv_make_invnt_saldo 599 599 0 0.00 0 2440 21 0 0
t$perf-norm:srv_make_money_saldo 811 811 0 0.00 0 1119 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 9 5 0 0
v_add_invoice_to_stock 10182 9890 292 2.86 0 7312 23 0 292
v_all_customers 37928 37928 0 0.00 0 45 1 0 0
v_all_suppliers 30030 30030 0 0.00 0 16 1 0 0
v_all_wares 41506 41506 0 0.00 0 51 1 0 0
v_cancel_adding_invoice 818 818 0 0.00 0 4132 11 0 0
v_cancel_client_order 3442 3442 0 0.00 0 6505 8 0 0
v_cancel_customer_prepayment 1338 1338 0 0.00 0 7277 14 0 0
v_cancel_customer_reserve 1334 1334 0 0.00 0 2148 6 0 0
v_cancel_payment_to_supplier 900 900 0 0.00 0 1796 6 0 0
v_cancel_supplier_invoice 900 882 18 2.00 0 7281 22 0 18
v_cancel_supplier_order 878 878 0 0.00 0 1957 6 0 0
v_cancel_write_off 2162 2162 0 0.00 0 4161 17 0 0
v_max_id_clo_ord 5412 5412 0 0.00 0 1988 2 0 0
v_max_id_clo_res 974 974 0 0.00 0 8492 31 0 0
v_max_id_ord_sup 5575 5575 0 0.00 0 4383 6 0 0
v_max_non_paid_invoice 1678 1678 0 0.00 0 3570 5 0 0
v_max_non_paid_realizn 3663 3663 0 0.00 0 1572 4 0 0
v_min_id_clo_ord 5412 5412 0 0.00 0 2508 8 0 0
v_min_id_clo_res 974 974 0 0.00 0 9655 25 0 0
v_min_id_ord_sup 5575 5575 0 0.00 0 6500 11 0 0
v_min_non_paid_invoice 5706 5706 0 0.00 0 12002 15 0 0
v_min_non_paid_realizn 7063 7063 0 0.00 0 13840 27 0 0
v_our_firm 10378 10378 0 0.00 0 13 1 0 0
v_reserve_write_off 20254 20254 0 0.00 0 8405 9 0 0
x_lock_depdocs_on_canc_invoice 11828 11828 0 0.00 0 22296 122 0 0
x_lock_depdocs_on_canc_sup_ord 439 439 0 0.00 0 3997 282 0 0
x_make_qstorno_1000_1200 5414 5414 0 0.00 2 23497 1188 0 0
x_make_qstorno_1200_2000 5585 5585 0 0.00 3 65261 3238 0 0
x_make_qstorno_2100_3300 461976 461976 0 0.00 0 116246 172 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-10-2020 16:27:59 to 27-10-2020 16:28:03.

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:00 1 8192000000 8798422528 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
13:58:01 27738 8192000000 10352827152 10666553344 1399877824 14.594 100 91 182 0 1435927968 5993904 30056240
13:59:32 21254 8192000000 10396907712 10722701312 1451063200 15.047 100 92 184 0 1489883232 8481568 30338464
14:01:05 19455 8192000000 10463277184 10801631232 1514898048 15.606 100 87 174 1 1552395424 10110928 27386448
14:02:33 10485 8192000000 10548088160 10902777856 1604721344 16.380 100 81 162 1 1639987312 10898912 24367056
14:03:48 9538 8192000000 10626255392 10996936704 1690135248 17.102 100 71 142 0 1721332128 10679168 20517712
14:05:01 5779 8192000000 10703159264 11078164480 1762619568 17.706 100 83 163 3 1798146112 12548080 22978464
14:06:09 2561 8192000000 10742116992 11120869376 1795953552 17.981 100 95 188 3 1837482448 15758512 25770384
14:07:13 4760 8192000000 10760969104 11151847424 1830289184 18.262 100 77 153 2 1864256464 13078608 20888672
14:08:21 2169 8192000000 10817622672 11209138176 1877071280 18.641 100 86 170 1 1914081056 13150704 23859072
14:09:25 3708 8192000000 10815355216 11213598720 1878172896 18.650 100 69 136 5 1906064368 8124352 19767120
14:10:29 4583 8192000000 10797374608 11224633344 1883447696 18.693 100 40 78 4 1902674000 8403840 10822464
14:11:35 14635 8192000000 10795941040 11226484736 1889128672 18.739 100 16 32 0 1899819088 6663808 4026608
14:12:51 9114 8192000000 10798517088 11232382976 1889663312 18.743 100 8 16 1 1897507808 5422384 2422112
14:14:02 51070 8192000000 10844811440 11258912768 1907757568 18.889 100 39 77 1 1924664016 5031472 11874976
14:16:03 4393 8192000000 10804279760 11237965824 1891049248 18.754 99 29 58 2 1905704704 6414352 8241104
14:17:08 7289 8192000000 10799000848 11236405248 1875267568 18.627 99 17 34 1 1885762592 5502272 4992752
14:18:18 10630 8192000000 10737229200 11158278144 1781340496 17.861 97 12 23 2 1790574016 5713728 3519792
14:19:30 22102 8192000000 10711873440 11138260992 1704298752 17.221 93 28 56 0 1719158048 5837568 9021728
14:20:55 3826 8192000000 10722258176 11137622016 1801667024 18.028 100 39 78 3 1821008176 8195504 11145648
14:22:02 15021 8192000000 10759909424 11174387712 1834384816 18.295 100 31 61 2 1849559664 5702480 9472368
14:23:19 29108 8192000000 10735715136 11168288768 1818218864 18.163 99 31 61 4 1833501360 6140400 9142096
14:25:14 8463 8192000000 10748841728 11185356800 1847759264 18.404 100 31 62 3 1863074320 5218400 10096656
14:26:25 7957 8192000000 10782258064 11205160960 1859655680 18.500 100 19 38 0 1870699680 5816240 5227760
14:27:36 20903 8192000000 10830624352 11251888128 1901902656 18.842 100 48 96 0 1923766272 8353792 13509824
14:29:08 14113 8192000000 10855822272 11278684160 1923380080 19.014 100 40 77 3 1941807408 7803632 10623696
14:30:26 25555 8192000000 10866680144 11302289408 1937104080 19.124 100 41 82 1 1964084736 14201696 12778960
14:32:16 24044 8192000000 10879400816 11323875328 1945343696 19.189 100 32 62 2 1974937920 20025168 9569056
14:33:45 3384 8192000000 10912243520 11336355840 1965791904 19.352 100 52 101 1 1997753536 17023744 14937888
14:34:56 4035 8192000000 10922318752 11346817024 1975882944 19.432 100 68 135 0 2013229312 18600064 18746304
14:36:05 9735 8192000000 10913198400 11344461824 1970485440 19.389 100 55 110 1 2005963936 19554912 15923584
14:37:17 6354 8192000000 10927702720 11353038848 1977772848 19.447 100 63 124 0 2017144336 20648608 18722880
14:38:29 3495 8192000000 10957009120 11374678016 1990903472 19.551 100 76 150 1 2040072496 26302016 22867008
14:39:36 8517 8192000000 10921871168 11350487040 1960500768 19.310 99 59 118 0 2003704688 25220592 17983328
14:40:52 2291 8192000000 10927189056 11341795328 1958489584 19.294 100 83 162 4 2013618352 29906496 25222272
14:41:58 3258 8192000000 10940004208 11359145984 1977807360 19.447 100 73 145 0 2025954160 25946464 22200336
14:43:12 7322 8192000000 10917710048 11346472960 1961129968 19.315 100 62 124 0 2006928832 26785136 19013728
14:44:26 4453 8192000000 10948242464 11363614720 1973889808 19.416 100 79 158 1 2027131264 28868224 24373232
14:45:35 2476 8192000000 10914218112 11344330752 1951674960 19.240 100 56 107 1 1999495632 28793728 19026944
14:46:40 10433 8192000000 10852808688 11293859840 1907755936 18.889 100 43 86 0 1947857328 26353152 13748240
14:48:08 13932 8192000000 10875030464 11311583232 1910317040 18.909 99 44 88 1 1951549344 27090928 14141376
14:49:26 13268 8192000000 10887121280 11329122304 1930241504 19.069 100 43 86 2 1983546688 39229984 14075200
14:50:43 16990 8192000000 10862036112 11307479040 1866943568 18.560 97 21 42 3 1907203072 33364912 6894592
14:52:01 17082 8192000000 10906453520 11342331904 1934654224 19.104 100 33 66 2 1984889952 39060144 11175584
14:53:21 7503 8192000000 10916036384 11356667904 1938889632 19.138 100 36 72 1 1993783856 42715040 12179184
14:54:44 26871 8192000000 10914604240 11362099200 1954097424 19.259 100 27 54 4 2003258416 39644176 9516816
14:56:20 17304 8192000000 10897582112 11350564864 1938757168 19.137 99 28 56 2 1976672592 29123488 8791936
14:58:12 51714 8192000000 10907325888 11336601600 1921457456 18.999 99 29 58 0 1962678576 32082304 9138816
15:00:08 54556 8192000000 10866807664 11327598592 1929319472 19.061 99 20 37 1 1944471744 10747328 4404944
15:02:12 85527 8192000000 10853739216 11319091200 1906551632 18.879 98 15 30 0 1920263376 9603408 4108336
15:04:38 148698 8192000000 10877523696 11342921728 1945481040 19.190 99 30 59 0 1967575296 12894320 9199936
15:08:10 58797 8192000000 10935104352 11367079936 1980459824 19.468 100 54 107 2 2005351392 8879264 16012304
15:10:25 81462 8192000000 10934244208 11384053760 1989850288 19.543 100 22 44 1 2004670192 8411232 6408672
15:12:52 85576 8192000000 10917423584 11378511872 1944015760 19.179 97 20 40 2 1962343200 11394352 6933088
15:15:38 66778 8192000000 10965649728 11427745792 2024875312 19.818 100 30 60 3 2054924064 20384064 9664688
15:17:50 82742 8192000000 10933170272 11426701312 2006561504 19.674 100 18 36 0 2035871584 22664032 6646048
15:20:14 77243 8192000000 10924797120 11412180992 2000959296 19.630 100 12 24 0 2028101856 23571712 3570848
15:22:33 42285 8192000000 10930846672 11395629056 1974721872 19.423 98 29 59 0 1991615152 8634704 8258576
15:24:21 34002 8192000000 10921913184 11392835584 1990873168 19.551 100 9 18 0 2001723232 8139744 2710320
15:25:55 42717 8192000000 10942032992 11413164032 1997636016 19.604 99 34 68 2 2021609312 13859568 10113728
15:27:58 56630 8192000000 10905469360 11372445696 1919473248 18.983 96 12 24 0 1932990128 10562336 2954544
15:29:54 78524 8192000000 10886668912 11346558976 1857477584 18.483 94 16 32 1 1869604288 8931520 3195184
15:32:14 7306 8192000000 10916154672 11353944064 1932259376 19.085 98 64 128 0 1964249600 13555616 18434608
15:33:24 103736 8192000000 10849533136 11322822656 1759340096 17.679 90 10 21 0 1780279488 18464144 2475248
15:36:08 43522 8192000000 10853601888 11311628288 1899536592 18.823 98 27 54 1 1917519088 10316384 7666112
15:37:51 69009 8192000000 10822181584 11289595904 1863093968 18.528 96 22 42 2 1877046128 7693456 6258704
15:40:13 72586 8192000000 10790670800 11262824448 1812650320 18.118 96 19 38 0 1833221104 15049824 5520960
15:42:36 55083 8192000000 10805494560 11264671744 1818969152 18.169 96 17 32 1 1833915152 10706816 4239184
15:44:39 67361 8192000000 10803316512 11272519680 1844148976 18.375 97 17 34 1 1858179536 9213696 4816864
15:46:49 39071 8192000000 10833093920 11297714176 1911042976 18.915 100 16 30 1 1930162272 14898176 4221120
15:48:28 77493 8192000000 10830742416 11291078656 1856195120 18.472 97 15 30 1 1871255472 10715168 4345184
15:50:53 56179 8192000000 10844762272 11312570368 1923184176 19.012 100 13 26 2 1935228912 8517648 3527088
15:52:54 79752 8192000000 10840235680 11310592000 1870586304 18.589 97 14 28 0 1891258816 16661744 4010768
15:55:14 52411 8192000000 10871015088 11327184896 1907129904 18.884 98 10 20 0 1922714592 12475440 3109248
15:57:07 87737 8192000000 10869160336 11335376896 1870763168 18.590 96 12 24 2 1891201024 17110064 3327792
15:59:36 60947 8192000000 10900081456 11372482560 1969284944 19.380 100 20 40 0 1988173248 13457520 5430784
16:01:41 56545 8192000000 10911075264 11375157248 1947764784 19.209 98 19 38 1 1970226384 17024048 5437552
16:03:49 86571 8192000000 10936369888 11391504384 1953646928 19.256 98 23 46 0 1970718704 11346048 5725728
16:06:25 115680 8192000000 10934056288 11408699392 1999383904 19.618 100 12 24 0 2014802032 11995856 3422272
16:09:26 102174 8192000000 10935981824 11404525568 1989783680 19.542 99 22 44 0 2005056608 8816448 6456480
16:12:09 63866 8192000000 10908145488 11395678208 1976600704 19.438 99 15 30 0 1991358672 10671552 4086416
16:14:13 75861 8192000000 10904722480 11378200576 1895639984 18.791 95 10 20 0 1907468720 9184704 2644032
16:16:29 70889 8192000000 10890063616 11366764544 1957059088 19.283 99 16 32 0 1977076704 15410320 4607296
16:18:59 69498 8192000000 10872349024 11350372352 1920134048 18.988 98 16 32 0 1936922176 12337648 4450480
16:21:09 32681 8192000000 10889352672 11353411584 1927417856 19.046 98 24 48 3 1942902464 8401248 7083360
16:22:47 43376 8192000000 10904093536 11358003200 1923853648 19.018 98 30 60 1 1942052320 9625216 8573456
16:24:31 50924 8192000000 10853093136 11326156800 1899818960 18.825 98 16 32 0 1918422704 14276032 4327712
16:26:22 27963 8192000000 10863705824 11299512320 1855658592 18.468 96 35 68 0 1874886016 9468416 9759008

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 146 v_add_invoice_to_stock 335544517
user_exc 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 27-10-2020 16:28:21 to 27-10-2020 16:28:23.

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          237196
MON$OLDEST_ACTIVE               239968
MON$OLDEST_SNAPSHOT             239968
MON$NEXT_TRANSACTION            239968
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                       2016320
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.33380 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.7.33380 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.7.33380 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.7.33380 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 ---
:153: 
:154: mscs-test-161.budzdorov.ru    Tue Oct 27 16:26:58 2020
:155:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:156: 
:157: 
:158: mscs-test-161.budzdorov.ru    Tue Oct 27 16:26:58 2020
:159:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:160: 
:161: 
:162: mscs-test-161.budzdorov.ru    Tue Oct 27 16:27:00 2020
:163:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:164: 
:165: 
:166: mscs-test-161.budzdorov.ru    Tue Oct 27 16:27:00 2020
:167:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:168: 
:169: 
:170: mscs-test-161.budzdorov.ru    Tue Oct 27 16:27:03 2020
:171:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:172: 
:173: 
:174: mscs-test-161.budzdorov.ru    Tue Oct 27 16:27:03 2020
:175:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:176: 
--- end of diff output ---

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

27.10.20 16:28:24. 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-27 16:28:26 No severe PSQL-related problems occured
27.10.20 16:28:27. End of report.