Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.10.2020 10: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 16711520 kB
MemAvailable 31586764 kB
Buffers 0 kB
Cached 14969672 kB
SwapCached 5432 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 4.0.0
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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-18 10:26:50
DTS_END                         2020-10-18 10:26:50.3110
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(67:17)
IP                              ::1/54740
TRN_ID                          234697
ATT_ID                          630
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: 9252.17 22474 1110261
customer order: creation 166.59 395 19991
customer order: refuse 14.52 356 1742
order to supplier: creation 44.59 1455 5351
order to supplier: removal 3.65 114698 438
invoice (draft): creation 45.66 3529 5479
invoice (draft): removal 54.74 956 6569
invoice accept: apply 41.42 15847 4970
invoice accept: cancel 49.02 866 5882
customer reserve: creation 3702.66 159 444319
customer reserve: removal 4832.93 69 579951
realization accept: apply 82.56 172 9907
realization accept: cancel 89.42 112 10730
payment from customer: creation 57.31 102 6877
payment from customer: removal 5.89 98 707
payment to supplier: creation 47.44 109 5693
payment to supplier: removal 3.37 91 404
service: total inventory turnovers 3.71 3518 445
service: total monetary turnovers 5.83 576 700
service: aggregate perf. data 0.85 16139 102
service: refresh index statistics 0.03 290237 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 18-10-2020 10:28:19 to 18-10-2020 10:28:22.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2288
TEST_TIME 2 3401
TEST_TIME 3 4678
TEST_TIME 4 5248
TEST_TIME 5 5405
TEST_TIME 6 5823
TEST_TIME 7 5898
TEST_TIME 8 6274
TEST_TIME 9 6333
TEST_TIME 10 6563
TEST_TIME 11 7060
TEST_TIME 12 7297
TEST_TIME 13 7575
TEST_TIME 14 7906
TEST_TIME 15 8252
TEST_TIME 16 8410
TEST_TIME 17 8429
TEST_TIME 18 8635
TEST_TIME 19 9122
TEST_TIME 20 9223
TEST_TIME 21 9187
TEST_TIME 22 9422
TEST_TIME 23 9211
TEST_TIME 24 9287
TEST_TIME 25 9703
TEST_TIME 26 9946
TEST_TIME 27 10526
TEST_TIME 28 11753
TEST_TIME 29 11812
TEST_TIME 30 11646
TEST_TIME 31 11647
TEST_TIME 32 11520
TEST_TIME 33 11492
TEST_TIME 34 11463
TEST_TIME 35 11387
TEST_TIME 36 11150
TEST_TIME 37 10997
TEST_TIME 38 10812
TEST_TIME 39 10660
TEST_TIME 40 10613
TEST_TIME 41 10466
TEST_TIME 42 10401
TEST_TIME 43 10559
TEST_TIME 44 10444
TEST_TIME 45 10385
TEST_TIME 46 10317
TEST_TIME 47 10290
TEST_TIME 48 10150
TEST_TIME 49 10367
TEST_TIME 50 10261
TEST_TIME 51 10282
TEST_TIME 52 10305
TEST_TIME 53 10280
TEST_TIME 54 10324
TEST_TIME 55 10270
TEST_TIME 56 10352
TEST_TIME 57 10433
TEST_TIME 58 10362
TEST_TIME 59 10271
TEST_TIME 60 10126
TEST_TIME 61 10081
TEST_TIME 62 10007
TEST_TIME 63 9935
TEST_TIME 64 9873
TEST_TIME 65 9888
TEST_TIME 66 9812
TEST_TIME 67 9847
TEST_TIME 68 9809
TEST_TIME 69 9897
TEST_TIME 70 9890
TEST_TIME 71 9854
TEST_TIME 72 9806
TEST_TIME 73 9981
TEST_TIME 74 10018
TEST_TIME 75 10016
TEST_TIME 76 10193
TEST_TIME 77 10063
TEST_TIME 78 9971
TEST_TIME 79 9908
TEST_TIME 80 9847
TEST_TIME 81 9767
TEST_TIME 82 9733
TEST_TIME 83 9759
TEST_TIME 84 9706
TEST_TIME 85 9699
TEST_TIME 86 9657
TEST_TIME 87 9620
TEST_TIME 88 9598
TEST_TIME 89 9565
TEST_TIME 90 9589
TEST_TIME 91 9587
TEST_TIME 92 9865
TEST_TIME 93 9907
TEST_TIME 94 9932
TEST_TIME 95 9905
TEST_TIME 96 9847
TEST_TIME 97 9761
TEST_TIME 98 9735
TEST_TIME 99 9676
TEST_TIME 100 9626
TEST_TIME 101 9582
TEST_TIME 102 9521
TEST_TIME 103 9470
TEST_TIME 104 9441
TEST_TIME 105 9417
TEST_TIME 106 9431
TEST_TIME 107 9401
TEST_TIME 108 9403
TEST_TIME 109 9405
TEST_TIME 110 9425
TEST_TIME 111 9483
TEST_TIME 112 9461
TEST_TIME 113 9433
TEST_TIME 114 9477
TEST_TIME 115 9485
TEST_TIME 116 9431
TEST_TIME 117 9369
TEST_TIME 118 9315
TEST_TIME 119 9273
TEST_TIME 120 9254

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 18-10-2020 10:28:23 to 18-10-2020 10:28:26.

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 1524536 1524536 0 0.00 0 14201 6 0 0
doc_list_biud 1524536 1524536 0 0.00 0 33262 33 0 0
sp_add_invoice_to_stock 5031 4970 61 1.21 32 162868 15848 0 61
sp_cancel_adding_invoice 5882 5882 0 0.00 5 78626 866 0 0
sp_cancel_client_order 1742 1742 0 0.00 4 14020 356 0 0
sp_cancel_customer_reserve 579951 579951 0 0.00 1 33264 70 0 0
sp_cancel_pay_from_customer 707 707 0 0.00 3 6851 98 0 0
sp_cancel_pay_to_supplier 404 404 0 0.00 3 8588 92 0 0
sp_cancel_supplier_invoice 6574 6569 5 0.07 3 26764 956 0 5
sp_cancel_supplier_order 438 438 0 0.00 5 882483 114699 0 0
sp_cancel_write_off 10730 10730 0 0.00 1 12100 112 0 0
sp_client_order 19991 19991 0 0.00 4 44291 395 0 0
sp_customer_reserve 444319 444319 0 0.00 0 102851 159 0 0
sp_fill_shopping_cart 30801 30801 0 0.00 1 24463 42 0 0
sp_fill_shopping_cart_clo_res 444308 444308 0 0.00 0 17996 16 0 0
sp_get_clo_for_invoice 4959 4959 0 0.00 0 29401 358 0 0
sp_kill_cost_storno 17723 17723 0 0.00 0 8583 54 0 0
sp_kill_qstorno_ret_qs2qd 1177639 1177639 0 0.00 0 33260 38 0 0
sp_kill_qty_storno 620178 620178 0 0.00 0 33262 81 0 0
sp_lock_selected_doc 20565 20565 0 0.00 0 8829 8 0 0
sp_make_cost_storno 27436 27436 0 0.00 0 14040 49 0 0
sp_multiply_rows_for_pdistr 27436 27436 0 0.00 0 9840 30 0 0
sp_multiply_rows_for_qdistr 19991 19991 0 0.00 0 44274 222 0 0
sp_pay_from_customer 6877 6877 0 0.00 4 15631 102 0 0
sp_pay_to_supplier 5693 5693 0 0.00 5 14205 110 0 0
sp_payment_common 12570 12570 0 0.00 1 8245 19 0 0
sp_qd_handle_on_cancel_clo 1742 1742 0 0.00 0 13997 326 0 0
sp_qd_handle_on_invoice_upd_sts 10841 10841 0 0.00 0 18524 368 0 0
sp_qd_handle_on_reserve_upd_sts 9907 9907 0 0.00 0 15378 49 0 0
sp_reserve_write_off 9907 9907 0 0.00 7 15419 172 0 0
sp_supplier_invoice 5479 5479 0 0.00 7 77726 3530 0 0
sp_supplier_order 5351 5351 0 0.00 6 37090 1455 0 0
srv_aggregate_perf_data 102 102 0 0.00 2822 186242 16140 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2404 111233 43689 0 0
srv_find_qd_qs_mism 1065525 1065525 0 0.00 0 9374 4 0 0
srv_make_invnt_saldo 445 445 0 0.00 40 87530 3519 0 0
srv_make_money_saldo 700 700 0 0.00 1 14782 577 0 0
srv_recalc_idx_stat 4 4 0 0.00 190940 369500 290238 0 0
t$perf-abend:sp_add_invoice_to_stock 61 61 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4970 4970 0 0.00 0 14166 168 0 0
t$perf-norm:sp_cancel_adding_invoice 388 388 0 0.00 0 3365 100 0 0
t$perf-norm:sp_cancel_client_order 1742 1742 0 0.00 0 5086 13 0 0
t$perf-norm:sp_cancel_customer_reserve 672 672 0 0.00 0 6891 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 707 707 0 0.00 0 1480 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 404 404 0 0.00 0 97 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 404 404 0 0.00 0 5466 26 0 0
t$perf-norm:sp_cancel_supplier_order 438 438 0 0.00 0 15414 1397 0 0
t$perf-norm:sp_cancel_write_off 944 944 0 0.00 0 11112 22 0 0
t$perf-norm:sp_client_order 19991 19991 0 0.00 0 7584 18 0 0
t$perf-norm:sp_customer_reserve 1005 1005 0 0.00 0 3255 8 0 0
t$perf-norm:sp_pay_from_customer 6877 6877 0 0.00 0 11849 8 0 0
t$perf-norm:sp_pay_to_supplier 5693 5693 0 0.00 0 11123 11 0 0
t$perf-norm:sp_reserve_write_off 9907 9907 0 0.00 0 11823 11 0 0
t$perf-norm:sp_supplier_invoice 5479 5479 0 0.00 0 11117 20 0 0
t$perf-norm:sp_supplier_order 5351 5351 0 0.00 0 12134 14 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 450 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 989 21 0 0
t$perf-norm:srv_make_invnt_saldo 445 445 0 0.00 0 2229 20 0 0
t$perf-norm:srv_make_money_saldo 700 700 0 0.00 0 3379 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 62 18 0 0
v_add_invoice_to_stock 10040 9918 122 1.21 0 5559 17 0 122
v_all_customers 36178 36178 0 0.00 0 95 1 0 0
v_all_suppliers 29754 29754 0 0.00 0 95 1 0 0
v_all_wares 39982 39982 0 0.00 0 49 1 0 0
v_cancel_adding_invoice 776 776 0 0.00 0 3250 13 0 0
v_cancel_client_order 3484 3484 0 0.00 0 5080 8 0 0
v_cancel_customer_prepayment 1414 1414 0 0.00 0 3252 9 0 0
v_cancel_customer_reserve 1344 1344 0 0.00 0 1676 4 0 0
v_cancel_payment_to_supplier 808 808 0 0.00 0 1681 5 0 0
v_cancel_supplier_invoice 818 808 10 1.22 0 6854 23 0 10
v_cancel_supplier_order 876 876 0 0.00 0 4887 13 0 0
v_cancel_write_off 1888 1888 0 0.00 0 3567 14 0 0
v_max_id_clo_ord 5350 5350 0 0.00 0 553 1 0 0
v_max_id_clo_res 1005 1005 0 0.00 0 1898 12 0 0
v_max_id_ord_sup 5460 5460 0 0.00 0 5010 5 0 0
v_max_non_paid_invoice 1626 1626 0 0.00 0 1066 3 0 0
v_max_non_paid_realizn 3736 3736 0 0.00 0 3134 4 0 0
v_min_id_clo_ord 5350 5350 0 0.00 0 1113 4 0 0
v_min_id_clo_res 1005 1005 0 0.00 0 6533 19 0 0
v_min_id_ord_sup 5460 5460 0 0.00 0 12840 9 0 0
v_min_non_paid_invoice 5693 5693 0 0.00 0 6004 10 0 0
v_min_non_paid_realizn 6877 6877 0 0.00 0 6242 13 0 0
v_our_firm 10086 10086 0 0.00 0 15 1 0 0
v_reserve_write_off 19814 19814 0 0.00 0 5124 7 0 0
x_lock_depdocs_on_canc_invoice 12047 12047 0 0.00 0 12368 95 0 0
x_lock_depdocs_on_canc_sup_ord 438 438 0 0.00 0 6419 273 0 0
x_make_qstorno_1000_1200 5351 5351 0 0.00 2 37082 1386 0 0
x_make_qstorno_1200_2000 5479 5479 0 0.00 3 77713 3361 0 0
x_make_qstorno_2100_3300 444319 444319 0 0.00 0 102812 141 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-10-2020 10:28:27 to 18-10-2020 10:28:31.

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
07:56:53 8 8192000000 8796624000 8807108608 3156752 0.038 1 1 2 0 3254112 61504 35856
07:57:54 27266 8192000000 9727382464 10026762240 803325344 8.930 100 88 177 0 832351936 7657696 21368896
07:59:32 16025 8192000000 9764227520 10073948160 836698576 9.267 100 87 174 0 871397216 14302320 20396320
08:01:03 9376 8192000000 9822033264 10144247808 884400288 9.743 100 84 168 0 934854672 31917008 18537376
08:02:17 10816 8192000000 9891367232 10225934336 917925552 10.076 100 87 171 0 995348736 58730368 18692816
08:03:30 7138 8192000000 9937449936 10280009728 954915264 10.439 100 81 162 1 1048588016 76879536 16793216
08:04:39 5732 8192000000 9967424528 10323714048 998034128 10.859 100 79 158 1 1084758592 70654240 16070224
08:05:49 3892 8192000000 9990451280 10354647040 1022327968 11.094 100 85 164 3 1100546512 61662288 16556256
08:06:54 1502 8192000000 10020763392 10390298624 1048283120 11.344 100 81 163 1 1130063296 65225184 16554992
08:07:57 1544 8192000000 10027225440 10401185792 1064245888 11.497 100 84 168 1 1136942288 56208880 16487520
08:08:59 7180 8192000000 10009624944 10400313344 1076758704 11.617 100 41 82 0 1142804784 58178992 7867088
08:10:08 8405 8192000000 9984717904 10383646720 1081989664 11.666 100 19 37 1 1111635008 24861648 4783696
08:11:18 63221 8192000000 9992108976 10402549760 1088688608 11.730 100 37 73 2 1111141968 14213600 8239760
08:13:25 6569 8192000000 10007137440 10425532416 1095177312 11.792 100 27 54 4 1125865632 24809968 5878352
08:14:32 5188 8192000000 10007833712 10412789760 1101252160 11.850 100 48 97 1 1134923616 22764112 10907344
08:15:40 5261 8192000000 10002502768 10411237376 1087158176 11.716 99 42 85 0 1113066704 15993120 9915408
08:16:51 46797 8192000000 9969161952 10371772416 994964416 10.830 91 21 42 0 1018268080 18677392 4626272
08:18:38 3724 8192000000 9963134672 10340585472 1019233184 11.065 97 51 99 2 1044423728 14229536 10961008
08:19:47 6669 8192000000 9920642848 10292891648 989345552 10.775 97 40 78 2 1012963568 14668192 8949824
08:20:55 6985 8192000000 9923253088 10298277888 1005183008 10.929 98 33 65 4 1031844080 18588240 8072832
08:22:03 34095 8192000000 9936171392 10334715904 991664560 10.798 96 15 28 0 1020449856 25982560 2802736
08:23:43 6019 8192000000 9973322640 10358853632 1049160320 11.353 100 49 99 4 1090809584 31254032 10395232
08:24:49 7599 8192000000 9968467952 10371883008 1059452128 11.451 100 40 77 5 1086293264 17293504 9547632
08:25:57 63465 8192000000 9985360752 10375860224 1072804176 11.579 100 31 61 3 1099126544 19258752 7063616
08:28:09 20606 8192000000 10054420672 10453106688 1096404016 11.804 100 53 103 0 1157629328 51299648 9925664
08:29:49 24387 8192000000 10099304880 10503634944 1116921344 11.998 100 49 97 1 1197291984 69547424 10823216
08:31:19 15401 8192000000 10136068864 10533179392 1134099856 12.160 100 58 112 1 1246023760 99571792 12352112
08:32:37 9265 8192000000 10154281744 10562367488 1143706640 12.250 100 48 96 1 1253973040 99154464 11111936
08:33:55 10425 8192000000 10198461952 10604548096 1152324432 12.331 100 50 100 0 1299375632 135740432 11310768
08:35:11 17976 8192000000 10199700784 10604642304 1150665408 12.316 100 60 119 1 1297953152 134120832 13166912
08:36:34 2402 8192000000 10233216512 10632908800 1150182544 12.311 100 72 144 3 1338459936 171725232 16552160
08:37:43 6259 8192000000 10247139920 10653650944 1155800144 12.364 100 64 127 2 1345719808 175317680 14601984
08:38:54 4771 8192000000 10250304080 10663071744 1151975872 12.328 100 61 121 0 1351420608 185999312 13445424
08:40:03 3504 8192000000 10293646848 10691719168 1155111104 12.357 100 79 158 1 1396360656 223923200 17326352
08:41:10 4296 8192000000 10319972080 10721902592 1161695760 12.419 100 70 139 3 1415763184 238306096 15761328
08:42:21 8102 8192000000 10329618480 10740543488 1161443968 12.417 100 60 118 1 1424688144 249073632 14170544
08:43:34 6903 8192000000 10318011072 10731593728 1153881296 12.346 99 53 106 1 1415790016 249087920 12820800
08:44:45 9155 8192000000 10344047616 10764562432 1133329776 12.153 98 45 90 2 1428919040 284550336 11038928
08:45:58 12409 8192000000 10327878080 10747158528 1158878000 12.393 100 36 72 0 1433512016 265603120 9030896
08:47:13 10513 8192000000 10351065888 10759626752 1150472080 12.314 99 63 122 5 1443809968 278510512 14827376
08:48:27 13419 8192000000 10383116608 10824011776 1151456976 12.323 100 30 60 0 1495874800 336604576 7813248
08:49:47 6183 8192000000 10441263616 10859872256 1134266160 12.162 98 44 88 0 1521350608 376309088 10775360
08:51:01 9991 8192000000 10459252144 10938843136 1143269168 12.246 100 37 74 0 1575911440 422732528 9909744
08:52:42 28218 8192000000 10428556944 10972401664 1137994032 12.197 100 30 60 2 1600560320 454339536 8226752
08:54:24 31214 8192000000 10100669200 10668560384 1102237536 11.859 99 15 30 1 1243553168 137488640 3826992
08:55:59 57419 8192000000 10063129392 10489495552 1090194096 11.745 97 13 26 1 1117097504 24374224 2529184
08:58:12 104957 8192000000 10079823504 10545242112 1115853168 11.988 99 17 34 1 1161779936 42297840 3628928
09:00:58 110122 8192000000 10114807520 10569269248 1133839168 12.158 99 13 26 2 1189096912 52582416 2675328
09:04:39 87461 8192000000 10092575184 10536103936 1118544576 12.013 98 25 51 1 1185063104 61427072 5091456
09:07:44 39289 8192000000 10153417728 10599161856 1139795104 12.214 100 47 93 0 1256016064 104824976 11395984
09:09:27 41285 8192000000 10118609072 10597036032 1151335216 12.322 100 19 38 0 1236869312 80975376 4558720
09:11:13 41850 8192000000 10087064768 10556940288 1145498496 12.267 100 13 26 1 1225653136 77002240 3152400
09:12:55 27403 8192000000 10094950368 10554662912 1132371664 12.144 99 4 8 0 1196087392 62711568 1004160
09:14:24 44438 8192000000 10140990864 10612781056 1141382080 12.229 99 17 34 1 1259348368 114296304 3669984
09:17:08 41564 8192000000 10096182784 10557198336 1144797520 12.261 100 17 34 1 1214051488 65679408 3574560
09:18:56 60080 8192000000 10117291552 10568400896 1131742896 12.138 98 21 43 4 1191724736 55677376 4304464
09:21:00 63546 8192000000 10165881232 10618703872 1135024704 12.169 98 15 31 1 1230195968 91891184 3280080
09:23:09 64926 8192000000 10088775584 10539548672 1123397312 12.059 98 9 19 0 1151709792 26326512 1985968
09:25:16 95617 8192000000 10055707344 10507149312 1110994992 11.942 97 19 39 0 1146891296 32035600 3860704
09:27:53 33902 8192000000 10063079088 10517028864 1138277440 12.199 100 16 30 2 1165868464 24511984 3079040
09:29:30 51514 8192000000 10040052848 10499870720 1097314512 11.812 97 4 8 0 1117468576 19638144 515920
09:31:22 54587 8192000000 10090146496 10568048640 1095389760 11.794 97 12 24 0 1165800528 68098416 2312352
09:33:22 26788 8192000000 10083741152 10523242496 1122890256 12.054 100 14 28 1 1174527200 48820224 2816720
09:34:51 73640 8192000000 10103672704 10569699328 1086804240 11.712 96 19 38 0 1176601520 85826288 3970992
09:37:04 57863 8192000000 10189807616 10650234880 1100560208 11.843 98 39 78 2 1196023376 87460624 8002544
09:39:09 33284 8192000000 10079367984 10508845056 1082307072 11.669 98 15 30 0 1174693728 88681344 3705312
09:40:43 15414 8192000000 10079916576 10500743168 1091650848 11.758 98 39 78 0 1134193680 34552336 7990496
09:42:02 68545 8192000000 10063836896 10629832704 1105261024 11.888 99 18 36 0 1247948352 138890704 3796624
09:45:18 55211 8192000000 10031881824 10474962944 1091344912 11.755 97 9 18 0 1115411664 22508736 1558016
09:47:19 77443 8192000000 10093070032 10568200192 1112717536 11.958 99 9 18 0 1166309616 51585440 2006640
09:50:27 67233 8192000000 10073221008 10541424640 1128750656 12.110 100 23 46 2 1183574080 49889920 4933504
09:52:54 25827 8192000000 10131988768 10562301952 1132306832 12.143 100 37 72 2 1221484912 81455888 7722192
09:54:21 47388 8192000000 10197120496 10650312704 1142060224 12.235 100 31 63 1 1281099184 131408208 7630752
09:56:09 111233 8192000000 10144917552 10741006336 1127273088 12.096 98 15 30 0 1338482160 207389360 3819712
09:59:09 82228 8192000000 10124427856 10587508736 1140563120 12.221 100 11 22 1 1193990736 51249696 2177920
10:01:32 59504 8192000000 10103118176 10540343296 1134707168 12.166 99 30 61 1 1165288256 25041216 5539872
10:04:34 71529 8192000000 10087700416 10545569792 1153652032 12.344 100 8 16 0 1187249632 32151344 1446256
10:06:46 50226 8192000000 10073485984 10555953152 1149367408 12.304 100 4 8 1 1183775408 33460736 947264
10:08:36 67388 8192000000 10118900448 10576740352 1155166096 12.358 100 15 30 0 1201135712 42944784 3024832
10:10:53 47541 8192000000 10136441712 10587738112 1137600304 12.193 98 20 40 1 1227970096 85757040 4612752
10:12:51 37983 8192000000 10139517296 10587926528 1133265312 12.152 98 32 65 1 1224889424 84732096 6892016
10:14:29 73446 8192000000 10157561648 10614308864 1129702992 12.119 98 21 42 0 1240153744 105450800 4999952
10:16:43 68171 8192000000 10097663456 10579939328 1101219440 11.849 96 26 53 0 1186369024 79420864 5728720
10:18:51 34911 8192000000 10106542480 10552139776 1119536672 12.023 98 34 67 1 1175416016 49294272 6585072
10:20:37 34744 8192000000 10050149776 10489946112 1101736768 11.854 98 25 50 0 1148729088 42233632 4758688
10:22:16 105871 8192000000 10021115264 10467663872 1090658064 11.749 97 18 36 0 1112947968 18554336 3735568
10:25:02 63788 8192000000 10030161472 10506080256 1089968800 11.742 97 7 14 0 1134376448 42585152 1822496

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 18-10-2020 10:28:33 to 18-10-2020 10:28:48.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 61 v_add_invoice_to_stock 335544517
user_exc 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 18-10-2020 10:28:49 to 18-10-2020 10:28:50.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          233653
MON$OLDEST_ACTIVE               235063
MON$OLDEST_SNAPSHOT             235063
MON$NEXT_TRANSACTION            235063
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-30 07:30:08.6030 GMT
MON$PAGES                       2124608
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             783
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2226 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2226 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2226 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2226 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
on disk structure version 13.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 ---
:171: 
:172: mscs-test-161.budzdorov.ru    Sun Oct 18 10:26:54 2020
:173:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:174: 
--- end of diff output ---

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

18.10.20 10:28:51. There are 6 logs in /var/tmp/logs-oltp40 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-18 10:28:54 No severe PSQL-related problems occured
18.10.20 10:28:55. End of report.