Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.11.2020 13:28
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 16747908 kB
MemAvailable 31475956 kB
Buffers 0 kB
Cached 14940976 kB
SwapCached 2832 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-11-08 13:27:01
DTS_END                         2020-11-08 13:26:54.2820
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/40848
TRN_ID                          237319
ATT_ID                          259
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: 8837.24 27636 1060469
customer order: creation 163.18 399 19581
customer order: refuse 14.52 285 1742
order to supplier: creation 43.82 1445 5258
order to supplier: removal 3.38 110231 406
invoice (draft): creation 44.64 4056 5357
invoice (draft): removal 50.48 933 6058
invoice accept: apply 40.15 17886 4818
invoice accept: cancel 45.55 874 5466
customer reserve: creation 3702.78 174 444334
customer reserve: removal 4446.41 66 533569
realization accept: apply 81.06 174 9727
realization accept: cancel 76.61 128 9193
payment from customer: creation 57.48 105 6897
payment from customer: removal 5.69 87 683
payment to supplier: creation 47.57 123 5708
payment to supplier: removal 3.47 72 416
service: total inventory turnovers 3.71 3492 445
service: total monetary turnovers 5.86 531 703
service: aggregate perf. data 0.87 15976 104
service: refresh index statistics 0.03 395686 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-11-2020 13:28:37 to 08-11-2020 13:28:39.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2719
TEST_TIME 2 4109
TEST_TIME 3 4165
TEST_TIME 4 4937
TEST_TIME 5 4941
TEST_TIME 6 4805
TEST_TIME 7 5132
TEST_TIME 8 5176
TEST_TIME 9 5639
TEST_TIME 10 5794
TEST_TIME 11 6110
TEST_TIME 12 6202
TEST_TIME 13 6638
TEST_TIME 14 6955
TEST_TIME 15 7154
TEST_TIME 16 7302
TEST_TIME 17 7384
TEST_TIME 18 7625
TEST_TIME 19 8178
TEST_TIME 20 8368
TEST_TIME 21 8541
TEST_TIME 22 8800
TEST_TIME 23 8876
TEST_TIME 24 9062
TEST_TIME 25 9095
TEST_TIME 26 9355
TEST_TIME 27 10267
TEST_TIME 28 11191
TEST_TIME 29 11280
TEST_TIME 30 11253
TEST_TIME 31 10966
TEST_TIME 32 10760
TEST_TIME 33 10784
TEST_TIME 34 10484
TEST_TIME 35 10461
TEST_TIME 36 10313
TEST_TIME 37 10202
TEST_TIME 38 10012
TEST_TIME 39 9856
TEST_TIME 40 9814
TEST_TIME 41 9753
TEST_TIME 42 9820
TEST_TIME 43 9748
TEST_TIME 44 9622
TEST_TIME 45 9487
TEST_TIME 46 9394
TEST_TIME 47 9493
TEST_TIME 48 9454
TEST_TIME 49 9406
TEST_TIME 50 9321
TEST_TIME 51 9462
TEST_TIME 52 9415
TEST_TIME 53 9370
TEST_TIME 54 9290
TEST_TIME 55 9328
TEST_TIME 56 9501
TEST_TIME 57 9555
TEST_TIME 58 9550
TEST_TIME 59 9445
TEST_TIME 60 9675
TEST_TIME 61 9572
TEST_TIME 62 9428
TEST_TIME 63 9347
TEST_TIME 64 9310
TEST_TIME 65 9222
TEST_TIME 66 9246
TEST_TIME 67 9191
TEST_TIME 68 9181
TEST_TIME 69 9108
TEST_TIME 70 9112
TEST_TIME 71 9152
TEST_TIME 72 9249
TEST_TIME 73 9229
TEST_TIME 74 9254
TEST_TIME 75 9281
TEST_TIME 76 9306
TEST_TIME 77 9338
TEST_TIME 78 9395
TEST_TIME 79 9370
TEST_TIME 80 9382
TEST_TIME 81 9318
TEST_TIME 82 9250
TEST_TIME 83 9204
TEST_TIME 84 9134
TEST_TIME 85 9070
TEST_TIME 86 9080
TEST_TIME 87 9050
TEST_TIME 88 9064
TEST_TIME 89 9032
TEST_TIME 90 9045
TEST_TIME 91 9137
TEST_TIME 92 9097
TEST_TIME 93 9130
TEST_TIME 94 9114
TEST_TIME 95 9146
TEST_TIME 96 9099
TEST_TIME 97 9215
TEST_TIME 98 9169
TEST_TIME 99 9094
TEST_TIME 100 9056
TEST_TIME 101 9034
TEST_TIME 102 8976
TEST_TIME 103 8961
TEST_TIME 104 8952
TEST_TIME 105 8930
TEST_TIME 106 8906
TEST_TIME 107 8892
TEST_TIME 108 8908
TEST_TIME 109 8939
TEST_TIME 110 8994
TEST_TIME 111 8949
TEST_TIME 112 8937
TEST_TIME 113 9042
TEST_TIME 114 9014
TEST_TIME 115 9008
TEST_TIME 116 9027
TEST_TIME 117 8972
TEST_TIME 118 8930
TEST_TIME 119 8885
TEST_TIME 120 8845

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 08-11-2020 13:28:41 to 08-11-2020 13:28:44.

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 1471513 1471513 0 0.00 0 36495 7 0 0
doc_list_biud 1471501 1471501 0 0.00 0 39665 31 0 0
sp_add_invoice_to_stock 4949 4818 131 2.64 36 191331 17886 0 131
sp_cancel_adding_invoice 5466 5466 0 0.00 4 74943 874 0 0
sp_cancel_client_order 1742 1742 0 0.00 4 7925 286 0 0
sp_cancel_customer_reserve 533569 533569 0 0.00 1 39667 66 0 0
sp_cancel_pay_from_customer 683 683 0 0.00 3 8333 88 0 0
sp_cancel_pay_to_supplier 416 416 0 0.00 3 6167 73 0 0
sp_cancel_supplier_invoice 6070 6058 12 0.19 2 31699 933 0 12
sp_cancel_supplier_order 406 406 0 0.00 9 1153832 110231 0 0
sp_cancel_write_off 9193 9193 0 0.00 1 10359 128 0 0
sp_client_order 19581 19581 0 0.00 3 87858 400 0 0
sp_customer_reserve 444334 444334 0 0.00 0 45404 175 0 0
sp_fill_shopping_cart 30189 30189 0 0.00 1 21547 51 0 0
sp_fill_shopping_cart_clo_res 444318 444318 0 0.00 0 21894 17 0 0
sp_get_clo_for_invoice 4802 4802 0 0.00 0 32687 396 0 0
sp_kill_cost_storno 15758 15758 0 0.00 0 10355 54 0 0
sp_kill_qstorno_ret_qs2qd 1082795 1082795 0 0.00 0 39659 36 0 0
sp_kill_qty_storno 570961 570961 0 0.00 0 39664 78 0 0
sp_lock_selected_doc 20179 20179 0 0.00 0 8324 5 0 0
sp_make_cost_storno 27134 27134 0 0.00 0 15568 54 0 0
sp_multiply_rows_for_pdistr 27133 27133 0 0.00 0 10569 30 0 0
sp_multiply_rows_for_qdistr 19581 19581 0 0.00 0 87814 234 0 0
sp_pay_from_customer 6897 6897 0 0.00 4 16217 105 0 0
sp_pay_to_supplier 5708 5708 0 0.00 5 11736 123 0 0
sp_payment_common 12603 12603 0 0.00 1 11466 20 0 0
sp_qd_handle_on_cancel_clo 1742 1742 0 0.00 0 4892 259 0 0
sp_qd_handle_on_invoice_upd_sts 10266 10266 0 0.00 0 21480 394 0 0
sp_qd_handle_on_reserve_upd_sts 9727 9727 0 0.00 0 10321 51 0 0
sp_reserve_write_off 9727 9727 0 0.00 6 16042 175 0 0
sp_supplier_invoice 5357 5357 0 0.00 8 105192 4057 0 0
sp_supplier_order 5258 5258 0 0.00 6 60085 1446 0 0
srv_aggregate_perf_data 104 104 0 0.00 2786 135475 15977 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1109 131460 46145 0 0
srv_find_qd_qs_mism 1014391 1014391 0 0.00 0 36495 6 0 0
srv_make_invnt_saldo 445 445 0 0.00 54 102263 3493 0 0
srv_make_money_saldo 703 703 0 0.00 0 19944 532 0 0
srv_recalc_idx_stat 4 4 0 0.00 252886 515608 395687 0 0
t$perf-abend:sp_add_invoice_to_stock 131 131 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4818 4818 0 0.00 0 10478 169 0 0
t$perf-norm:sp_cancel_adding_invoice 393 393 0 0.00 1 4659 90 0 0
t$perf-norm:sp_cancel_client_order 1742 1742 0 0.00 0 1187 8 0 0
t$perf-norm:sp_cancel_customer_reserve 644 644 0 0.00 0 1204 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 683 683 0 0.00 0 912 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 416 416 0 0.00 0 275 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 394 394 0 0.00 0 669 14 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 54086 1510 0 0
t$perf-norm:sp_cancel_write_off 974 974 0 0.00 0 541 4 0 0
t$perf-norm:sp_client_order 19581 19581 0 0.00 0 12622 17 0 0
t$perf-norm:sp_customer_reserve 1000 1000 0 0.00 0 915 5 0 0
t$perf-norm:sp_pay_from_customer 6897 6897 0 0.00 0 4327 11 0 0
t$perf-norm:sp_pay_to_supplier 5708 5708 0 0.00 0 3430 8 0 0
t$perf-norm:sp_reserve_write_off 9727 9727 0 0.00 0 9463 11 0 0
t$perf-norm:sp_supplier_invoice 5357 5357 0 0.00 0 9318 23 0 0
t$perf-norm:sp_supplier_order 5258 5258 0 0.00 0 4089 17 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 328 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 55 2 0 0
t$perf-norm:srv_make_invnt_saldo 445 445 0 0.00 0 595 8 0 0
t$perf-norm:srv_make_money_saldo 703 703 0 0.00 0 1706 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 132 35 0 0
v_add_invoice_to_stock 9860 9598 262 2.65 0 4912 17 0 262
v_all_customers 35884 35884 0 0.00 0 219 1 0 0
v_all_suppliers 29508 29508 0 0.00 0 28 1 0 0
v_all_wares 39160 39160 0 0.00 0 59 1 0 0
v_cancel_adding_invoice 786 786 0 0.00 0 1638 9 0 0
v_cancel_client_order 3484 3484 0 0.00 0 1446 4 0 0
v_cancel_customer_prepayment 1366 1366 0 0.00 0 1948 7 0 0
v_cancel_customer_reserve 1288 1288 0 0.00 0 1755 6 0 0
v_cancel_payment_to_supplier 832 832 0 0.00 0 2175 7 0 0
v_cancel_supplier_invoice 812 788 24 2.95 0 497 13 0 24
v_cancel_supplier_order 812 812 0 0.00 0 1772 7 0 0
v_cancel_write_off 1948 1948 0 0.00 0 5006 18 0 0
v_max_id_clo_ord 5254 5254 0 0.00 0 3594 3 0 0
v_max_id_clo_res 1000 1000 0 0.00 0 1546 11 0 0
v_max_id_ord_sup 5355 5355 0 0.00 0 7890 6 0 0
v_max_non_paid_invoice 1562 1562 0 0.00 0 128 2 0 0
v_max_non_paid_realizn 3684 3684 0 0.00 0 4194 4 0 0
v_min_id_clo_ord 5254 5254 0 0.00 0 4285 5 0 0
v_min_id_clo_res 1000 1000 0 0.00 0 2487 7 0 0
v_min_id_ord_sup 5355 5355 0 0.00 0 6598 5 0 0
v_min_non_paid_invoice 5707 5707 0 0.00 0 11463 16 0 0
v_min_non_paid_realizn 6896 6896 0 0.00 0 6758 12 0 0
v_our_firm 9700 9700 0 0.00 0 18 1 0 0
v_reserve_write_off 19454 19454 0 0.00 0 2563 4 0 0
x_lock_depdocs_on_canc_invoice 11130 11130 0 0.00 0 13048 109 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 20662 400 0 0
x_make_qstorno_1000_1200 5258 5258 0 0.00 1 59987 1361 0 0
x_make_qstorno_1200_2000 5357 5357 0 0.00 5 104925 3862 0 0
x_make_qstorno_2100_3300 444334 444334 0 0.00 0 45403 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-11-2020 13:28:45 to 08-11-2020 13:28:49.

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
10:56:57 1 8192000000 8796633152 8807174144 3163616 0.038 1 1 2 0 3261312 61840 35856
10:57:58 29770 8192000000 9711637600 10007527424 788468400 8.779 100 85 169 2 817295840 7948672 20878768
10:59:33 25575 8192000000 9761584432 10065289216 829187600 9.191 100 88 176 1 863384672 13353584 20843488
11:01:03 20947 8192000000 9801038832 10117230592 860338912 9.504 100 84 168 1 902733568 23097312 19297344
11:02:26 13947 8192000000 9871903344 10202468352 908935440 9.987 100 79 157 1 977718016 51896784 16885792
11:03:42 9777 8192000000 9906436624 10247606272 943202752 10.324 100 71 141 2 1018647648 60204064 15240832
11:04:58 6462 8192000000 9965738496 10316275712 980438064 10.688 100 87 169 2 1071657072 73997776 17221232
11:06:06 3386 8192000000 9988364144 10348834816 1013411200 11.008 100 91 182 0 1104095776 71829120 18855456
11:07:11 1979 8192000000 10017001888 10387365888 1043279200 11.296 100 83 165 0 1127280784 66611280 17390304
11:08:14 2123 8192000000 10038079728 10410762240 1059802592 11.455 100 76 148 3 1142121968 67610128 14709248
11:09:16 43774 8192000000 10002161184 10384130048 1069763744 11.550 100 58 117 3 1098788224 16503760 12520720
11:11:00 6162 8192000000 10003832528 10400382976 1078273408 11.631 100 38 75 1 1118251168 32409920 7567840
11:12:11 8219 8192000000 10001857600 10400985088 1087356688 11.718 100 39 77 2 1129196560 34187696 7652176
11:13:20 7916 8192000000 10007026320 10412982272 1095826528 11.798 100 28 56 0 1123326992 21473760 6026704
11:14:35 42231 8192000000 10010225296 10425528320 1099562032 11.833 100 22 42 0 1121151600 17166960 4422608
11:16:18 3553 8192000000 10038718944 10440716288 1101932864 11.856 100 62 125 3 1159882208 44306000 13643344
11:17:22 6646 8192000000 9992107792 10389381120 1056706144 11.425 98 39 77 1 1092785328 27480448 8598736
11:18:31 6921 8192000000 9948300832 10336899072 999841808 10.877 95 33 65 0 1021213952 14270816 7101328
11:19:38 42576 8192000000 9928523936 10313883648 871676928 9.617 83 29 57 2 893154608 14680688 6796992
11:21:22 3083 8192000000 9929071472 10305249280 1006100352 10.938 98 59 118 1 1040255824 20346624 13808848
11:22:27 4709 8192000000 9944348416 10321514496 1024062480 11.111 99 43 87 1 1043747456 10666864 9018112
11:23:32 55751 8192000000 9970957824 10368266240 1054965856 11.408 100 30 59 3 1088911712 26829584 7116272
11:25:31 4500 8192000000 9979212608 10372263936 1068477920 11.538 100 45 91 0 1099182592 20320448 10384224
11:26:37 9294 8192000000 9988938368 10393952256 1074353104 11.594 100 42 85 3 1101713184 17963968 9396112
11:27:58 8365 8192000000 10027849056 10423304192 1087322880 11.717 100 46 92 2 1135078912 37770864 9985168
11:29:10 55740 8192000000 10076881472 10482044928 1105800832 11.893 100 55 108 5 1192892224 75750608 11340784
11:31:10 14075 8192000000 10107868624 10513948672 1120254112 12.029 100 42 82 0 1213001440 84100912 8646416
11:32:34 33922 8192000000 10134181376 10549370880 1132052992 12.141 100 45 89 0 1260263728 118620800 9589936
11:34:16 12498 8192000000 10167439344 10576322560 1151244208 12.321 100 51 100 1 1259542656 97168160 11130288
11:35:33 4204 8192000000 10194038176 10611101696 1146430752 12.276 100 57 114 0 1303053280 142768928 13853600
11:36:41 3169 8192000000 10201871296 10596282368 1130862384 12.129 99 69 130 1 1306380800 160369120 15149296
11:37:48 10016 8192000000 10223206592 10631610368 1146246512 12.274 99 39 78 1 1318752144 163229856 9275776
11:39:05 2008 8192000000 10254267600 10653540352 1152430304 12.332 100 85 170 1 1350992368 179391088 19170976
11:40:12 1108 8192000000 10277235536 10666872832 1154923568 12.356 100 96 187 1 1372849200 197784416 20141216
11:41:15 2791 8192000000 10258427072 10646953984 1118375536 12.012 98 82 163 1 1351327552 214868832 18083184
11:42:24 4409 8192000000 10271006816 10662621184 1108349504 11.917 99 75 150 0 1365112944 239490016 17273424
11:43:33 1751 8192000000 10312355280 10704523264 1133439248 12.154 100 87 173 1 1407629440 253875776 20314416
11:44:40 4852 8192000000 10355735392 10750754816 1139345152 12.209 100 76 151 0 1444298128 287889056 17063920
11:45:49 2342 8192000000 10331610704 10744008704 1153115824 12.339 100 67 133 3 1424235248 256125568 14993856
11:46:57 4731 8192000000 10340053360 10744537088 1142319040 12.237 100 41 82 0 1441418304 288884208 10215056
11:48:03 14257 8192000000 10347301424 10778955776 1142745072 12.241 100 42 84 1 1447486096 294664688 10076336
11:49:28 6212 8192000000 10366041712 10787577856 1142692608 12.241 100 42 79 1 1462659472 310561968 9404896
11:50:43 15755 8192000000 10394964496 10822479872 1152027280 12.329 100 33 66 2 1500447888 339783904 8636704
11:52:07 16891 8192000000 10431771984 10897149952 1137825536 12.195 100 29 58 1 1533289936 387188928 8275472
11:53:28 30640 8192000000 10287864448 10793840640 1129221632 12.114 100 18 35 1 1429348208 295180368 4946208
11:55:24 109618 8192000000 10088586816 10548174848 1118527024 12.013 99 30 60 0 1255434320 130122688 6784608
11:58:21 81344 8192000000 10102660832 10600841216 1135600880 12.174 100 11 22 0 1226922752 88701360 2620512
12:01:31 70341 8192000000 10055487024 10514518016 1123510480 12.060 99 18 35 0 1159171600 32125424 3535696
12:03:47 48856 8192000000 10062700000 10500239360 1121891952 12.045 99 23 46 2 1159038848 31846144 5300752
12:06:28 61296 8192000000 10114492512 10557505536 1137882816 12.196 99 28 56 1 1218957536 74995376 6079344
12:08:44 52359 8192000000 10076002272 10577829888 1146249968 12.274 100 11 22 1 1198788528 50300224 2238336
12:10:37 58614 8192000000 10053669136 10510385152 1121373392 12.040 99 11 21 0 1150551440 27309376 1868672
12:14:11 49655 8192000000 10076951184 10502717440 1093125008 11.772 95 32 62 2 1124446592 25211872 6109712
12:16:01 34863 8192000000 10096947232 10524532736 1117560832 12.004 98 29 56 0 1180365344 56905488 5899024
12:17:36 65145 8192000000 10063990992 10502393856 1092641360 11.768 96 12 24 2 1139865888 45144960 2079568
12:19:47 35359 8192000000 10140613264 10569297920 1152443056 12.332 100 34 67 1 1228630640 69164960 7022624
12:21:26 59905 8192000000 10177565632 10648784896 1148973936 12.300 100 23 46 0 1303083504 148583664 5525904
12:23:26 49454 8192000000 10110142656 10557698048 1113858464 11.969 97 13 26 0 1144414160 27794768 2760928
12:26:12 95010 8192000000 10098432560 10582515712 1138016976 12.197 100 13 26 1 1219895008 79543312 2334720
12:28:57 52865 8192000000 10098715504 10556162048 1131007968 12.131 99 13 25 2 1195513024 61693952 2811104
12:31:02 62161 8192000000 10082638080 10568589312 1091175088 11.754 97 10 20 0 1156623104 63440416 2007600
12:33:04 106136 8192000000 10066275904 10524401664 1096427376 11.804 97 15 30 0 1171133680 71404928 3301376
12:35:50 42767 8192000000 10122559568 10569601024 1119078944 12.018 99 28 56 1 1196698368 71134416 6485008
12:37:50 64575 8192000000 10093939264 10591047680 1116638592 11.995 99 17 34 0 1224172544 104022416 3511536
12:40:17 97507 8192000000 10090590064 10558873600 1102191136 11.858 97 17 34 3 1160128000 54336240 3600624
12:43:02 45836 8192000000 10106195808 10524200960 1085724304 11.702 96 32 62 0 1162179280 70115824 6339152
12:44:54 51743 8192000000 10038337456 10459410432 1071732752 11.569 96 22 44 1 1104710256 28760384 4217120
12:46:47 131460 8192000000 10065492368 10547974144 1115313984 11.983 100 21 42 1 1175241872 55496032 4431856
12:50:21 62126 8192000000 10063571728 10512191488 1104336864 11.879 98 10 20 0 1146140368 39411600 2391904
12:52:25 60763 8192000000 10076844160 10538856448 1114410464 11.974 99 12 24 0 1158147328 41392688 2344176
12:54:31 76254 8192000000 10108941472 10556452864 1133343584 12.153 100 13 26 0 1190119760 53771808 3004368
12:56:54 99723 8192000000 10067132480 10517045248 1122840240 12.054 99 19 38 1 1197603344 70698448 4064656
12:59:42 86707 8192000000 10107603616 10563788800 1129394160 12.116 99 24 48 0 1187868560 53594576 4879824
13:02:13 46483 8192000000 10140954560 10570641408 1125906896 12.083 99 29 58 0 1228083808 96203408 5973504
13:04:00 75632 8192000000 10078054880 10512818176 1135619632 12.174 100 24 47 2 1167468480 27143520 4705328
13:06:43 59967 8192000000 10060992304 10538352640 1123045872 12.056 98 6 12 0 1156578544 32543856 988816
13:08:47 34580 8192000000 10063334144 10551033856 1131065056 12.131 99 5 10 2 1167257504 35465168 727280
13:10:21 21198 8192000000 10068687808 10515447808 1130427296 12.125 99 20 39 1 1148729968 14470640 3832032
13:11:43 35947 8192000000 10106534688 10547433472 1152924688 12.337 100 26 52 1 1203931888 45655808 5351392
13:13:27 64357 8192000000 10148892480 10608300032 1147801776 12.289 99 35 70 0 1246558688 90968864 7788048
13:15:32 70038 8192000000 10104923040 10591191040 1121851472 12.044 98 16 31 1 1189950592 64511440 3587680
13:17:43 29056 8192000000 10112222144 10535387136 1123804960 12.063 98 40 80 2 1188245040 55702976 8737104
13:19:12 46433 8192000000 10076121040 10594992128 1104626512 11.882 97 15 30 1 1200318544 91854272 3837760
13:21:47 119839 8192000000 10083510464 10571358208 1089438848 11.737 96 16 32 1 1127513296 35020032 3054416
13:25:27 49062 8192000000 10031610592 10491117568 1087248768 11.716 96 16 32 0 1109969472 19444192 3276512

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 08-11-2020 13:28:51 to 08-11-2020 13:29:06.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 131 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-11-2020 13:29:07 to 08-11-2020 13:29:09.

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          241389
MON$OLDEST_ACTIVE               241390
MON$OLDEST_SNAPSHOT             241390
MON$NEXT_TRANSACTION            241390
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                       2109184
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             806
MON$NEXT_STATEMENT              53


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

08.11.20 13:29:10. 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-11-08 13:29:13 No severe PSQL-related problems occured
08.11.20 13:29:14. End of report.