Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.11.2020 13: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 16501080 kB
MemAvailable 31378064 kB
Buffers 0 kB
Cached 15125696 kB
SwapCached 3828 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-16 13:26:49
DTS_END                         2020-11-16 13:26:49.3920
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/54900
TRN_ID                          243277
ATT_ID                          632
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: 9156.19 20253 1098743
customer order: creation 179.69 373 21563
customer order: refuse 15.53 300 1863
order to supplier: creation 47.72 1374 5726
order to supplier: removal 3.54 97442 425
invoice (draft): creation 48.69 3402 5843
invoice (draft): removal 50.04 867 6005
invoice accept: apply 44.42 16510 5330
invoice accept: cancel 44.92 808 5390
customer reserve: creation 4016.85 166 482022
customer reserve: removal 4402.99 62 528359
realization accept: apply 88.62 169 10634
realization accept: cancel 77.91 125 9349
payment from customer: creation 62.23 102 7467
payment from customer: removal 5.78 85 693
payment to supplier: creation 52.19 107 6263
payment to supplier: removal 3.63 95 435
service: total inventory turnovers 4.13 3141 496
service: total monetary turnovers 6.43 546 772
service: aggregate perf. data 0.87 19705 104
service: refresh index statistics 0.03 259681 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-11-2020 13:27:28 to 16-11-2020 13:27:30.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1860
TEST_TIME 2 4402
TEST_TIME 3 5683
TEST_TIME 4 5513
TEST_TIME 5 5762
TEST_TIME 6 6071
TEST_TIME 7 5937
TEST_TIME 8 6238
TEST_TIME 9 6535
TEST_TIME 10 6782
TEST_TIME 11 6713
TEST_TIME 12 6930
TEST_TIME 13 7026
TEST_TIME 14 6992
TEST_TIME 15 7349
TEST_TIME 16 7328
TEST_TIME 17 7535
TEST_TIME 18 7837
TEST_TIME 19 7747
TEST_TIME 20 7900
TEST_TIME 21 8066
TEST_TIME 22 8507
TEST_TIME 23 8679
TEST_TIME 24 9218
TEST_TIME 25 10128
TEST_TIME 26 10413
TEST_TIME 27 10180
TEST_TIME 28 10661
TEST_TIME 29 10489
TEST_TIME 30 10393
TEST_TIME 31 10427
TEST_TIME 32 10272
TEST_TIME 33 10248
TEST_TIME 34 10334
TEST_TIME 35 10235
TEST_TIME 36 10096
TEST_TIME 37 10225
TEST_TIME 38 10208
TEST_TIME 39 10160
TEST_TIME 40 10162
TEST_TIME 41 10117
TEST_TIME 42 10011
TEST_TIME 43 9838
TEST_TIME 44 9876
TEST_TIME 45 9767
TEST_TIME 46 9703
TEST_TIME 47 9602
TEST_TIME 48 9701
TEST_TIME 49 9586
TEST_TIME 50 9537
TEST_TIME 51 9490
TEST_TIME 52 9435
TEST_TIME 53 9425
TEST_TIME 54 9589
TEST_TIME 55 9549
TEST_TIME 56 9572
TEST_TIME 57 9524
TEST_TIME 58 9515
TEST_TIME 59 9514
TEST_TIME 60 9606
TEST_TIME 61 9705
TEST_TIME 62 9691
TEST_TIME 63 9724
TEST_TIME 64 9736
TEST_TIME 65 9848
TEST_TIME 66 9821
TEST_TIME 67 9740
TEST_TIME 68 9672
TEST_TIME 69 9588
TEST_TIME 70 9566
TEST_TIME 71 9488
TEST_TIME 72 9444
TEST_TIME 73 9390
TEST_TIME 74 9356
TEST_TIME 75 9300
TEST_TIME 76 9268
TEST_TIME 77 9242
TEST_TIME 78 9245
TEST_TIME 79 9236
TEST_TIME 80 9296
TEST_TIME 81 9306
TEST_TIME 82 9314
TEST_TIME 83 9371
TEST_TIME 84 9346
TEST_TIME 85 9376
TEST_TIME 86 9359
TEST_TIME 87 9383
TEST_TIME 88 9284
TEST_TIME 89 9314
TEST_TIME 90 9242
TEST_TIME 91 9176
TEST_TIME 92 9203
TEST_TIME 93 9217
TEST_TIME 94 9172
TEST_TIME 95 9244
TEST_TIME 96 9211
TEST_TIME 97 9221
TEST_TIME 98 9215
TEST_TIME 99 9215
TEST_TIME 100 9274
TEST_TIME 101 9232
TEST_TIME 102 9235
TEST_TIME 103 9226
TEST_TIME 104 9224
TEST_TIME 105 9227
TEST_TIME 106 9213
TEST_TIME 107 9149
TEST_TIME 108 9145
TEST_TIME 109 9096
TEST_TIME 110 9089
TEST_TIME 111 9049
TEST_TIME 112 9089
TEST_TIME 113 9063
TEST_TIME 114 9050
TEST_TIME 115 9065
TEST_TIME 116 9046
TEST_TIME 117 9020
TEST_TIME 118 9017
TEST_TIME 119 9137
TEST_TIME 120 9157

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 16-11-2020 13:27:32 to 16-11-2020 13:27:35.

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 1543711 1543711 0 0.00 0 17904 7 0 0
doc_list_biud 1543709 1543709 0 0.00 0 59626 27 0 0
sp_add_invoice_to_stock 5456 5330 126 2.30 35 196493 16511 0 126
sp_cancel_adding_invoice 5390 5390 0 0.00 4 64966 808 0 0
sp_cancel_client_order 1863 1863 0 0.00 5 8613 300 0 0
sp_cancel_customer_reserve 528359 528359 0 0.00 1 59627 63 0 0
sp_cancel_pay_from_customer 693 693 0 0.00 3 14747 86 0 0
sp_cancel_pay_to_supplier 435 435 0 0.00 3 4076 95 0 0
sp_cancel_supplier_invoice 6021 6005 16 0.26 2 38531 867 0 16
sp_cancel_supplier_order 425 425 0 0.00 6 1116733 97442 0 0
sp_cancel_write_off 9349 9349 0 0.00 1 17906 125 0 0
sp_client_order 21563 21563 0 0.00 4 21187 373 0 0
sp_customer_reserve 482022 482022 0 0.00 0 41013 167 0 0
sp_fill_shopping_cart 33118 33118 0 0.00 1 14486 42 0 0
sp_fill_shopping_cart_clo_res 482021 482021 0 0.00 0 17664 18 0 0
sp_get_clo_for_invoice 5329 5329 0 0.00 0 40472 388 0 0
sp_kill_cost_storno 15867 15867 0 0.00 0 17903 52 0 0
sp_kill_qstorno_ret_qs2qd 1072497 1072497 0 0.00 0 59560 34 0 0
sp_kill_qty_storno 567353 567353 0 0.00 0 59625 73 0 0
sp_lock_selected_doc 22064 22064 0 0.00 0 9236 6 0 0
sp_make_cost_storno 29693 29693 0 0.00 0 15998 49 0 0
sp_multiply_rows_for_pdistr 29692 29692 0 0.00 0 9744 30 0 0
sp_multiply_rows_for_qdistr 21563 21563 0 0.00 0 21171 211 0 0
sp_pay_from_customer 7467 7467 0 0.00 4 17111 102 0 0
sp_pay_to_supplier 6263 6263 0 0.00 5 14996 108 0 0
sp_payment_common 13729 13729 0 0.00 1 6034 16 0 0
sp_qd_handle_on_cancel_clo 1863 1863 0 0.00 0 8511 269 0 0
sp_qd_handle_on_invoice_upd_sts 10719 10719 0 0.00 0 23903 330 0 0
sp_qd_handle_on_reserve_upd_sts 10633 10633 0 0.00 0 17625 47 0 0
sp_reserve_write_off 10634 10634 0 0.00 7 22016 170 0 0
sp_supplier_invoice 5843 5843 0 0.00 7 93004 3403 0 0
sp_supplier_order 5726 5726 0 0.00 5 75859 1374 0 0
srv_aggregate_perf_data 104 104 0 0.00 2352 367321 19705 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1639 149197 46519 0 0
srv_find_qd_qs_mism 1048110 1048110 0 0.00 0 13787 6 0 0
srv_make_invnt_saldo 496 496 0 0.00 58 90117 3142 0 0
srv_make_money_saldo 772 772 0 0.00 1 22907 546 0 0
srv_recalc_idx_stat 4 4 0 0.00 109801 362397 259682 0 0
t$perf-abend:sp_add_invoice_to_stock 126 126 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5330 5330 0 0.00 0 27322 156 0 0
t$perf-norm:sp_cancel_adding_invoice 423 423 0 0.00 0 8042 112 0 0
t$perf-norm:sp_cancel_client_order 1863 1863 0 0.00 0 1893 10 0 0
t$perf-norm:sp_cancel_customer_reserve 758 758 0 0.00 0 3752 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 693 693 0 0.00 0 5097 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 435 435 0 0.00 0 1776 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 445 445 0 0.00 0 1580 21 0 0
t$perf-norm:sp_cancel_supplier_order 425 425 0 0.00 0 19302 1305 0 0
t$perf-norm:sp_cancel_write_off 1060 1060 0 0.00 0 5070 9 0 0
t$perf-norm:sp_client_order 21563 21563 0 0.00 0 24134 18 0 0
t$perf-norm:sp_customer_reserve 1044 1044 0 0.00 0 3637 9 0 0
t$perf-norm:sp_pay_from_customer 7467 7467 0 0.00 0 16082 8 0 0
t$perf-norm:sp_pay_to_supplier 6263 6263 0 0.00 0 9182 10 0 0
t$perf-norm:sp_reserve_write_off 10634 10634 0 0.00 0 7876 10 0 0
t$perf-norm:sp_supplier_invoice 5843 5843 0 0.00 0 6149 19 0 0
t$perf-norm:sp_supplier_order 5726 5726 0 0.00 0 12489 17 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 88 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 1110 18 0 0
t$perf-norm:srv_make_invnt_saldo 496 496 0 0.00 0 598 5 0 0
t$perf-norm:srv_make_money_saldo 772 772 0 0.00 0 755 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 10910 10658 252 2.30 0 7079 18 0 252
v_all_customers 39658 39658 0 0.00 0 45 1 0 0
v_all_suppliers 31842 31842 0 0.00 0 18 1 0 0
v_all_wares 43126 43126 0 0.00 0 24 1 0 0
v_cancel_adding_invoice 846 846 0 0.00 0 496 6 0 0
v_cancel_client_order 3726 3726 0 0.00 0 4268 5 0 0
v_cancel_customer_prepayment 1386 1386 0 0.00 0 7131 11 0 0
v_cancel_customer_reserve 1516 1516 0 0.00 0 3848 9 0 0
v_cancel_payment_to_supplier 870 870 0 0.00 0 2595 7 0 0
v_cancel_supplier_invoice 922 890 32 3.47 0 5846 26 0 32
v_cancel_supplier_order 850 850 0 0.00 0 2761 9 0 0
v_cancel_write_off 2120 2120 0 0.00 0 5968 19 0 0
v_max_id_clo_ord 5726 5726 0 0.00 0 329 1 0 0
v_max_id_clo_res 1044 1044 0 0.00 0 3585 16 0 0
v_max_id_ord_sup 5829 5829 0 0.00 0 7419 6 0 0
v_max_non_paid_invoice 1896 1896 0 0.00 0 156 2 0 0
v_max_non_paid_realizn 3786 3786 0 0.00 0 1703 4 0 0
v_min_id_clo_ord 5726 5726 0 0.00 0 1995 6 0 0
v_min_id_clo_res 1044 1044 0 0.00 0 3198 5 0 0
v_min_id_ord_sup 5829 5829 0 0.00 0 7276 9 0 0
v_min_non_paid_invoice 6262 6262 0 0.00 0 4089 9 0 0
v_min_non_paid_realizn 7467 7467 0 0.00 0 6030 11 0 0
v_our_firm 10830 10830 0 0.00 0 11 1 0 0
v_reserve_write_off 21266 21266 0 0.00 0 4269 7 0 0
x_lock_depdocs_on_canc_invoice 10950 10950 0 0.00 0 15089 95 0 0
x_lock_depdocs_on_canc_sup_ord 425 425 0 0.00 0 5523 280 0 0
x_make_qstorno_1000_1200 5726 5726 0 0.00 2 75822 1302 0 0
x_make_qstorno_1200_2000 5843 5843 0 0.00 3 92952 3238 0 0
x_make_qstorno_2100_3300 482022 482022 0 0.00 0 41011 146 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-11-2020 13:27:36 to 16-11-2020 13:27:40.

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:53 2 8192000000 8796641312 8807108608 3168704 0.038 1 1 2 0 3266400 61840 35856
10:57:54 22038 8192000000 9725560320 10019840000 804141968 8.938 100 92 184 0 833274048 7425120 21706960
10:59:24 8767 8192000000 9748603776 10050646016 824418864 9.143 100 89 178 0 857991216 12585520 20986832
11:00:38 17464 8192000000 9794088880 10107305984 852980160 9.430 100 83 165 2 901075696 28813184 19282352
11:02:07 11454 8192000000 9867756976 10199453696 913722560 10.034 100 75 148 1 974407936 44739248 15946128
11:03:25 9505 8192000000 9914701008 10262462464 962407760 10.513 100 76 152 3 1031145856 52961104 15776992
11:04:40 5266 8192000000 9973857504 10326081536 1001536240 10.893 100 84 168 1 1091950624 73651280 16763104
11:05:47 2153 8192000000 10020313184 10388619264 1026588992 11.136 100 86 171 0 1135494816 91649552 17256272
11:06:51 1428 8192000000 10025879568 10396291072 1055881440 11.417 100 81 159 1 1140163648 68551968 15730240
11:07:53 12312 8192000000 10008801472 10417532928 1068170656 11.535 100 63 126 0 1132802416 51715744 12916016
11:09:08 26031 8192000000 9999244944 10412699648 1073167696 11.582 100 27 54 3 1113802640 35388400 5246544
11:10:34 9564 8192000000 10001059712 10405662720 1080867808 11.656 100 32 64 3 1110611792 23471200 6272784
11:11:46 3740 8192000000 10005610624 10398470144 1087836656 11.722 100 36 71 3 1120685472 24761184 8087632
11:12:51 63782 8192000000 10013813984 10402586624 1091969712 11.761 100 42 84 1 1123958496 22616320 9372464
11:14:55 3765 8192000000 10038005392 10435022848 1093581232 11.777 100 48 95 3 1142192992 37992016 10619744
11:16:01 4867 8192000000 10001137856 10394341376 1081341840 11.660 99 49 95 2 1103569152 11615344 10611968
11:17:07 12403 8192000000 9993953584 10407297024 1070577056 11.558 99 26 52 0 1094944352 18512304 5854992
11:18:20 42150 8192000000 9984636976 10364985344 1055286432 11.411 99 66 133 3 1084561264 14255520 15019312
11:20:02 12785 8192000000 9947142176 10342576128 1012005600 10.995 98 34 68 3 1040456384 21264384 7186400
11:21:20 8036 8192000000 9934039200 10317377536 1016061104 11.034 99 33 66 1 1041421584 17455744 7904736
11:22:30 7457 8192000000 9959322576 10358489088 1032309040 11.191 100 25 50 1 1063380928 26148208 4923680
11:23:42 65652 8192000000 9944057136 10352353280 1047683824 11.338 100 38 76 2 1078705200 21326880 9694496
11:25:49 4960 8192000000 9974231120 10369499136 1058218208 11.439 100 54 113 0 1082583712 11522800 12842704
11:27:01 30425 8192000000 9990904992 10388422656 1083893472 11.685 100 32 65 0 1100990864 10329088 6768304
11:28:38 18418 8192000000 10073234368 10478804992 1098338176 11.822 100 49 96 4 1181690640 73626496 9725968
11:29:59 25233 8192000000 10071911056 10494177280 1114810336 11.978 100 31 61 2 1189251152 68063760 6377056
11:31:30 25857 8192000000 10104030080 10507849728 1126559920 12.089 100 44 88 2 1223085008 87141648 9383440
11:33:01 9596 8192000000 10131893712 10535358464 1137577488 12.193 100 57 114 0 1241067984 92071504 11418992
11:34:15 4911 8192000000 10156112064 10553987072 1146783440 12.279 100 61 123 1 1256004672 95310832 13910400
11:35:22 3587 8192000000 10187167504 10587803648 1154668640 12.353 100 72 142 1 1295006384 124614288 15723456
11:36:29 1637 8192000000 10225859280 10639998976 1151272192 12.321 99 74 148 1 1322066496 153745712 17048592
11:37:37 3219 8192000000 10253328288 10650038272 1159735392 12.401 100 86 168 3 1354273056 175909584 18628080
11:38:45 13890 8192000000 10271997936 10688466944 1169379408 12.491 100 60 116 1 1381101856 198196528 13525920
11:40:04 15061 8192000000 10259969312 10677039104 1147630112 12.287 98 48 94 1 1351298704 193241504 10427088
11:41:26 6253 8192000000 10320178576 10730704896 1169374944 12.491 100 91 180 2 1415148560 225747024 20026592
11:42:37 8537 8192000000 10336439776 10747789312 1166408912 12.463 99 67 132 1 1429581584 247446400 15726272
11:43:57 9710 8192000000 10333305472 10743566336 1141426864 12.229 99 71 141 4 1422992192 265219664 16345664
11:45:11 12415 8192000000 10340658368 10762801152 1144572784 12.259 99 31 62 2 1433029888 280474448 7982656
11:46:30 5843 8192000000 10393564368 10805075968 1141086944 12.226 99 65 128 0 1483468768 326781920 15599904
11:47:38 8715 8192000000 10433147424 10862448640 1148449280 12.295 100 48 96 0 1513894864 353607360 11838224
11:48:54 21426 8192000000 10380317776 10815459328 1131483888 12.135 99 40 79 2 1464245024 322897824 9863312
11:50:24 16004 8192000000 10293687792 10738253824 1109554128 11.928 98 32 64 2 1406156240 288534864 8067248
11:51:42 51191 8192000000 10097496960 10547810304 1084827152 11.693 97 17 34 1 1280643360 191186272 4629936
11:53:35 39860 8192000000 10080287824 10588061696 1069471664 11.547 96 27 54 1 1186899696 111229680 6198352
11:55:55 149197 8192000000 10059062496 10502856704 1098357504 11.822 97 13 27 0 1128143472 27690576 2095392
11:59:28 48001 8192000000 10095580496 10545901568 1118604944 12.014 98 16 32 1 1204026224 82094288 3326992
12:01:17 80823 8192000000 10118226064 10550403072 1098944304 11.828 96 10 20 0 1139317312 38208464 2164544
12:04:48 48484 8192000000 10060474352 10509725696 1125790224 12.082 99 15 30 1 1158662688 30104768 2767696
12:06:45 85157 8192000000 10124605280 10569420800 1131148160 12.132 98 19 35 1 1193242192 58259760 3834272
12:09:18 79407 8192000000 10074725312 10521260032 1126616560 12.089 98 36 71 2 1190248896 56113936 7518400
12:12:40 41840 8192000000 10169836544 10595487744 1136328720 12.181 98 32 63 3 1212759520 68886400 7544400
12:14:46 31599 8192000000 10119487840 10606940160 1131536912 12.136 99 16 32 0 1218517856 83683184 3297760
12:16:18 62114 8192000000 10144413472 10628513792 1155581776 12.362 100 13 26 1 1241434320 83262896 2589648
12:18:21 54053 8192000000 10122172160 10609463296 1142293264 12.237 99 14 28 0 1218185984 72986848 2905872
12:20:25 48222 8192000000 10086728256 10555965440 1144899984 12.262 99 8 16 0 1200388208 53903600 1584624
12:22:14 27783 8192000000 10058117584 10485178368 1114121552 11.971 97 28 55 1 1156666160 35554176 6990432
12:23:46 13799 8192000000 10120977456 10544480256 1147465888 12.286 99 48 97 1 1202838400 45704208 9668304
12:25:00 41390 8192000000 10178005824 10647150592 1143919952 12.252 99 32 64 0 1253471824 102662064 6889808
12:26:48 80434 8192000000 10093719360 10595770368 1080252224 11.650 94 14 26 2 1126244032 43301776 2690032
12:29:09 46617 8192000000 10070689760 10504269824 1064661024 11.501 94 26 52 0 1114498560 44536816 5300720
12:30:56 93209 8192000000 10068122592 10546679808 1099448912 11.832 97 10 20 0 1165628320 63882048 2297360
12:33:39 121326 8192000000 10055949440 10517352448 1113243920 11.963 99 32 64 1 1165917616 45979152 6694544
12:37:00 55583 8192000000 10030533648 10489548800 1091897824 11.761 97 7 14 0 1119204816 25637728 1669264
12:38:56 52083 8192000000 10062954864 10507186176 1102900224 11.865 98 17 34 0 1143746576 37413184 3433168
12:40:49 55066 8192000000 10073227056 10551488512 1127650576 12.099 100 13 26 1 1180440640 50062528 2727536
12:42:58 32901 8192000000 10073610464 10513080320 1106444688 11.899 99 19 38 0 1177986256 66942576 4598992
12:44:42 52367 8192000000 10111557936 10546081792 1095172064 11.792 97 28 56 1 1186895216 85077456 6645696
12:46:40 106300 8192000000 10090742848 10527191040 1074744624 11.597 96 19 38 1 1126295264 47764496 3786144
12:49:27 46839 8192000000 10077532432 10491211776 1099414016 11.832 98 48 96 1 1143628736 33985344 10229376
12:51:14 60483 8192000000 10097528064 10521632768 1132701856 12.147 100 21 42 0 1178705584 41517968 4485760
12:53:16 55741 8192000000 10112497408 10592530432 1139363408 12.210 100 5 10 0 1191670752 51349696 957648
12:55:50 65789 8192000000 10077680288 10575130624 1133041088 12.150 99 11 22 0 1174657712 39240448 2376176
12:58:26 48242 8192000000 10082287152 10565881856 1097454832 11.813 97 9 18 1 1156261744 57551424 1255488
13:00:22 105825 8192000000 10082517920 10590285824 1104264336 11.878 97 14 28 0 1184546576 77330256 2951984
13:03:09 55657 8192000000 10108645056 10578259968 1116860352 11.997 98 22 44 1 1199066480 77473072 4733056
13:05:07 32503 8192000000 10088868576 10525974528 1117541504 12.004 98 23 46 1 1157520240 35373712 4605024
13:06:46 47358 8192000000 10074197120 10495479808 1128765248 12.110 99 27 53 1 1163114768 28014704 6334816
13:08:34 58365 8192000000 10059100064 10516000768 1103242448 11.868 98 30 60 2 1151396224 42479504 5674272
13:10:33 50425 8192000000 10097030864 10534244352 1130151648 12.123 100 23 46 2 1175135312 40050512 4933152
13:12:28 129737 8192000000 10066642992 10556026880 1117047552 11.999 99 18 36 1 1169002816 47012992 4942272
13:16:04 55966 8192000000 10054369536 10531348480 1092545648 11.767 97 7 14 0 1126261744 32398496 1317600
13:18:00 95477 8192000000 10053949296 10522775552 1081580096 11.663 97 16 30 0 1131684112 46814656 3289360
13:20:38 47780 8192000000 10075990064 10508513280 1107879360 11.912 99 25 50 0 1177507984 64229600 5399024
13:22:27 48182 8192000000 10131193152 10558590976 1098358944 11.822 97 43 86 1 1168407232 61141568 8906720
13:24:19 59553 8192000000 10147574336 10621370368 1126489312 12.088 100 30 60 2 1234905376 101270912 7145152
13:26:30 27183 8192000000 8922643888 9116618752 81945520 0.990 8 2 4 0 87539280 5522480 71280

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 16-11-2020 13:27:42 to 16-11-2020 13:27:57.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 126 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-11-2020 13:27:57 to 16-11-2020 13:27:59.

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          242457
MON$OLDEST_ACTIVE               243578
MON$OLDEST_SNAPSHOT             243578
MON$NEXT_TRANSACTION            243578
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                       2125440
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             811
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2265 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2265 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2265 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.2265 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 ---
:152: 
:153: mscs-test-161.budzdorov.ru    Mon Nov 16 13:26:59 2020
:154:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:155: 
--- end of diff output ---

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

16.11.20 13:28:00. 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-16 13:28:03 No severe PSQL-related problems occured
16.11.20 13:28:04. End of report.