Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.02.2021 04: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 16597040 kB
MemAvailable 31149860 kB
Buffers 20 kB
Cached 15057940 kB
SwapCached 4096 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 2021-02-17 04:26:56
DTS_END                         2021-02-17 04:26:56.6690
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_CLIENT_ORDER(32:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/35122
TRN_ID                          225385
ATT_ID                          672
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 9050.66 28903 1086079
customer order: creation 160.60 443 19272
customer order: refuse 13.56 342 1627
order to supplier: creation 42.99 1403 5159
order to supplier: removal 3.58 126671 429
invoice (draft): creation 43.85 3689 5262
invoice (draft): removal 50.48 1089 6057
invoice accept: apply 40.28 19984 4834
invoice accept: cancel 45.48 984 5457
customer reserve: creation 3823.65 193 458838
customer reserve: removal 4541.90 78 545028
realization accept: apply 79.64 189 9557
realization accept: cancel 81.16 144 9739
payment from customer: creation 57.04 118 6845
payment from customer: removal 5.40 105 648
payment to supplier: creation 46.78 131 5613
payment to supplier: removal 3.31 106 397
service: total inventory turnovers 4.07 3498 488
service: total monetary turnovers 5.98 637 718
service: aggregate perf. data 0.89 16238 107
service: refresh index statistics 0.03 402015 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-02-2021 04:29:02 to 17-02-2021 04:29:04.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2117
TEST_TIME 2 3031
TEST_TIME 3 3975
TEST_TIME 4 4223
TEST_TIME 5 4516
TEST_TIME 6 4539
TEST_TIME 7 4310
TEST_TIME 8 4402
TEST_TIME 9 4783
TEST_TIME 10 5322
TEST_TIME 11 5148
TEST_TIME 12 5556
TEST_TIME 13 5714
TEST_TIME 14 6008
TEST_TIME 15 6055
TEST_TIME 16 6608
TEST_TIME 17 6547
TEST_TIME 18 6692
TEST_TIME 19 6940
TEST_TIME 20 7225
TEST_TIME 21 7270
TEST_TIME 22 7545
TEST_TIME 23 7763
TEST_TIME 24 7720
TEST_TIME 25 7919
TEST_TIME 26 8063
TEST_TIME 27 8316
TEST_TIME 28 8423
TEST_TIME 29 9426
TEST_TIME 30 10743
TEST_TIME 31 10820
TEST_TIME 32 10653
TEST_TIME 33 10592
TEST_TIME 34 10344
TEST_TIME 35 10121
TEST_TIME 36 10093
TEST_TIME 37 9935
TEST_TIME 38 9883
TEST_TIME 39 9914
TEST_TIME 40 9875
TEST_TIME 41 9807
TEST_TIME 42 9710
TEST_TIME 43 9574
TEST_TIME 44 9665
TEST_TIME 45 9591
TEST_TIME 46 9720
TEST_TIME 47 10033
TEST_TIME 48 9985
TEST_TIME 49 9985
TEST_TIME 50 9986
TEST_TIME 51 9973
TEST_TIME 52 9994
TEST_TIME 53 9997
TEST_TIME 54 9928
TEST_TIME 55 9903
TEST_TIME 56 9887
TEST_TIME 57 10057
TEST_TIME 58 9999
TEST_TIME 59 10011
TEST_TIME 60 9866
TEST_TIME 61 9765
TEST_TIME 62 9676
TEST_TIME 63 9715
TEST_TIME 64 9612
TEST_TIME 65 9542
TEST_TIME 66 9495
TEST_TIME 67 9532
TEST_TIME 68 9502
TEST_TIME 69 9452
TEST_TIME 70 9395
TEST_TIME 71 9471
TEST_TIME 72 9443
TEST_TIME 73 9487
TEST_TIME 74 9437
TEST_TIME 75 9553
TEST_TIME 76 9630
TEST_TIME 77 9582
TEST_TIME 78 9598
TEST_TIME 79 9576
TEST_TIME 80 9579
TEST_TIME 81 9623
TEST_TIME 82 9587
TEST_TIME 83 9476
TEST_TIME 84 9417
TEST_TIME 85 9367
TEST_TIME 86 9296
TEST_TIME 87 9259
TEST_TIME 88 9250
TEST_TIME 89 9214
TEST_TIME 90 9171
TEST_TIME 91 9134
TEST_TIME 92 9188
TEST_TIME 93 9205
TEST_TIME 94 9235
TEST_TIME 95 9274
TEST_TIME 96 9283
TEST_TIME 97 9279
TEST_TIME 98 9249
TEST_TIME 99 9264
TEST_TIME 100 9185
TEST_TIME 101 9165
TEST_TIME 102 9139
TEST_TIME 103 9079
TEST_TIME 104 9077
TEST_TIME 105 9089
TEST_TIME 106 9083
TEST_TIME 107 9070
TEST_TIME 108 9054
TEST_TIME 109 9065
TEST_TIME 110 9103
TEST_TIME 111 9055
TEST_TIME 112 9125
TEST_TIME 113 9119
TEST_TIME 114 9131
TEST_TIME 115 9138
TEST_TIME 116 9184
TEST_TIME 117 9192
TEST_TIME 118 9139
TEST_TIME 119 9068
TEST_TIME 120 9053

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 17-02-2021 04:29:05 to 17-02-2021 04:29:09.

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 1508946 1508946 0 0.00 0 17298 8 0 0
doc_list_biud 1508945 1508945 0 0.00 0 25883 35 0 0
sp_add_invoice_to_stock 4902 4834 68 1.38 25 208983 19984 0 68
sp_cancel_adding_invoice 5457 5457 0 0.00 5 90186 984 0 0
sp_cancel_client_order 1627 1627 0 0.00 5 9717 342 0 0
sp_cancel_customer_reserve 545028 545028 0 0.00 1 23721 79 0 0
sp_cancel_pay_from_customer 648 648 0 0.00 3 14917 106 0 0
sp_cancel_pay_to_supplier 397 397 0 0.00 3 9583 106 0 0
sp_cancel_supplier_invoice 6062 6057 5 0.08 1 25889 1090 0 5
sp_cancel_supplier_order 429 429 0 0.00 6 1210039 126672 0 0
sp_cancel_write_off 9739 9739 0 0.00 1 9178 145 0 0
sp_client_order 19272 19272 0 0.00 4 17166 443 0 0
sp_customer_reserve 458838 458838 0 0.00 0 46013 193 0 0
sp_fill_shopping_cart 29671 29671 0 0.00 1 9098 38 0 0
sp_fill_shopping_cart_clo_res 458829 458829 0 0.00 0 25729 17 0 0
sp_get_clo_for_invoice 4824 4824 0 0.00 0 27213 335 0 0
sp_kill_cost_storno 16241 16241 0 0.00 0 9124 69 0 0
sp_kill_qstorno_ret_qs2qd 1106281 1106281 0 0.00 0 25882 43 0 0
sp_kill_qty_storno 582717 582717 0 0.00 0 25883 91 0 0
sp_lock_selected_doc 19913 19913 0 0.00 0 10860 6 0 0
sp_make_cost_storno 26838 26838 0 0.00 0 10782 57 0 0
sp_multiply_rows_for_pdistr 26838 26838 0 0.00 0 9634 34 0 0
sp_multiply_rows_for_qdistr 19272 19272 0 0.00 0 17071 249 0 0
sp_pay_from_customer 6845 6845 0 0.00 4 16199 118 0 0
sp_pay_to_supplier 5613 5613 0 0.00 5 16239 132 0 0
sp_payment_common 12458 12458 0 0.00 1 8912 19 0 0
sp_qd_handle_on_cancel_clo 1627 1627 0 0.00 0 9710 312 0 0
sp_qd_handle_on_invoice_upd_sts 10280 10280 0 0.00 0 23219 413 0 0
sp_qd_handle_on_reserve_upd_sts 9557 9557 0 0.00 0 19929 54 0 0
sp_reserve_write_off 9557 9557 0 0.00 7 24148 189 0 0
sp_supplier_invoice 5262 5262 0 0.00 8 94809 3689 0 0
sp_supplier_order 5159 5159 0 0.00 6 96266 1403 0 0
srv_aggregate_perf_data 107 107 0 0.00 2629 371833 16238 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1095 139337 43890 0 0
srv_find_qd_qs_mism 1039095 1039095 0 0.00 0 11026 7 0 0
srv_make_invnt_saldo 488 488 0 0.00 32 89664 3499 0 0
srv_make_money_saldo 718 718 0 0.00 1 15298 637 0 0
srv_recalc_idx_stat 4 4 0 0.00 204413 564966 402016 0 0
t$perf-abend:sp_add_invoice_to_stock 68 68 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4834 4834 0 0.00 0 21599 190 0 0
t$perf-norm:sp_cancel_adding_invoice 421 421 0 0.00 0 2879 81 0 0
t$perf-norm:sp_cancel_client_order 1627 1627 0 0.00 0 10110 15 0 0
t$perf-norm:sp_cancel_customer_reserve 679 679 0 0.00 0 5904 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 648 648 0 0.00 0 1863 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 397 397 0 0.00 0 2602 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 356 356 0 0.00 0 4450 20 0 0
t$perf-norm:sp_cancel_supplier_order 429 429 0 0.00 0 27898 1626 0 0
t$perf-norm:sp_cancel_write_off 979 979 0 0.00 0 612 4 0 0
t$perf-norm:sp_client_order 19272 19272 0 0.00 0 7863 20 0 0
t$perf-norm:sp_customer_reserve 1013 1013 0 0.00 0 1860 6 0 0
t$perf-norm:sp_pay_from_customer 6845 6845 0 0.00 0 8323 10 0 0
t$perf-norm:sp_pay_to_supplier 5613 5613 0 0.00 0 7430 11 0 0
t$perf-norm:sp_reserve_write_off 9557 9557 0 0.00 0 6562 10 0 0
t$perf-norm:sp_supplier_invoice 5262 5262 0 0.00 0 8881 28 0 0
t$perf-norm:sp_supplier_order 5159 5159 0 0.00 0 5181 13 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 430 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 146 3 0 0
t$perf-norm:srv_make_invnt_saldo 488 488 0 0.00 0 628 6 0 0
t$perf-norm:srv_make_money_saldo 718 718 0 0.00 0 4663 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 32 9 0 0
v_add_invoice_to_stock 9776 9640 136 1.39 0 11385 17 0 136
v_all_customers 35750 35750 0 0.00 0 23 1 0 0
v_all_suppliers 29484 29484 0 0.00 0 20 1 0 0
v_all_wares 38544 38544 0 0.00 0 149 1 0 0
v_cancel_adding_invoice 842 842 0 0.00 0 2207 9 0 0
v_cancel_client_order 3254 3254 0 0.00 0 1057 3 0 0
v_cancel_customer_prepayment 1296 1296 0 0.00 0 1811 5 0 0
v_cancel_customer_reserve 1358 1358 0 0.00 0 901 4 0 0
v_cancel_payment_to_supplier 794 794 0 0.00 0 805 5 0 0
v_cancel_supplier_invoice 722 712 10 1.38 0 4217 18 0 10
v_cancel_supplier_order 858 858 0 0.00 0 432 4 0 0
v_cancel_write_off 1958 1958 0 0.00 0 3235 16 0 0
v_max_id_clo_ord 5146 5146 0 0.00 0 1325 1 0 0
v_max_id_clo_res 1013 1013 0 0.00 0 4564 20 0 0
v_max_id_ord_sup 5253 5253 0 0.00 0 4972 6 0 0
v_max_non_paid_invoice 1270 1270 0 0.00 0 346 2 0 0
v_max_non_paid_realizn 3431 3431 0 0.00 0 4569 5 0 0
v_min_id_clo_ord 5146 5146 0 0.00 0 4744 6 0 0
v_min_id_clo_res 1013 1013 0 0.00 0 2540 6 0 0
v_min_id_ord_sup 5253 5253 0 0.00 0 4351 6 0 0
v_min_non_paid_invoice 5613 5613 0 0.00 0 6286 9 0 0
v_min_non_paid_realizn 6845 6845 0 0.00 0 8907 15 0 0
v_our_firm 9622 9622 0 0.00 0 135 1 0 0
v_reserve_write_off 19114 19114 0 0.00 0 10782 6 0 0
x_lock_depdocs_on_canc_invoice 11158 11158 0 0.00 0 21109 128 0 0
x_lock_depdocs_on_canc_sup_ord 429 429 0 0.00 0 22149 419 0 0
x_make_qstorno_1000_1200 5159 5159 0 0.00 1 96252 1323 0 0
x_make_qstorno_1200_2000 5262 5262 0 0.00 3 94795 3552 0 0
x_make_qstorno_2100_3300 458838 458838 0 0.00 0 46012 174 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-02-2021 04:29:10 to 17-02-2021 04:29:14.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
01:57:00 6 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
01:58:01 19155 8192000000 9735672768 10031480832 810414096 9.002 100 90 180 0 839711120 7505680 21791344
01:59:29 9219 8192000000 9765668656 10068697088 839659792 9.296 100 92 184 0 873276256 11811104 21805360
02:00:45 26795 8192000000 9796001040 10110517248 864902336 9.549 100 86 172 1 903862368 19027424 19932608
02:02:33 18033 8192000000 9873906160 10205503488 909786928 9.995 100 76 152 0 983795104 57357520 16650656
02:03:55 11719 8192000000 9905942256 10249043968 946687584 10.359 100 80 160 1 1011969216 48414544 16867088
02:05:10 6213 8192000000 9954550928 10304929792 992712272 10.808 100 77 155 0 1067827440 59675504 15439664
02:06:21 3930 8192000000 9999000528 10358943744 1020189872 11.074 100 82 160 1 1103405808 66453552 16762384
02:07:27 2911 8192000000 10019200240 10385076224 1043473936 11.298 100 84 166 1 1130959664 70556976 16928752
02:08:32 2047 8192000000 10043825920 10416209920 1058335968 11.441 100 85 169 0 1159384112 83953552 17094592
02:09:37 2296 8192000000 10058307280 10441183232 1069054128 11.543 100 69 138 0 1167989296 84732064 14203104
02:10:40 8671 8192000000 9985104960 10387783680 1072751920 11.578 100 34 66 2 1116675248 36452112 7471216
02:11:49 5204 8192000000 9987557920 10386677760 1084454320 11.690 100 41 84 1 1117642032 23707488 9480224
02:12:56 5884 8192000000 10019977520 10422263808 1092421568 11.766 100 33 66 1 1123754400 24020976 7311856
02:14:02 41742 8192000000 10020177328 10411778048 1094339184 11.784 100 59 121 1 1118265520 10496096 13430240
02:15:44 3440 8192000000 10010989632 10407256064 1098367120 11.822 100 53 107 1 1118360416 6911984 13081312
02:16:49 8765 8192000000 10032335136 10441502720 1100603808 11.843 100 46 90 5 1143993568 33374224 10015536
02:17:59 6348 8192000000 10014731600 10414989312 1088670016 11.730 99 38 76 0 1121952960 25372928 7910016
02:19:05 77946 8192000000 9942731776 10343342080 939858256 10.292 88 22 44 1 955953248 11417536 4677456
02:21:25 4935 8192000000 9963774512 10347802624 1000723568 10.886 95 43 84 3 1033951808 23235328 9992912
02:22:32 5266 8192000000 9924316224 10310377472 996615568 10.846 97 41 84 0 1027866240 21719520 9531152
02:23:39 7567 8192000000 9958784960 10344775680 1027768928 11.147 100 50 97 2 1052351744 13997088 10585728
02:24:51 69660 8192000000 9953227424 10365255680 1046344592 11.326 100 19 38 1 1079441920 28560256 4537072
02:27:03 4123 8192000000 9984273344 10374914048 1059034416 11.447 100 49 97 0 1089150512 20035568 10080528
02:28:22 27277 8192000000 10026572608 10420236288 1073965712 11.590 100 49 95 2 1138594464 54509744 10119008
02:29:53 8633 8192000000 10047890256 10449862656 1095332496 11.793 100 49 98 1 1168911728 63003440 10575792
02:31:05 23911 8192000000 10072335712 10477314048 1106664544 11.901 100 35 70 1 1199828128 85709776 7453808
02:32:33 52166 8192000000 10124014096 10546466816 1111198272 11.944 100 38 73 1 1243453136 123603200 8651664
02:34:36 15435 8192000000 10152649600 10560708608 1122200192 12.048 100 31 60 2 1251069392 121816576 7052624
02:35:57 5401 8192000000 10168171072 10589790208 1132187440 12.142 100 45 90 0 1275560016 133673904 9698672
02:37:15 26679 8192000000 10214485568 10621956096 1150385936 12.313 100 59 118 1 1316032528 152790032 12856560
02:38:51 9252 8192000000 10229123536 10639699968 1164956592 12.450 100 61 120 1 1333867952 155683600 13227760
02:40:06 1094 8192000000 10298407296 10698989568 1178382592 12.575 100 95 188 0 1404511712 205927840 20201280
02:41:14 7873 8192000000 10326474640 10734227456 1182978912 12.618 100 76 152 0 1421184128 221288160 16917056
02:42:25 6549 8192000000 10355593984 10784980992 1192596176 12.708 100 81 161 1 1449354704 238739392 18019136
02:43:37 3790 8192000000 10356181488 10765709312 1203940832 12.813 100 82 164 0 1452253280 230494336 17818112
02:44:45 2636 8192000000 10408174736 10822742016 1219935184 12.961 100 83 167 0 1512637808 273803040 18899584
02:45:54 1420 8192000000 10450600608 10861256704 1212298576 12.890 100 87 174 0 1546474928 313657856 20518496
02:46:58 12634 8192000000 10431693856 10874343424 1171031488 12.506 98 45 87 0 1539178224 356763712 11383024
02:48:17 14749 8192000000 10466155600 10904039424 1160128448 12.404 97 42 84 0 1545488416 374509424 10850544
02:49:38 3630 8192000000 10502154032 10919329792 1194964432 12.730 100 66 131 3 1598781152 388020608 15796112
02:50:45 11627 8192000000 10517576976 10959888384 1165166912 12.452 99 47 94 0 1612461872 434753872 12541088
02:52:08 25899 8192000000 10524888512 10994085888 1163962496 12.440 99 35 70 0 1633153936 459811008 9380432
02:53:42 18203 8192000000 10539446688 11044327424 1149369344 12.304 100 30 60 2 1652358016 494190048 8798624
02:55:11 19927 8192000000 10407705600 10875215872 1091923744 11.761 98 28 56 0 1498614272 399240112 7450416
02:56:36 90280 8192000000 10051102960 10526633984 1068865760 11.541 95 5 10 0 1101064160 31369712 828688
02:59:07 68739 8192000000 10043987040 10459709440 1086955248 11.714 97 31 62 0 1128665568 34647264 7063056
03:01:43 24506 8192000000 10074867776 10504433664 1108222720 11.916 99 57 111 2 1165452512 44414240 12815552
03:03:11 29253 8192000000 10067151456 10498916352 1093223936 11.773 98 42 85 1 1140347040 38043312 9079792
03:04:44 9572 8192000000 10089554448 10518937600 1118209376 12.010 100 36 71 0 1173004240 47692384 7102480
03:05:56 28229 8192000000 10096416160 10524286976 1103041360 11.866 98 50 99 2 1169795808 55003952 11750496
03:07:25 15633 8192000000 10119710640 10564468736 1131638624 12.137 100 30 60 0 1225044752 87361376 6044752
03:08:44 59189 8192000000 10185523120 10634297344 1105023760 11.885 97 25 50 1 1262149728 151110736 6015232
03:10:44 58965 8192000000 10164080880 10595688448 1114971184 11.979 99 18 36 0 1274543904 155545728 4026992
03:13:30 55807 8192000000 10069334416 10495434752 1126816704 12.091 100 24 48 0 1224133184 92136000 5180480
03:15:27 82577 8192000000 10076477248 10541060096 1111709600 11.949 97 13 26 0 1159858480 45511216 2637664
03:17:54 36656 8192000000 10126573168 10559029248 1149553120 12.305 100 49 98 1 1206093776 46495232 10045424
03:20:20 28835 8192000000 10113027600 10540863488 1120886496 12.035 98 39 77 1 1157496784 28908832 7701456
03:21:53 69805 8192000000 10191401184 10667892736 1148358592 12.294 100 24 48 0 1276433568 123002432 5072544
03:24:18 50554 8192000000 10118275856 10552111104 1116656400 11.995 97 22 44 0 1161179600 39720896 4802304
03:26:09 139337 8192000000 10107860576 10585219072 1158610816 12.390 100 21 42 0 1228847744 65848544 4388384
03:29:44 46236 8192000000 10090167760 10567106560 1143245776 12.246 98 8 16 0 1190006688 45427200 1333712
03:31:30 67000 8192000000 10118742624 10572652544 1126916752 12.092 97 17 35 0 1182813136 52115312 3781072
03:33:38 50369 8192000000 10070625008 10518331392 1127200080 12.095 98 12 24 0 1155472096 26227760 2044256
03:35:46 44619 8192000000 10115591680 10558541824 1141977088 12.234 99 28 56 0 1188378416 40320736 6080592
03:37:38 30708 8192000000 10086817664 10518822912 1116532672 11.994 97 24 48 0 1152861040 31683168 4645200
03:39:10 84205 8192000000 10122112160 10572673024 1107688736 11.911 96 23 46 1 1212320368 99534304 5097328
03:41:54 70864 8192000000 10071054288 10540953600 1116832464 11.997 98 11 21 2 1168952704 50260032 1860208
03:45:05 65817 8192000000 10078217040 10538938368 1107792672 11.912 98 30 60 1 1173197920 58913792 6491456
03:47:14 55142 8192000000 10074482336 10573758464 1081798752 11.665 96 35 70 1 1137965040 48731712 7434576
03:49:11 94839 8192000000 10017463616 10462277632 1084113680 11.687 97 20 40 1 1110051728 21287840 4650208
03:52:02 61334 8192000000 10058653536 10530512896 1106876768 11.903 100 6 12 0 1160137840 51967712 1293360
03:54:16 69374 8192000000 10041595152 10495594496 1076917952 11.618 97 20 40 1 1114708432 33681344 4109136
03:56:32 28427 8192000000 10128962640 10572644352 1132147216 12.142 100 41 82 0 1217847392 76858368 8841808
03:58:02 55040 8192000000 10134584416 10616193024 1120882256 12.035 99 31 62 1 1237720336 110073504 6764576
04:00:05 51344 8192000000 10087941744 10559836160 1107312912 11.907 98 16 32 1 1186875376 76024208 3538256
04:01:57 30259 8192000000 10094691888 10536718336 1117165696 12.000 99 39 78 2 1184759824 59543984 8050144
04:03:29 32295 8192000000 10059819488 10503049216 1089727520 11.740 97 28 56 3 1136052000 40749344 5575136
04:05:09 28398 8192000000 10088178400 10556956672 1107292464 11.907 100 53 105 1 1189994704 72373584 10328656
04:08:08 98559 8192000000 10080942928 10568966144 1122686208 12.052 99 12 24 1 1172272144 46859264 2726672
04:10:47 90734 8192000000 10067251520 10506641408 1103942896 11.875 97 18 36 2 1141884416 34238096 3703424
04:13:27 66292 8192000000 10105356224 10564141056 1130321936 12.124 99 23 46 1 1186822944 51670176 4830832
04:15:38 120586 8192000000 10096201168 10527866880 1099706080 11.835 96 25 50 0 1136748352 31565936 5476336
04:18:42 74586 8192000000 10087706944 10547982336 1123023680 12.056 98 14 28 0 1178881248 53157312 2700256
04:20:57 86973 8192000000 10077232176 10575888384 1117781296 12.006 97 10 20 1 1183476704 63274528 2420880
04:23:24 92907 8192000000 10053980800 10527256576 1118759616 12.015 98 23 45 0 1152312016 28912656 4639744
04:25:58 61038 8192000000 9327346064 9641975808 460220320 5.319 41 9 16 0 471357744 9506160 1631264

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 17-02-2021 04:29:16 to 17-02-2021 04:29:31.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 68 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-02-2021 04:29:32 to 17-02-2021 04:29:34.

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          225689
MON$OLDEST_ACTIVE               225690
MON$OLDEST_SNAPSHOT             225690
MON$NEXT_TRANSACTION            225690
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                       2085024
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                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             778
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

17.02.21 04:29:35. 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
2021-02-17 04:29:37 No severe PSQL-related problems occured
17.02.21 04:29:38. End of report.