Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.11.2020 13: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 16530532 kB
MemAvailable 31391468 kB
Buffers 0 kB
Cached 15093276 kB
SwapCached 2820 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-12 13:26:49
DTS_END                         2020-11-12 13:26:49.9200
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> X_MAKE_QSTORNO_1000_1200(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/48742
TRN_ID                          230592
ATT_ID                          635
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: 8992.97 24870 1079156
customer order: creation 161.55 370 19386
customer order: refuse 13.56 343 1627
order to supplier: creation 43.88 1379 5265
order to supplier: removal 3.28 133304 394
invoice (draft): creation 43.97 3715 5276
invoice (draft): removal 51.18 1078 6142
invoice accept: apply 39.77 15009 4772
invoice accept: cancel 45.61 882 5473
customer reserve: creation 3755.33 141 450640
customer reserve: removal 4559.78 75 547174
realization accept: apply 78.74 193 9449
realization accept: cancel 76.59 122 9191
payment from customer: creation 56.08 112 6730
payment from customer: removal 5.69 100 683
payment to supplier: creation 44.68 131 5361
payment to supplier: removal 3.23 93 388
service: total inventory turnovers 3.38 3988 405
service: total monetary turnovers 5.68 596 681
service: aggregate perf. data 0.96 14284 115
service: refresh index statistics 0.03 321494 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-11-2020 13:28:05 to 12-11-2020 13:28:07.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2987
TEST_TIME 2 4299
TEST_TIME 3 3960
TEST_TIME 4 3951
TEST_TIME 5 4201
TEST_TIME 6 4631
TEST_TIME 7 4461
TEST_TIME 8 4357
TEST_TIME 9 4448
TEST_TIME 10 4493
TEST_TIME 11 5018
TEST_TIME 12 4924
TEST_TIME 13 5294
TEST_TIME 14 5410
TEST_TIME 15 5524
TEST_TIME 16 5452
TEST_TIME 17 5902
TEST_TIME 18 6196
TEST_TIME 19 6183
TEST_TIME 20 6336
TEST_TIME 21 6564
TEST_TIME 22 7316
TEST_TIME 23 7560
TEST_TIME 24 7726
TEST_TIME 25 7736
TEST_TIME 26 7796
TEST_TIME 27 8677
TEST_TIME 28 8742
TEST_TIME 29 9378
TEST_TIME 30 10160
TEST_TIME 31 10040
TEST_TIME 32 10202
TEST_TIME 33 10221
TEST_TIME 34 10123
TEST_TIME 35 10053
TEST_TIME 36 9915
TEST_TIME 37 9765
TEST_TIME 38 9839
TEST_TIME 39 9721
TEST_TIME 40 9611
TEST_TIME 41 9763
TEST_TIME 42 9862
TEST_TIME 43 9923
TEST_TIME 44 9835
TEST_TIME 45 9884
TEST_TIME 46 9839
TEST_TIME 47 9771
TEST_TIME 48 9584
TEST_TIME 49 9427
TEST_TIME 50 9384
TEST_TIME 51 9359
TEST_TIME 52 9346
TEST_TIME 53 9421
TEST_TIME 54 9367
TEST_TIME 55 9332
TEST_TIME 56 9327
TEST_TIME 57 9255
TEST_TIME 58 9163
TEST_TIME 59 9231
TEST_TIME 60 9155
TEST_TIME 61 9140
TEST_TIME 62 9210
TEST_TIME 63 9228
TEST_TIME 64 9279
TEST_TIME 65 9266
TEST_TIME 66 9270
TEST_TIME 67 9388
TEST_TIME 68 9394
TEST_TIME 69 9420
TEST_TIME 70 9451
TEST_TIME 71 9520
TEST_TIME 72 9558
TEST_TIME 73 9611
TEST_TIME 74 9511
TEST_TIME 75 9408
TEST_TIME 76 9334
TEST_TIME 77 9281
TEST_TIME 78 9233
TEST_TIME 79 9209
TEST_TIME 80 9146
TEST_TIME 81 9083
TEST_TIME 82 8999
TEST_TIME 83 8966
TEST_TIME 84 8997
TEST_TIME 85 9033
TEST_TIME 86 9022
TEST_TIME 87 9052
TEST_TIME 88 9110
TEST_TIME 89 9164
TEST_TIME 90 9193
TEST_TIME 91 9185
TEST_TIME 92 9243
TEST_TIME 93 9286
TEST_TIME 94 9309
TEST_TIME 95 9369
TEST_TIME 96 9287
TEST_TIME 97 9230
TEST_TIME 98 9196
TEST_TIME 99 9230
TEST_TIME 100 9190
TEST_TIME 101 9208
TEST_TIME 102 9207
TEST_TIME 103 9165
TEST_TIME 104 9149
TEST_TIME 105 9170
TEST_TIME 106 9181
TEST_TIME 107 9203
TEST_TIME 108 9215
TEST_TIME 109 9280
TEST_TIME 110 9228
TEST_TIME 111 9258
TEST_TIME 112 9180
TEST_TIME 113 9162
TEST_TIME 114 9121
TEST_TIME 115 9069
TEST_TIME 116 9058
TEST_TIME 117 9018
TEST_TIME 118 9041
TEST_TIME 119 9009
TEST_TIME 120 8993

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 12-11-2020 13:28:08 to 12-11-2020 13:28:12.

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 1496224 1496224 0 0.00 0 20781 5 0 0
doc_list_biud 1496224 1496224 0 0.00 0 28972 35 0 0
sp_add_invoice_to_stock 4875 4772 103 2.11 20 161291 15009 0 103
sp_cancel_adding_invoice 5473 5473 0 0.00 6 59146 883 0 0
sp_cancel_client_order 1627 1627 0 0.00 4 10028 343 0 0
sp_cancel_customer_reserve 547174 547174 0 0.00 1 17029 76 0 0
sp_cancel_pay_from_customer 683 683 0 0.00 3 6892 100 0 0
sp_cancel_pay_to_supplier 388 388 0 0.00 3 5440 93 0 0
sp_cancel_supplier_invoice 6148 6142 6 0.09 2 28976 1079 0 6
sp_cancel_supplier_order 394 394 0 0.00 6 1388368 133305 0 0
sp_cancel_write_off 9191 9191 0 0.00 1 14173 122 0 0
sp_client_order 19386 19386 0 0.00 4 20783 371 0 0
sp_customer_reserve 450640 450640 0 0.00 0 100861 142 0 0
sp_fill_shopping_cart 29914 29914 0 0.00 1 20091 47 0 0
sp_fill_shopping_cart_clo_res 450639 450639 0 0.00 0 27416 14 0 0
sp_get_clo_for_invoice 4771 4771 0 0.00 0 72256 375 0 0
sp_kill_cost_storno 15735 15735 0 0.00 0 19493 63 0 0
sp_kill_qstorno_ret_qs2qd 1110075 1110075 0 0.00 0 28971 41 0 0
sp_kill_qty_storno 584219 584219 0 0.00 0 28972 88 0 0
sp_lock_selected_doc 19757 19757 0 0.00 0 11069 8 0 0
sp_make_cost_storno 26309 26309 0 0.00 0 17647 57 0 0
sp_multiply_rows_for_pdistr 26309 26309 0 0.00 0 8898 31 0 0
sp_multiply_rows_for_qdistr 19386 19386 0 0.00 0 20776 194 0 0
sp_pay_from_customer 6730 6730 0 0.00 4 14594 112 0 0
sp_pay_to_supplier 5361 5361 0 0.00 5 25355 132 0 0
sp_payment_common 12091 12091 0 0.00 1 17330 23 0 0
sp_qd_handle_on_cancel_clo 1627 1627 0 0.00 0 3769 308 0 0
sp_qd_handle_on_invoice_upd_sts 10242 10242 0 0.00 0 24671 403 0 0
sp_qd_handle_on_reserve_upd_sts 9449 9449 0 0.00 0 12471 57 0 0
sp_reserve_write_off 9449 9449 0 0.00 7 21299 193 0 0
sp_supplier_invoice 5276 5276 0 0.00 6 94776 3715 0 0
sp_supplier_order 5265 5265 0 0.00 6 41745 1379 0 0
srv_aggregate_perf_data 115 115 0 0.00 2129 189858 14284 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1902 128911 47952 0 0
srv_find_qd_qs_mism 1033646 1033646 0 0.00 0 8535 4 0 0
srv_make_invnt_saldo 405 405 0 0.00 62 109476 3989 0 0
srv_make_money_saldo 681 681 0 0.00 1 18438 597 0 0
srv_recalc_idx_stat 4 4 0 0.00 145941 418957 321494 0 0
t$perf-abend:sp_add_invoice_to_stock 103 103 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4772 4772 0 0.00 0 8300 147 0 0
t$perf-norm:sp_cancel_adding_invoice 370 370 0 0.00 1 2511 87 0 0
t$perf-norm:sp_cancel_client_order 1627 1627 0 0.00 0 1504 10 0 0
t$perf-norm:sp_cancel_customer_reserve 685 685 0 0.00 0 4038 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 683 683 0 0.00 0 1646 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 388 388 0 0.00 0 2097 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 427 427 0 0.00 0 824 15 0 0
t$perf-norm:sp_cancel_supplier_order 394 394 0 0.00 0 21666 1540 0 0
t$perf-norm:sp_cancel_write_off 965 965 0 0.00 0 5313 13 0 0
t$perf-norm:sp_client_order 19386 19386 0 0.00 0 8657 19 0 0
t$perf-norm:sp_customer_reserve 970 970 0 0.00 0 5409 16 0 0
t$perf-norm:sp_pay_from_customer 6730 6730 0 0.00 0 7309 12 0 0
t$perf-norm:sp_pay_to_supplier 5361 5361 0 0.00 0 7657 11 0 0
t$perf-norm:sp_reserve_write_off 9449 9449 0 0.00 0 5198 9 0 0
t$perf-norm:sp_supplier_invoice 5276 5276 0 0.00 0 4223 18 0 0
t$perf-norm:sp_supplier_order 5265 5265 0 0.00 0 3467 14 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 86 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 125 3 0 0
t$perf-norm:srv_make_invnt_saldo 405 405 0 0.00 0 540 11 0 0
t$perf-norm:srv_make_money_saldo 681 681 0 0.00 0 1148 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 69 19 0 0
v_add_invoice_to_stock 9744 9538 206 2.11 0 4757 14 0 206
v_all_customers 35034 35034 0 0.00 0 13 1 0 0
v_all_suppliers 29118 29118 0 0.00 0 19 1 0 0
v_all_wares 38772 38772 0 0.00 0 115 1 0 0
v_cancel_adding_invoice 740 740 0 0.00 0 6041 19 0 0
v_cancel_client_order 3254 3254 0 0.00 0 5512 7 0 0
v_cancel_customer_prepayment 1366 1366 0 0.00 0 2902 6 0 0
v_cancel_customer_reserve 1370 1370 0 0.00 0 440 3 0 0
v_cancel_payment_to_supplier 776 776 0 0.00 0 2289 7 0 0
v_cancel_supplier_invoice 866 854 12 1.38 0 2556 14 0 12
v_cancel_supplier_order 788 788 0 0.00 0 370 3 0 0
v_cancel_write_off 1930 1930 0 0.00 0 4244 17 0 0
v_max_id_clo_ord 5265 5265 0 0.00 0 3358 2 0 0
v_max_id_clo_res 970 970 0 0.00 0 4791 28 0 0
v_max_id_ord_sup 5263 5263 0 0.00 0 3100 3 0 0
v_max_non_paid_invoice 1330 1330 0 0.00 0 286 2 0 0
v_max_non_paid_realizn 3704 3704 0 0.00 0 1543 4 0 0
v_min_id_clo_ord 5265 5265 0 0.00 0 7678 9 0 0
v_min_id_clo_res 970 970 0 0.00 0 5634 14 0 0
v_min_id_ord_sup 5263 5263 0 0.00 0 2953 5 0 0
v_min_non_paid_invoice 5361 5361 0 0.00 0 5245 11 0 0
v_min_non_paid_realizn 6730 6730 0 0.00 0 5685 19 0 0
v_our_firm 9790 9790 0 0.00 0 13 1 0 0
v_reserve_write_off 18898 18898 0 0.00 0 3730 6 0 0
x_lock_depdocs_on_canc_invoice 11188 11188 0 0.00 0 17894 119 0 0
x_lock_depdocs_on_canc_sup_ord 394 394 0 0.00 0 3984 337 0 0
x_make_qstorno_1000_1200 5265 5265 0 0.00 2 41739 1289 0 0
x_make_qstorno_1200_2000 5276 5276 0 0.00 3 94766 3538 0 0
x_make_qstorno_2100_3300 450640 450640 0 0.00 0 100826 125 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-11-2020 13:28:13 to 12-11-2020 13:28:17.

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
10:56:53 1 8192000000 8796633152 8807174144 3163616 0.038 1 1 2 0 3261312 61840 35856
10:57:54 27368 8192000000 9721114192 10015944704 797334272 8.869 100 89 178 0 826659760 7921488 21404000
10:59:24 13944 8192000000 9751525520 10055061504 831286448 9.212 100 89 176 1 864168576 11930240 20951888
11:00:46 19876 8192000000 9799786656 10115264512 863031696 9.530 100 79 158 1 900955792 19856032 18068064
11:02:10 12714 8192000000 9857059776 10185998336 902019616 9.918 100 77 154 0 958102064 39155840 16926608
11:03:28 8220 8192000000 9902170416 10240577536 937603440 10.269 100 81 161 0 1015098608 60033488 17461680
11:04:39 4527 8192000000 9957927808 10306203648 977283728 10.658 100 80 160 0 1064399424 70915152 16200544
11:05:47 4035 8192000000 9986994752 10348498944 1010278816 10.978 100 80 160 1 1109060560 82444544 16337200
11:06:54 2679 8192000000 9998306720 10364485632 1037075056 11.237 100 88 173 1 1112210192 56803120 18332016
11:07:59 1837 8192000000 10040758752 10411102208 1060425456 11.461 100 91 182 1 1148078352 69657312 17995584
11:09:02 2982 8192000000 9999695280 10381414400 1065870576 11.513 100 70 141 1 1108458464 27403504 15184384
11:10:06 6779 8192000000 10009222144 10397810688 1069478864 11.547 100 46 92 3 1111096816 31556256 10061696
11:11:13 7043 8192000000 9980489056 10380984320 1077250848 11.621 100 27 52 1 1097961280 15191232 5519200
11:12:25 64875 8192000000 9987600080 10398547968 1087538144 11.719 100 41 82 0 1111685248 15022160 9124944
11:14:32 5443 8192000000 10014960864 10414616576 1093410128 11.775 100 50 100 4 1120557056 16575808 10571120
11:15:42 6413 8192000000 10004957536 10412318720 1089690048 11.740 100 29 58 2 1106077696 9955184 6432464
11:16:51 7291 8192000000 9975790176 10374508544 1045751664 11.320 96 29 58 2 1077842976 25243536 6847776
11:18:00 43273 8192000000 9984924304 10387755008 1042575392 11.289 96 26 52 1 1062037072 13717424 5744256
11:19:45 7931 8192000000 9960500720 10343424000 993845760 10.819 96 38 75 0 1022596912 20695984 8055168
11:20:57 10441 8192000000 9959119936 10342428672 1017625648 11.049 99 36 72 0 1050822208 25161872 8034688
11:22:08 58561 8192000000 9935319824 10314219520 985377520 10.737 96 22 44 2 1008889008 17954624 5556864
11:24:26 6308 8192000000 9972533344 10361667584 1041044784 11.275 100 37 74 3 1065427392 16197664 8184944
11:25:34 7477 8192000000 9966408032 10364993536 1056876304 11.427 100 39 78 1 1086628624 20678816 9073504
11:26:41 104614 8192000000 10019731360 10414612480 1083047840 11.677 100 31 63 0 1113865632 23975088 6842704
11:29:33 13820 8192000000 10060304240 10457767936 1108551008 11.919 100 39 78 0 1172654864 56495904 7607952
11:31:04 7152 8192000000 10082210528 10479779840 1125483120 12.079 100 53 106 0 1196823648 60165808 11174720
11:32:21 11190 8192000000 10153772752 10569654272 1141729264 12.232 100 54 107 1 1263354160 110286336 11338560
11:33:41 22724 8192000000 10182340800 10593153024 1151045360 12.319 100 46 90 5 1276435696 115065072 10325264
11:35:08 8402 8192000000 10212266944 10634969088 1155824992 12.364 99 36 72 3 1317401152 152803952 8772208
11:36:21 5842 8192000000 10272758480 10680762368 1172935600 12.524 100 63 126 2 1385057728 197763760 14358368
11:37:31 5248 8192000000 10308584832 10721271808 1165234832 12.452 99 61 122 1 1408187008 228181296 14770880
11:38:42 2044 8192000000 10350516448 10747875328 1174039120 12.535 100 82 163 0 1448715520 255475168 19201232
11:39:49 5157 8192000000 10362319424 10780553216 1185004944 12.637 100 63 125 0 1460000016 260196032 14799040
11:41:01 5243 8192000000 10381982672 10795319296 1186041888 12.647 100 58 115 0 1486553408 286602720 13908800
11:42:21 4879 8192000000 10411904160 10821996544 1175859488 12.552 100 66 130 2 1513726864 321803744 16063632
11:43:34 1899 8192000000 10401397776 10803179520 1180574608 12.596 100 80 159 0 1507360928 307637232 19149088
11:44:39 12825 8192000000 10390873728 10802659328 1144697280 12.260 98 45 88 0 1486410384 330063424 11649680
11:45:56 4386 8192000000 10464514512 10864533504 1147363440 12.285 99 83 166 0 1554457184 387426064 19667680
11:47:04 8228 8192000000 10447111520 10879844352 1135108288 12.169 100 46 92 2 1545073040 398641776 11322976
11:48:30 15616 8192000000 10403630192 10827890688 1146630352 12.278 100 31 62 4 1509215936 354675712 7909872
11:49:52 15754 8192000000 10395417856 10843373568 1136447248 12.182 99 26 52 0 1478663600 335405712 6810640
11:51:21 7914 8192000000 10437963424 10859388928 1140936768 12.224 100 44 88 1 1530410832 378204784 11269280
11:52:34 6067 8192000000 10499513056 10932981760 1145098144 12.263 100 38 75 0 1601511232 446642000 9771088
11:53:45 16994 8192000000 10356347600 10802315264 1129254048 12.114 99 42 84 1 1459367568 318923216 11190304
11:55:11 10613 8192000000 10349809280 10806407168 1110190112 11.934 99 30 60 0 1438447008 320061008 8195888
11:56:25 58807 8192000000 10101135200 10576433152 1107724624 11.911 98 34 68 4 1214865616 99524224 7616768
11:58:44 41359 8192000000 10053781088 10491359232 1110662048 11.939 98 9 16 1 1134216944 22301920 1252976
12:00:32 82630 8192000000 10095685968 10560868352 1128336112 12.106 99 9 18 0 1157007952 26595488 2076352
12:02:58 26052 8192000000 10102247776 10521247744 1107429136 11.908 97 39 76 1 1158996864 43632176 7935552
12:04:29 38858 8192000000 10099809696 10516975616 1132700448 12.147 100 24 47 1 1211335712 73728016 4907248
12:06:09 71068 8192000000 10089735360 10552123392 1109950736 11.932 98 14 28 1 1211436192 98439072 3046384
12:08:53 128911 8192000000 10082220768 10515521536 1108080512 11.914 97 21 39 1 1137105632 24251456 4773664
12:12:03 109889 8192000000 10063405568 10540756992 1096553712 11.805 96 10 20 0 1155029904 56425184 2051008
12:15:41 120813 8192000000 10128499584 10579701760 1117000768 11.999 98 21 42 0 1173690192 52086656 4602768
12:18:48 66746 8192000000 10108813136 10626867200 1133000464 12.150 99 18 36 2 1230113312 93001120 4111728
12:20:55 61906 8192000000 10080723632 10533318656 1125408144 12.078 99 15 30 2 1195173984 66164928 3600912
12:23:39 42465 8192000000 10087928976 10543800320 1128617040 12.108 99 8 16 0 1178861008 48622448 1621520
12:25:57 62513 8192000000 10081147296 10604986368 1089668352 11.740 96 5 10 1 1141599280 51328608 602320
12:28:00 79025 8192000000 10087829280 10567225344 1085733984 11.702 95 10 18 1 1133040624 45859360 1447280
12:30:19 41632 8192000000 10068546480 10539016192 1122883888 12.054 99 16 32 1 1155528624 29472384 3172352
12:32:01 45390 8192000000 10109045840 10549411840 1107151664 11.905 97 19 38 1 1179523056 68349024 4022368
12:34:14 75126 8192000000 10115338848 10551062528 1126456080 12.088 99 31 61 0 1174210384 41369648 6384656
12:36:32 43051 8192000000 10089565376 10552102912 1115590160 11.985 99 23 46 1 1173254112 53002528 4661424
12:38:21 80602 8192000000 10111631632 10573910016 1101601712 11.853 98 7 14 0 1173230288 69963440 1665136
12:40:43 98052 8192000000 10041995872 10480685056 1111106944 11.943 99 17 34 0 1142605648 28010832 3487872
12:43:23 21164 8192000000 10026073536 10469486592 1097315632 11.812 98 15 30 1 1117240992 16804288 3121072
12:44:49 61371 8192000000 10007371120 10452058112 1016054432 11.034 91 10 18 0 1056333104 38049824 2228848
12:46:53 60696 8192000000 10051791456 10500870144 1078634304 11.634 97 9 18 1 1124970288 44350432 1985552
12:49:09 90307 8192000000 10109444064 10565574656 1074115056 11.591 96 27 53 0 1169958464 90184640 5658768
12:51:42 38514 8192000000 10086789184 10536181760 1095776864 11.798 98 28 56 2 1185150080 83119664 6253552
12:53:25 61696 8192000000 10167701360 10606571520 1115191200 11.982 99 20 40 1 1203869104 84606272 4071632
12:55:53 74727 8192000000 10100751328 10566672384 1115480624 11.984 99 19 38 0 1206659776 87270496 3908656
12:58:19 71476 8192000000 10071088128 10557333504 1115098080 11.981 98 15 30 1 1197240384 79194800 2947504
13:00:31 72230 8192000000 10091620176 10599698432 1124004560 12.065 98 13 26 1 1233803680 107270320 2528800
13:02:44 78316 8192000000 10090532720 10537598976 1117722128 12.005 98 17 34 1 1150060080 28849264 3488688
13:05:03 81230 8192000000 10093796816 10575257600 1128689472 12.109 99 13 26 0 1167946416 36646832 2610112
13:07:54 40563 8192000000 10064990512 10518347776 1106743376 11.902 97 16 32 2 1145306800 35351616 3211808
13:09:35 127636 8192000000 10089143104 10534064128 1103542336 11.871 97 11 22 1 1148275856 42568528 2164992
13:12:42 65110 8192000000 10080524944 10517229568 1123420304 12.059 98 12 24 1 1193979984 68212992 2346688
13:14:50 64882 8192000000 10095515920 10579849216 1080215920 11.650 95 37 74 1 1168846528 81348416 7282192
13:16:55 36927 8192000000 10126956064 10572197888 1126190192 12.085 99 33 66 1 1210626480 77353408 7082880
13:18:40 90966 8192000000 10059955440 10498547712 1136350336 12.181 100 27 53 3 1189611296 47097856 6163104
13:21:11 65286 8192000000 10063751952 10508451840 1104821280 11.883 99 14 28 0 1167356720 59418192 3117248
13:23:20 61676 8192000000 10068842704 10553257984 1101342704 11.850 98 16 31 1 1169187152 64378992 3465456
13:25:30 36697 8192000000 10056025040 10481463296 1094525920 11.786 98 21 42 0 1128549504 29401200 4622384

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 12-11-2020 13:28:19 to 12-11-2020 13:28:33.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 103 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-11-2020 13:28:34 to 12-11-2020 13:28:36.

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          228699
MON$OLDEST_ACTIVE               230734
MON$OLDEST_SNAPSHOT             230734
MON$NEXT_TRANSACTION            230734
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                       2128128
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             793
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2250 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2250 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2250 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.2250 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 ---
:76: 
:77: mscs-test-161.budzdorov.ru    Thu Nov 12 13:26:51 2020
:78:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:79: 
:80: 
:81: mscs-test-161.budzdorov.ru    Thu Nov 12 13:26:53 2020
:82:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:83: 
:84: 
:85: mscs-test-161.budzdorov.ru    Thu Nov 12 13:26:53 2020
:86:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:87: 
:88: 
:89: mscs-test-161.budzdorov.ru    Thu Nov 12 13:26:53 2020
:90:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:91: 
--- end of diff output ---

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

12.11.20 13:28:37. 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-12 13:28:40 No severe PSQL-related problems occured
12.11.20 13:28:41. End of report.