Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.02.2021 04:27
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID e0e7b46250ba4f9795ceb55e0face73f
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2020-09-25 13:42
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.00
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16493704 kB
MemAvailable 31045500 kB
Buffers 20 kB
Cached 15121316 kB
SwapCached 3252 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-28 04:26:56
DTS_END                         2021-02-28 04:26:56.2120
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/36090
TRN_ID                          235421
ATT_ID                          617
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: 8900.18 21937 1068022
customer order: creation 166.98 446 20038
customer order: refuse 14.08 308 1689
order to supplier: creation 44.86 1546 5383
order to supplier: removal 3.45 109896 414
invoice (draft): creation 45.39 3707 5447
invoice (draft): removal 50.18 953 6021
invoice accept: apply 40.17 18061 4821
invoice accept: cancel 45.10 861 5412
customer reserve: creation 3768.32 174 452198
customer reserve: removal 4435.41 67 532249
realization accept: apply 80.72 163 9686
realization accept: cancel 81.96 115 9835
payment from customer: creation 57.08 116 6850
payment from customer: removal 5.47 73 656
payment to supplier: creation 46.99 128 5639
payment to supplier: removal 3.35 80 402
service: total inventory turnovers 3.64 3278 437
service: total monetary turnovers 6.13 493 736
service: aggregate perf. data 0.88 12206 105
service: refresh index statistics 0.03 286059 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-02-2021 04:28:19 to 28-02-2021 04:28:21.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4478
TEST_TIME 2 4674
TEST_TIME 3 4590
TEST_TIME 4 4959
TEST_TIME 5 5484
TEST_TIME 6 5106
TEST_TIME 7 5096
TEST_TIME 8 5451
TEST_TIME 9 5478
TEST_TIME 10 5722
TEST_TIME 11 6253
TEST_TIME 12 6419
TEST_TIME 13 6532
TEST_TIME 14 6879
TEST_TIME 15 7159
TEST_TIME 16 7236
TEST_TIME 17 7374
TEST_TIME 18 7403
TEST_TIME 19 7674
TEST_TIME 20 8025
TEST_TIME 21 8239
TEST_TIME 22 8461
TEST_TIME 23 8882
TEST_TIME 24 8986
TEST_TIME 25 10175
TEST_TIME 26 11652
TEST_TIME 27 11847
TEST_TIME 28 11636
TEST_TIME 29 11383
TEST_TIME 30 11081
TEST_TIME 31 10922
TEST_TIME 32 10862
TEST_TIME 33 10749
TEST_TIME 34 10715
TEST_TIME 35 10583
TEST_TIME 36 10468
TEST_TIME 37 10338
TEST_TIME 38 10217
TEST_TIME 39 10184
TEST_TIME 40 10031
TEST_TIME 41 10026
TEST_TIME 42 9888
TEST_TIME 43 10021
TEST_TIME 44 9903
TEST_TIME 45 9962
TEST_TIME 46 9949
TEST_TIME 47 9847
TEST_TIME 48 10070
TEST_TIME 49 9999
TEST_TIME 50 10027
TEST_TIME 51 9977
TEST_TIME 52 10074
TEST_TIME 53 10091
TEST_TIME 54 10054
TEST_TIME 55 9908
TEST_TIME 56 9800
TEST_TIME 57 9703
TEST_TIME 58 9629
TEST_TIME 59 9554
TEST_TIME 60 9458
TEST_TIME 61 9542
TEST_TIME 62 9542
TEST_TIME 63 9483
TEST_TIME 64 9422
TEST_TIME 65 9414
TEST_TIME 66 9513
TEST_TIME 67 9421
TEST_TIME 68 9470
TEST_TIME 69 9504
TEST_TIME 70 9547
TEST_TIME 71 9554
TEST_TIME 72 9575
TEST_TIME 73 9667
TEST_TIME 74 9583
TEST_TIME 75 9463
TEST_TIME 76 9430
TEST_TIME 77 9357
TEST_TIME 78 9283
TEST_TIME 79 9260
TEST_TIME 80 9223
TEST_TIME 81 9218
TEST_TIME 82 9259
TEST_TIME 83 9186
TEST_TIME 84 9158
TEST_TIME 85 9156
TEST_TIME 86 9162
TEST_TIME 87 9143
TEST_TIME 88 9198
TEST_TIME 89 9188
TEST_TIME 90 9218
TEST_TIME 91 9269
TEST_TIME 92 9178
TEST_TIME 93 9106
TEST_TIME 94 9051
TEST_TIME 95 9110
TEST_TIME 96 9114
TEST_TIME 97 9090
TEST_TIME 98 9056
TEST_TIME 99 9039
TEST_TIME 100 9077
TEST_TIME 101 9074
TEST_TIME 102 9070
TEST_TIME 103 9039
TEST_TIME 104 9120
TEST_TIME 105 9121
TEST_TIME 106 9155
TEST_TIME 107 9143
TEST_TIME 108 9202
TEST_TIME 109 9123
TEST_TIME 110 9070
TEST_TIME 111 9020
TEST_TIME 112 8995
TEST_TIME 113 8968
TEST_TIME 114 8928
TEST_TIME 115 8921
TEST_TIME 116 8968
TEST_TIME 117 8947
TEST_TIME 118 8934
TEST_TIME 119 8916
TEST_TIME 120 8901

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 28-02-2021 04:28:22 to 28-02-2021 04:28:26.

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 1485569 1485569 0 0.00 0 16819 6 0 0
doc_list_biud 1485562 1485562 0 0.00 0 64441 31 0 0
sp_add_invoice_to_stock 4943 4821 122 2.46 22 200101 18062 0 122
sp_cancel_adding_invoice 5412 5412 0 0.00 5 71983 862 0 0
sp_cancel_client_order 1689 1689 0 0.00 5 12602 308 0 0
sp_cancel_customer_reserve 532249 532249 0 0.00 1 55094 68 0 0
sp_cancel_pay_from_customer 656 656 0 0.00 3 4366 73 0 0
sp_cancel_pay_to_supplier 402 402 0 0.00 3 5158 80 0 0
sp_cancel_supplier_invoice 6033 6021 12 0.19 1 64449 954 0 12
sp_cancel_supplier_order 414 414 0 0.00 10 1356726 109897 0 0
sp_cancel_write_off 9835 9835 0 0.00 1 5199 115 0 0
sp_client_order 20038 20038 0 0.00 4 22241 447 0 0
sp_customer_reserve 452198 452198 0 0.00 0 45796 175 0 0
sp_fill_shopping_cart 30843 30843 0 0.00 1 14212 42 0 0
sp_fill_shopping_cart_clo_res 452194 452194 0 0.00 0 24018 17 0 0
sp_get_clo_for_invoice 4817 4817 0 0.00 0 37324 420 0 0
sp_kill_cost_storno 16305 16305 0 0.00 0 5196 54 0 0
sp_kill_qstorno_ret_qs2qd 1080768 1080768 0 0.00 0 64440 37 0 0
sp_kill_qty_storno 570118 570118 0 0.00 0 64441 79 0 0
sp_lock_selected_doc 20096 20096 0 0.00 0 7925 6 0 0
sp_make_cost_storno 26992 26992 0 0.00 0 14022 51 0 0
sp_multiply_rows_for_pdistr 26989 26989 0 0.00 0 14989 28 0 0
sp_multiply_rows_for_qdistr 20037 20037 0 0.00 0 13195 238 0 0
sp_pay_from_customer 6850 6850 0 0.00 4 15163 117 0 0
sp_pay_to_supplier 5639 5639 0 0.00 4 16380 129 0 0
sp_payment_common 12489 12489 0 0.00 1 6445 16 0 0
sp_qd_handle_on_cancel_clo 1689 1689 0 0.00 0 12592 277 0 0
sp_qd_handle_on_invoice_upd_sts 10227 10227 0 0.00 0 25304 378 0 0
sp_qd_handle_on_reserve_upd_sts 9683 9683 0 0.00 0 13998 51 0 0
sp_reserve_write_off 9686 9686 0 0.00 6 17278 164 0 0
sp_supplier_invoice 5447 5447 0 0.00 7 90111 3708 0 0
sp_supplier_order 5383 5383 0 0.00 6 99544 1547 0 0
srv_aggregate_perf_data 105 105 0 0.00 2653 131855 12206 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2382 127965 44445 0 0
srv_find_qd_qs_mism 1021081 1021081 0 0.00 0 16818 5 0 0
srv_make_invnt_saldo 437 437 0 0.00 67 138151 3278 0 0
srv_make_money_saldo 736 736 0 0.00 1 12066 494 0 0
srv_recalc_idx_stat 4 4 0 0.00 178235 360697 286059 0 0
t$perf-abend:sp_add_invoice_to_stock 122 122 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4821 4821 0 0.00 0 12606 176 0 0
t$perf-norm:sp_cancel_adding_invoice 389 389 0 0.00 0 4275 118 0 0
t$perf-norm:sp_cancel_client_order 1689 1689 0 0.00 0 2728 8 0 0
t$perf-norm:sp_cancel_customer_reserve 670 670 0 0.00 0 3032 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 656 656 0 0.00 0 219 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 402 402 0 0.00 0 2059 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 397 397 0 0.00 0 1181 18 0 0
t$perf-norm:sp_cancel_supplier_order 414 414 0 0.00 0 17238 1289 0 0
t$perf-norm:sp_cancel_write_off 987 987 0 0.00 0 5192 18 0 0
t$perf-norm:sp_client_order 20038 20038 0 0.00 0 6118 15 0 0
t$perf-norm:sp_customer_reserve 952 952 0 0.00 0 2412 11 0 0
t$perf-norm:sp_pay_from_customer 6850 6850 0 0.00 0 3176 7 0 0
t$perf-norm:sp_pay_to_supplier 5639 5639 0 0.00 0 4824 8 0 0
t$perf-norm:sp_reserve_write_off 9686 9686 0 0.00 0 3654 8 0 0
t$perf-norm:sp_supplier_invoice 5447 5447 0 0.00 0 6786 19 0 0
t$perf-norm:sp_supplier_order 5383 5383 0 0.00 0 8617 17 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 156 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 289 7 0 0
t$perf-norm:srv_make_invnt_saldo 437 437 0 0.00 0 2636 14 0 0
t$perf-norm:srv_make_money_saldo 736 736 0 0.00 0 3109 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 3 0 0
v_add_invoice_to_stock 9862 9618 244 2.47 0 5139 18 0 244
v_all_customers 36244 36244 0 0.00 0 20 1 0 0
v_all_suppliers 29730 29730 0 0.00 0 15 1 0 0
v_all_wares 40070 40070 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 778 778 0 0.00 0 134 6 0 0
v_cancel_client_order 3378 3378 0 0.00 0 4149 6 0 0
v_cancel_customer_prepayment 1312 1312 0 0.00 0 4293 10 0 0
v_cancel_customer_reserve 1340 1340 0 0.00 0 3888 8 0 0
v_cancel_payment_to_supplier 804 804 0 0.00 0 5108 14 0 0
v_cancel_supplier_invoice 818 794 24 2.93 0 1555 17 0 24
v_cancel_supplier_order 828 828 0 0.00 0 2011 6 0 0
v_cancel_write_off 1974 1974 0 0.00 0 3849 18 0 0
v_max_id_clo_ord 5381 5381 0 0.00 0 2734 2 0 0
v_max_id_clo_res 952 952 0 0.00 0 2644 13 0 0
v_max_id_ord_sup 5425 5425 0 0.00 0 7306 5 0 0
v_max_non_paid_invoice 1580 1580 0 0.00 0 184 2 0 0
v_max_non_paid_realizn 3664 3664 0 0.00 0 3502 3 0 0
v_min_id_clo_ord 5381 5381 0 0.00 0 3795 7 0 0
v_min_id_clo_res 952 952 0 0.00 0 6218 18 0 0
v_min_id_ord_sup 5425 5425 0 0.00 0 7075 5 0 0
v_min_non_paid_invoice 5639 5639 0 0.00 0 4759 9 0 0
v_min_non_paid_realizn 6850 6850 0 0.00 0 6440 11 0 0
v_our_firm 10198 10198 0 0.00 0 13 1 0 0
v_reserve_write_off 19366 19366 0 0.00 0 5295 7 0 0
x_lock_depdocs_on_canc_invoice 11036 11036 0 0.00 0 10588 111 0 0
x_lock_depdocs_on_canc_sup_ord 414 414 0 0.00 0 9556 310 0 0
x_make_qstorno_1000_1200 5383 5383 0 0.00 1 99529 1470 0 0
x_make_qstorno_1200_2000 5447 5447 0 0.00 3 90088 3545 0 0
x_make_qstorno_2100_3300 452198 452198 0 0.00 0 44230 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-02-2021 04:28:27 to 28-02-2021 04:28:31.

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 4 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
01:58:01 26257 8192000000 9712120400 10006224896 791801152 8.813 100 86 172 0 820581632 7490528 21289952
01:59:31 31012 8192000000 9757519536 10061492224 827856432 9.178 100 81 162 0 859925328 12540208 19528688
02:01:08 17475 8192000000 9813164336 10132672512 877286608 9.673 100 83 166 1 921302048 24657856 19357584
02:02:35 11584 8192000000 9871436000 10208657408 928687008 10.182 100 70 140 0 979574864 35576976 15310880
02:03:52 8114 8192000000 9947451104 10293420032 975098848 10.636 100 82 162 0 1057861984 65978032 16785104
02:05:03 3404 8192000000 9997384912 10354589696 1003419600 10.912 100 75 150 1 1101276608 82489104 15367904
02:06:08 3789 8192000000 10015732704 10380513280 1029814128 11.167 100 91 179 1 1141754688 93930464 18010096
02:07:14 1764 8192000000 10035515440 10403557376 1057734912 11.435 100 91 182 0 1152010736 75148288 19127536
02:08:16 17774 8192000000 10024700496 10412175360 1069831008 11.550 100 60 122 1 1151402048 68925120 12645920
02:09:35 5674 8192000000 10007702880 10394345472 1077158800 11.620 100 57 112 2 1124403728 34938624 12306304
02:10:42 11570 8192000000 10006975648 10410680320 1084570176 11.691 100 29 56 1 1117217872 25819456 6828240
02:11:55 67933 8192000000 10001820704 10408222720 1090880336 11.751 100 24 48 2 1116454592 20532224 5042032
02:14:05 3391 8192000000 10017293680 10428452864 1097120288 11.810 100 37 74 1 1138696704 33799632 7776784
02:15:10 6051 8192000000 10018341344 10427142144 1101691280 11.854 100 45 90 0 1130209248 18822176 9695792
02:16:18 14973 8192000000 9992392928 10387804160 1082009312 11.667 99 33 66 2 1118626768 29457264 7160192
02:17:34 14572 8192000000 9989359888 10399354880 1028169216 11.151 95 22 44 2 1060159248 27425248 4564784
02:18:50 6668 8192000000 9956913840 10330349568 999743088 10.876 96 39 77 3 1031851600 22909088 9199424
02:19:59 9088 8192000000 9934797952 10329124864 988048352 10.762 96 36 72 0 1027789872 31137504 8604016
02:21:11 22799 8192000000 9926835104 10316886016 985469472 10.737 96 33 63 2 1009923952 16678032 7776448
02:23:08 8034 8192000000 9967740496 10361286656 1042801216 11.292 100 44 88 1 1075572896 22956256 9815424
02:24:19 8440 8192000000 9975874416 10373267456 1058814048 11.445 100 35 69 2 1086431856 19386976 8230832
02:25:28 7770 8192000000 9970815184 10379005952 1068375024 11.537 100 44 88 1 1090912272 12372400 10164848
02:26:36 47316 8192000000 9994734528 10399150080 1076635152 11.615 100 35 70 1 1106331120 21938400 7757568
02:28:39 25218 8192000000 10058741072 10464481280 1104188048 11.877 100 52 103 2 1169965360 55565440 10211872
02:30:10 5008 8192000000 10076598272 10475462656 1122907264 12.054 100 64 127 1 1184786752 48329936 13549552
02:31:20 16177 8192000000 10140327072 10545602560 1127744720 12.100 100 50 98 0 1240740320 102218288 10777312
02:32:43 25677 8192000000 10139766768 10574819328 1139767424 12.213 100 39 76 2 1250779056 101795968 9215664
02:34:12 11244 8192000000 10179436080 10604523520 1150640096 12.316 100 46 91 1 1286808880 126197168 9971616
02:35:36 7406 8192000000 10212097344 10632122368 1151713968 12.326 99 48 96 1 1320753680 158044592 10995120
02:36:47 3988 8192000000 10270452560 10671878144 1156234064 12.368 99 73 144 1 1363583984 191546576 15803344
02:37:56 2392 8192000000 10280324480 10681397248 1159870704 12.402 100 81 161 0 1378180752 199987744 18322304
02:39:01 3979 8192000000 10333353952 10738737152 1160408112 12.407 100 68 136 1 1422090768 246394000 15288656
02:40:15 3599 8192000000 10329220912 10738458624 1155745744 12.363 100 68 136 1 1431688464 260059104 15883616
02:41:28 3212 8192000000 10372526304 10786988032 1167369056 12.472 100 87 173 1 1473148480 285929120 19850304
02:42:39 2863 8192000000 10416604944 10819080192 1168338096 12.481 100 80 161 2 1516068512 328977952 18752464
02:43:49 2380 8192000000 10415445264 10807422976 1137506160 12.192 99 86 172 1 1512108544 354987120 19615264
02:44:56 3064 8192000000 10498312352 10897588224 1158227072 12.387 100 81 163 1 1593667664 415464224 19976368
02:46:03 8784 8192000000 10523720112 10958802944 1162005696 12.422 100 42 84 0 1623265952 450111168 11149088
02:47:24 12995 8192000000 10548735920 10974863360 1156488272 12.370 100 47 90 1 1640083312 471694752 11900288
02:48:40 20170 8192000000 10518969792 10997039104 1144189344 12.255 100 33 66 1 1665975024 512862256 8923424
02:50:15 18196 8192000000 10529803376 10976010240 1107856288 11.912 100 28 56 1 1628212528 512080080 8276160
02:52:07 10155 8192000000 10141561808 10839695360 1112648112 11.957 100 13 26 2 1321492096 205676032 3167952
02:53:20 96004 8192000000 10082555840 10519732224 1108073696 11.914 99 18 36 0 1157001008 45154224 3773088
02:56:14 103104 8192000000 10083233328 10545356800 1123818016 12.063 100 34 68 1 1182853344 51448864 7586464
02:58:59 37429 8192000000 10071215504 10503507968 1093486016 11.776 96 32 63 3 1145866240 45982048 6398176
03:00:38 42497 8192000000 10058199568 10500296704 1113760128 11.968 98 17 34 1 1160593056 43354064 3478864
03:02:21 59732 8192000000 10071926784 10530066432 1115670240 11.986 99 16 31 0 1169423328 49821920 3931168
03:04:23 49139 8192000000 10090008784 10533502976 1095040336 11.791 97 21 40 1 1162885232 63351856 4493040
03:07:05 41827 8192000000 10081928480 10520965120 1119542928 12.023 100 23 46 3 1195859776 70856848 5460000
03:08:49 67228 8192000000 10065089072 10589712384 1109919616 11.932 99 9 18 0 1198419904 86369760 2130528
03:11:01 70894 8192000000 10106595184 10536620032 1132371120 12.144 100 19 38 1 1167609424 31175168 4063136
03:13:12 39862 8192000000 10165842960 10608381952 1148447872 12.295 100 39 76 2 1244279328 87543280 8288176
03:14:53 88257 8192000000 10092796864 10542899200 1132661280 12.146 99 21 42 2 1175900784 39284032 3955472
03:17:28 73127 8192000000 10111905392 10595516416 1143468560 12.248 99 15 30 0 1228315312 81879408 2967344
03:19:52 25408 8192000000 10079783728 10515746816 1130700176 12.128 100 50 98 2 1169279088 27563520 11015392
03:21:20 91797 8192000000 10073801152 10518802432 1138330368 12.200 100 22 44 1 1175098768 32180432 4587968
03:24:01 52053 8192000000 10079455328 10561560576 1118196992 12.010 99 6 12 0 1179920896 60265568 1458336
03:27:29 73633 8192000000 10087118912 10542149632 1094520880 11.786 96 17 34 0 1141162256 42632768 4008608
03:29:44 52955 8192000000 10097802464 10535075840 1099363440 11.832 96 23 46 0 1138269776 34524976 4381360
03:31:37 60818 8192000000 10044196640 10509651968 1083862416 11.684 96 15 30 1 1159712256 71242416 4607424
03:33:38 19944 8192000000 10091455408 10510516224 1081120224 11.658 96 56 111 1 1142755792 49974400 11661168
03:34:59 102832 8192000000 10116595456 10600620032 1104503120 11.880 98 23 46 0 1200531440 91046576 4981744
03:37:45 67193 8192000000 10127798096 10606014464 1107159792 11.906 98 12 24 0 1164905280 55296624 2448864
03:39:53 96569 8192000000 10041219344 10494365696 1107675216 11.910 99 13 26 0 1130630032 20246048 2708768
03:42:55 30868 8192000000 10043587600 10509926400 1120897392 12.035 100 3 6 0 1144984480 23594672 492416
03:44:30 44230 8192000000 10087436784 10549026816 1107283328 11.907 98 11 22 0 1172537824 62516384 2738112
03:46:21 91577 8192000000 10084589136 10542264320 1102926880 11.865 98 11 22 0 1158277360 53103520 2246960
03:48:57 39269 8192000000 10060558160 10499235840 1115547888 11.985 99 35 71 1 1143771856 21100480 7123488
03:50:40 39553 8192000000 10091421712 10528280576 1130196128 12.123 99 37 75 0 1170282976 32204448 7882400
03:52:21 68142 8192000000 10130691232 10578378752 1132375040 12.144 100 15 30 1 1227927200 92237216 3314944
03:54:49 45880 8192000000 10113292928 10558447616 1127669792 12.099 99 32 64 1 1193199952 58961392 6568768
03:56:35 53338 8192000000 10110210464 10536185856 1114426464 11.974 98 19 38 1 1171804800 53196496 4181840
03:58:30 127965 8192000000 10127590768 10580783104 1133165856 12.151 99 19 37 0 1216866224 79977552 3722816
04:02:57 67344 8192000000 10085038032 10541371392 1135293680 12.171 99 19 38 2 1171698304 32356784 4047840
04:05:04 44325 8192000000 10107297312 10545729536 1123886688 12.064 99 27 53 0 1186154720 55939248 6328784
04:06:56 40654 8192000000 10101701488 10552385536 1138371600 12.200 99 20 39 0 1198200992 55729520 4099872
04:08:37 66673 8192000000 10143364352 10588168192 1126983040 12.093 99 22 44 2 1192101680 60340912 4777728
04:10:44 24392 8192000000 10099706736 10560159744 1133924480 12.158 100 37 74 0 1218150416 76361056 7864880
04:12:12 29606 8192000000 10085612576 10568077312 1130594176 12.127 100 16 32 0 1194706784 61038560 3074048
04:13:42 24126 8192000000 10104663024 10530545664 1123481920 12.060 99 39 76 1 1170246272 38582352 8182000
04:15:30 73618 8192000000 10060923936 10525577216 1129109376 12.113 99 14 28 0 1164281808 32609472 2562960
04:17:47 57348 8192000000 10055407120 10510467072 1107593568 11.910 98 6 12 0 1122854912 14027968 1233376
04:19:45 68691 8192000000 10075630112 10536472576 1108654272 11.920 98 11 22 1 1156862128 45958144 2249712
04:21:55 71905 8192000000 10065006736 10583867392 1125995536 12.084 100 9 18 2 1183235216 55275872 1963808
04:24:07 38321 8192000000 10095087552 10543779840 1115359536 11.983 99 28 56 2 1175051168 53793568 5898064
04:25:45 50491 8192000000 10140954672 10577358848 1110596832 11.938 98 27 54 2 1187639680 70177216 6865632

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 28-02-2021 04:28:33 to 28-02-2021 04:28:48.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 28-02-2021 04:28:49 to 28-02-2021 04:28: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          230858
MON$OLDEST_ACTIVE               235591
MON$OLDEST_SNAPSHOT             235591
MON$NEXT_TRANSACTION            235591
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                       2083040
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             802
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2377 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2377 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2377 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.2377 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    Sun Feb 28 04:27:02 2021
:39:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:40: 
:41: 
:42: mscs-test-161.budzdorov.ru    Sun Feb 28 04:27:06 2021
:43:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:44: 
:45: 
:46: mscs-test-161.budzdorov.ru    Sun Feb 28 04:27:11 2021
:47:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:48: 
:49: 
:50: mscs-test-161.budzdorov.ru    Sun Feb 28 04:27:12 2021
:51:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:52: 
--- end of diff output ---

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

28.02.21 04:28: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
2021-02-28 04:28:54 No severe PSQL-related problems occured
28.02.21 04:28:55. End of report.