Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.11.2020 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 16743788 kB
MemAvailable 31392408 kB
Buffers 20 kB
Cached 14842240 kB
SwapCached 4216 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.0
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2020-11-18 04:26:56
DTS_END                         2020-11-18 04:26:56.3520
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(68:17)
IP                              ::1/52404
TRN_ID                          215523
ATT_ID                          602
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: 8715.33 22360 1045840
customer order: creation 152.56 448 18307
customer order: refuse 12.48 417 1498
order to supplier: creation 41.51 1476 4981
order to supplier: removal 3.33 141054 399
invoice (draft): creation 41.25 4114 4950
invoice (draft): removal 53.13 1120 6376
invoice accept: apply 37.38 19227 4486
invoice accept: cancel 47.57 925 5708
customer reserve: creation 3442.79 186 413135
customer reserve: removal 4607.55 78 552906
realization accept: apply 75.05 204 9006
realization accept: cancel 87.73 122 10527
payment from customer: creation 51.79 134 6215
payment from customer: removal 4.96 151 595
payment to supplier: creation 44.11 135 5293
payment to supplier: removal 3.12 112 374
service: total inventory turnovers 3.12 4824 374
service: total monetary turnovers 5.13 791 615
service: aggregate perf. data 0.76 21934 91
service: refresh index statistics 0.03 249756 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-11-2020 04:27:46 to 18-11-2020 04:27:48.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3494
TEST_TIME 2 3825
TEST_TIME 3 4069
TEST_TIME 4 4434
TEST_TIME 5 4777
TEST_TIME 6 4723
TEST_TIME 7 4898
TEST_TIME 8 5275
TEST_TIME 9 5285
TEST_TIME 10 5837
TEST_TIME 11 5828
TEST_TIME 12 5921
TEST_TIME 13 6088
TEST_TIME 14 6375
TEST_TIME 15 6301
TEST_TIME 16 6760
TEST_TIME 17 6977
TEST_TIME 18 7007
TEST_TIME 19 7118
TEST_TIME 20 7084
TEST_TIME 21 7101
TEST_TIME 22 7674
TEST_TIME 23 7784
TEST_TIME 24 7836
TEST_TIME 25 7902
TEST_TIME 26 8139
TEST_TIME 27 8510
TEST_TIME 28 8978
TEST_TIME 29 8956
TEST_TIME 30 9628
TEST_TIME 31 10590
TEST_TIME 32 10870
TEST_TIME 33 10770
TEST_TIME 34 10511
TEST_TIME 35 10339
TEST_TIME 36 10216
TEST_TIME 37 10034
TEST_TIME 38 9871
TEST_TIME 39 9807
TEST_TIME 40 9806
TEST_TIME 41 9795
TEST_TIME 42 9834
TEST_TIME 43 9728
TEST_TIME 44 9776
TEST_TIME 45 9621
TEST_TIME 46 9525
TEST_TIME 47 9367
TEST_TIME 48 9286
TEST_TIME 49 9147
TEST_TIME 50 9198
TEST_TIME 51 9095
TEST_TIME 52 9013
TEST_TIME 53 8991
TEST_TIME 54 8853
TEST_TIME 55 8861
TEST_TIME 56 8880
TEST_TIME 57 8866
TEST_TIME 58 8841
TEST_TIME 59 8884
TEST_TIME 60 8992
TEST_TIME 61 9072
TEST_TIME 62 9060
TEST_TIME 63 9089
TEST_TIME 64 9240
TEST_TIME 65 9295
TEST_TIME 66 9306
TEST_TIME 67 9262
TEST_TIME 68 9328
TEST_TIME 69 9276
TEST_TIME 70 9162
TEST_TIME 71 9037
TEST_TIME 72 8984
TEST_TIME 73 9002
TEST_TIME 74 9016
TEST_TIME 75 8953
TEST_TIME 76 8913
TEST_TIME 77 8885
TEST_TIME 78 8915
TEST_TIME 79 8893
TEST_TIME 80 8859
TEST_TIME 81 8826
TEST_TIME 82 8866
TEST_TIME 83 8983
TEST_TIME 84 8970
TEST_TIME 85 9000
TEST_TIME 86 8983
TEST_TIME 87 8954
TEST_TIME 88 9075
TEST_TIME 89 8975
TEST_TIME 90 8918
TEST_TIME 91 8877
TEST_TIME 92 8832
TEST_TIME 93 8846
TEST_TIME 94 8823
TEST_TIME 95 8825
TEST_TIME 96 8783
TEST_TIME 97 8820
TEST_TIME 98 8809
TEST_TIME 99 8777
TEST_TIME 100 8778
TEST_TIME 101 8793
TEST_TIME 102 8784
TEST_TIME 103 8816
TEST_TIME 104 8862
TEST_TIME 105 8910
TEST_TIME 106 8905
TEST_TIME 107 8929
TEST_TIME 108 8877
TEST_TIME 109 8798
TEST_TIME 110 8765
TEST_TIME 111 8712
TEST_TIME 112 8693
TEST_TIME 113 8725
TEST_TIME 114 8683
TEST_TIME 115 8698
TEST_TIME 116 8673
TEST_TIME 117 8679
TEST_TIME 118 8685
TEST_TIME 119 8724
TEST_TIME 120 8721

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 18-11-2020 04:27:50 to 18-11-2020 04:27:53.

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 1429070 1429070 0 0.00 0 20307 7 0 0
doc_list_biud 1429069 1429069 0 0.00 0 31603 38 0 0
sp_add_invoice_to_stock 4640 4486 154 3.31 36 164681 19228 0 154
sp_cancel_adding_invoice 5708 5708 0 0.00 4 76896 926 0 0
sp_cancel_client_order 1498 1498 0 0.00 5 10332 417 0 0
sp_cancel_customer_reserve 552906 552906 0 0.00 1 31640 78 0 0
sp_cancel_pay_from_customer 595 595 0 0.00 3 16568 152 0 0
sp_cancel_pay_to_supplier 374 374 0 0.00 3 8766 112 0 0
sp_cancel_supplier_invoice 6391 6376 15 0.23 2 28333 1121 0 15
sp_cancel_supplier_order 399 399 0 0.00 10 1183635 141055 0 0
sp_cancel_write_off 10527 10527 0 0.00 1 12828 122 0 0
sp_client_order 18307 18307 0 0.00 4 45657 449 0 0
sp_customer_reserve 413135 413135 0 0.00 0 91291 186 0 0
sp_fill_shopping_cart 28221 28221 0 0.00 1 29849 53 0 0
sp_fill_shopping_cart_clo_res 413130 413130 0 0.00 0 15470 19 0 0
sp_get_clo_for_invoice 4479 4479 0 0.00 0 24650 455 0 0
sp_kill_cost_storno 17204 17204 0 0.00 0 10179 71 0 0
sp_kill_qstorno_ret_qs2qd 1123114 1123114 0 0.00 0 31600 42 0 0
sp_kill_qty_storno 590899 590899 0 0.00 0 31602 92 0 0
sp_lock_selected_doc 18575 18575 0 0.00 0 9003 3 0 0
sp_make_cost_storno 24993 24993 0 0.00 0 13497 67 0 0
sp_multiply_rows_for_pdistr 24993 24993 0 0.00 0 12547 40 0 0
sp_multiply_rows_for_qdistr 18307 18307 0 0.00 0 14659 250 0 0
sp_pay_from_customer 6215 6215 0 0.00 4 14084 135 0 0
sp_pay_to_supplier 5293 5293 0 0.00 5 16507 136 0 0
sp_payment_common 11508 11508 0 0.00 1 8580 17 0 0
sp_qd_handle_on_cancel_clo 1498 1498 0 0.00 0 10325 386 0 0
sp_qd_handle_on_invoice_upd_sts 10187 10187 0 0.00 0 18212 471 0 0
sp_qd_handle_on_reserve_upd_sts 9006 9006 0 0.00 0 13226 59 0 0
sp_reserve_write_off 9006 9006 0 0.00 6 17232 204 0 0
sp_supplier_invoice 4950 4950 0 0.00 8 94024 4114 0 0
sp_supplier_order 4981 4981 0 0.00 5 26091 1477 0 0
srv_aggregate_perf_data 91 91 0 0.00 2820 460405 21934 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1000 145061 43149 0 0
srv_find_qd_qs_mism 1003756 1003756 0 0.00 0 20306 5 0 0
srv_make_invnt_saldo 374 374 0 0.00 61 93695 4824 0 0
srv_make_money_saldo 615 615 0 0.00 1 16847 792 0 0
srv_recalc_idx_stat 4 4 0 0.00 193771 312765 249756 0 0
t$perf-abend:sp_add_invoice_to_stock 154 154 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4486 4486 0 0.00 0 11368 182 0 0
t$perf-norm:sp_cancel_adding_invoice 320 320 0 0.00 0 4158 101 0 0
t$perf-norm:sp_cancel_client_order 1498 1498 0 0.00 0 8120 18 0 0
t$perf-norm:sp_cancel_customer_reserve 606 606 0 0.00 0 6137 25 0 0
t$perf-norm:sp_cancel_pay_from_customer 595 595 0 0.00 0 930 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 374 374 0 0.00 0 3770 25 0 0
t$perf-norm:sp_cancel_supplier_invoice 395 395 0 0.00 0 1812 22 0 0
t$perf-norm:sp_cancel_supplier_order 399 399 0 0.00 0 25208 1940 0 0
t$perf-norm:sp_cancel_write_off 903 903 0 0.00 0 1756 6 0 0
t$perf-norm:sp_client_order 18307 18307 0 0.00 0 28847 23 0 0
t$perf-norm:sp_customer_reserve 924 924 0 0.00 0 2895 7 0 0
t$perf-norm:sp_pay_from_customer 6215 6215 0 0.00 0 3975 8 0 0
t$perf-norm:sp_pay_to_supplier 5293 5293 0 0.00 0 11908 10 0 0
t$perf-norm:sp_reserve_write_off 9006 9006 0 0.00 0 5822 9 0 0
t$perf-norm:sp_supplier_invoice 4950 4950 0 0.00 0 5195 20 0 0
t$perf-norm:sp_supplier_order 4981 4981 0 0.00 0 12189 20 0 0
t$perf-norm:srv_aggregate_perf_data 91 91 0 0.00 0 654 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 3531 58 0 0
t$perf-norm:srv_make_invnt_saldo 374 374 0 0.00 0 3218 31 0 0
t$perf-norm:srv_make_money_saldo 615 615 0 0.00 0 879 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 28 10 0 0
v_add_invoice_to_stock 9264 8956 308 3.32 0 8590 20 0 308
v_all_customers 33370 33370 0 0.00 0 17 1 0 0
v_all_suppliers 27372 27372 0 0.00 0 39 1 0 0
v_all_wares 36614 36614 0 0.00 0 100 1 0 0
v_cancel_adding_invoice 640 640 0 0.00 0 831 8 0 0
v_cancel_client_order 2996 2996 0 0.00 0 3453 5 0 0
v_cancel_customer_prepayment 1190 1190 0 0.00 0 7554 17 0 0
v_cancel_customer_reserve 1212 1212 0 0.00 0 2470 8 0 0
v_cancel_payment_to_supplier 748 748 0 0.00 0 987 7 0 0
v_cancel_supplier_invoice 820 790 30 3.65 0 350 14 0 30
v_cancel_supplier_order 798 798 0 0.00 0 438 4 0 0
v_cancel_write_off 1806 1806 0 0.00 0 1751 17 0 0
v_max_id_clo_ord 4974 4974 0 0.00 0 948 2 0 0
v_max_id_clo_res 924 924 0 0.00 0 2238 13 0 0
v_max_id_ord_sup 4939 4939 0 0.00 0 9074 10 0 0
v_max_non_paid_invoice 1520 1520 0 0.00 0 451 2 0 0
v_max_non_paid_realizn 3215 3215 0 0.00 0 1538 3 0 0
v_min_id_clo_ord 4974 4974 0 0.00 0 4794 9 0 0
v_min_id_clo_res 924 924 0 0.00 0 8458 13 0 0
v_min_id_ord_sup 4939 4939 0 0.00 0 6912 11 0 0
v_min_non_paid_invoice 5293 5293 0 0.00 0 3028 9 0 0
v_min_non_paid_realizn 6215 6215 0 0.00 0 6329 10 0 0
v_our_firm 9244 9244 0 0.00 0 15 1 0 0
v_reserve_write_off 18012 18012 0 0.00 0 3246 5 0 0
x_lock_depdocs_on_canc_invoice 11689 11689 0 0.00 0 36500 144 0 0
x_lock_depdocs_on_canc_sup_ord 399 399 0 0.00 0 28074 478 0 0
x_make_qstorno_1000_1200 4981 4981 0 0.00 2 25083 1380 0 0
x_make_qstorno_1200_2000 4950 4950 0 0.00 3 94009 3913 0 0
x_make_qstorno_2100_3300 413135 413135 0 0.00 0 91269 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-11-2020 04:27:54 to 18-11-2020 04:27:58.

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 1490 8192000000 8952364272 9013665792 31303712 0.380 9 4 8 0 32274880 271472 699696
01:58:02 20967 8192000000 9722022992 10016591872 786113152 8.755 100 81 162 0 813359856 7215840 20030864
01:59:31 22700 8192000000 9754121504 10058653696 813441200 9.032 100 88 176 0 846298512 11540192 21317120
02:01:02 12686 8192000000 9798916576 10119311360 850680800 9.407 100 78 154 1 886449552 18124720 17644032
02:02:20 14367 8192000000 9875460080 10209370112 904566688 9.944 100 80 160 0 961683792 39500928 17616176
02:03:41 9135 8192000000 9904734032 10251689984 946651568 10.358 100 77 154 2 1006948096 43779760 16516768
02:04:53 5969 8192000000 9973688768 10329432064 981873936 10.702 100 82 162 0 1067170704 68231296 17065472
02:06:03 5533 8192000000 9999330352 10361675776 1002712272 10.905 100 81 162 0 1091042896 72052272 16278352
02:07:10 2674 8192000000 10035072784 10404155392 1041918384 11.283 100 87 173 2 1136351008 76815664 17616960
02:08:15 2160 8192000000 10039778144 10410881024 1053207072 11.391 100 89 179 1 1134663168 63911296 17544800
02:09:19 6255 8192000000 10045114560 10425151488 1066889760 11.522 100 68 133 1 1152231280 70918864 14422656
02:10:28 10267 8192000000 10000798656 10408108032 1072680736 11.578 100 33 64 3 1127710832 49094560 5935536
02:11:42 4456 8192000000 10001419808 10412896256 1079151408 11.639 100 26 52 1 1115113536 30877808 5084320
02:12:47 8859 8192000000 10018347232 10420928512 1087051296 11.715 100 38 75 2 1126299104 31353952 7893856
02:13:58 71827 8192000000 10017000896 10430111744 1091032640 11.752 100 35 70 3 1110115808 10266416 8816752
02:16:11 9345 8192000000 10029351344 10442248192 1084400192 11.689 99 46 90 1 1128003024 34217696 9385136
02:17:22 5879 8192000000 10001049728 10394562560 1065913888 11.513 98 38 76 3 1094298848 19203248 9181712
02:18:31 6584 8192000000 9985523168 10369359872 1026181312 11.132 96 29 56 0 1059528000 26514288 6832400
02:19:39 27301 8192000000 9962749648 10365722624 998057840 10.860 94 16 32 0 1027314496 26040192 3216464
02:21:06 6126 8192000000 9957682480 10348920832 981731264 10.701 95 34 69 1 1015585040 26759952 7093824
02:22:13 7170 8192000000 9922374480 10299187200 971029472 10.597 95 45 87 4 999062720 17889088 10144160
02:23:21 70646 8192000000 9939802848 10350100480 996845408 10.848 97 18 37 1 1030067056 29589056 3632592
02:25:36 6409 8192000000 9967964224 10366046208 1041929936 11.283 100 52 103 4 1063929072 10087296 11911840
02:26:44 9056 8192000000 9973225312 10379874304 1054296256 11.402 100 42 81 2 1081854368 18929088 8629024
02:28:05 8389 8192000000 10030613840 10420813824 1081088768 11.658 100 58 116 0 1132260640 39293152 11878720
02:29:21 42623 8192000000 10054463616 10458947584 1094326096 11.784 100 51 101 0 1191522736 86104240 11092400
02:31:13 15583 8192000000 10101169280 10500796416 1107213568 11.906 100 45 88 0 1209525856 93027168 9285120
02:32:39 18143 8192000000 10105353136 10518986752 1119707536 12.024 100 45 89 3 1219787776 90221792 9858448
02:34:10 2432 8192000000 10180072816 10576146432 1140692464 12.222 100 81 158 5 1282170944 124454080 17024400
02:35:16 5605 8192000000 10212400560 10614423552 1142565712 12.240 100 66 128 2 1314629728 157333280 14730736
02:36:26 8290 8192000000 10236648336 10640965632 1148315984 12.294 100 56 110 1 1338698224 179027712 11354528
02:37:38 2946 8192000000 10280549744 10677788672 1161046256 12.413 100 83 165 2 1385716752 206616208 18054288
02:38:44 4986 8192000000 10298863296 10708512768 1154006960 12.347 100 75 147 2 1395609488 223609120 17993408
02:39:57 4325 8192000000 10301656992 10711937024 1160041200 12.404 100 72 145 1 1415284784 238852384 16391200
02:41:06 997 8192000000 10328802752 10731589632 1172584576 12.521 100 80 159 1 1427047840 236282816 18180448
02:42:10 21675 8192000000 10368547472 10812108800 1166288896 12.462 99 64 128 3 1469441488 288325728 14826864
02:43:40 3043 8192000000 10355824640 10755293184 1160588240 12.409 99 88 175 1 1449057792 268751216 19718336
02:44:48 6209 8192000000 10384608288 10784362496 1155392784 12.360 100 85 169 0 1477786144 302517152 19876208
02:46:00 1833 8192000000 10416561344 10818486272 1168442032 12.482 100 81 162 0 1514514304 326169584 19902688
02:47:08 2709 8192000000 10467186640 10870808576 1173366896 12.528 100 90 178 5 1555518624 361013664 21138064
02:48:17 2744 8192000000 10519934736 10918244352 1165408944 12.454 99 88 176 0 1614935216 428442096 21084176
02:49:26 7106 8192000000 10488823680 10924044288 1195134768 12.731 100 47 94 0 1596584624 389827536 11622320
02:50:47 3510 8192000000 10533715648 10956103680 1193314192 12.714 100 75 147 0 1619422288 408424784 17683312
02:51:58 11054 8192000000 10544305424 10980630528 1184343568 12.631 100 46 93 1 1644425808 447905792 12176448
02:53:16 18573 8192000000 10515043584 10946764800 1165600272 12.456 99 42 84 0 1623732432 447015248 11116912
02:54:39 23573 8192000000 10429606896 10897289216 1151905360 12.327 100 30 60 0 1544858112 384723776 8228976
02:56:24 15366 8192000000 10350401056 10835324928 1130011088 12.121 100 25 50 1 1469265648 332129776 7124784
02:58:00 47460 8192000000 10066301488 10493444096 1093790464 11.779 97 21 42 3 1189672352 91561120 4320768
02:59:53 45600 8192000000 10046946720 10463821824 1104800448 11.883 99 37 73 0 1131188992 18440592 7947952
03:01:47 82811 8192000000 10099617376 10551095296 1119370448 12.021 99 26 53 1 1200254080 75273536 5610096
03:04:11 78813 8192000000 10140294544 10597240832 1116539344 11.994 98 16 32 0 1224627056 104609920 3477792
03:07:45 85716 8192000000 10127083824 10597916672 1128921200 12.111 99 21 42 0 1232301504 98750720 4629584
03:10:13 76099 8192000000 10068428208 10513068032 1122977504 12.055 99 15 32 1 1151337216 25067744 3291968
03:13:31 33974 8192000000 10060492736 10506039296 1101392128 11.851 97 38 78 3 1130237280 20722336 8122816
03:16:01 53765 8192000000 10089485280 10592571392 1116631216 11.995 98 14 27 0 1202148896 82165392 3352288
03:17:57 31317 8192000000 10109515344 10583986176 1127886528 12.101 100 12 24 0 1232460368 102114816 2459024
03:19:31 70553 8192000000 10120591136 10601840640 1120428256 12.031 100 11 22 0 1212334560 89398064 2508240
03:21:42 62759 8192000000 10115552912 10589478912 1129622128 12.118 100 26 52 3 1217730608 82160928 5947552
03:23:46 62618 8192000000 10128743792 10593837056 1134403280 12.163 99 23 45 2 1209231152 70046848 4781024
03:25:51 77617 8192000000 10148267248 10678927360 1113051904 11.961 97 27 54 1 1256658848 137400320 6206624
03:28:11 67637 8192000000 10141870384 10599473152 1138679296 12.203 99 17 34 3 1183370928 40870032 3821600
03:31:07 104134 8192000000 10079553968 10547523584 1130978784 12.131 98 17 34 1 1192111280 56726960 4405536
03:33:56 23132 8192000000 10103961408 10533662720 1145092784 12.263 99 34 69 2 1194323792 41970064 7260944
03:35:21 93732 8192000000 10126364352 10600779776 1132263504 12.143 98 21 42 3 1195699632 58730512 4705616
03:39:19 55483 8192000000 10118989344 10616152064 1124018944 12.065 99 9 18 0 1224049088 97847984 2182160
03:41:15 34849 8192000000 10095777472 10645409792 1141760320 12.232 100 5 10 0 1204368176 61619648 988208
03:43:24 44589 8192000000 10102391312 10550022144 1124289312 12.067 98 18 36 0 1166576304 38628992 3658000
03:45:14 50681 8192000000 10109502704 10554523648 1135192224 12.170 99 23 45 1 1183666320 43579024 4895072
03:47:07 75729 8192000000 10164442304 10619965440 1156065552 12.366 100 29 59 2 1267975104 105436112 6473440
03:49:23 54482 8192000000 10105651392 10572492800 1146643696 12.278 100 19 38 4 1195720080 44781760 4294624
03:51:18 49534 8192000000 10108722992 10570072064 1127897552 12.102 99 14 28 0 1180335696 49517984 2920160
03:53:08 56356 8192000000 10179421728 10635526144 1137511136 12.192 100 12 24 1 1247402000 107192672 2698192
03:55:10 77824 8192000000 10085346656 10558644224 1145104832 12.264 100 18 36 1 1176894960 28124880 3665248
03:59:32 60952 8192000000 10067371168 10511044608 1120409104 12.031 98 4 8 0 1152815360 31949152 457104
04:01:46 42356 8192000000 10077018416 10504781824 1093097376 11.772 96 29 58 1 1127146368 27625152 6423840
04:03:30 47430 8192000000 10063310288 10504466432 1117332896 12.002 99 21 43 0 1181082272 58784064 4965312
04:05:18 47423 8192000000 10106189152 10547736576 1112770704 11.959 98 27 54 0 1192029360 73867968 5390688
04:07:18 75107 8192000000 10086106032 10506711040 1058206320 11.439 94 22 44 0 1109287360 46437856 4643184
04:09:34 40324 8192000000 10097321136 10605092864 1112267296 11.954 99 24 48 2 1206621312 89589536 4764480
04:11:19 55517 8192000000 10075465952 10548670464 1115187952 11.982 100 22 44 0 1182507440 62638576 4680912
04:13:15 145061 8192000000 10051854624 10509176832 1080761920 11.655 96 16 32 0 1133582720 49372640 3448160
04:16:44 62382 8192000000 10085619728 10535563264 1104156944 11.877 98 12 24 1 1160984208 54315744 2511520
04:18:51 52216 8192000000 10063566560 10571325440 1107829920 11.912 99 7 14 1 1191638880 82259184 1549776
04:20:45 46018 8192000000 10079455008 10553765888 1127951168 12.102 100 18 36 0 1164128208 32256912 3920128
04:22:33 95781 8192000000 10132751200 10570526720 1128180384 12.104 99 29 58 1 1193337328 58430144 6726800
04:25:13 75466 8192000000 10084037936 10547937280 1086892160 11.713 96 12 24 1 1165032592 75534928 2605504

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 18-11-2020 04:28:00 to 18-11-2020 04:28:15.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 154 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-11-2020 04:28:16 to 18-11-2020 04:28:18.

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          215522
MON$OLDEST_ACTIVE               217731
MON$OLDEST_SNAPSHOT             217731
MON$NEXT_TRANSACTION            217731
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                       2101696
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             773
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2267 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2267 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2267 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.2267 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 ---
:931: 
:932: mscs-test-161.budzdorov.ru    Wed Nov 18 04:26:57 2020
:933:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:934: 
--- end of diff output ---

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

18.11.20 04:28:19. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2020-11-18 04:28:21 No severe PSQL-related problems occured
18.11.20 04:28:22. End of report.