Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.12.2020 10:28
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 16855896 kB
MemAvailable 31543824 kB
Buffers 0 kB
Cached 14768604 kB
SwapCached 1972 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-04 10:26:53
DTS_END                         2020-12-04 10:26:53.7980
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/60436
TRN_ID                          225437
ATT_ID                          612
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: 8862.10 20025 1063452
customer order: creation 167.29 450 20075
customer order: refuse 14.04 431 1685
order to supplier: creation 44.18 1434 5302
order to supplier: removal 3.23 116150 387
invoice (draft): creation 44.43 3796 5331
invoice (draft): removal 48.88 855 5865
invoice accept: apply 40.55 19425 4866
invoice accept: cancel 44.45 971 5334
customer reserve: creation 3733.24 190 447989
customer reserve: removal 4445.41 68 533449
realization accept: apply 79.76 198 9571
realization accept: cancel 73.67 133 8840
payment from customer: creation 57.28 125 6873
payment from customer: removal 5.29 78 635
payment to supplier: creation 47.06 147 5647
payment to supplier: removal 3.24 95 389
service: total inventory turnovers 3.36 3977 403
service: total monetary turnovers 5.86 590 703
service: aggregate perf. data 0.86 17798 103
service: refresh index statistics 0.04 233598 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 04-12-2020 10:29:10 to 04-12-2020 10:29:12.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3493
TEST_TIME 2 3131
TEST_TIME 3 3711
TEST_TIME 4 4070
TEST_TIME 5 4696
TEST_TIME 6 4573
TEST_TIME 7 5031
TEST_TIME 8 5407
TEST_TIME 9 5685
TEST_TIME 10 5672
TEST_TIME 11 6014
TEST_TIME 12 6519
TEST_TIME 13 6703
TEST_TIME 14 6953
TEST_TIME 15 7005
TEST_TIME 16 7106
TEST_TIME 17 7175
TEST_TIME 18 7271
TEST_TIME 19 7469
TEST_TIME 20 7639
TEST_TIME 21 7706
TEST_TIME 22 7844
TEST_TIME 23 8167
TEST_TIME 24 8505
TEST_TIME 25 8272
TEST_TIME 26 8227
TEST_TIME 27 8184
TEST_TIME 28 8043
TEST_TIME 29 8345
TEST_TIME 30 8614
TEST_TIME 31 8812
TEST_TIME 32 9135
TEST_TIME 33 9694
TEST_TIME 34 9613
TEST_TIME 35 9733
TEST_TIME 36 9546
TEST_TIME 37 9655
TEST_TIME 38 9715
TEST_TIME 39 9503
TEST_TIME 40 9271
TEST_TIME 41 9191
TEST_TIME 42 9206
TEST_TIME 43 9171
TEST_TIME 44 9098
TEST_TIME 45 9019
TEST_TIME 46 9054
TEST_TIME 47 9042
TEST_TIME 48 9042
TEST_TIME 49 8978
TEST_TIME 50 9097
TEST_TIME 51 9085
TEST_TIME 52 9031
TEST_TIME 53 9031
TEST_TIME 54 9158
TEST_TIME 55 9226
TEST_TIME 56 9293
TEST_TIME 57 9252
TEST_TIME 58 9236
TEST_TIME 59 9195
TEST_TIME 60 9213
TEST_TIME 61 9087
TEST_TIME 62 9063
TEST_TIME 63 9050
TEST_TIME 64 9022
TEST_TIME 65 8930
TEST_TIME 66 8914
TEST_TIME 67 8925
TEST_TIME 68 8978
TEST_TIME 69 8960
TEST_TIME 70 8960
TEST_TIME 71 8928
TEST_TIME 72 9116
TEST_TIME 73 9110
TEST_TIME 74 9130
TEST_TIME 75 9199
TEST_TIME 76 9151
TEST_TIME 77 9203
TEST_TIME 78 9243
TEST_TIME 79 9257
TEST_TIME 80 9201
TEST_TIME 81 9106
TEST_TIME 82 9066
TEST_TIME 83 9020
TEST_TIME 84 8986
TEST_TIME 85 8918
TEST_TIME 86 8889
TEST_TIME 87 8838
TEST_TIME 88 8802
TEST_TIME 89 8798
TEST_TIME 90 8787
TEST_TIME 91 8794
TEST_TIME 92 8807
TEST_TIME 93 8874
TEST_TIME 94 8955
TEST_TIME 95 8942
TEST_TIME 96 8947
TEST_TIME 97 9082
TEST_TIME 98 9101
TEST_TIME 99 9050
TEST_TIME 100 9026
TEST_TIME 101 8946
TEST_TIME 102 8894
TEST_TIME 103 8892
TEST_TIME 104 8840
TEST_TIME 105 8836
TEST_TIME 106 8859
TEST_TIME 107 8861
TEST_TIME 108 8880
TEST_TIME 109 8880
TEST_TIME 110 8867
TEST_TIME 111 8840
TEST_TIME 112 8906
TEST_TIME 113 8882
TEST_TIME 114 8934
TEST_TIME 115 8990
TEST_TIME 116 8970
TEST_TIME 117 8975
TEST_TIME 118 8918
TEST_TIME 119 8898
TEST_TIME 120 8862

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 04-12-2020 10:29:13 to 04-12-2020 10:29:17.

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 1476924 1476924 0 0.00 0 22561 7 0 0
doc_list_biud 1476924 1476924 0 0.00 0 47658 31 0 0
sp_add_invoice_to_stock 4990 4866 124 2.48 37 212390 19425 0 124
sp_cancel_adding_invoice 5334 5334 0 0.00 6 87660 971 0 0
sp_cancel_client_order 1685 1685 0 0.00 4 18293 432 0 0
sp_cancel_customer_reserve 533449 533449 0 0.00 1 28371 68 0 0
sp_cancel_pay_from_customer 635 635 0 0.00 3 2646 79 0 0
sp_cancel_pay_to_supplier 389 389 0 0.00 3 3169 95 0 0
sp_cancel_supplier_invoice 5874 5865 9 0.15 2 19348 855 0 9
sp_cancel_supplier_order 387 387 0 0.00 12 924207 116150 0 0
sp_cancel_write_off 8840 8840 0 0.00 1 10649 133 0 0
sp_client_order 20075 20075 0 0.00 4 83321 451 0 0
sp_customer_reserve 447989 447989 0 0.00 0 56547 191 0 0
sp_fill_shopping_cart 30680 30680 0 0.00 1 14167 44 0 0
sp_fill_shopping_cart_clo_res 447986 447986 0 0.00 0 24706 18 0 0
sp_get_clo_for_invoice 4863 4863 0 0.00 0 49979 439 0 0
sp_kill_cost_storno 15198 15198 0 0.00 0 10559 61 0 0
sp_kill_qstorno_ret_qs2qd 1081990 1081990 0 0.00 0 28320 37 0 0
sp_kill_qty_storno 569994 569994 0 0.00 0 47658 79 0 0
sp_lock_selected_doc 19939 19939 0 0.00 0 5972 3 0 0
sp_make_cost_storno 26954 26954 0 0.00 0 22493 61 0 0
sp_multiply_rows_for_pdistr 26954 26954 0 0.00 0 14040 38 0 0
sp_multiply_rows_for_qdistr 20075 20075 0 0.00 0 83161 269 0 0
sp_pay_from_customer 6873 6873 0 0.00 4 15135 125 0 0
sp_pay_to_supplier 5647 5647 0 0.00 5 20923 148 0 0
sp_payment_common 12520 12520 0 0.00 1 16025 32 0 0
sp_qd_handle_on_cancel_clo 1685 1685 0 0.00 0 18288 406 0 0
sp_qd_handle_on_invoice_upd_sts 10197 10197 0 0.00 0 47657 401 0 0
sp_qd_handle_on_reserve_upd_sts 9571 9571 0 0.00 0 10563 57 0 0
sp_reserve_write_off 9571 9571 0 0.00 7 15682 198 0 0
sp_supplier_invoice 5331 5331 0 0.00 7 117776 3797 0 0
sp_supplier_order 5302 5302 0 0.00 6 55468 1434 0 0
srv_aggregate_perf_data 103 103 0 0.00 2655 323260 17799 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1731 153806 44743 0 0
srv_find_qd_qs_mism 1016700 1016700 0 0.00 0 21034 6 0 0
srv_make_invnt_saldo 403 403 0 0.00 44 95524 3978 0 0
srv_make_money_saldo 703 703 0 0.00 1 14150 591 0 0
srv_recalc_idx_stat 5 5 0 0.00 76927 391890 233598 0 0
t$perf-abend:sp_add_invoice_to_stock 124 124 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4866 4866 0 0.00 0 7949 183 0 0
t$perf-norm:sp_cancel_adding_invoice 412 412 0 0.00 1 6146 113 0 0
t$perf-norm:sp_cancel_client_order 1685 1685 0 0.00 0 2930 13 0 0
t$perf-norm:sp_cancel_customer_reserve 654 654 0 0.00 0 317 5 0 0
t$perf-norm:sp_cancel_pay_from_customer 635 635 0 0.00 0 4005 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 389 389 0 0.00 0 47 1 0 0
t$perf-norm:sp_cancel_supplier_invoice 387 387 0 0.00 0 578 19 0 0
t$perf-norm:sp_cancel_supplier_order 387 387 0 0.00 0 13280 1467 0 0
t$perf-norm:sp_cancel_write_off 956 956 0 0.00 0 1660 8 0 0
t$perf-norm:sp_client_order 20075 20075 0 0.00 0 7543 20 0 0
t$perf-norm:sp_customer_reserve 947 947 0 0.00 0 1624 6 0 0
t$perf-norm:sp_pay_from_customer 6873 6873 0 0.00 0 9686 13 0 0
t$perf-norm:sp_pay_to_supplier 5647 5647 0 0.00 0 4364 11 0 0
t$perf-norm:sp_reserve_write_off 9571 9571 0 0.00 0 7310 9 0 0
t$perf-norm:sp_supplier_invoice 5331 5331 0 0.00 0 4322 19 0 0
t$perf-norm:sp_supplier_order 5302 5302 0 0.00 0 3870 18 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 266 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 1055 26 0 0
t$perf-norm:srv_make_invnt_saldo 403 403 0 0.00 0 2878 17 0 0
t$perf-norm:srv_make_money_saldo 703 703 0 0.00 0 315 3 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 15 5 0 0
v_add_invoice_to_stock 9974 9726 248 2.48 0 6707 17 0 248
v_all_customers 36702 36702 0 0.00 0 101 1 0 0
v_all_suppliers 29230 29230 0 0.00 0 74 1 0 0
v_all_wares 40150 40150 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 824 824 0 0.00 0 96 5 0 0
v_cancel_client_order 3370 3370 0 0.00 0 4561 5 0 0
v_cancel_customer_prepayment 1270 1270 0 0.00 0 1452 6 0 0
v_cancel_customer_reserve 1308 1308 0 0.00 0 1093 5 0 0
v_cancel_payment_to_supplier 778 778 0 0.00 0 201 3 0 0
v_cancel_supplier_invoice 792 774 18 2.27 0 657 15 0 18
v_cancel_supplier_order 774 774 0 0.00 0 462 4 0 0
v_cancel_write_off 1912 1912 0 0.00 0 1164 13 0 0
v_max_id_clo_ord 5297 5297 0 0.00 0 381 1 0 0
v_max_id_clo_res 947 947 0 0.00 0 4519 15 0 0
v_max_id_ord_sup 5308 5308 0 0.00 0 3040 3 0 0
v_max_non_paid_invoice 1637 1637 0 0.00 0 394 2 0 0
v_max_non_paid_realizn 3638 3638 0 0.00 0 2147 4 0 0
v_min_id_clo_ord 5297 5297 0 0.00 0 1249 6 0 0
v_min_id_clo_res 947 947 0 0.00 0 6426 11 0 0
v_min_id_ord_sup 5308 5308 0 0.00 0 7810 7 0 0
v_min_non_paid_invoice 5647 5647 0 0.00 0 11607 23 0 0
v_min_non_paid_realizn 6873 6873 0 0.00 0 11469 25 0 0
v_our_firm 9918 9918 0 0.00 0 46 1 0 0
v_reserve_write_off 19142 19142 0 0.00 0 3294 5 0 0
x_lock_depdocs_on_canc_invoice 10812 10812 0 0.00 0 29445 116 0 0
x_lock_depdocs_on_canc_sup_ord 387 387 0 0.00 0 15720 368 0 0
x_make_qstorno_1000_1200 5302 5302 0 0.00 2 55455 1346 0 0
x_make_qstorno_1200_2000 5331 5331 0 0.00 3 117767 3635 0 0
x_make_qstorno_2100_3300 447989 447989 0 0.00 0 56546 170 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-12-2020 10:29:18 to 04-12-2020 10:29:22.

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
07:56:57 6 8192000000 8796641392 8807174144 3168704 0.038 1 1 2 0 3266400 61840 35856
07:57:58 23354 8192000000 9725353200 10021867520 795298064 8.849 100 86 172 0 823573376 7422768 20852544
07:59:29 23875 8192000000 9760795024 10066014208 834205552 9.242 100 88 176 0 867267184 12194656 20866976
08:00:57 18910 8192000000 9793626992 10109825024 859301328 9.493 100 84 169 0 896741392 18479952 18960112
08:02:21 17481 8192000000 9853847968 10182234112 893010768 9.829 100 81 162 0 957540928 46083088 18447072
08:03:45 12187 8192000000 9902276176 10245132288 944938896 10.341 100 82 164 2 1016263872 54044336 17280640
08:05:03 5395 8192000000 9966422064 10322145280 984605856 10.729 100 86 172 1 1080568656 78794176 17168624
08:06:10 2787 8192000000 9984471456 10342449152 1006447632 10.941 100 89 175 3 1096828768 72210736 18170400
08:07:16 6707 8192000000 9996509392 10370138112 1024609776 11.116 100 70 140 1 1114561168 76298704 13652688
08:08:24 1450 8192000000 10026875344 10394685440 1055893328 11.417 100 93 186 1 1136158784 61363808 18901648
08:09:27 11094 8192000000 10016164208 10391736320 1063991072 11.495 100 66 127 2 1123304288 45917584 13395632
08:10:40 5731 8192000000 10014140256 10403348480 1073982944 11.590 100 34 68 1 1128302400 47382928 6936528
08:11:47 4563 8192000000 9986320688 10379227136 1077760608 11.626 100 49 98 0 1100784704 12474672 10549424
08:12:53 6936 8192000000 10005331824 10411778048 1087063760 11.715 100 26 50 0 1111426384 19274576 5088048
08:14:03 27356 8192000000 10017504528 10409046016 1090257296 11.745 100 72 149 1 1118678160 12236608 16184256
08:15:30 4491 8192000000 10035012656 10432655360 1096591792 11.805 100 70 141 1 1141539664 30263360 14684512
08:16:37 4498 8192000000 10020487200 10417164288 1097661008 11.815 100 37 71 2 1125198256 19010000 8527248
08:17:43 55766 8192000000 9977838416 10388131840 1036830160 11.234 95 31 62 4 1054624928 11566416 6228352
08:19:39 6931 8192000000 9971218464 10370850816 1042421696 11.288 98 41 82 1 1071897024 19986816 9488512
08:20:50 7641 8192000000 9967155248 10359422976 1029826592 11.167 98 44 87 0 1069396032 29654416 9915024
08:21:58 4680 8192000000 9934040576 10311462912 1024819616 11.119 99 35 67 7 1056839856 24309952 7710288
08:23:03 40201 8192000000 9946958848 10325057536 998857968 10.867 97 50 95 9 1026833936 17925200 10050768
08:24:43 5207 8192000000 9943422896 10328494080 1009216208 10.968 97 40 80 1 1027161888 9517088 8428592
08:25:50 9021 8192000000 9969394848 10377953280 1053510464 11.394 100 34 69 3 1079779216 18003216 8265536
08:27:00 18614 8192000000 10011787840 10394824704 1082182176 11.668 100 45 87 1 1102897984 10708224 10007584
08:28:21 27048 8192000000 10029434256 10430443520 1093856288 11.779 100 28 56 2 1124521424 25064256 5600880
08:29:52 25341 8192000000 10051891744 10453942272 1108446768 11.918 100 62 122 1 1170271024 49834848 11989408
08:31:22 15156 8192000000 10074821760 10476539904 1114266592 11.973 100 45 89 1 1177435504 53815952 9352960
08:32:40 17260 8192000000 10117735088 10522152960 1120065280 12.028 100 40 79 2 1219089136 90660944 8362912
08:34:06 18448 8192000000 10108240800 10525839360 1133795040 12.157 100 49 98 1 1221211568 76662848 10753680
08:35:29 9174 8192000000 10128316672 10553122816 1140434240 12.220 100 32 64 1 1237389808 89866240 7089328
08:36:41 4770 8192000000 10167033904 10577088512 1147315584 12.284 100 70 140 1 1276859984 114686896 14857504
08:37:50 5166 8192000000 10158144016 10571177984 1141181904 12.227 99 59 116 2 1263678096 110257296 12238896
08:39:02 1728 8192000000 10172085536 10581299200 1162399536 12.426 100 61 121 2 1274762720 99607840 12755344
08:40:08 3250 8192000000 10192888816 10603446272 1172474880 12.520 100 78 156 1 1297187952 107925632 16787440
08:41:17 5860 8192000000 10167172928 10580865024 1155089808 12.357 100 52 103 0 1270039632 103704416 11245408
08:42:36 1792 8192000000 10199152960 10600255488 1143987344 12.253 99 88 174 1 1293702736 129935232 19780160
08:43:44 3117 8192000000 10232826336 10636890112 1152990864 12.338 99 84 166 0 1325592592 154320192 18281536
08:44:56 2236 8192000000 10230805040 10635579392 1166764768 12.467 100 73 145 1 1333257536 150210192 16282576
08:46:02 7198 8192000000 10253833312 10666045440 1137767008 12.195 99 61 121 0 1342938784 191072864 14098912
08:47:14 4149 8192000000 10290474992 10695884800 1155296960 12.359 100 72 143 0 1386690752 214581200 16812592
08:48:22 2481 8192000000 10286193568 10680868864 1137442416 12.191 99 83 164 0 1379459856 222777680 19239760
08:49:28 8140 8192000000 10287265472 10732044288 1154487936 12.352 100 46 90 0 1412034368 246540928 11005504
08:50:44 8435 8192000000 10285728800 10699579392 1164012144 12.441 100 47 94 0 1391588960 215774256 11802560
08:51:55 32881 8192000000 10293886800 10737950720 1147448976 12.286 99 39 78 1 1411763264 254725216 9589072
08:53:41 8598 8192000000 10339612848 10768125952 1136496544 12.183 99 40 76 1 1437619104 291688608 9433952
08:55:09 12548 8192000000 10362154272 10821722112 1127585168 12.099 99 43 86 0 1463226384 324835488 10805728
08:56:26 24498 8192000000 10299510544 10743894016 1116999008 11.999 99 24 48 2 1387478352 264015824 6463520
08:57:53 52757 8192000000 10199650544 10671333376 1117306960 12.002 100 15 30 0 1356575072 235235536 4032576
08:59:48 51343 8192000000 10077017120 10497708032 1102127408 11.858 98 24 48 3 1140807904 33488416 5192080
09:01:40 46798 8192000000 10058892288 10500165632 1094292960 11.783 97 35 69 2 1126844912 25299680 7252272
09:03:31 96176 8192000000 10064705328 10535964672 1113444560 11.965 99 21 42 2 1162763584 45135520 4183504
09:06:48 57558 8192000000 10085992816 10548092928 1119404672 12.021 99 19 38 0 1163612960 40376800 3831488
09:08:47 17337 8192000000 10075334480 10547609600 1135940240 12.177 100 23 45 3 1184500432 43722240 4837952
09:10:06 90323 8192000000 10116895872 10559176704 1123832144 12.063 98 14 28 1 1189999520 63059552 3107824
09:12:38 49803 8192000000 10137085632 10582118400 1138920064 12.205 99 21 43 2 1227356624 84321312 4115248
09:14:34 30143 8192000000 10107773616 10569572352 1139483776 12.211 100 17 34 1 1226970624 83371296 4115552
09:16:12 48266 8192000000 10096844656 10596257792 1145442352 12.267 100 15 30 0 1209069568 60320080 3307136
09:18:05 42181 8192000000 10105738736 10550267904 1140934544 12.224 99 20 38 1 1189687680 44592832 4160304
09:19:50 95478 8192000000 10097634880 10600075264 1105161072 11.887 96 16 32 0 1183106656 74139792 3805792
09:22:28 102735 8192000000 10069936880 10510401536 1087702272 11.721 95 14 28 1 1129512976 38974240 2836464
09:25:15 43828 8192000000 10121216912 10545426432 1122111744 12.047 98 20 40 1 1166658528 40667776 3879008
09:27:01 131370 8192000000 10112738416 10581401600 1114822560 11.978 97 26 52 0 1199802000 79766608 5212832
09:30:48 33309 8192000000 10083487792 10590793728 1134732576 12.166 99 3 6 0 1181999952 46915232 352144
09:32:32 32376 8192000000 10114586176 10564812800 1141225840 12.227 100 29 58 2 1192176528 44307312 6643376
09:34:07 49784 8192000000 10110762736 10609979392 1107846928 11.912 97 15 30 1 1205963872 94393184 3723760
09:35:57 63340 8192000000 10138853744 10590789632 1126471392 12.088 98 24 47 1 1219377152 87838576 5067184
09:38:05 92004 8192000000 10107173904 10576576512 1038848256 11.254 91 12 24 0 1073720128 32498720 2373152
09:40:53 29633 8192000000 10088960672 10532278272 1103148288 11.868 98 12 24 1 1168773328 63027088 2597952
09:42:23 22310 8192000000 10081983456 10500833280 1106114160 11.896 99 54 108 0 1154573104 36860240 11598704
09:43:46 54431 8192000000 10108859584 10555584512 1082470256 11.671 97 11 22 0 1132347760 47634736 2242768
09:45:41 127591 8192000000 10051406384 10502033408 1091002560 11.752 98 19 38 1 1134922736 40128464 3791712
09:48:49 58374 8192000000 10050476880 10506964992 1109379952 11.927 99 16 32 0 1157043648 44448528 3215168
09:50:49 46004 8192000000 10038122448 10519826432 1108350112 11.917 99 4 8 0 1142165536 32624176 1191248
09:52:41 56389 8192000000 10048559056 10490609664 1101670768 11.853 99 16 31 0 1128992928 24073120 3249040
09:54:44 77448 8192000000 10069549712 10533785600 1051000112 11.370 94 22 44 1 1123903504 68083136 4820256
09:57:07 56195 8192000000 10125157920 10556387328 1091604656 11.758 97 23 46 0 1187080640 90160432 5315552
09:59:12 68746 8192000000 10108702352 10580262912 1115150320 11.981 99 13 26 1 1230352832 112345472 2857040
10:01:21 21832 8192000000 10133495920 10564632576 1138106864 12.198 100 59 117 1 1223725376 72254960 13363552
10:02:46 46754 8192000000 10096886768 10659926016 1139433520 12.210 100 7 14 0 1239811696 98684160 1694016
10:04:33 43423 8192000000 10075314464 10506280960 1119933968 12.026 98 26 52 0 1148009456 21460144 6615344
10:06:30 153800 8192000000 10165279808 10649460736 1152609120 12.334 100 21 40 2 1270194096 113025488 4559488
10:10:09 88113 8192000000 10154210976 10654699520 1154820128 12.355 100 9 18 0 1269724064 112835840 2068096
10:12:51 102710 8192000000 10113889184 10604548096 1144732400 12.260 99 20 40 1 1230427584 81153136 4542048
10:15:36 50406 8192000000 10109534928 10566111232 1120797760 12.035 97 20 40 0 1189367056 64204592 4364704
10:17:27 65015 8192000000 10123743520 10614063104 1136985008 12.187 99 23 46 1 1238751152 96141680 5624464
10:19:40 39851 8192000000 10164542016 10612011008 1154368944 12.350 100 30 60 1 1225264544 64730704 6164896
10:21:40 88904 8192000000 10113604208 10605510656 1112810128 11.959 97 7 14 1 1193210656 79057312 1343216
10:24:12 115508 8192000000 10078632768 10587414528 1141957104 12.234 100 14 28 0 1187065680 42333104 2775472

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 04-12-2020 10:29:24 to 04-12-2020 10:29:39.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 124 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 04-12-2020 10:29:40 to 04-12-2020 10:29:42.

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          225266
MON$OLDEST_ACTIVE               225617
MON$OLDEST_SNAPSHOT             225617
MON$NEXT_TRANSACTION            225617
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                       2108992
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             776
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2288 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2288 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2288 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.2288 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 ---
:27: 
:28: mscs-test-161.budzdorov.ru    Fri Dec  4 10:27:02 2020
:29:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:30: 
:31: 
:32: mscs-test-161.budzdorov.ru    Fri Dec  4 10:27:07 2020
:33:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:34: 
--- end of diff output ---

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

04.12.20 10:29:43. 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-04 10:29:45 No severe PSQL-related problems occured
04.12.20 10:29:46. End of report.