Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.10.2020 04: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 17086308 kB
MemAvailable 31524696 kB
Buffers 20 kB
Cached 14492568 kB
SwapCached 5424 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-14 04:26:52
DTS_END                         2020-10-14 04:26:52.2890
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(67:17)
IP                              ::1/42978
TRN_ID                          233808
ATT_ID                          672
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: 8602.61 23805 1032313
customer order: creation 167.40 420 20088
customer order: refuse 13.93 337 1671
order to supplier: creation 43.62 1355 5234
order to supplier: removal 3.32 109577 398
invoice (draft): creation 43.81 3576 5257
invoice (draft): removal 47.62 958 5714
invoice accept: apply 39.79 17220 4775
invoice accept: cancel 43.04 886 5165
customer reserve: creation 3643.60 169 437232
customer reserve: removal 4280.76 68 513691
realization accept: apply 80.31 164 9637
realization accept: cancel 72.53 112 8703
payment from customer: creation 56.59 120 6791
payment from customer: removal 5.39 92 647
payment to supplier: creation 46.98 120 5637
payment to supplier: removal 3.39 64 407
service: total inventory turnovers 3.94 3065 473
service: total monetary turnovers 5.84 527 701
service: aggregate perf. data 0.73 20773 88
service: refresh index statistics 0.03 316492 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-10-2020 04:27:41 to 14-10-2020 04:27:43.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2444
TEST_TIME 2 2648
TEST_TIME 3 3358
TEST_TIME 4 4117
TEST_TIME 5 4590
TEST_TIME 6 4775
TEST_TIME 7 4748
TEST_TIME 8 4916
TEST_TIME 9 5210
TEST_TIME 10 5402
TEST_TIME 11 5516
TEST_TIME 12 5738
TEST_TIME 13 6135
TEST_TIME 14 6488
TEST_TIME 15 7008
TEST_TIME 16 7031
TEST_TIME 17 7356
TEST_TIME 18 7632
TEST_TIME 19 7638
TEST_TIME 20 8174
TEST_TIME 21 8539
TEST_TIME 22 8567
TEST_TIME 23 9040
TEST_TIME 24 8980
TEST_TIME 25 9466
TEST_TIME 26 9523
TEST_TIME 27 10477
TEST_TIME 28 10620
TEST_TIME 29 10371
TEST_TIME 30 10134
TEST_TIME 31 10121
TEST_TIME 32 9908
TEST_TIME 33 9736
TEST_TIME 34 9643
TEST_TIME 35 9756
TEST_TIME 36 9645
TEST_TIME 37 9565
TEST_TIME 38 9371
TEST_TIME 39 9276
TEST_TIME 40 9238
TEST_TIME 41 9182
TEST_TIME 42 9146
TEST_TIME 43 9164
TEST_TIME 44 9071
TEST_TIME 45 9010
TEST_TIME 46 8981
TEST_TIME 47 9040
TEST_TIME 48 9053
TEST_TIME 49 9164
TEST_TIME 50 9190
TEST_TIME 51 9083
TEST_TIME 52 9243
TEST_TIME 53 9199
TEST_TIME 54 9047
TEST_TIME 55 8957
TEST_TIME 56 8924
TEST_TIME 57 8838
TEST_TIME 58 8850
TEST_TIME 59 8809
TEST_TIME 60 8829
TEST_TIME 61 8796
TEST_TIME 62 8758
TEST_TIME 63 8789
TEST_TIME 64 8886
TEST_TIME 65 8841
TEST_TIME 66 8906
TEST_TIME 67 8918
TEST_TIME 68 8967
TEST_TIME 69 8938
TEST_TIME 70 9082
TEST_TIME 71 9117
TEST_TIME 72 9085
TEST_TIME 73 9065
TEST_TIME 74 8955
TEST_TIME 75 8912
TEST_TIME 76 8864
TEST_TIME 77 8801
TEST_TIME 78 8787
TEST_TIME 79 8762
TEST_TIME 80 8715
TEST_TIME 81 8681
TEST_TIME 82 8659
TEST_TIME 83 8686
TEST_TIME 84 8699
TEST_TIME 85 8685
TEST_TIME 86 8666
TEST_TIME 87 8740
TEST_TIME 88 8790
TEST_TIME 89 8789
TEST_TIME 90 8811
TEST_TIME 91 8846
TEST_TIME 92 8782
TEST_TIME 93 8814
TEST_TIME 94 8741
TEST_TIME 95 8782
TEST_TIME 96 8788
TEST_TIME 97 8783
TEST_TIME 98 8786
TEST_TIME 99 8756
TEST_TIME 100 8740
TEST_TIME 101 8763
TEST_TIME 102 8726
TEST_TIME 103 8720
TEST_TIME 104 8675
TEST_TIME 105 8788
TEST_TIME 106 8786
TEST_TIME 107 8875
TEST_TIME 108 8874
TEST_TIME 109 8882
TEST_TIME 110 8843
TEST_TIME 111 8776
TEST_TIME 112 8739
TEST_TIME 113 8681
TEST_TIME 114 8649
TEST_TIME 115 8639
TEST_TIME 116 8629
TEST_TIME 117 8621
TEST_TIME 118 8642
TEST_TIME 119 8610
TEST_TIME 120 8606

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-10-2020 04:27:45 to 14-10-2020 04:27:48.

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 1437708 1437708 0 0.00 0 14003 6 0 0
doc_list_biud 1437693 1437693 0 0.00 0 87577 31 0 0
sp_add_invoice_to_stock 4883 4775 108 2.21 28 187087 17221 0 108
sp_cancel_adding_invoice 5165 5165 0 0.00 6 56820 886 0 0
sp_cancel_client_order 1671 1671 0 0.00 4 19757 338 0 0
sp_cancel_customer_reserve 513691 513691 0 0.00 1 87579 68 0 0
sp_cancel_pay_from_customer 647 647 0 0.00 3 11314 92 0 0
sp_cancel_pay_to_supplier 407 407 0 0.00 3 2618 64 0 0
sp_cancel_supplier_invoice 5723 5714 9 0.15 2 18988 959 0 9
sp_cancel_supplier_order 398 398 0 0.00 10 994837 109577 0 0
sp_cancel_write_off 8703 8703 0 0.00 1 8140 112 0 0
sp_client_order 20088 20088 0 0.00 4 36142 421 0 0
sp_customer_reserve 437232 437232 0 0.00 0 57104 169 0 0
sp_fill_shopping_cart 30575 30575 0 0.00 1 45658 54 0 0
sp_fill_shopping_cart_clo_res 437225 437225 0 0.00 0 27570 21 0 0
sp_get_clo_for_invoice 4768 4768 0 0.00 0 63608 513 0 0
sp_kill_cost_storno 14922 14922 0 0.00 0 11310 51 0 0
sp_kill_qstorno_ret_qs2qd 1042197 1042197 0 0.00 0 86698 37 0 0
sp_kill_qty_storno 549744 549744 0 0.00 0 87577 80 0 0
sp_lock_selected_doc 19956 19956 0 0.00 0 9639 7 0 0
sp_make_cost_storno 26833 26833 0 0.00 0 11596 49 0 0
sp_multiply_rows_for_pdistr 26826 26826 0 0.00 0 9034 29 0 0
sp_multiply_rows_for_qdistr 20088 20088 0 0.00 0 23074 246 0 0
sp_pay_from_customer 6791 6791 0 0.00 4 11099 120 0 0
sp_pay_to_supplier 5637 5637 0 0.00 4 12649 120 0 0
sp_payment_common 12416 12416 0 0.00 1 11096 20 0 0
sp_qd_handle_on_cancel_clo 1671 1671 0 0.00 0 19748 295 0 0
sp_qd_handle_on_invoice_upd_sts 9930 9930 0 0.00 0 29170 368 0 0
sp_qd_handle_on_reserve_upd_sts 9637 9637 0 0.00 0 20638 48 0 0
sp_reserve_write_off 9637 9637 0 0.00 7 20726 165 0 0
sp_supplier_invoice 5257 5257 0 0.00 6 90844 3576 0 0
sp_supplier_order 5234 5234 0 0.00 5 34211 1355 0 0
srv_aggregate_perf_data 88 88 0 0.00 2729 545138 20773 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1599 134667 47093 0 0
srv_find_qd_qs_mism 986987 986987 0 0.00 0 14002 6 0 0
srv_make_invnt_saldo 473 473 0 0.00 30 112407 3066 0 0
srv_make_money_saldo 701 701 0 0.00 1 17833 528 0 0
srv_recalc_idx_stat 4 4 0 0.00 115396 533751 316493 0 0
t$perf-abend:sp_add_invoice_to_stock 108 108 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 4776 4776 0 0.00 0 9730 146 0 0
t$perf-norm:sp_cancel_adding_invoice 413 413 0 0.00 0 6351 105 0 0
t$perf-norm:sp_cancel_client_order 1671 1671 0 0.00 0 7759 16 0 0
t$perf-norm:sp_cancel_customer_reserve 659 659 0 0.00 0 6928 35 0 0
t$perf-norm:sp_cancel_pay_from_customer 647 647 0 0.00 0 1086 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 407 407 0 0.00 0 4342 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 394 394 0 0.00 0 8847 32 0 0
t$perf-norm:sp_cancel_supplier_order 398 398 0 0.00 0 25063 1273 0 0
t$perf-norm:sp_cancel_write_off 966 966 0 0.00 0 1336 10 0 0
t$perf-norm:sp_client_order 20088 20088 0 0.00 0 7690 16 0 0
t$perf-norm:sp_customer_reserve 967 967 0 0.00 0 1611 6 0 0
t$perf-norm:sp_pay_from_customer 6791 6791 0 0.00 0 4260 7 0 0
t$perf-norm:sp_pay_to_supplier 5637 5637 0 0.00 0 6529 9 0 0
t$perf-norm:sp_reserve_write_off 9637 9637 0 0.00 0 5650 7 0 0
t$perf-norm:sp_supplier_invoice 5257 5257 0 0.00 0 4533 15 0 0
t$perf-norm:sp_supplier_order 5234 5234 0 0.00 0 3642 15 0 0
t$perf-norm:srv_aggregate_perf_data 88 88 0 0.00 0 186 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 100 3 0 0
t$perf-norm:srv_make_invnt_saldo 473 473 0 0.00 0 1275 7 0 0
t$perf-norm:srv_make_money_saldo 701 701 0 0.00 0 678 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 3 0 0
v_add_invoice_to_stock 9744 9528 216 2.21 0 7316 21 0 216
v_all_customers 36090 36090 0 0.00 0 35 1 0 0
v_all_suppliers 29124 29124 0 0.00 0 35 1 0 0
v_all_wares 40176 40176 0 0.00 0 79 1 0 0
v_cancel_adding_invoice 826 826 0 0.00 0 531 5 0 0
v_cancel_client_order 3342 3342 0 0.00 0 6257 10 0 0
v_cancel_customer_prepayment 1294 1294 0 0.00 0 2681 10 0 0
v_cancel_customer_reserve 1318 1318 0 0.00 0 1894 9 0 0
v_cancel_payment_to_supplier 814 814 0 0.00 0 2473 13 0 0
v_cancel_supplier_invoice 806 788 18 2.23 0 853 12 0 18
v_cancel_supplier_order 796 796 0 0.00 0 190 3 0 0
v_cancel_write_off 1932 1932 0 0.00 0 1476 13 0 0
v_max_id_clo_ord 5233 5233 0 0.00 0 857 1 0 0
v_max_id_clo_res 967 967 0 0.00 0 6298 35 0 0
v_max_id_ord_sup 5254 5254 0 0.00 0 6226 8 0 0
v_max_non_paid_invoice 1559 1559 0 0.00 0 813 2 0 0
v_max_non_paid_realizn 3731 3731 0 0.00 0 2260 4 0 0
v_min_id_clo_ord 5233 5233 0 0.00 0 5918 9 0 0
v_min_id_clo_res 967 967 0 0.00 0 9858 18 0 0
v_min_id_ord_sup 5254 5254 0 0.00 0 6812 12 0 0
v_min_non_paid_invoice 5634 5634 0 0.00 0 4079 8 0 0
v_min_non_paid_realizn 6782 6782 0 0.00 0 11093 15 0 0
v_our_firm 10188 10188 0 0.00 0 17 1 0 0
v_reserve_write_off 19274 19274 0 0.00 0 6247 9 0 0
x_lock_depdocs_on_canc_invoice 10485 10485 0 0.00 0 20169 113 0 0
x_lock_depdocs_on_canc_sup_ord 398 398 0 0.00 0 9994 343 0 0
x_make_qstorno_1000_1200 5234 5234 0 0.00 2 34192 1277 0 0
x_make_qstorno_1200_2000 5257 5257 0 0.00 3 81777 3346 0 0
x_make_qstorno_2100_3300 437232 437232 0 0.00 0 57103 146 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-10-2020 04:27:49 to 14-10-2020 04:27:53.

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
01:56:56 1 8192000000 8796624000 8807108608 3156752 0.038 1 1 2 0 3254112 61504 35856
01:57:56 16090 8192000000 9710925072 10006573056 794270128 8.838 100 86 172 0 823065088 7735824 21059136
01:59:17 23694 8192000000 9751151616 10057003008 829732912 9.197 100 83 166 0 861361376 11964720 19663744
02:00:44 7535 8192000000 9791399744 10106187776 861178160 9.512 100 89 178 0 900500016 18341568 20980288
02:01:59 11578 8192000000 9856599248 10186510336 909519152 9.993 100 81 162 0 966637840 38975920 18142768
02:03:13 6653 8192000000 9912222736 10250555392 946581344 10.358 100 80 160 0 1023940496 60895200 16463952
02:04:21 5733 8192000000 9952249984 10304565248 980731104 10.691 100 85 171 0 1058152880 59528752 17893024
02:05:32 1888 8192000000 9998925424 10361950208 1018989984 11.062 100 85 168 1 1112822992 76622768 17210240
02:06:35 1610 8192000000 10011471120 10380136448 1052805088 11.388 100 89 180 2 1126705456 55769392 18130976
02:07:37 1290 8192000000 10033199008 10404392960 1071209632 11.564 100 91 183 1 1149774224 59578496 18986096
02:08:39 4501 8192000000 10017008752 10416218112 1069949664 11.552 100 46 92 1 1128986496 49767888 9268944
02:09:45 14523 8192000000 10004676736 10409648128 1073381712 11.584 100 29 57 2 1097692016 18492256 5818048
02:11:00 8689 8192000000 9981091680 10381565952 1075285408 11.603 100 23 44 1 1091715072 10579088 5850576
02:12:12 66875 8192000000 10000773264 10406137856 1080876960 11.656 100 29 58 1 1109305712 21555392 6873360
02:14:21 4295 8192000000 9998758544 10403414016 1085306800 11.698 100 48 95 0 1111495120 14867536 11320784
02:15:29 4830 8192000000 10018015888 10409361408 1074007936 11.590 99 54 107 2 1118320656 33012704 11300016
02:16:35 7344 8192000000 9982368320 10376392704 1043239712 11.296 99 24 48 1 1084885200 37099984 4545504
02:17:46 26194 8192000000 9937910432 10324942848 971026368 10.597 92 28 56 1 1008145584 31189920 5929296
02:19:14 6917 8192000000 9941369888 10327203840 975343280 10.639 95 35 70 3 1008470864 25552496 7575088
02:20:25 1796 8192000000 9919100720 10285486080 992563632 10.806 96 37 73 2 1017200000 13902240 10734128
02:21:28 9102 8192000000 9949763536 10335551488 1020353024 11.075 99 48 92 3 1049063952 17825040 10885888
02:22:39 32247 8192000000 9964001616 10361552896 1042718256 11.291 100 19 35 2 1072704224 26373344 3612624
02:24:13 13421 8192000000 9977246688 10376794112 1059619824 11.453 100 26 52 0 1084481024 18789520 6071680
02:25:28 7257 8192000000 9996654848 10390745088 1070119872 11.553 100 32 64 1 1104311776 27324224 6867680
02:26:40 7387 8192000000 10024775440 10429493248 1095899024 11.799 100 40 80 4 1139306240 35614800 7792416
02:27:53 42154 8192000000 10041824016 10446487552 1107868320 11.912 100 34 67 2 1155490416 40392640 7229456
02:29:45 13680 8192000000 10073402960 10474332160 1120289952 12.030 100 51 102 1 1188149568 56457904 11401712
02:31:06 18569 8192000000 10112973808 10511659008 1137784176 12.195 100 56 112 1 1214507200 64952528 11770496
02:32:26 7547 8192000000 10157767600 10557759488 1149513888 12.305 100 60 116 3 1271344000 108607296 13222816
02:33:46 22182 8192000000 10208720944 10613268480 1154332192 12.350 100 58 116 1 1302741984 134955216 13454576
02:35:21 16573 8192000000 10233889392 10652024832 1172196416 12.517 100 51 102 3 1339823616 155736080 11891120
02:36:44 8138 8192000000 10297567728 10711969792 1179116688 12.582 100 67 135 2 1412732768 217984224 15631856
02:38:02 9688 8192000000 10311153968 10729775104 1164170672 12.442 100 60 120 0 1419830432 241612288 14047472
02:39:19 3283 8192000000 10345776720 10750259200 1164381696 12.444 100 64 128 2 1442802896 263672496 14748704
02:40:27 2065 8192000000 10337638928 10741751808 1148243296 12.293 99 90 178 1 1429920704 260748592 20928816
02:41:39 1598 8192000000 10361005760 10760773632 1149043600 12.301 100 84 164 2 1454183680 286246880 18893200
02:42:46 2783 8192000000 10353074464 10758422528 1150948768 12.318 100 69 137 1 1449641184 282844816 15847600
02:43:56 16904 8192000000 10379728560 10806595584 1164342656 12.444 100 44 88 1 1491530368 315472304 11715408
02:45:22 5881 8192000000 10385583824 10802212864 1131793040 12.138 98 52 101 0 1467873584 323839936 12240608
02:46:31 6413 8192000000 10298135600 10765787136 1131755456 12.138 99 36 70 0 1400343600 259827568 8760576
02:47:45 5054 8192000000 10316771504 10752921600 1146210736 12.274 100 43 86 1 1410849424 254503040 10135648
02:48:54 8301 8192000000 10324112352 10746236928 1139379792 12.210 100 31 61 1 1422368048 275125376 7862880
02:50:05 33564 8192000000 10308407264 10741145600 1134207328 12.161 99 21 42 2 1396447520 256862016 5378176
02:51:41 23148 8192000000 10272778784 10767245312 1100096912 11.839 98 13 26 0 1360314512 256726448 3491152
02:53:30 25248 8192000000 10117292320 10614890496 1094719872 11.788 98 20 38 2 1262563440 163263344 4580224
02:55:03 50269 8192000000 10050516928 10466766848 1113307712 11.964 100 26 52 0 1151118992 31745568 6065712
02:56:58 26033 8192000000 10080874096 10514296832 1109604160 11.929 99 42 84 3 1184415312 65853248 8957904
02:58:29 11351 8192000000 10064705072 10513219584 1123267440 12.058 100 12 24 0 1162708576 37419104 2022032
02:59:41 122852 8192000000 10090525552 10518560768 1068528064 11.538 95 14 28 0 1150250864 78888992 2833808
03:03:00 134666 8192000000 10052248976 10521231360 1106003552 11.895 99 25 50 0 1160300272 48123136 6173584
03:06:19 52397 8192000000 10054344704 10494861312 1123522096 12.060 100 6 12 2 1159312592 34751504 1038992
03:08:13 78964 8192000000 10063839360 10538192896 1118866032 12.016 99 20 40 2 1148573008 25892976 3814000
03:10:37 45065 8192000000 10087213552 10521305088 1140609888 12.221 100 38 76 1 1196809616 48445040 7754688
03:12:23 89666 8192000000 10150942832 10598031360 1137444272 12.191 99 24 48 1 1239680720 97247264 4989184
03:15:23 45322 8192000000 10084767296 10520285184 1132783488 12.148 99 18 36 1 1167823776 31310672 3729616
03:17:13 46396 8192000000 10121213248 10533629952 1105940272 11.894 96 37 75 2 1180582256 66878032 7763952
03:19:04 123915 8192000000 10068285680 10524995584 1120789664 12.034 99 16 32 1 1163983792 39943744 3250384
03:22:26 80592 8192000000 10075198080 10552815616 1099943904 11.837 96 15 30 0 1171061392 67699392 3418096
03:25:59 86164 8192000000 10065016560 10520817664 1090534896 11.748 96 15 30 0 1163010608 68824512 3651200
03:28:42 83035 8192000000 10111625120 10572943360 1116584432 11.995 99 16 32 2 1195373920 75441328 3348160
03:31:12 64315 8192000000 10087497408 10539352064 1089185984 11.735 96 15 30 1 1121781744 29765552 2830208
03:33:16 57169 8192000000 10075643424 10496221184 1078851120 11.637 95 32 64 0 1145977648 59820176 7306352
03:35:15 86044 8192000000 10076216064 10582540288 1099340992 11.831 97 14 28 0 1217381568 115034032 3006544
03:37:42 64142 8192000000 10026447840 10457255936 1020352496 11.075 91 18 36 2 1054780384 31368272 3059616
03:39:54 94409 8192000000 10022539120 10468569088 1088721008 11.730 98 18 36 0 1127157664 34900128 3536528
03:42:30 61654 8192000000 10046653888 10497454080 1111219568 11.944 99 12 24 2 1133987680 20147568 2620544
03:44:32 34358 8192000000 10049571056 10499031040 1097398064 11.813 98 19 38 1 1128227984 26893904 3936016
03:46:08 55198 8192000000 10050436048 10501181440 1109761936 11.930 99 14 28 0 1146208176 33789376 2656864
03:48:10 33956 8192000000 10073429456 10506207232 1108815248 11.921 99 18 36 2 1155715296 43321184 3578864
03:49:44 20879 8192000000 10044177792 10477563904 1118859344 12.016 100 46 93 3 1156544592 27661872 10023376
03:51:10 34310 8192000000 10097437936 10523746304 1113251344 11.963 99 28 55 1 1168830624 49692048 5887232
03:53:02 48304 8192000000 10105687280 10564247552 1114828368 11.978 99 18 35 1 1218779344 99833696 4117280
03:54:50 72190 8192000000 10104766080 10559627264 1105862832 11.893 98 8 16 2 1192928560 85257952 1807776
03:57:03 49502 8192000000 10153786496 10595463168 1139740976 12.213 99 24 48 2 1227636640 82959648 4936016
03:58:52 127445 8192000000 10159127840 10599067648 1135564128 12.174 99 21 42 1 1272170704 131865408 4741168
04:02:17 64352 8192000000 10067479120 10561118208 1108779664 11.921 97 8 16 0 1145028064 34723392 1525008
04:04:23 45721 8192000000 10076046832 10521272320 1120019984 12.027 98 18 36 0 1149840176 26299520 3520672
04:06:15 46644 8192000000 10109696272 10548776960 1146290320 12.275 100 23 43 2 1189645520 37929472 5425728
04:08:04 70715 8192000000 10106229872 10535534592 1113951168 11.970 97 21 40 1 1153887472 35925008 4011296
04:10:19 103650 8192000000 10090757648 10623725568 1132710672 12.147 98 26 51 2 1214573184 76169072 5693440
04:13:07 89175 8192000000 10079131232 10577338368 1091724080 11.759 96 8 15 1 1159248768 66120016 1404672
04:15:36 36275 8192000000 10085066352 10521665536 1125480144 12.079 100 32 64 0 1175501408 43828496 6192768
04:17:13 72641 8192000000 10039231728 10480697344 1073746128 11.588 95 17 34 0 1106664016 29092688 3825200
04:19:42 50604 8192000000 10051070160 10504687616 1121585632 12.042 100 6 12 0 1153856944 30895792 1375520
04:21:38 47057 8192000000 10041417008 10486050816 1106226880 11.897 99 12 24 1 1135746496 27093392 2426224
04:23:25 39656 8192000000 10070022848 10516590592 1123214288 12.057 100 17 33 2 1165494352 38630864 3649200
04:25:12 19000 8192000000 10046815648 10464387072 1089681024 11.740 98 28 56 2 1114099184 18773648 5644512
04:26:32 27929 8192000000 9156801040 9437716480 298361968 3.514 27 3 6 0 312643280 13989712 291600

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 108 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 14-10-2020 04:28:11 to 14-10-2020 04:28:13.

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          235149
MON$OLDEST_ACTIVE               235150
MON$OLDEST_SNAPSHOT             235150
MON$NEXT_TRANSACTION            235150
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                       2081184
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.2225 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2225 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2225 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.2225 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 ---
--- end of diff output ---

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

14.10.20 04:28:14. There are 3 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-14 04:28:16 No severe PSQL-related problems occured
14.10.20 04:28:18. End of report.