Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.12.2020 10:27
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 16964856 kB
MemAvailable 31391096 kB
Buffers 0 kB
Cached 14584816 kB
SwapCached 3712 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-12-15 10:26:54
DTS_END                         2020-12-15 10:26:54.2740
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(68:17)
IP                              ::1/33714
TRN_ID                          231818
ATT_ID                          578
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: 8722.05 19691 1046646
customer order: creation 165.36 399 19843
customer order: refuse 14.15 312 1698
order to supplier: creation 43.24 1231 5189
order to supplier: removal 3.16 99074 379
invoice (draft): creation 42.93 3376 5152
invoice (draft): removal 48.31 775 5797
invoice accept: apply 41.12 17207 4934
invoice accept: cancel 43.94 802 5273
customer reserve: creation 3766.48 170 451977
customer reserve: removal 4275.61 59 513073
realization accept: apply 79.22 157 9507
realization accept: cancel 77.08 108 9250
payment from customer: creation 55.81 119 6697
payment from customer: removal 5.25 71 630
payment to supplier: creation 46.35 123 5562
payment to supplier: removal 3.15 91 378
service: total inventory turnovers 3.97 2728 476
service: total monetary turnovers 5.94 415 713
service: aggregate perf. data 0.95 13591 114
service: refresh index statistics 0.03 253019 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-12-2020 10:27:55 to 15-12-2020 10:27:57.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4833
TEST_TIME 2 4704
TEST_TIME 3 3720
TEST_TIME 4 3522
TEST_TIME 5 3407
TEST_TIME 6 4282
TEST_TIME 7 4665
TEST_TIME 8 5244
TEST_TIME 9 5658
TEST_TIME 10 5992
TEST_TIME 11 5907
TEST_TIME 12 5973
TEST_TIME 13 6107
TEST_TIME 14 6735
TEST_TIME 15 7475
TEST_TIME 16 7779
TEST_TIME 17 7917
TEST_TIME 18 8106
TEST_TIME 19 8441
TEST_TIME 20 8546
TEST_TIME 21 8767
TEST_TIME 22 9642
TEST_TIME 23 10425
TEST_TIME 24 10360
TEST_TIME 25 10318
TEST_TIME 26 10073
TEST_TIME 27 9910
TEST_TIME 28 9828
TEST_TIME 29 9630
TEST_TIME 30 9613
TEST_TIME 31 9428
TEST_TIME 32 9329
TEST_TIME 33 9281
TEST_TIME 34 9174
TEST_TIME 35 9035
TEST_TIME 36 8879
TEST_TIME 37 9094
TEST_TIME 38 8964
TEST_TIME 39 8937
TEST_TIME 40 8886
TEST_TIME 41 8938
TEST_TIME 42 9040
TEST_TIME 43 9025
TEST_TIME 44 9038
TEST_TIME 45 8999
TEST_TIME 46 9167
TEST_TIME 47 9140
TEST_TIME 48 9235
TEST_TIME 49 9199
TEST_TIME 50 9438
TEST_TIME 51 9368
TEST_TIME 52 9203
TEST_TIME 53 9080
TEST_TIME 54 9151
TEST_TIME 55 9035
TEST_TIME 56 8982
TEST_TIME 57 8898
TEST_TIME 58 8970
TEST_TIME 59 8965
TEST_TIME 60 8953
TEST_TIME 61 9022
TEST_TIME 62 8993
TEST_TIME 63 9018
TEST_TIME 64 9147
TEST_TIME 65 9125
TEST_TIME 66 9165
TEST_TIME 67 9338
TEST_TIME 68 9314
TEST_TIME 69 9294
TEST_TIME 70 9269
TEST_TIME 71 9238
TEST_TIME 72 9162
TEST_TIME 73 9100
TEST_TIME 74 9031
TEST_TIME 75 8952
TEST_TIME 76 8933
TEST_TIME 77 8864
TEST_TIME 78 8892
TEST_TIME 79 8856
TEST_TIME 80 8821
TEST_TIME 81 8836
TEST_TIME 82 8881
TEST_TIME 83 8943
TEST_TIME 84 8916
TEST_TIME 85 8885
TEST_TIME 86 8967
TEST_TIME 87 9011
TEST_TIME 88 9075
TEST_TIME 89 9107
TEST_TIME 90 9050
TEST_TIME 91 8988
TEST_TIME 92 8951
TEST_TIME 93 8867
TEST_TIME 94 8902
TEST_TIME 95 8860
TEST_TIME 96 8897
TEST_TIME 97 8892
TEST_TIME 98 8878
TEST_TIME 99 8857
TEST_TIME 100 8891
TEST_TIME 101 8916
TEST_TIME 102 8897
TEST_TIME 103 8862
TEST_TIME 104 9022
TEST_TIME 105 8996
TEST_TIME 106 9007
TEST_TIME 107 8993
TEST_TIME 108 8997
TEST_TIME 109 8941
TEST_TIME 110 8905
TEST_TIME 111 8862
TEST_TIME 112 8819
TEST_TIME 113 8794
TEST_TIME 114 8767
TEST_TIME 115 8758
TEST_TIME 116 8723
TEST_TIME 117 8693
TEST_TIME 118 8720
TEST_TIME 119 8748
TEST_TIME 120 8728

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 15-12-2020 10:27:59 to 15-12-2020 10:28:02.

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 1464918 1464918 0 0.00 0 19867 6 0 0
doc_list_biud 1464917 1464917 0 0.00 0 30238 26 0 0
sp_add_invoice_to_stock 5074 4934 140 2.75 40 230376 17208 0 140
sp_cancel_adding_invoice 5273 5273 0 0.00 6 81160 803 0 0
sp_cancel_client_order 1698 1698 0 0.00 5 11409 312 0 0
sp_cancel_customer_reserve 513073 513073 0 0.00 1 30240 59 0 0
sp_cancel_pay_from_customer 630 630 0 0.00 2 4777 71 0 0
sp_cancel_pay_to_supplier 378 378 0 0.00 3 5651 91 0 0
sp_cancel_supplier_invoice 5807 5797 10 0.17 3 14182 775 0 10
sp_cancel_supplier_order 379 379 0 0.00 8 883471 99075 0 0
sp_cancel_write_off 9250 9250 0 0.00 1 14664 109 0 0
sp_client_order 19843 19843 0 0.00 4 63208 399 0 0
sp_customer_reserve 451977 451977 0 0.00 0 100099 171 0 0
sp_fill_shopping_cart 30177 30177 0 0.00 1 18985 44 0 0
sp_fill_shopping_cart_clo_res 451970 451970 0 0.00 0 25541 20 0 0
sp_get_clo_for_invoice 4927 4927 0 0.00 0 57372 532 0 0
sp_kill_cost_storno 15531 15531 0 0.00 0 14581 50 0 0
sp_kill_qstorno_ret_qs2qd 1041572 1041572 0 0.00 0 26564 32 0 0
sp_kill_qty_storno 549903 549903 0 0.00 0 30237 69 0 0
sp_lock_selected_doc 19898 19898 0 0.00 0 6102 4 0 0
sp_make_cost_storno 26693 26693 0 0.00 0 13184 51 0 0
sp_multiply_rows_for_pdistr 26692 26692 0 0.00 0 15687 31 0 0
sp_multiply_rows_for_qdistr 19843 19843 0 0.00 0 63197 247 0 0
sp_pay_from_customer 6697 6697 0 0.00 4 15981 119 0 0
sp_pay_to_supplier 5562 5562 0 0.00 5 14721 124 0 0
sp_payment_common 12259 12259 0 0.00 1 14425 30 0 0
sp_qd_handle_on_cancel_clo 1698 1698 0 0.00 0 11403 272 0 0
sp_qd_handle_on_invoice_upd_sts 10199 10199 0 0.00 0 24158 304 0 0
sp_qd_handle_on_reserve_upd_sts 9507 9507 0 0.00 0 11773 43 0 0
sp_reserve_write_off 9507 9507 0 0.00 7 15001 158 0 0
sp_supplier_invoice 5152 5152 0 0.00 7 87245 3376 0 0
sp_supplier_order 5189 5189 0 0.00 5 60010 1231 0 0
srv_aggregate_perf_data 115 114 1 0.86 2753 136392 13592 0 1
srv_fill_mon_cache_memory 62 62 0 0.00 1691 130023 49800 0 0
srv_find_qd_qs_mism 1000781 1000781 0 0.00 0 19866 6 0 0
srv_make_invnt_saldo 476 476 0 0.00 39 92011 2728 0 0
srv_make_money_saldo 713 713 0 0.00 1 21445 415 0 0
srv_recalc_idx_stat 4 4 0 0.00 100374 460705 253019 0 0
t$perf-abend:sp_add_invoice_to_stock 140 140 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4935 4935 0 0.00 0 19360 148 0 0
t$perf-norm:sp_cancel_adding_invoice 398 398 0 0.00 0 2272 73 0 0
t$perf-norm:sp_cancel_client_order 1698 1698 0 0.00 0 4765 14 0 0
t$perf-norm:sp_cancel_customer_reserve 672 672 0 0.00 0 4128 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 630 630 0 0.00 0 3403 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 378 378 0 0.00 0 612 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 374 374 0 0.00 0 974 18 0 0
t$perf-norm:sp_cancel_supplier_order 379 379 0 0.00 0 17096 1191 0 0
t$perf-norm:sp_cancel_write_off 938 938 0 0.00 0 945 4 0 0
t$perf-norm:sp_client_order 19844 19844 0 0.00 0 18970 16 0 0
t$perf-norm:sp_customer_reserve 991 991 0 0.00 0 2318 7 0 0
t$perf-norm:sp_pay_from_customer 6697 6697 0 0.00 0 6536 9 0 0
t$perf-norm:sp_pay_to_supplier 5562 5562 0 0.00 0 4842 7 0 0
t$perf-norm:sp_reserve_write_off 9507 9507 0 0.00 0 5961 7 0 0
t$perf-norm:sp_supplier_invoice 5152 5152 0 0.00 0 4317 11 0 0
t$perf-norm:sp_supplier_order 5189 5189 0 0.00 0 1323 9 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 237 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 109 3 0 0
t$perf-norm:srv_make_invnt_saldo 476 476 0 0.00 0 2739 12 0 0
t$perf-norm:srv_make_money_saldo 713 713 0 0.00 0 2653 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 2 0 0
v_add_invoice_to_stock 10128 9848 280 2.76 0 6733 16 0 280
v_all_customers 36098 36098 0 0.00 0 17 1 0 0
v_all_suppliers 28874 28874 0 0.00 0 18 1 0 0
v_all_wares 39684 39684 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 796 796 0 0.00 0 93 5 0 0
v_cancel_client_order 3396 3396 0 0.00 0 2899 6 0 0
v_cancel_customer_prepayment 1260 1260 0 0.00 0 2138 6 0 0
v_cancel_customer_reserve 1344 1344 0 0.00 0 733 3 0 0
v_cancel_payment_to_supplier 756 756 0 0.00 0 2897 9 0 0
v_cancel_supplier_invoice 768 748 20 2.60 0 1163 15 0 20
v_cancel_supplier_order 758 758 0 0.00 0 1745 5 0 0
v_cancel_write_off 1876 1876 0 0.00 0 2601 19 0 0
v_max_id_clo_ord 5187 5187 0 0.00 0 4893 2 0 0
v_max_id_clo_res 991 991 0 0.00 0 6528 33 0 0
v_max_id_ord_sup 5146 5146 0 0.00 0 5526 9 0 0
v_max_non_paid_invoice 1458 1458 0 0.00 0 542 2 0 0
v_max_non_paid_realizn 3565 3565 0 0.00 0 2173 3 0 0
v_min_id_clo_ord 5187 5187 0 0.00 0 8670 7 0 0
v_min_id_clo_res 991 991 0 0.00 0 4125 22 0 0
v_min_id_ord_sup 5146 5146 0 0.00 0 11597 14 0 0
v_min_non_paid_invoice 5562 5562 0 0.00 0 14414 18 0 0
v_min_non_paid_realizn 6697 6697 0 0.00 0 13254 24 0 0
v_our_firm 9850 9850 0 0.00 0 24 1 0 0
v_reserve_write_off 19014 19014 0 0.00 0 2904 5 0 0
x_lock_depdocs_on_canc_invoice 10696 10696 0 0.00 0 13455 103 0 0
x_lock_depdocs_on_canc_sup_ord 379 379 0 0.00 0 23705 314 0 0
x_make_qstorno_1000_1200 5189 5189 0 0.00 2 53428 1160 0 0
x_make_qstorno_1200_2000 5152 5152 0 0.00 3 86718 3199 0 0
x_make_qstorno_2100_3300 451976 451976 0 0.00 0 100059 149 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-12-2020 10:28:03 to 15-12-2020 10:28:07.

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:58 5 8192000000 8796641776 8807174144 3168704 0.038 1 1 2 0 3266400 61840 35856
07:57:59 34183 8192000000 9731690640 10030301184 806236880 8.959 100 91 182 1 836213376 7826864 22149632
07:59:41 21838 8192000000 9765248304 10070999040 836182160 9.261 100 93 186 0 872491104 13835760 22473184
08:01:19 19522 8192000000 9823373552 10147102720 873566848 9.636 100 81 162 1 919029808 26718992 18743968
08:02:41 9696 8192000000 9865550096 10200166400 921829024 10.114 100 79 158 0 977860944 39475568 16556352
08:03:53 7957 8192000000 9948316864 10291388416 960160880 10.491 100 93 186 0 1062617888 83061072 19395936
08:05:05 4575 8192000000 9955564400 10310496256 992831808 10.809 100 83 164 1 1070072752 60835904 16405040
08:06:10 3164 8192000000 9994148816 10355585024 1025139664 11.122 100 79 160 1 1111225920 69997760 16088496
08:07:17 2186 8192000000 10023790336 10389458944 1046116480 11.323 100 91 182 0 1134288016 69039904 19131632
08:08:20 5903 8192000000 10037675184 10412822528 1063887504 11.494 100 74 147 2 1147569648 68150864 15531280
08:09:26 9486 8192000000 10008984592 10406825984 1069780064 11.550 100 43 86 2 1125639808 46625632 9234112
08:10:37 6134 8192000000 9994260112 10390085632 1080201248 11.649 100 42 85 0 1107026848 16863040 9962560
08:11:44 6428 8192000000 10002393072 10399662080 1091442144 11.756 100 43 86 2 1116905552 16114560 9348848
08:12:55 6613 8192000000 10007768880 10408226816 1099484128 11.833 100 23 46 1 1131019072 26168976 5365968
08:14:02 32707 8192000000 9996516384 10405679104 1099428640 11.832 100 39 77 1 1130217216 22551840 8236736
08:15:35 5532 8192000000 10021778112 10422747136 1102943552 11.866 100 36 72 0 1136437232 25391152 8102528
08:16:42 8213 8192000000 10013785104 10411663360 1090140096 11.744 99 39 77 3 1118647392 19108064 9399232
08:17:52 37946 8192000000 9972124048 10369884160 1021702992 11.088 95 18 36 0 1039451072 13671680 4076400
08:19:40 8404 8192000000 9974881872 10361737216 1022311696 11.094 97 41 82 4 1053061504 21593552 9156256
08:20:50 7326 8192000000 9912781920 10303361024 976166704 10.647 95 35 70 5 1008140080 23348288 8625088
08:21:58 13191 8192000000 9951553104 10354540544 1035904928 11.225 100 34 68 0 1066000320 21396192 8699200
08:23:11 48320 8192000000 9951526384 10354114560 1038027184 11.246 99 27 54 0 1065438912 21132688 6279040
08:25:03 12065 8192000000 9976130704 10385805312 1065315504 11.507 100 30 60 2 1090520768 18887760 6317504
08:26:15 5680 8192000000 9993102960 10396725248 1083346800 11.679 100 26 51 4 1107518816 18884176 5287840
08:27:27 23604 8192000000 10031441616 10432659456 1097368176 11.813 100 53 105 3 1137822000 29583568 10870256
08:28:59 130023 8192000000 10099913888 10518720512 1121809760 12.044 100 31 62 2 1200433760 71681936 6942064
08:32:16 13335 8192000000 10158900672 10567876608 1138608464 12.202 100 49 98 1 1254539968 105232384 10699120
08:33:40 14180 8192000000 10196367008 10613444608 1156131696 12.367 100 62 115 0 1306030576 137306752 12592128
08:35:03 2122 8192000000 10230568848 10641846272 1168247776 12.480 100 62 124 0 1332616096 150869008 13499312
08:36:18 23477 8192000000 10232694880 10666188800 1183487280 12.623 100 53 105 3 1343804336 148345968 11971088
08:37:49 11002 8192000000 10259522560 10670350336 1162091072 12.423 99 60 120 1 1348826784 172662720 14072992
08:39:06 1690 8192000000 10326574688 10733670400 1182782432 12.616 100 79 155 1 1428370160 227906464 17681264
08:40:11 2354 8192000000 10361457904 10775089152 1156205456 12.368 98 84 164 0 1447508832 271969904 19333472
08:41:16 1794 8192000000 10349889824 10755334144 1166862896 12.467 100 85 168 4 1447936480 261857712 19215872
08:42:21 2291 8192000000 10327815632 10738339840 1168453984 12.482 100 69 138 1 1427591728 242872624 16265120
08:43:25 16558 8192000000 10315140128 10755297280 1151923760 12.328 100 36 72 1 1434205776 272894144 9387872
08:44:47 11286 8192000000 10319925104 10737790976 1142235856 12.237 100 33 66 3 1420834416 270351184 8247376
08:46:03 19497 8192000000 10304216144 10772250624 1085742384 11.702 96 25 50 1 1362238672 270039488 6456800
08:47:36 19430 8192000000 10276936816 10697060352 1097933280 11.818 99 24 48 1 1372678560 268150800 6594480
08:48:59 50954 8192000000 10073768352 10513993728 1078679328 11.635 98 12 24 0 1237203552 155160528 3363696
08:50:51 26448 8192000000 10063412848 10505494528 1099724272 11.835 100 31 61 4 1162036544 55793152 6519120
08:52:19 23625 8192000000 10013763920 10429403136 1059526416 11.452 96 33 65 0 1091684208 25030048 7127744
08:53:50 66148 8192000000 10025610864 10485129216 1073475280 11.585 98 16 32 1 1133653536 56831040 3347216
08:55:57 51622 8192000000 10058371392 10516750336 1099534592 11.833 100 15 30 2 1153372976 51022720 2815664
08:57:49 92466 8192000000 10059894560 10515730432 1097236384 11.811 99 22 45 1 1163282704 61211856 4834464
09:00:32 31332 8192000000 10025274560 10460016640 1090132336 11.744 99 18 36 2 1121093104 27068416 3892352
09:02:05 66612 8192000000 10093756512 10561855488 1092392944 11.765 99 17 34 0 1194504384 98609440 3502000
09:04:46 38850 8192000000 10062600256 10500448256 1078654832 11.635 97 14 28 1 1137782016 55875056 3252128
09:06:34 61286 8192000000 10069302384 10534035456 1099620704 11.834 99 17 34 1 1188221696 84642832 3958160
09:08:36 39922 8192000000 10074423936 10508476416 1118906992 12.017 100 32 65 3 1180531920 55285552 6339376
09:10:20 41729 8192000000 10049420304 10483441664 1117724016 12.005 99 23 46 2 1156686128 34314704 4647408
09:12:03 76877 8192000000 10070125904 10512130048 1129193648 12.114 100 20 40 2 1192598608 59426368 3978592
09:14:25 47298 8192000000 10099135072 10553118720 1140076288 12.216 100 19 38 0 1203694432 59892768 3725376
09:16:13 74658 8192000000 10089126960 10569555968 1141677040 12.231 100 12 24 0 1248286192 103350176 3258976
09:18:36 67045 8192000000 10116362832 10555924480 1153975040 12.347 100 27 54 0 1203671584 44200608 5495936
09:20:44 43307 8192000000 10096960448 10592927744 1141386416 12.229 99 14 28 0 1192897024 48037920 3472688
09:22:27 92622 8192000000 10141968656 10627330048 1144232336 12.255 99 13 26 0 1240027088 92882592 2912160
09:25:00 62381 8192000000 10095860224 10541580288 1134836704 12.167 98 13 26 0 1195882000 57694048 3351248
09:27:03 53137 8192000000 10074555888 10551611392 1124171280 12.066 98 13 26 1 1170694688 44054736 2468672
09:29:01 103085 8192000000 10072906976 10582937600 1088357856 11.727 95 13 26 1 1149099680 58086000 2655824
09:31:52 54292 8192000000 10159466048 10621546496 1133946272 12.159 99 24 48 0 1198684112 59893264 4844576
09:33:56 87273 8192000000 10070381632 10505248768 1084892048 11.694 96 5 10 0 1136288992 50525552 871392
09:36:24 20791 8192000000 10076836128 10500886528 1093048944 11.772 98 56 111 0 1154825616 51107088 10669584
09:37:44 92055 8192000000 10026547344 10466242560 1063832368 11.493 96 29 58 0 1121041632 51348240 5861024
09:40:32 33414 8192000000 10031106128 10470596608 1079985584 11.647 98 13 24 1 1112533904 29970048 2578272
09:42:06 71979 8192000000 10035406224 10504712192 1088910384 11.732 99 7 14 0 1120453008 29928240 1614384
09:44:25 57655 8192000000 10037686688 10469863424 1071619392 11.568 97 11 22 0 1114453936 40821088 2013456
09:46:36 75933 8192000000 10140295728 10605391872 1098528064 11.824 98 22 45 2 1200050592 96963040 4559488
09:49:20 61903 8192000000 10065504272 10497380352 1070785856 11.560 96 10 20 0 1098115024 25174960 2154208
09:51:23 119836 8192000000 10071738208 10577608704 1061707760 11.473 95 11 22 0 1144277904 80253648 2316496
09:54:24 79375 8192000000 10058039280 10566459392 1100470704 11.842 99 9 18 1 1172287040 70101920 1714416
09:56:54 101479 8192000000 10071739408 10534260736 1125439376 12.078 100 33 66 0 1157906832 25652720 6814736
09:59:41 36119 8192000000 10112391376 10588827648 1126906784 12.092 99 7 14 0 1208922848 80560416 1455648
10:01:29 72970 8192000000 10070153216 10522804224 1127310176 12.096 99 12 24 0 1163792976 34492416 1990384
10:03:43 45729 8192000000 10105437744 10554159104 1151082832 12.320 100 28 56 2 1193893504 36997632 5813040
10:06:10 66328 8192000000 10138786848 10604761088 1136099152 12.179 98 20 40 1 1215799216 75279632 4420432
10:08:16 50115 8192000000 10162232736 10597937152 1164272688 12.443 100 25 50 2 1225113600 55289424 5551488
10:10:17 62045 8192000000 10120416032 10651746304 1157324176 12.378 100 12 24 1 1256687536 96865888 2497472
10:12:29 39994 8192000000 10116050816 10537295872 1150878544 12.318 99 31 56 2 1188069328 31534720 5656064
10:14:12 8034 8192000000 10147985632 10569973760 1155791280 12.364 99 54 108 2 1223066240 56799888 10475072
10:15:23 86565 8192000000 10076451792 10534920192 1137170848 12.189 98 14 28 0 1175574000 35481648 2921504
10:17:58 53066 8192000000 10073348576 10529423360 1132186880 12.142 98 7 15 1 1154733472 21381504 1165088
10:20:07 67800 8192000000 10072822352 10547175424 1132083520 12.141 98 13 26 1 1173022240 38112144 2826576
10:22:28 36887 8192000000 10102215776 10544910336 1124734928 12.072 98 18 36 1 1169583664 41096912 3751824
10:24:06 50654 8192000000 10106426064 10553622528 1114459904 11.975 97 30 59 2 1202700640 82204352 6036384
10:26:00 51197 8192000000 10114619392 10553868288 1111235408 11.944 98 18 37 0 1164232976 49352464 3645104

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 15-12-2020 10:28:09 to 15-12-2020 10:28:24.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 140 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-12-2020 10:28:25 to 15-12-2020 10:28:27.

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          232130
MON$OLDEST_ACTIVE               232131
MON$OLDEST_SNAPSHOT             232131
MON$NEXT_TRANSACTION            232131
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                       2075936
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             789
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2296 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2296 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2296 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.2296 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'

15.12.20 10:28:28. 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-12-15 10:28:30 No severe PSQL-related problems occured
15.12.20 10:28:32. End of report.