Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.12.2020 07:38
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 16758476 kB
MemAvailable 31382904 kB
Buffers 0 kB
Cached 14785212 kB
SwapCached 3704 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-12-18 07:37:30
DTS_END                         2020-12-18 07:37:30.4180
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/52228
TRN_ID                          242110
ATT_ID                          713
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: 9106.44 20990 1092773
customer order: creation 179.12 397 21494
customer order: refuse 14.57 321 1748
order to supplier: creation 47.30 1352 5676
order to supplier: removal 3.33 116304 400
invoice (draft): creation 48.08 3406 5769
invoice (draft): removal 51.07 998 6128
invoice accept: apply 42.32 16262 5078
invoice accept: cancel 45.21 825 5425
customer reserve: creation 3920.54 158 470465
customer reserve: removal 4464.01 69 535681
realization accept: apply 86.34 148 10361
realization accept: cancel 73.21 125 8785
payment from customer: creation 61.16 92 7339
payment from customer: removal 6.08 57 730
payment to supplier: creation 49.60 112 5952
payment to supplier: removal 3.65 56 438
service: total inventory turnovers 3.98 2907 478
service: total monetary turnovers 5.89 623 707
service: aggregate perf. data 0.95 13006 114
service: refresh index statistics 0.04 262577 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 18-12-2020 07:38:27 to 18-12-2020 07:38:30.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 10265
TEST_TIME 2 7364
TEST_TIME 3 7155
TEST_TIME 4 6796
TEST_TIME 5 6964
TEST_TIME 6 7211
TEST_TIME 7 7146
TEST_TIME 8 7008
TEST_TIME 9 6796
TEST_TIME 10 6930
TEST_TIME 11 7010
TEST_TIME 12 7293
TEST_TIME 13 7360
TEST_TIME 14 7291
TEST_TIME 15 7558
TEST_TIME 16 7972
TEST_TIME 17 7969
TEST_TIME 18 8907
TEST_TIME 19 9401
TEST_TIME 20 9329
TEST_TIME 21 9098
TEST_TIME 22 9364
TEST_TIME 23 9562
TEST_TIME 24 9652
TEST_TIME 25 9912
TEST_TIME 26 9641
TEST_TIME 27 10288
TEST_TIME 28 11627
TEST_TIME 29 11420
TEST_TIME 30 11187
TEST_TIME 31 10894
TEST_TIME 32 10684
TEST_TIME 33 10502
TEST_TIME 34 10682
TEST_TIME 35 10629
TEST_TIME 36 10475
TEST_TIME 37 10446
TEST_TIME 38 10334
TEST_TIME 39 10271
TEST_TIME 40 10122
TEST_TIME 41 10120
TEST_TIME 42 10278
TEST_TIME 43 10262
TEST_TIME 44 10211
TEST_TIME 45 10270
TEST_TIME 46 10335
TEST_TIME 47 10257
TEST_TIME 48 10356
TEST_TIME 49 10292
TEST_TIME 50 10279
TEST_TIME 51 10305
TEST_TIME 52 10266
TEST_TIME 53 10107
TEST_TIME 54 10050
TEST_TIME 55 10002
TEST_TIME 56 9914
TEST_TIME 57 9840
TEST_TIME 58 9786
TEST_TIME 59 9711
TEST_TIME 60 9697
TEST_TIME 61 9697
TEST_TIME 62 9699
TEST_TIME 63 9621
TEST_TIME 64 9626
TEST_TIME 65 9737
TEST_TIME 66 9775
TEST_TIME 67 9714
TEST_TIME 68 9797
TEST_TIME 69 9869
TEST_TIME 70 9839
TEST_TIME 71 9871
TEST_TIME 72 9878
TEST_TIME 73 9788
TEST_TIME 74 9689
TEST_TIME 75 9662
TEST_TIME 76 9556
TEST_TIME 77 9471
TEST_TIME 78 9496
TEST_TIME 79 9470
TEST_TIME 80 9411
TEST_TIME 81 9408
TEST_TIME 82 9387
TEST_TIME 83 9397
TEST_TIME 84 9320
TEST_TIME 85 9415
TEST_TIME 86 9484
TEST_TIME 87 9482
TEST_TIME 88 9564
TEST_TIME 89 9569
TEST_TIME 90 9493
TEST_TIME 91 9466
TEST_TIME 92 9429
TEST_TIME 93 9392
TEST_TIME 94 9346
TEST_TIME 95 9269
TEST_TIME 96 9255
TEST_TIME 97 9303
TEST_TIME 98 9329
TEST_TIME 99 9293
TEST_TIME 100 9293
TEST_TIME 101 9261
TEST_TIME 102 9246
TEST_TIME 103 9372
TEST_TIME 104 9386
TEST_TIME 105 9358
TEST_TIME 106 9391
TEST_TIME 107 9415
TEST_TIME 108 9412
TEST_TIME 109 9368
TEST_TIME 110 9308
TEST_TIME 111 9278
TEST_TIME 112 9227
TEST_TIME 113 9172
TEST_TIME 114 9136
TEST_TIME 115 9147
TEST_TIME 116 9143
TEST_TIME 117 9132
TEST_TIME 118 9118
TEST_TIME 119 9119
TEST_TIME 120 9107

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 18-12-2020 07:38:31 to 18-12-2020 07:38:35.

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 1528555 1528555 0 0.00 0 17041 6 0 0
doc_list_biud 1528553 1528553 0 0.00 0 51820 31 0 0
sp_add_invoice_to_stock 5144 5078 66 1.28 48 205492 16263 0 66
sp_cancel_adding_invoice 5425 5425 0 0.00 4 59246 826 0 0
sp_cancel_client_order 1748 1748 0 0.00 4 12840 321 0 0
sp_cancel_customer_reserve 535681 535681 0 0.00 1 51822 69 0 0
sp_cancel_pay_from_customer 730 730 0 0.00 3 1480 58 0 0
sp_cancel_pay_to_supplier 438 438 0 0.00 2 1059 56 0 0
sp_cancel_supplier_invoice 6134 6128 6 0.09 1 18728 998 0 6
sp_cancel_supplier_order 400 400 0 0.00 8 1027768 116304 0 0
sp_cancel_write_off 8785 8785 0 0.00 1 13391 125 0 0
sp_client_order 21494 21494 0 0.00 4 27920 398 0 0
sp_customer_reserve 470465 470465 0 0.00 0 85930 159 0 0
sp_fill_shopping_cart 32913 32913 0 0.00 1 16795 43 0 0
sp_fill_shopping_cart_clo_res 470462 470462 0 0.00 0 28575 20 0 0
sp_get_clo_for_invoice 5074 5074 0 0.00 0 57846 385 0 0
sp_kill_cost_storno 15378 15378 0 0.00 0 6239 51 0 0
sp_kill_qstorno_ret_qs2qd 1086675 1086675 0 0.00 0 51818 38 0 0
sp_kill_qty_storno 573601 573601 0 0.00 0 51820 81 0 0
sp_lock_selected_doc 21290 21290 0 0.00 0 10220 4 0 0
sp_make_cost_storno 28726 28726 0 0.00 0 11446 45 0 0
sp_multiply_rows_for_pdistr 28725 28725 0 0.00 0 12078 26 0 0
sp_multiply_rows_for_qdistr 21494 21494 0 0.00 0 27910 224 0 0
sp_pay_from_customer 7339 7339 0 0.00 4 17045 92 0 0
sp_pay_to_supplier 5952 5952 0 0.00 5 12859 112 0 0
sp_payment_common 13291 13291 0 0.00 2 12174 17 0 0
sp_qd_handle_on_cancel_clo 1748 1748 0 0.00 0 12832 291 0 0
sp_qd_handle_on_invoice_upd_sts 10499 10499 0 0.00 0 29094 358 0 0
sp_qd_handle_on_reserve_upd_sts 10360 10360 0 0.00 0 18986 51 0 0
sp_reserve_write_off 10361 10361 0 0.00 6 19031 149 0 0
sp_supplier_invoice 5769 5769 0 0.00 6 86948 3407 0 0
sp_supplier_order 5676 5676 0 0.00 5 26092 1353 0 0
srv_aggregate_perf_data 114 114 0 0.00 2758 133540 13006 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 2383 176462 45020 0 0
srv_find_qd_qs_mism 1043862 1043862 0 0.00 0 13377 5 0 0
srv_make_invnt_saldo 478 478 0 0.00 57 125312 2907 0 0
srv_make_money_saldo 707 707 0 0.00 1 14575 623 0 0
srv_recalc_idx_stat 5 5 0 0.00 95222 431419 262577 0 0
t$perf-abend:sp_add_invoice_to_stock 66 66 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 5078 5078 0 0.00 0 15065 183 0 0
t$perf-norm:sp_cancel_adding_invoice 386 386 0 0.00 0 4517 93 0 0
t$perf-norm:sp_cancel_client_order 1748 1748 0 0.00 0 1528 10 0 0
t$perf-norm:sp_cancel_customer_reserve 714 714 0 0.00 0 277 4 0 0
t$perf-norm:sp_cancel_pay_from_customer 730 730 0 0.00 0 876 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 438 438 0 0.00 0 2722 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 430 430 0 0.00 0 989 15 0 0
t$perf-norm:sp_cancel_supplier_order 400 400 0 0.00 0 20896 1410 0 0
t$perf-norm:sp_cancel_write_off 1010 1010 0 0.00 0 6670 14 0 0
t$perf-norm:sp_client_order 21494 21494 0 0.00 0 13099 15 0 0
t$perf-norm:sp_customer_reserve 1127 1127 0 0.00 0 766 4 0 0
t$perf-norm:sp_pay_from_customer 7339 7339 0 0.00 0 4025 7 0 0
t$perf-norm:sp_pay_to_supplier 5952 5952 0 0.00 0 6428 8 0 0
t$perf-norm:sp_reserve_write_off 10361 10361 0 0.00 0 12845 9 0 0
t$perf-norm:sp_supplier_invoice 5769 5769 0 0.00 0 6316 15 0 0
t$perf-norm:sp_supplier_order 5676 5676 0 0.00 0 7367 17 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 750 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 153 3 0 0
t$perf-norm:srv_make_invnt_saldo 478 478 0 0.00 0 441 5 0 0
t$perf-norm:srv_make_money_saldo 707 707 0 0.00 0 1329 8 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10280 10148 132 1.28 0 10981 17 0 132
v_all_customers 38450 38450 0 0.00 0 42 1 0 0
v_all_suppliers 31308 31308 0 0.00 0 30 1 0 0
v_all_wares 42986 42986 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 772 772 0 0.00 0 1707 9 0 0
v_cancel_client_order 3496 3496 0 0.00 0 2181 5 0 0
v_cancel_customer_prepayment 1460 1460 0 0.00 0 1117 5 0 0
v_cancel_customer_reserve 1428 1428 0 0.00 0 1325 5 0 0
v_cancel_payment_to_supplier 876 876 0 0.00 0 201 3 0 0
v_cancel_supplier_invoice 872 860 12 1.37 0 1255 13 0 12
v_cancel_supplier_order 800 800 0 0.00 0 767 4 0 0
v_cancel_write_off 2020 2020 0 0.00 0 3150 13 0 0
v_max_id_clo_ord 5671 5671 0 0.00 0 16080 4 0 0
v_max_id_clo_res 1127 1127 0 0.00 0 1241 16 0 0
v_max_id_ord_sup 5747 5747 0 0.00 0 6391 7 0 0
v_max_non_paid_invoice 1716 1716 0 0.00 0 1936 3 0 0
v_max_non_paid_realizn 4125 4125 0 0.00 0 9698 4 0 0
v_min_id_clo_ord 5671 5671 0 0.00 0 1090 4 0 0
v_min_id_clo_res 1127 1127 0 0.00 0 5401 10 0 0
v_min_id_ord_sup 5747 5747 0 0.00 0 6161 8 0 0
v_min_non_paid_invoice 5952 5952 0 0.00 0 5881 11 0 0
v_min_non_paid_realizn 7339 7339 0 0.00 0 7114 10 0 0
v_our_firm 10964 10964 0 0.00 0 16 1 0 0
v_reserve_write_off 20720 20720 0 0.00 0 3126 4 0 0
x_lock_depdocs_on_canc_invoice 11123 11123 0 0.00 0 10622 93 0 0
x_lock_depdocs_on_canc_sup_ord 400 400 0 0.00 0 13314 288 0 0
x_make_qstorno_1000_1200 5676 5676 0 0.00 2 26080 1280 0 0
x_make_qstorno_1200_2000 5769 5769 0 0.00 3 86833 3239 0 0
x_make_qstorno_2100_3300 470465 470465 0 0.00 0 85928 136 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-12-2020 07:38:36 to 18-12-2020 07:38:40.

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
05:07:34 1 8192000000 8796637808 8807108608 3167696 0.038 1 1 2 0 3265392 61840 35856
05:08:35 6595 8192000000 9712619344 10005970944 790941552 8.804 100 84 168 0 818475648 7581200 19952896
05:09:56 17514 8192000000 9752224896 10054717440 827887552 9.178 100 87 174 1 860770848 12252400 20630896
05:11:18 22214 8192000000 9800779168 10115309568 862994656 9.530 100 88 177 0 900815104 17368320 20452128
05:12:45 10482 8192000000 9854873504 10179866624 901031328 9.909 100 80 159 3 960837056 41992976 17812752
05:14:00 6564 8192000000 9904258400 10242617344 942489552 10.317 100 81 161 1 1015729856 56206464 17033840
05:15:08 5133 8192000000 9927137312 10276229120 979948944 10.684 100 74 146 3 1035357472 40131696 15276832
05:16:15 2985 8192000000 9987020112 10341994496 1009612608 10.972 100 87 172 4 1099672000 73283376 16776016
05:17:19 4253 8192000000 10012046416 10370715648 1032991120 11.197 100 83 164 2 1119072400 70120048 15961232
05:18:25 1301 8192000000 10037922400 10405453824 1062012464 11.476 100 91 184 1 1144107680 62490432 19604784
05:19:27 1844 8192000000 10016754016 10394882048 1072311344 11.574 100 69 138 1 1139397984 52270160 14816480
05:20:30 8346 8192000000 9988906976 10373713920 1077324656 11.622 100 50 100 1 1102910672 14913648 10672368
05:21:39 38654 8192000000 9988893600 10397433856 1080494336 11.652 100 25 50 0 1115767200 29859424 5413440
05:23:18 8898 8192000000 10004586000 10403352576 1083160016 11.678 100 35 68 0 1118163088 28158064 6845008
05:24:28 7808 8192000000 10004377808 10399764480 1087452064 11.718 100 22 44 2 1120822000 28440336 4929600
05:25:37 22161 8192000000 10019795712 10423750656 1071781536 11.569 98 20 39 3 1094306464 18342816 4182112
05:27:00 2437 8192000000 10011596016 10395525120 1088428400 11.728 100 68 132 1 1113744608 10602016 14714192
05:28:04 5907 8192000000 10006734016 10395746304 1070259472 11.555 99 46 93 2 1114200848 33875600 10065776
05:29:10 5912 8192000000 9968165872 10348756992 1025724032 11.127 98 38 76 0 1066669920 32347152 8598736
05:30:16 69503 8192000000 9919990848 10315116544 960157024 10.491 92 37 73 3 985967792 16973584 8837184
05:32:27 9002 8192000000 9946533280 10336751616 1013257600 11.007 99 48 95 3 1043743936 20013952 10472384
05:33:37 5516 8192000000 9964905904 10358505472 1039724000 11.262 100 41 83 0 1071838384 22687168 9427216
05:34:46 5185 8192000000 9972949072 10368106496 1055835808 11.417 100 47 94 0 1085068960 18162512 11070640
05:35:55 21102 8192000000 9970162528 10370805760 1062372368 11.479 100 20 40 3 1093898784 27572688 3953728
05:37:21 3064 8192000000 9990838176 10389536768 1083707248 11.683 100 38 76 1 1111960496 19667152 8586096
05:38:29 4306 8192000000 10038933248 10435616768 1105503136 11.890 100 51 102 0 1145598272 29684528 10410608
05:39:39 38290 8192000000 10095849920 10504024064 1121426480 12.040 100 48 96 0 1202013168 70798560 9788128
05:41:23 10358 8192000000 10088072480 10493599744 1134795824 12.167 100 38 76 2 1198599088 56009328 7793936
05:42:38 7725 8192000000 10173973888 10578665472 1143028528 12.244 100 71 140 0 1278720800 121005408 14686864
05:43:49 7616 8192000000 10163478992 10575609856 1150033968 12.310 100 65 128 1 1273794464 109770944 13989552
05:45:01 9658 8192000000 10175085824 10583691264 1157353904 12.378 100 46 92 1 1284670080 117869056 9447120
05:46:14 10186 8192000000 10217725536 10634072064 1170596064 12.502 100 64 129 0 1323399472 138180112 14623296
05:47:28 7833 8192000000 10251362944 10661740544 1162388480 12.426 99 55 108 3 1351182224 176506784 12286960
05:48:38 7917 8192000000 10259546784 10667601920 1164332256 12.444 100 68 136 1 1358364832 178623936 15408640
05:49:49 2379 8192000000 10294499280 10691751936 1154121120 12.348 100 86 172 0 1393691456 220034288 19536048
05:51:00 9194 8192000000 10302383728 10705375232 1117694720 12.005 98 63 126 0 1394227216 261884544 14647952
05:52:15 7671 8192000000 10355077968 10764439552 1140127712 12.217 100 61 122 2 1459840080 305420496 14291872
05:53:29 2662 8192000000 10315565456 10724024320 1116803312 11.997 100 63 125 3 1425745408 292801280 16140816
05:54:39 7848 8192000000 10315815504 10717077504 1069499072 11.547 95 51 102 2 1381564320 299775776 12289472
05:55:50 4644 8192000000 10283285136 10698125312 1122084640 12.047 100 70 140 0 1376979792 238345040 16550112
05:57:06 7626 8192000000 10269053568 10680881152 1140919504 12.224 100 36 72 0 1373723568 224489856 8314208
05:58:15 16503 8192000000 10334095856 10753253376 1143455968 12.248 99 34 69 0 1415963408 263398288 9109152
05:59:34 5276 8192000000 10395677184 10825478144 1154761232 12.354 100 46 92 1 1499732080 333439888 11530960
06:00:50 11248 8192000000 10374218064 10821505024 1151586832 12.324 100 37 74 0 1479444864 318299056 9558976
06:02:09 20332 8192000000 10363574672 10801889280 1132606272 12.146 99 37 75 0 1456859248 314830256 9422720
06:03:31 21369 8192000000 10423283264 10868957184 1128788720 12.110 100 26 51 1 1525724160 389694624 7240816
06:04:55 14803 8192000000 10282296048 10745724928 1099132768 11.829 98 27 54 1 1401737552 296172512 6432272
06:06:12 47850 8192000000 10064180224 10480730112 1121163792 12.038 100 33 67 0 1142775312 14262912 7348608
06:08:09 105662 8192000000 10104841872 10543644672 1103524800 11.871 97 22 44 3 1171237504 63480208 4232496
06:10:56 29619 8192000000 10122567376 10563825664 1123568880 12.061 99 25 48 1 1204382800 75591232 5222688
06:12:27 27229 8192000000 10086274848 10544971776 1136835632 12.186 99 23 44 1 1183920144 41854528 5229984
06:14:01 31072 8192000000 10086108272 10546536448 1126080992 12.084 99 15 30 1 1187548720 58115200 3352528
06:15:33 18042 8192000000 10092829680 10540900352 1132188912 12.142 99 15 30 1 1178804512 43396560 3219040
06:16:52 32780 8192000000 10133528928 10610872320 1151304176 12.322 100 9 18 1 1224783392 71276736 2202480
06:18:33 47187 8192000000 10143376448 10633920512 1153264624 12.340 100 9 18 1 1259847328 104567920 2014784
06:20:21 48524 8192000000 10110872320 10580561920 1139546112 12.211 99 29 56 2 1223103200 77297920 6259168
06:22:10 26024 8192000000 10095485904 10560536576 1154853984 12.355 100 32 65 1 1205290752 43247696 7189072
06:23:41 57888 8192000000 10125044912 10583515136 1167795920 12.476 100 23 47 2 1212468256 40015104 4657232
06:25:41 97238 8192000000 10132593344 10580742144 1114724128 11.977 96 11 22 1 1167255552 49972544 2558880
06:28:19 57561 8192000000 10089935152 10522591232 1100773008 11.845 96 23 46 1 1143278864 37708528 4797328
06:30:17 161752 8192000000 10071935104 10539233280 1137623328 12.193 100 12 24 0 1199723856 59689392 2411136
06:34:06 49892 8192000000 10057194080 10530119680 1100189664 11.839 96 5 10 0 1122473216 21423408 860144
06:36:04 43547 8192000000 10066048560 10502201344 1110868816 11.941 98 13 25 0 1147865296 34086176 2910304
06:37:53 60082 8192000000 10061836432 10501484544 1047947536 11.341 93 25 48 4 1091811632 38741888 5122208
06:39:53 36372 8192000000 10082555312 10510467072 1091357920 11.756 97 22 44 0 1137129200 41418240 4353040
06:41:30 78369 8192000000 10067888656 10530549760 1059912224 11.456 95 32 64 4 1141123920 74638224 6573472
06:43:49 51419 8192000000 10052995856 10483003392 1079698944 11.645 98 19 38 1 1102839648 19225424 3915280
06:45:46 94911 8192000000 10067210704 10569728000 1090393952 11.746 98 12 24 0 1150822896 58099584 2329360
06:48:27 176462 8192000000 10050105312 10508849152 1110295888 11.935 99 5 10 1 1158041648 46895616 850144
06:52:36 60384 8192000000 10049562304 10511679488 1112048432 11.952 99 8 16 2 1158132160 44718560 1365168
06:54:39 76953 8192000000 10060426368 10551451648 1116890752 11.998 100 13 25 0 1149267104 29622800 2753552
06:57:07 38150 8192000000 10052071264 10491576320 1090978672 11.752 97 18 35 1 1113576384 19051936 3545776
06:58:57 45023 8192000000 10094243344 10544365568 1126343424 12.087 100 24 49 1 1176068752 43670512 6054816
07:00:46 80180 8192000000 10186599568 10638995456 1141212464 12.227 100 20 40 0 1265972912 120079888 4680560
07:03:32 63639 8192000000 10084199888 10555035648 1101195456 11.849 97 14 28 0 1153717520 49581872 2940192
07:05:42 84036 8192000000 10083971104 10539708416 1129925360 12.121 100 9 18 0 1163087680 31451712 1710608
07:08:06 125859 8192000000 10072147632 10539606016 1131099600 12.132 99 35 70 1 1196283488 58555184 6628704
07:11:13 56031 8192000000 10084984864 10564493312 1132109040 12.141 100 5 10 0 1187193952 54170992 913920
07:13:09 66315 8192000000 10093032832 10562392064 1120018688 12.027 98 18 36 1 1185665408 61669648 3977072
07:15:21 58444 8192000000 10066467184 10520350720 1126361904 12.087 99 15 30 3 1160723712 31000272 3361536
07:17:27 54584 8192000000 10097099360 10544181248 1140485648 12.220 99 26 52 2 1177680944 31487840 5707456
07:19:22 78764 8192000000 10194713728 10746982400 1160978352 12.412 100 29 58 3 1260852560 93725696 6148512
07:21:42 61669 8192000000 10097296464 10533920768 1091178752 11.754 94 18 36 0 1121681792 26946496 3556544
07:23:48 88199 8192000000 10091039120 10585210880 1105145136 11.886 96 11 22 0 1167136208 59209072 2782000
07:26:16 41667 8192000000 10085899488 10533158912 1126662368 12.090 99 43 86 0 1168760720 33487088 8611264
07:28:45 32196 8192000000 10055396480 10507870208 1117584784 12.004 98 7 14 1 1140420864 21708512 1127568
07:30:28 50804 8192000000 10062581136 10516742144 1108534560 11.919 98 9 19 0 1149280352 39017648 1728144
07:32:20 56442 8192000000 10060008304 10494869504 1076405968 11.613 95 16 32 1 1122385056 42315696 3663392
07:34:17 57319 8192000000 10060610208 10506264576 1030977792 11.178 92 22 44 1 1101251024 64999264 5273968
07:36:22 34631 8192000000 10098435472 10539651072 1060353712 11.460 96 37 74 3 1168596352 100377120 7865520

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-12-2020 07:38:42 to 18-12-2020 07:38:57.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 66 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 18-12-2020 07:38:58 to 18-12-2020 07:39:00.

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          237225
MON$OLDEST_ACTIVE               242231
MON$OLDEST_SNAPSHOT             242231
MON$NEXT_TRANSACTION            242231
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                       2099968
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             821
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2298 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2298 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2298 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.2298 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Fri Dec 18 07:37:39 2020
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Fri Dec 18 07:37:39 2020
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Fri Dec 18 07:37:39 2020
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:17: 
:18: 
:19: mscs-test-161.budzdorov.ru    Fri Dec 18 07:37:39 2020
:20:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:21: 
:22: 
:23: mscs-test-161.budzdorov.ru    Fri Dec 18 07:37:39 2020
:24:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:25: 
--- end of diff output ---

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

18.12.20 07:39:01. 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-12-18 07:39:03 No severe PSQL-related problems occured
18.12.20 07:39:04. End of report.