Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.10.2020 04:27
Common Performance Final Results
  1. Hardware and OS info
  2. DB and 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, full
  3. Ratio "Versions / Records" for tables
  4. Database Validation Results
  5. New in firebird.log while test was run
  6. 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 16887968 kB
MemAvailable 31492072 kB
Buffers 20 kB
Cached 14709612 kB
SwapCached 3048 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-10-24 04:26:53
DTS_END                         2020-10-24 04:26:53.0050
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(67:17)
IP                              ::1/34436
TRN_ID                          228525
ATT_ID                          564
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: 9043.39 23145 1085207
customer order: creation 167.28 399 20073
customer order: refuse 14.72 317 1766
order to supplier: creation 45.13 1306 5415
order to supplier: removal 3.31 125233 397
invoice (draft): creation 44.73 3283 5368
invoice (draft): removal 50.76 868 6091
invoice accept: apply 40.83 17289 4899
invoice accept: cancel 45.89 860 5507
customer reserve: creation 3821.95 167 458634
customer reserve: removal 4522.89 74 542747
realization accept: apply 81.36 177 9763
realization accept: cancel 79.18 126 9501
payment from customer: creation 57.71 110 6925
payment from customer: removal 5.65 81 678
payment to supplier: creation 47.74 127 5729
payment to supplier: removal 3.40 78 408
service: total inventory turnovers 3.79 3232 455
service: total monetary turnovers 6.03 492 723
service: aggregate perf. data 1.03 11236 124
service: refresh index statistics 0.03 297442 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-10-2020 04:27:58 to 24-10-2020 04:28:00.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1698
TEST_TIME 2 2056
TEST_TIME 3 3300
TEST_TIME 4 3968
TEST_TIME 5 4368
TEST_TIME 6 4486
TEST_TIME 7 4519
TEST_TIME 8 4554
TEST_TIME 9 4824
TEST_TIME 10 5271
TEST_TIME 11 5608
TEST_TIME 12 5852
TEST_TIME 13 6615
TEST_TIME 14 6754
TEST_TIME 15 7135
TEST_TIME 16 7758
TEST_TIME 17 7666
TEST_TIME 18 7625
TEST_TIME 19 8310
TEST_TIME 20 8379
TEST_TIME 21 8465
TEST_TIME 22 8702
TEST_TIME 23 9101
TEST_TIME 24 9596
TEST_TIME 25 9684
TEST_TIME 26 10731
TEST_TIME 27 10900
TEST_TIME 28 10860
TEST_TIME 29 11420
TEST_TIME 30 11307
TEST_TIME 31 11045
TEST_TIME 32 10766
TEST_TIME 33 10573
TEST_TIME 34 10449
TEST_TIME 35 10346
TEST_TIME 36 10405
TEST_TIME 37 10365
TEST_TIME 38 10207
TEST_TIME 39 10066
TEST_TIME 40 10048
TEST_TIME 41 9867
TEST_TIME 42 9732
TEST_TIME 43 9814
TEST_TIME 44 9904
TEST_TIME 45 9847
TEST_TIME 46 9882
TEST_TIME 47 9897
TEST_TIME 48 9894
TEST_TIME 49 9923
TEST_TIME 50 9875
TEST_TIME 51 9908
TEST_TIME 52 10073
TEST_TIME 53 10094
TEST_TIME 54 10173
TEST_TIME 55 10082
TEST_TIME 56 9916
TEST_TIME 57 9815
TEST_TIME 58 9770
TEST_TIME 59 9676
TEST_TIME 60 9679
TEST_TIME 61 9627
TEST_TIME 62 9623
TEST_TIME 63 9603
TEST_TIME 64 9566
TEST_TIME 65 9700
TEST_TIME 66 9667
TEST_TIME 67 9651
TEST_TIME 68 9683
TEST_TIME 69 9728
TEST_TIME 70 9727
TEST_TIME 71 9727
TEST_TIME 72 9749
TEST_TIME 73 9762
TEST_TIME 74 9793
TEST_TIME 75 9684
TEST_TIME 76 9598
TEST_TIME 77 9557
TEST_TIME 78 9457
TEST_TIME 79 9404
TEST_TIME 80 9373
TEST_TIME 81 9315
TEST_TIME 82 9272
TEST_TIME 83 9276
TEST_TIME 84 9302
TEST_TIME 85 9318
TEST_TIME 86 9303
TEST_TIME 87 9326
TEST_TIME 88 9313
TEST_TIME 89 9337
TEST_TIME 90 9310
TEST_TIME 91 9424
TEST_TIME 92 9468
TEST_TIME 93 9368
TEST_TIME 94 9311
TEST_TIME 95 9267
TEST_TIME 96 9250
TEST_TIME 97 9239
TEST_TIME 98 9236
TEST_TIME 99 9195
TEST_TIME 100 9157
TEST_TIME 101 9139
TEST_TIME 102 9145
TEST_TIME 103 9149
TEST_TIME 104 9147
TEST_TIME 105 9323
TEST_TIME 106 9317
TEST_TIME 107 9315
TEST_TIME 108 9338
TEST_TIME 109 9303
TEST_TIME 110 9222
TEST_TIME 111 9205
TEST_TIME 112 9175
TEST_TIME 113 9129
TEST_TIME 114 9095
TEST_TIME 115 9093
TEST_TIME 116 9067
TEST_TIME 117 9079
TEST_TIME 118 9059
TEST_TIME 119 9076
TEST_TIME 120 9046

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 24-10-2020 04:28:01 to 24-10-2020 04:28:05.

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 1508992 1508992 0 0.00 0 19617 7 0 0
doc_list_biud 1508992 1508992 0 0.00 0 43475 33 0 0
sp_add_invoice_to_stock 5000 4899 101 2.02 19 189528 17289 0 101
sp_cancel_adding_invoice 5507 5507 0 0.00 6 81768 860 0 0
sp_cancel_client_order 1766 1766 0 0.00 4 9113 317 0 0
sp_cancel_customer_reserve 542747 542747 0 0.00 1 38631 74 0 0
sp_cancel_pay_from_customer 678 678 0 0.00 3 13249 81 0 0
sp_cancel_pay_to_supplier 408 408 0 0.00 3 3749 78 0 0
sp_cancel_supplier_invoice 6094 6091 3 0.04 2 43480 868 0 3
sp_cancel_supplier_order 397 397 0 0.00 10 1017061 125233 0 0
sp_cancel_write_off 9501 9501 0 0.00 1 5850 127 0 0
sp_client_order 20073 20073 0 0.00 4 26808 400 0 0
sp_customer_reserve 458634 458634 0 0.00 0 108046 167 0 0
sp_fill_shopping_cart 30851 30851 0 0.00 1 14103 43 0 0
sp_fill_shopping_cart_clo_res 458630 458630 0 0.00 0 19018 18 0 0
sp_get_clo_for_invoice 4895 4895 0 0.00 0 52079 368 0 0
sp_kill_cost_storno 16094 16094 0 0.00 0 5089 58 0 0
sp_kill_qstorno_ret_qs2qd 1101483 1101483 0 0.00 0 43474 39 0 0
sp_kill_qty_storno 580665 580665 0 0.00 0 43475 84 0 0
sp_lock_selected_doc 20351 20351 0 0.00 0 10580 7 0 0
sp_make_cost_storno 27310 27310 0 0.00 0 11460 60 0 0
sp_multiply_rows_for_pdistr 27310 27310 0 0.00 0 6475 31 0 0
sp_multiply_rows_for_qdistr 20073 20073 0 0.00 0 18722 240 0 0
sp_pay_from_customer 6925 6925 0 0.00 4 11029 111 0 0
sp_pay_to_supplier 5729 5729 0 0.00 5 16323 128 0 0
sp_payment_common 12654 12654 0 0.00 1 11914 22 0 0
sp_qd_handle_on_cancel_clo 1766 1766 0 0.00 0 9081 288 0 0
sp_qd_handle_on_invoice_upd_sts 10400 10400 0 0.00 0 30728 388 0 0
sp_qd_handle_on_reserve_upd_sts 9763 9763 0 0.00 0 21666 49 0 0
sp_reserve_write_off 9763 9763 0 0.00 6 21675 178 0 0
sp_supplier_invoice 5368 5368 0 0.00 6 84256 3284 0 0
sp_supplier_order 5415 5415 0 0.00 5 46685 1306 0 0
srv_aggregate_perf_data 124 124 0 0.00 2687 84904 11236 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1269 154160 48378 0 0
srv_find_qd_qs_mism 1037962 1037962 0 0.00 0 19617 6 0 0
srv_make_invnt_saldo 455 455 0 0.00 51 82963 3233 0 0
srv_make_money_saldo 723 723 0 0.00 1 9501 493 0 0
srv_recalc_idx_stat 4 4 0 0.00 192718 371848 297442 0 0
t$perf-abend:sp_add_invoice_to_stock 101 101 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4900 4900 0 0.00 0 10300 160 0 0
t$perf-norm:sp_cancel_adding_invoice 384 384 0 0.00 0 11086 98 0 0
t$perf-norm:sp_cancel_client_order 1766 1766 0 0.00 0 12780 22 0 0
t$perf-norm:sp_cancel_customer_reserve 685 685 0 0.00 0 1846 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 678 678 0 0.00 0 1656 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 408 408 0 0.00 0 2837 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 407 407 0 0.00 0 1848 18 0 0
t$perf-norm:sp_cancel_supplier_order 397 397 0 0.00 0 16305 1482 0 0
t$perf-norm:sp_cancel_write_off 970 970 0 0.00 0 3187 6 0 0
t$perf-norm:sp_client_order 20073 20073 0 0.00 0 16022 20 0 0
t$perf-norm:sp_customer_reserve 969 969 0 0.00 0 1356 4 0 0
t$perf-norm:sp_pay_from_customer 6925 6925 0 0.00 0 11885 11 0 0
t$perf-norm:sp_pay_to_supplier 5729 5729 0 0.00 0 3385 8 0 0
t$perf-norm:sp_reserve_write_off 9763 9763 0 0.00 0 8624 13 0 0
t$perf-norm:sp_supplier_invoice 5368 5368 0 0.00 0 4223 16 0 0
t$perf-norm:sp_supplier_order 5415 5415 0 0.00 0 10159 16 0 0
t$perf-norm:srv_aggregate_perf_data 124 124 0 0.00 0 611 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 507 13 0 0
t$perf-norm:srv_make_invnt_saldo 455 455 0 0.00 0 224 3 0 0
t$perf-norm:srv_make_money_saldo 723 723 0 0.00 0 2315 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 9988 9786 202 2.02 0 5127 15 0 202
v_all_customers 36566 36566 0 0.00 0 14 1 0 0
v_all_suppliers 30216 30216 0 0.00 0 18 1 0 0
v_all_wares 40146 40146 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 768 768 0 0.00 0 6071 25 0 0
v_cancel_client_order 3532 3532 0 0.00 0 4785 7 0 0
v_cancel_customer_prepayment 1356 1356 0 0.00 0 4392 8 0 0
v_cancel_customer_reserve 1370 1370 0 0.00 0 5321 10 0 0
v_cancel_payment_to_supplier 816 816 0 0.00 0 3729 12 0 0
v_cancel_supplier_invoice 820 814 6 0.73 0 1085 15 0 6
v_cancel_supplier_order 794 794 0 0.00 0 1634 7 0 0
v_cancel_write_off 1940 1940 0 0.00 0 5038 16 0 0
v_max_id_clo_ord 5412 5412 0 0.00 0 3244 2 0 0
v_max_id_clo_res 969 969 0 0.00 0 4397 15 0 0
v_max_id_ord_sup 5364 5364 0 0.00 0 8376 7 0 0
v_max_non_paid_invoice 1397 1397 0 0.00 0 451 2 0 0
v_max_non_paid_realizn 3654 3654 0 0.00 0 5447 6 0 0
v_min_id_clo_ord 5412 5412 0 0.00 0 3125 8 0 0
v_min_id_clo_res 969 969 0 0.00 0 868 4 0 0
v_min_id_ord_sup 5364 5364 0 0.00 0 4761 6 0 0
v_min_non_paid_invoice 5729 5729 0 0.00 0 6512 13 0 0
v_min_non_paid_realizn 6925 6925 0 0.00 0 6658 15 0 0
v_our_firm 10122 10122 0 0.00 0 15 1 0 0
v_reserve_write_off 19526 19526 0 0.00 0 5922 7 0 0
x_lock_depdocs_on_canc_invoice 11191 11191 0 0.00 0 15217 114 0 0
x_lock_depdocs_on_canc_sup_ord 397 397 0 0.00 0 4378 293 0 0
x_make_qstorno_1000_1200 5415 5415 0 0.00 2 46679 1226 0 0
x_make_qstorno_1200_2000 5368 5368 0 0.00 2 84223 3119 0 0
x_make_qstorno_2100_3300 458634 458634 0 0.00 0 108045 147 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-10-2020 04:28:06 to 24-10-2020 04:28:10.

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:56:56 2 8192000000 8796633056 8807174144 3163600 0.038 1 1 2 0 3261296 61840 35856
01:57:58 28493 8192000000 9715278912 10008756224 790644400 8.801 100 88 176 1 821217056 8121360 22451296
01:59:32 20309 8192000000 9749399984 10054914048 824607232 9.145 100 82 164 1 856517008 12532224 19377552
02:00:58 14507 8192000000 9786887664 10101764096 858866640 9.489 100 85 170 0 899417168 20948336 19602192
02:02:18 7767 8192000000 9852671232 10183024640 906978816 9.967 100 80 160 1 964200784 39813712 17408256
02:03:28 8271 8192000000 9915073840 10254888960 937189360 10.265 100 75 150 1 1021151824 67999040 15963424
02:04:41 6946 8192000000 9949772400 10297442304 978005920 10.665 100 84 168 0 1056698080 61452560 17239600
02:05:49 3463 8192000000 9961456912 10320596992 1011332656 10.988 100 82 160 1 1073974304 46880608 15761040
02:06:56 2497 8192000000 10020638288 10385641472 1041840720 11.282 100 91 181 1 1132174336 71885456 18448160
02:07:59 1785 8192000000 10013790144 10385203200 1056935120 11.427 100 82 164 0 1134822752 61776640 16110992
02:09:02 1957 8192000000 10016847408 10388250624 1062841696 11.484 100 92 183 2 1126497232 44158544 19496992
02:10:05 5526 8192000000 9998074864 10398408704 1072487984 11.576 100 26 50 3 1125756032 48368064 4899984
02:11:12 39798 8192000000 10001322064 10411905024 1083802928 11.684 100 40 79 1 1104473776 12733712 7937136
02:12:54 7185 8192000000 9992828416 10385793024 1086981680 11.714 100 31 62 3 1127028944 33577776 6469488
02:14:02 4757 8192000000 10002882288 10397507584 1093134544 11.772 100 36 68 0 1118013616 16946240 7932832
02:15:09 6278 8192000000 10008849008 10411315200 1097182544 11.811 100 29 59 1 1124199744 20768144 6249056
02:16:21 24436 8192000000 10018383600 10425761792 1076374992 11.613 98 34 67 1 1106957456 24088784 6493680
02:17:49 5922 8192000000 9997701328 10390466560 1057475488 11.432 97 51 102 1 1082888336 14234928 11177920
02:19:00 5864 8192000000 9963983744 10359136256 1044749744 11.310 99 42 82 1 1082099744 27622176 9727824
02:20:09 8995 8192000000 9946411696 10333007872 1009452384 10.970 98 29 57 3 1043698496 28246144 5999968
02:21:21 39111 8192000000 9933679600 10326679552 1017424000 11.047 99 26 51 2 1036354000 13027872 5902128
02:23:06 7499 8192000000 9950014672 10338205696 1019217936 11.064 99 52 100 4 1049249536 18608672 11422928
02:24:15 4997 8192000000 9953766576 10339160064 1046701984 11.329 100 49 98 1 1066801472 8947104 11152384
02:25:21 60327 8192000000 9969605600 10361376768 1058957040 11.446 100 26 52 0 1089858160 25169328 5731792
02:27:28 18918 8192000000 9999993312 10399592448 1085235056 11.697 100 44 88 3 1108910224 14805520 8869648
02:28:52 21696 8192000000 10063616032 10465464320 1106111440 11.896 100 46 91 1 1182358944 67607536 8639968
02:30:16 4265 8192000000 10088877152 10492006400 1122731888 12.053 100 50 100 4 1202849360 68876640 11240832
02:31:25 19065 8192000000 10157016848 10560053248 1136157520 12.179 100 52 104 1 1264447824 117407712 10882592
02:32:51 31810 8192000000 10179783888 10591232000 1155263888 12.359 100 46 92 3 1277590528 111638304 10688336
02:34:39 7112 8192000000 10204805296 10619928576 1159428432 12.398 100 56 113 0 1319094512 147110160 12555920
02:35:52 5190 8192000000 10271327472 10672119808 1168687584 12.485 100 84 166 2 1374530112 187026928 18815600
02:37:06 9387 8192000000 10262357728 10686255104 1176997184 12.562 100 68 136 2 1372955184 180621024 15336976
02:38:19 2286 8192000000 10307349520 10713014272 1172005344 12.516 100 81 160 1 1409156336 219025792 18125200
02:39:27 1265 8192000000 10311147984 10711474176 1177624112 12.568 100 83 164 1 1413238784 217020928 18593744
02:40:33 4076 8192000000 10307785808 10723700736 1174764896 12.541 100 71 141 1 1411524544 220097904 16661744
02:41:42 2510 8192000000 10332870448 10746294272 1189395344 12.678 100 69 137 1 1435808528 229738064 16675120
02:42:48 7305 8192000000 10335839888 10767536128 1188750240 12.672 100 45 89 2 1438011520 237704512 11556768
02:44:04 3309 8192000000 10404980736 10811727872 1178042128 12.572 99 84 168 0 1503043104 305715696 19285280
02:45:16 3496 8192000000 10464098832 10868404224 1175205200 12.545 100 74 148 0 1560382272 366663520 18513552
02:46:23 15057 8192000000 10422322176 10873397248 1179703232 12.587 100 37 74 0 1511881232 322606288 9571712
02:47:40 27936 8192000000 10441881728 10877333504 1157575392 12.381 99 30 60 0 1535833840 370051008 8207440
02:49:47 11707 8192000000 10394668112 10872614912 1143367136 12.247 100 24 48 0 1492453792 342572464 6514192
02:51:29 23906 8192000000 10294765952 10747822080 1098138416 11.820 98 18 36 0 1386586352 283409680 5038256
02:52:57 14617 8192000000 10113430720 10581852160 1122501616 12.051 100 21 42 1 1222016576 94537472 4977488
02:54:12 37394 8192000000 10168421024 10623795200 1115883328 11.988 99 30 59 2 1238647568 116226544 6537696
02:55:50 78386 8192000000 10044810336 10464583680 1058627088 11.443 94 12 25 0 1098888448 37889904 2371456
02:58:16 143145 8192000000 10042811328 10461761536 1060004192 11.457 95 42 84 0 1104310576 35167760 9138624
03:01:40 31757 8192000000 10079262896 10538721280 1090093552 11.744 99 34 67 2 1175246880 77995248 7158080
03:03:27 55792 8192000000 10041636848 10458857472 1089655760 11.739 99 34 68 1 1133871648 36944624 7271264
03:05:25 154159 8192000000 10120692496 10551062528 1096691184 11.806 98 23 46 1 1180683360 78784800 5207376
03:09:01 70577 8192000000 10112909984 10609483776 1123404272 12.059 100 13 26 0 1244681728 118059872 3217584
03:11:13 140682 8192000000 10067475968 10534793216 1101405008 11.851 97 19 38 2 1208293952 102404976 4483968
03:14:34 25213 8192000000 10134599984 10583306240 1140298944 12.218 100 36 72 1 1241533600 93532160 7702496
03:16:01 78331 8192000000 10147937616 10621231104 1123998672 12.065 98 18 36 0 1212993408 84661440 4333296
03:18:29 78662 8192000000 10068643536 10499014656 1117945280 12.008 98 13 26 0 1214721232 94031632 2744320
03:20:49 68770 8192000000 10084553872 10552967168 1132742960 12.147 99 23 46 1 1163429520 26091840 4594720
03:22:58 56656 8192000000 10098148800 10537738240 1142921008 12.243 99 31 62 3 1198356240 49202704 6232528
03:25:24 53042 8192000000 10086562736 10574610432 1151379184 12.322 100 6 12 0 1199235776 46547040 1309552
03:27:42 62683 8192000000 10124099440 10569056256 1155966560 12.365 100 11 22 0 1206752912 48523504 2262848
03:29:51 23422 8192000000 10089241776 10529529856 1135276336 12.171 99 30 58 3 1193487680 51572544 6638800
03:31:19 80758 8192000000 10081613952 10534187008 1085800096 11.703 95 15 30 0 1147030784 57910240 3320448
03:33:40 64661 8192000000 10085458000 10538205184 1060016304 11.457 93 31 62 2 1142873520 76865424 5991792
03:35:50 56661 8192000000 10073145648 10523521024 1096736704 11.807 98 22 44 1 1148909712 47719904 4453104
03:37:47 47178 8192000000 10068870128 10531250176 1082593248 11.672 96 18 36 0 1140594352 54297936 3703168
03:39:38 58673 8192000000 10093649504 10537050112 1096023712 11.800 99 25 50 2 1145805936 44776880 5005344
03:41:47 136493 8192000000 10023915312 10493427712 1077118960 11.620 97 14 28 0 1128645712 48540208 2986544
03:46:32 38418 8192000000 10027501072 10460717056 1099029792 11.828 99 15 30 1 1125099056 22366176 3703088
03:48:10 55526 8192000000 10076820896 10505220096 1060894016 11.465 95 27 54 2 1126080864 58758512 6428336
03:50:06 73789 8192000000 10083624608 10512101376 1110235664 11.935 100 26 52 1 1196364640 80770528 5358448
03:52:25 46668 8192000000 10052282400 10512699392 1090230368 11.745 98 25 50 1 1166353120 70659504 5463248
03:54:18 34256 8192000000 10072044528 10499399680 1076784384 11.617 97 36 72 0 1123778608 39809888 7184336
03:55:52 55038 8192000000 10122637056 10549481472 1107668224 11.910 99 33 66 0 1201773408 87225872 6879312
03:57:49 83251 8192000000 10067227488 10596302848 1108773040 11.921 99 5 10 0 1165091824 55455680 863104
04:00:22 66957 8192000000 10053399440 10500763648 1130737424 12.128 100 13 26 1 1153079120 19527024 2814672
04:02:33 40304 8192000000 10067156224 10515230720 1094605600 11.786 97 9 18 0 1129457504 32946320 1905584
04:04:21 74158 8192000000 10059729376 10484436992 1083558416 11.681 96 17 34 1 1121301520 33746432 3996672
04:06:36 55915 8192000000 10155865872 10603601920 1135307152 12.171 100 26 52 0 1240591808 99427200 5857456
04:08:35 62901 8192000000 10181128800 10631692288 1143148656 12.245 100 17 34 2 1279006448 131635008 4222784
04:10:55 28987 8192000000 10118262512 10662735872 1129959680 12.121 100 11 22 0 1296568048 163694288 2914080
04:12:30 42233 8192000000 10087060000 10520403968 1140272416 12.218 100 23 46 1 1177671072 32923808 4474848
04:14:22 62626 8192000000 10084598608 10544496640 1126078736 12.084 99 25 50 1 1180329744 48622624 5628384
04:16:24 76359 8192000000 10088529856 10550288384 1141376128 12.228 100 27 54 2 1203000400 55293584 6330688
04:18:56 65397 8192000000 10091637984 10547445760 1137220336 12.189 99 9 18 0 1188004576 48751072 2033168
04:21:01 88149 8192000000 10085345648 10528440320 1102642048 11.863 96 11 21 1 1141611824 37214496 1755280
04:23:35 38764 8192000000 10049783280 10486878208 1057699056 11.434 93 17 33 0 1089747136 29151664 2896416
04:25:15 71593 8192000000 10117033312 10584600576 1144300192 12.256 100 29 58 1 1224746032 74386384 6059456

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 101 v_add_invoice_to_stock 335544517
user_exc 3 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-10-2020 04:28:27 to 24-10-2020 04:28:29.

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          226736
MON$OLDEST_ACTIVE               230650
MON$OLDEST_SNAPSHOT             230650
MON$NEXT_TRANSACTION            230650
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                       2090816
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             771
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2234 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2234 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2234 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.2234 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 ---
:37: 
:38: mscs-test-161.budzdorov.ru    Sat Oct 24 04:26:59 2020
:39:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:40: 
--- end of diff output ---

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

24.10.20 04:28:30. 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-10-24 04:28:33 No severe PSQL-related problems occured
24.10.20 04:28:34. End of report.