Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.12.2020 07:38
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 16939360 kB
MemAvailable 31565452 kB
Buffers 0 kB
Cached 14688156 kB
SwapCached 1928 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-02 07:36:45
DTS_END                         2020-12-02 07:36:45.6570
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/55556
TRN_ID                          227919
ATT_ID                          625
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: 8940.67 21252 1072880
customer order: creation 157.33 428 18880
customer order: refuse 13.98 271 1677
order to supplier: creation 42.34 1207 5081
order to supplier: removal 3.33 111309 399
invoice (draft): creation 42.23 3361 5068
invoice (draft): removal 53.93 830 6472
invoice accept: apply 37.96 17450 4555
invoice accept: cancel 48.48 710 5818
customer reserve: creation 3521.21 171 422545
customer reserve: removal 4741.76 62 569011
realization accept: apply 77.67 169 9320
realization accept: cancel 82.58 116 9910
payment from customer: creation 55.21 112 6625
payment from customer: removal 5.63 87 675
payment to supplier: creation 43.78 127 5253
payment to supplier: removal 3.04 57 365
service: total inventory turnovers 3.78 2893 453
service: total monetary turnovers 5.45 443 654
service: aggregate perf. data 0.96 12563 115
service: refresh index statistics 0.03 272665 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 02-12-2020 07:39:18 to 02-12-2020 07:39:21.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4240
TEST_TIME 2 2370
TEST_TIME 3 3170
TEST_TIME 4 3082
TEST_TIME 5 3390
TEST_TIME 6 3566
TEST_TIME 7 3727
TEST_TIME 8 4131
TEST_TIME 9 4355
TEST_TIME 10 4653
TEST_TIME 11 4734
TEST_TIME 12 5105
TEST_TIME 13 5445
TEST_TIME 14 6004
TEST_TIME 15 6583
TEST_TIME 16 7357
TEST_TIME 17 7177
TEST_TIME 18 7566
TEST_TIME 19 8047
TEST_TIME 20 8797
TEST_TIME 21 10412
TEST_TIME 22 10605
TEST_TIME 23 10739
TEST_TIME 24 10610
TEST_TIME 25 10768
TEST_TIME 26 10751
TEST_TIME 27 10638
TEST_TIME 28 10490
TEST_TIME 29 10164
TEST_TIME 30 10091
TEST_TIME 31 9975
TEST_TIME 32 9840
TEST_TIME 33 10010
TEST_TIME 34 9821
TEST_TIME 35 9714
TEST_TIME 36 9622
TEST_TIME 37 9808
TEST_TIME 38 9699
TEST_TIME 39 9865
TEST_TIME 40 9765
TEST_TIME 41 9859
TEST_TIME 42 9947
TEST_TIME 43 9833
TEST_TIME 44 9936
TEST_TIME 45 10157
TEST_TIME 46 10060
TEST_TIME 47 10180
TEST_TIME 48 10028
TEST_TIME 49 9869
TEST_TIME 50 9799
TEST_TIME 51 9670
TEST_TIME 52 9580
TEST_TIME 53 9508
TEST_TIME 54 9487
TEST_TIME 55 9411
TEST_TIME 56 9373
TEST_TIME 57 9371
TEST_TIME 58 9421
TEST_TIME 59 9423
TEST_TIME 60 9640
TEST_TIME 61 9659
TEST_TIME 62 9678
TEST_TIME 63 9742
TEST_TIME 64 9797
TEST_TIME 65 9801
TEST_TIME 66 9830
TEST_TIME 67 9687
TEST_TIME 68 9608
TEST_TIME 69 9532
TEST_TIME 70 9444
TEST_TIME 71 9427
TEST_TIME 72 9402
TEST_TIME 73 9394
TEST_TIME 74 9398
TEST_TIME 75 9370
TEST_TIME 76 9363
TEST_TIME 77 9474
TEST_TIME 78 9446
TEST_TIME 79 9395
TEST_TIME 80 9532
TEST_TIME 81 9533
TEST_TIME 82 9500
TEST_TIME 83 9481
TEST_TIME 84 9403
TEST_TIME 85 9390
TEST_TIME 86 9304
TEST_TIME 87 9321
TEST_TIME 88 9274
TEST_TIME 89 9258
TEST_TIME 90 9247
TEST_TIME 91 9316
TEST_TIME 92 9249
TEST_TIME 93 9276
TEST_TIME 94 9235
TEST_TIME 95 9247
TEST_TIME 96 9221
TEST_TIME 97 9181
TEST_TIME 98 9256
TEST_TIME 99 9264
TEST_TIME 100 9295
TEST_TIME 101 9236
TEST_TIME 102 9166
TEST_TIME 103 9116
TEST_TIME 104 9052
TEST_TIME 105 9025
TEST_TIME 106 9012
TEST_TIME 107 8991
TEST_TIME 108 8982
TEST_TIME 109 8963
TEST_TIME 110 8949
TEST_TIME 111 8922
TEST_TIME 112 9030
TEST_TIME 113 9055
TEST_TIME 114 9014
TEST_TIME 115 9027
TEST_TIME 116 9103
TEST_TIME 117 9085
TEST_TIME 118 9044
TEST_TIME 119 8990
TEST_TIME 120 8945

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 02-12-2020 07:39:22 to 02-12-2020 07:39:25.

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 1466433 1466433 0 0.00 0 23041 6 0 0
doc_list_biud 1466430 1466430 0 0.00 0 59561 30 0 0
sp_add_invoice_to_stock 4701 4555 146 3.10 24 181056 17451 0 146
sp_cancel_adding_invoice 5818 5818 0 0.00 5 102326 710 0 0
sp_cancel_client_order 1677 1677 0 0.00 4 7353 271 0 0
sp_cancel_customer_reserve 569011 569011 0 0.00 1 37783 63 0 0
sp_cancel_pay_from_customer 675 675 0 0.00 3 6200 87 0 0
sp_cancel_pay_to_supplier 365 365 0 0.00 3 3814 58 0 0
sp_cancel_supplier_invoice 6483 6472 11 0.16 2 59643 831 0 11
sp_cancel_supplier_order 399 399 0 0.00 8 1084934 111310 0 0
sp_cancel_write_off 9910 9910 0 0.00 1 13022 117 0 0
sp_client_order 18880 18880 0 0.00 4 36652 429 0 0
sp_customer_reserve 422545 422545 0 0.00 0 71703 172 0 0
sp_fill_shopping_cart 29026 29026 0 0.00 1 15134 45 0 0
sp_fill_shopping_cart_clo_res 422541 422541 0 0.00 0 25341 20 0 0
sp_get_clo_for_invoice 4551 4551 0 0.00 0 28768 412 0 0
sp_kill_cost_storno 16768 16768 0 0.00 0 7537 47 0 0
sp_kill_qstorno_ret_qs2qd 1154803 1154803 0 0.00 0 59560 34 0 0
sp_kill_qty_storno 607155 607155 0 0.00 0 59561 72 0 0
sp_lock_selected_doc 19316 19316 0 0.00 0 9062 7 0 0
sp_make_cost_storno 25749 25749 0 0.00 0 15208 55 0 0
sp_multiply_rows_for_pdistr 25748 25748 0 0.00 0 7513 29 0 0
sp_multiply_rows_for_qdistr 18880 18880 0 0.00 0 36640 259 0 0
sp_pay_from_customer 6625 6625 0 0.00 4 15387 112 0 0
sp_pay_to_supplier 5253 5253 0 0.00 5 13912 128 0 0
sp_payment_common 11878 11878 0 0.00 1 9944 26 0 0
sp_qd_handle_on_cancel_clo 1677 1677 0 0.00 0 7347 237 0 0
sp_qd_handle_on_invoice_upd_sts 10367 10367 0 0.00 0 36088 327 0 0
sp_qd_handle_on_reserve_upd_sts 9319 9319 0 0.00 0 14131 45 0 0
sp_reserve_write_off 9320 9320 0 0.00 6 16180 170 0 0
sp_supplier_invoice 5068 5068 0 0.00 8 91789 3362 0 0
sp_supplier_order 5081 5081 0 0.00 5 40391 1208 0 0
srv_aggregate_perf_data 115 115 0 0.00 2795 137042 12564 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1511 142736 51799 0 0
srv_find_qd_qs_mism 1030337 1030337 0 0.00 0 23025 5 0 0
srv_make_invnt_saldo 453 453 0 0.00 47 113315 2894 0 0
srv_make_money_saldo 654 654 0 0.00 1 15161 443 0 0
srv_recalc_idx_stat 4 4 0 0.00 78581 416610 272665 0 0
t$perf-abend:sp_add_invoice_to_stock 146 146 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4556 4556 0 0.00 0 13323 143 0 0
t$perf-norm:sp_cancel_adding_invoice 376 376 0 0.00 0 3251 76 0 0
t$perf-norm:sp_cancel_client_order 1677 1677 0 0.00 0 3848 13 0 0
t$perf-norm:sp_cancel_customer_reserve 660 660 0 0.00 0 860 6 0 0
t$perf-norm:sp_cancel_pay_from_customer 675 675 0 0.00 0 730 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 365 365 0 0.00 0 281 1 0 0
t$perf-norm:sp_cancel_supplier_invoice 355 355 0 0.00 0 584 8 0 0
t$perf-norm:sp_cancel_supplier_order 399 399 0 0.00 0 23276 1363 0 0
t$perf-norm:sp_cancel_write_off 941 941 0 0.00 0 190 2 0 0
t$perf-norm:sp_client_order 18881 18881 0 0.00 0 9801 16 0 0
t$perf-norm:sp_customer_reserve 951 951 0 0.00 0 1157 4 0 0
t$perf-norm:sp_pay_from_customer 6625 6625 0 0.00 0 9697 11 0 0
t$perf-norm:sp_pay_to_supplier 5253 5253 0 0.00 0 9537 9 0 0
t$perf-norm:sp_reserve_write_off 9320 9320 0 0.00 0 7812 11 0 0
t$perf-norm:sp_supplier_invoice 5068 5068 0 0.00 0 10366 17 0 0
t$perf-norm:sp_supplier_order 5081 5081 0 0.00 0 4195 11 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 141 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 199 4 0 0
t$perf-norm:srv_make_invnt_saldo 453 453 0 0.00 0 5476 20 0 0
t$perf-norm:srv_make_money_saldo 654 654 0 0.00 0 818 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 24 8 0 0
v_add_invoice_to_stock 9390 9098 292 3.10 0 7936 16 0 292
v_all_customers 34338 34338 0 0.00 0 21 1 0 0
v_all_suppliers 27978 27978 0 0.00 0 17 1 0 0
v_all_wares 37760 37760 0 0.00 0 157 1 0 0
v_cancel_adding_invoice 752 752 0 0.00 0 533 5 0 0
v_cancel_client_order 3354 3354 0 0.00 0 5435 8 0 0
v_cancel_customer_prepayment 1350 1350 0 0.00 0 4023 10 0 0
v_cancel_customer_reserve 1320 1320 0 0.00 0 4451 6 0 0
v_cancel_payment_to_supplier 730 730 0 0.00 0 3785 8 0 0
v_cancel_supplier_invoice 732 710 22 3.00 0 1023 11 0 22
v_cancel_supplier_order 798 798 0 0.00 0 8500 18 0 0
v_cancel_write_off 1882 1882 0 0.00 0 8825 18 0 0
v_max_id_clo_ord 5080 5080 0 0.00 0 532 1 0 0
v_max_id_clo_res 951 951 0 0.00 0 2245 13 0 0
v_max_id_ord_sup 5066 5066 0 0.00 0 5298 8 0 0
v_max_non_paid_invoice 1410 1410 0 0.00 0 268 2 0 0
v_max_non_paid_realizn 3550 3550 0 0.00 0 2190 5 0 0
v_min_id_clo_ord 5080 5080 0 0.00 0 2421 6 0 0
v_min_id_clo_res 951 951 0 0.00 0 4637 16 0 0
v_min_id_ord_sup 5066 5066 0 0.00 0 5808 13 0 0
v_min_non_paid_invoice 5253 5253 0 0.00 0 6496 17 0 0
v_min_non_paid_realizn 6625 6625 0 0.00 0 4750 16 0 0
v_our_firm 9572 9572 0 0.00 0 80 1 0 0
v_reserve_write_off 18638 18638 0 0.00 0 8357 5 0 0
x_lock_depdocs_on_canc_invoice 11935 11935 0 0.00 0 18031 100 0 0
x_lock_depdocs_on_canc_sup_ord 399 399 0 0.00 0 9118 324 0 0
x_make_qstorno_1000_1200 5081 5081 0 0.00 2 40380 1143 0 0
x_make_qstorno_1200_2000 5068 5068 0 0.00 3 87118 3175 0 0
x_make_qstorno_2100_3300 422545 422545 0 0.00 0 71701 150 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-12-2020 07:39:26 to 02-12-2020 07:39:30.

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
05:06:49 8 8192000000 8796641392 8807174144 3168704 0.038 1 1 2 0 3266400 61840 35856
05:07:50 21871 8192000000 9736063744 10036514816 814248416 9.040 100 90 180 0 843492144 8011200 21232528
05:09:15 21567 8192000000 9773837360 10083975168 846230864 9.362 100 84 168 2 883252912 17610304 19411744
05:10:42 15512 8192000000 9807561680 10125746176 877565376 9.675 100 86 171 0 918056784 21246928 19244480
05:12:12 17782 8192000000 9892628240 10224418816 919501328 10.091 100 84 166 1 984547392 45994752 19051312
05:13:32 9993 8192000000 9934219440 10283118592 972356896 10.610 100 80 160 0 1045304240 56173696 16773648
05:14:46 4319 8192000000 9975551776 10335285248 1003934528 10.917 100 75 150 3 1091012688 72063984 15014176
05:15:53 3819 8192000000 10006086944 10371145728 1028273248 11.152 100 84 168 2 1121735248 76215552 17246448
05:16:59 2125 8192000000 10029682544 10395213824 1048606736 11.347 100 89 180 0 1137118224 70727776 17783712
05:18:03 6862 8192000000 10046881536 10435158016 1070067872 11.553 100 71 142 1 1147189664 62332000 14789792
05:19:17 4650 8192000000 10011201184 10409373696 1075386224 11.603 100 26 50 0 1124680512 43909232 5385056
05:20:22 6719 8192000000 10004832800 10400964608 1085174416 11.697 100 46 90 2 1119322128 25141840 9005872
05:21:31 4217 8192000000 10008499104 10408259584 1084611520 11.691 100 44 89 1 1128381584 34728000 9042064
05:22:36 52337 8192000000 10008684448 10402639872 1086916832 11.713 100 46 92 2 1109495968 12667328 9911808
05:24:31 7675 8192000000 10005622736 10410430464 1094082368 11.781 100 39 79 3 1132506192 29350752 9073072
05:25:41 4372 8192000000 10016006336 10415861760 1103809216 11.874 100 36 72 3 1133301696 21710416 7782064
05:26:46 8188 8192000000 10013187232 10421964800 1087817744 11.722 100 31 62 2 1107285280 12621072 6846464
05:27:57 33639 8192000000 9961601936 10357727232 993913664 10.819 92 30 57 3 1016897392 16345360 6638368
05:29:34 3376 8192000000 9957337248 10330652672 1018626016 11.059 98 53 106 2 1040943696 10061344 12256336
05:30:38 10049 8192000000 9919157632 10296836096 992969504 10.810 98 29 57 4 1015335984 15522576 6843904
05:31:52 7267 8192000000 9947317088 10341888000 1037306944 11.239 100 30 60 0 1066834144 23058272 6468928
05:33:01 24252 8192000000 9960652640 10370744320 1025689696 11.127 99 23 46 0 1057804464 27702384 4412384
05:34:26 8684 8192000000 9989518800 10389331968 1050137184 11.362 100 59 118 4 1083628080 21125632 12365264
05:35:37 9255 8192000000 9995713424 10398613504 1063916880 11.494 100 39 78 2 1095820752 24173248 7730624
05:36:51 24276 8192000000 10012582768 10404868096 1090831872 11.751 100 35 68 0 1113625856 15490192 7303792
05:38:19 72473 8192000000 10055733744 10465288192 1112291728 11.954 100 33 64 1 1178592096 59406800 6893568
05:40:41 11534 8192000000 10118878112 10530197504 1139331840 12.209 100 34 66 3 1229443024 83065856 7045328
05:41:56 10633 8192000000 10161694176 10567323648 1149256608 12.303 100 57 113 0 1270320368 108557648 12506112
05:43:11 8770 8192000000 10222637712 10631532544 1162788000 12.429 100 52 102 3 1339570848 165958688 10824160
05:44:27 7484 8192000000 10227512928 10642845696 1157553312 12.380 99 52 100 2 1343136896 173666080 11917504
05:45:41 6836 8192000000 10326221136 10739343360 1181613168 12.605 100 68 136 1 1433218256 235717904 15887184
05:46:51 3454 8192000000 10366794496 10776113152 1203956432 12.813 100 67 134 0 1466501664 246082288 16462944
05:47:58 11659 8192000000 10445103632 10861481984 1192194992 12.704 100 81 162 0 1543889824 332673600 19021232
05:49:16 9526 8192000000 10459452640 10885775360 1196730512 12.746 100 56 112 2 1569346512 358884768 13731232
05:50:34 1509 8192000000 10457499536 10895310848 1192029344 12.702 100 86 167 3 1556356784 343563664 20763776
05:51:39 4306 8192000000 10457697792 10909790208 1189844592 12.682 100 75 150 0 1564419952 356145408 18429952
05:52:47 15299 8192000000 10420243200 10886135808 1173739536 12.532 99 38 76 1 1540482000 357012976 9729488
05:54:05 6218 8192000000 10450798016 10875850752 1152837328 12.336 100 53 106 1 1551452384 384741488 13873568
05:55:42 20761 8192000000 10361509248 10832162816 1129144768 12.113 99 28 56 2 1490526928 353089104 8293056
05:57:27 76900 8192000000 10052586432 10497392640 1064659776 11.501 96 14 28 0 1160520080 92841520 3018784
05:59:45 18638 8192000000 10069961312 10509369344 1105784720 11.892 99 26 51 3 1158371728 47778832 4808176
06:01:08 58256 8192000000 10093887952 10573950976 1078315120 11.631 97 10 20 1 1175574304 95340272 1918912
06:03:18 33210 8192000000 10057000096 10479194112 1102946960 11.866 99 20 39 1 1131084080 23021904 5115216
06:04:53 137906 8192000000 10058426144 10491736064 1091246080 11.755 98 45 90 6 1142300384 41665552 9388752
06:09:01 39796 8192000000 10062345920 10574905344 1103376096 11.870 98 12 24 0 1164443200 58715904 2351200
06:10:46 50898 8192000000 10066250944 10515423232 1103211104 11.868 99 10 20 1 1170609968 65434480 1964384
06:12:59 57942 8192000000 10055428496 10559352832 1091869440 11.760 97 22 44 1 1183638992 86928128 4841424
06:15:02 18869 8192000000 10114010720 10537783296 1122710480 12.053 100 66 131 2 1200545376 64543344 13291552
06:16:28 39506 8192000000 10108337024 10546520064 1122327952 12.049 99 41 81 4 1211858240 80273712 9256576
06:18:07 57826 8192000000 10116582544 10588389376 1132896784 12.149 100 18 36 1 1252225488 115416768 3911936
06:20:14 55125 8192000000 10124845232 10603806720 1120895904 12.035 99 26 50 0 1227241632 101289376 5056352
06:22:26 26056 8192000000 10093894304 10513825792 1125273968 12.077 99 37 73 1 1169671968 37056704 7341296
06:23:59 82927 8192000000 10082411232 10523648000 1135025024 12.169 100 22 44 2 1165570128 25482672 5062432
06:26:45 20479 8192000000 10065447584 10527440896 1131403904 12.135 99 5 10 0 1167228832 35073200 751728
06:28:30 50564 8192000000 10084533840 10567553024 1145546096 12.268 100 9 18 3 1195801104 48424368 1830640
06:30:24 59833 8192000000 10098868432 10543325184 1107962432 11.913 96 23 45 3 1169658448 56702288 4993728
06:32:31 26917 8192000000 10133068032 10566348800 1118694624 12.015 98 26 52 1 1214982304 90247936 6039744
06:33:58 50530 8192000000 10158958112 10650263552 1134955552 12.168 99 20 40 1 1240469520 100693216 4820752
06:35:49 49109 8192000000 10109114928 10616377344 1137846992 12.195 100 18 36 0 1252131760 110214672 4070096
06:37:39 81856 8192000000 10087021168 10552016896 1094453488 11.785 96 15 30 0 1157001872 59720368 2828016
06:40:01 123189 8192000000 10094896304 10558861312 1073469072 11.585 94 23 46 2 1133455472 55535280 4451120
06:43:05 121010 8192000000 10059757536 10524971008 1113927440 11.970 99 19 38 0 1161278064 43389184 3961440
06:46:06 78801 8192000000 10093678624 10583179264 1132148672 12.142 100 11 22 2 1187226512 52636784 2441056
06:48:25 63052 8192000000 10097772144 10551787520 1101761760 11.854 97 18 37 1 1167975184 61490384 4723040
06:50:32 65064 8192000000 10100016832 10536890368 1072832672 11.579 95 20 40 1 1117204272 39946928 4424672
06:52:41 78693 8192000000 10070350352 10567589888 1087562704 11.719 97 20 40 0 1196003424 103950192 4490528
06:55:10 31194 8192000000 10113575920 10546003968 1115273232 11.982 99 33 66 0 1173718288 51710320 6734736
06:56:42 68235 8192000000 10086171008 10581598208 1120522336 12.032 99 5 10 0 1185888592 64414464 951792
06:58:51 21311 8192000000 10120610368 10540257280 1134784304 12.166 100 41 82 0 1206735248 63485008 8465936
07:00:14 76177 8192000000 10114758272 10606841856 1131217888 12.133 99 18 36 0 1210727200 75465008 4044304
07:02:44 20441 8192000000 10082393408 10618302464 1130493936 12.126 100 3 6 0 1218850032 87701552 654544
07:04:08 54631 8192000000 10077156880 10524459008 1080398144 11.651 96 25 49 1 1142896176 57117120 5380912
07:06:04 99869 8192000000 10069058256 10584276992 1110422128 11.936 98 17 34 2 1193221632 79122496 3677008
07:08:44 61836 8192000000 10056397744 10491232256 1113889408 11.969 98 17 32 2 1153383744 36249328 3245008
07:10:46 57289 8192000000 10089301152 10562441216 1124649168 12.071 99 31 62 2 1183467616 52719344 6099104
07:12:54 30467 8192000000 10093392336 10506612736 1120687712 12.033 99 49 98 2 1162164880 31571136 9906032
07:14:27 93598 8192000000 10103645328 10580627456 1109587360 11.929 97 17 33 1 1189134608 75773680 3773568
07:17:00 142736 8192000000 10063966656 10513911808 1131054352 12.131 99 21 42 1 1154160480 18763744 4342384
07:20:36 101617 8192000000 10066527744 10525401088 1106227696 11.897 97 15 30 1 1140698816 31329600 3141520
07:23:26 39430 8192000000 10077126608 10518016000 1146705152 12.279 100 24 48 1 1174730736 22949744 5075840
07:25:06 59518 8192000000 10111053776 10569023488 1131270960 12.133 98 25 50 0 1173919456 36752000 5896496
07:27:07 50598 8192000000 10155635600 10597883904 1148508464 12.295 99 19 38 1 1227077216 74361488 4207264
07:29:26 116197 8192000000 10146913456 10679840768 1107355648 11.907 96 8 16 0 1205409056 96402848 1650560
07:32:24 116379 8192000000 10082331216 10558885888 1054659600 11.405 92 19 37 3 1094174336 35802224 3712512
07:35:36 80461 8192000000 8884083872 9039073280 61899792 0.749 6 3 6 0 63288288 1053392 335104

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 02-12-2020 07:39:32 to 02-12-2020 07:39:47.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 146 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 02-12-2020 07:39:48 to 02-12-2020 07:39:50.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          228216
MON$OLDEST_ACTIVE               228217
MON$OLDEST_SNAPSHOT             228217
MON$NEXT_TRANSACTION            228217
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                       2095488
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             786
MON$NEXT_STATEMENT              53


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

02.12.20 07:39:51. 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-02 07:39:53 No severe PSQL-related problems occured
02.12.20 07:39:55. End of report.