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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID e0e7b46250ba4f9795ceb55e0face73f
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2020-09-25 13:42
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.00
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16554916 kB
MemAvailable 31277108 kB
Buffers 20 kB
Cached 14935000 kB
SwapCached 4820 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-31 04:26:56
DTS_END                         2020-12-31 04:26:56.1150
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_FROM_CUSTOMER(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/58836
TRN_ID                          233916
ATT_ID                          681
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: 9080.80 21826 1089696
customer order: creation 170.64 431 20477
customer order: refuse 14.76 315 1771
order to supplier: creation 45.41 1368 5449
order to supplier: removal 3.40 119211 408
invoice (draft): creation 45.93 3472 5511
invoice (draft): removal 50.68 977 6082
invoice accept: apply 42.03 17609 5043
invoice accept: cancel 45.32 931 5438
customer reserve: creation 3892.03 173 467043
customer reserve: removal 4479.12 72 537494
realization accept: apply 82.93 160 9951
realization accept: cancel 83.96 124 10075
payment from customer: creation 57.20 105 6864
payment from customer: removal 5.48 85 658
payment to supplier: creation 47.86 106 5743
payment to supplier: removal 3.37 88 404
service: total inventory turnovers 3.75 3425 450
service: total monetary turnovers 6.03 525 723
service: aggregate perf. data 0.90 13551 108
service: refresh index statistics 0.03 273789 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 31-12-2020 04:28:14 to 31-12-2020 04:28:16.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1493
TEST_TIME 2 2050
TEST_TIME 3 3021
TEST_TIME 4 3788
TEST_TIME 5 4042
TEST_TIME 6 4720
TEST_TIME 7 4929
TEST_TIME 8 4956
TEST_TIME 9 5136
TEST_TIME 10 5391
TEST_TIME 11 5536
TEST_TIME 12 5822
TEST_TIME 13 5852
TEST_TIME 14 6034
TEST_TIME 15 6286
TEST_TIME 16 6567
TEST_TIME 17 6814
TEST_TIME 18 6929
TEST_TIME 19 7281
TEST_TIME 20 8144
TEST_TIME 21 8173
TEST_TIME 22 8320
TEST_TIME 23 8177
TEST_TIME 24 8525
TEST_TIME 25 9092
TEST_TIME 26 9786
TEST_TIME 27 11083
TEST_TIME 28 11131
TEST_TIME 29 11389
TEST_TIME 30 11188
TEST_TIME 31 11095
TEST_TIME 32 11006
TEST_TIME 33 10833
TEST_TIME 34 10563
TEST_TIME 35 10449
TEST_TIME 36 10380
TEST_TIME 37 10273
TEST_TIME 38 10150
TEST_TIME 39 10156
TEST_TIME 40 10077
TEST_TIME 41 10051
TEST_TIME 42 9966
TEST_TIME 43 9796
TEST_TIME 44 10071
TEST_TIME 45 9943
TEST_TIME 46 10011
TEST_TIME 47 9920
TEST_TIME 48 9977
TEST_TIME 49 10022
TEST_TIME 50 10010
TEST_TIME 51 10068
TEST_TIME 52 9984
TEST_TIME 53 10015
TEST_TIME 54 10039
TEST_TIME 55 10030
TEST_TIME 56 9874
TEST_TIME 57 9857
TEST_TIME 58 9749
TEST_TIME 59 9685
TEST_TIME 60 9645
TEST_TIME 61 9567
TEST_TIME 62 9576
TEST_TIME 63 9611
TEST_TIME 64 9531
TEST_TIME 65 9623
TEST_TIME 66 9625
TEST_TIME 67 9712
TEST_TIME 68 9685
TEST_TIME 69 9683
TEST_TIME 70 9766
TEST_TIME 71 9740
TEST_TIME 72 9776
TEST_TIME 73 9760
TEST_TIME 74 9694
TEST_TIME 75 9654
TEST_TIME 76 9543
TEST_TIME 77 9525
TEST_TIME 78 9462
TEST_TIME 79 9398
TEST_TIME 80 9329
TEST_TIME 81 9318
TEST_TIME 82 9292
TEST_TIME 83 9273
TEST_TIME 84 9256
TEST_TIME 85 9246
TEST_TIME 86 9256
TEST_TIME 87 9331
TEST_TIME 88 9294
TEST_TIME 89 9289
TEST_TIME 90 9337
TEST_TIME 91 9437
TEST_TIME 92 9408
TEST_TIME 93 9352
TEST_TIME 94 9337
TEST_TIME 95 9355
TEST_TIME 96 9328
TEST_TIME 97 9290
TEST_TIME 98 9289
TEST_TIME 99 9326
TEST_TIME 100 9316
TEST_TIME 101 9298
TEST_TIME 102 9299
TEST_TIME 103 9365
TEST_TIME 104 9354
TEST_TIME 105 9373
TEST_TIME 106 9422
TEST_TIME 107 9416
TEST_TIME 108 9332
TEST_TIME 109 9267
TEST_TIME 110 9220
TEST_TIME 111 9173
TEST_TIME 112 9172
TEST_TIME 113 9146
TEST_TIME 114 9148
TEST_TIME 115 9129
TEST_TIME 116 9132
TEST_TIME 117 9120
TEST_TIME 118 9098
TEST_TIME 119 9105
TEST_TIME 120 9083

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 31-12-2020 04:28:18 to 31-12-2020 04:28:21.

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 1521386 1521386 0 0.00 0 18769 7 0 0
doc_list_biud 1521386 1521386 0 0.00 0 82569 32 0 0
sp_add_invoice_to_stock 5142 5043 99 1.92 22 194411 17609 0 99
sp_cancel_adding_invoice 5438 5438 0 0.00 4 95392 932 0 0
sp_cancel_client_order 1771 1771 0 0.00 5 9401 316 0 0
sp_cancel_customer_reserve 537494 537494 0 0.00 1 20017 72 0 0
sp_cancel_pay_from_customer 658 658 0 0.00 3 5090 85 0 0
sp_cancel_pay_to_supplier 404 404 0 0.00 3 5625 88 0 0
sp_cancel_supplier_invoice 6089 6082 7 0.11 2 82575 978 0 7
sp_cancel_supplier_order 408 408 0 0.00 11 1067033 119212 0 0
sp_cancel_write_off 10075 10075 0 0.00 1 12442 125 0 0
sp_client_order 20477 20477 0 0.00 4 17805 431 0 0
sp_customer_reserve 467043 467043 0 0.00 0 90367 173 0 0
sp_fill_shopping_cart 31407 31407 0 0.00 1 13954 42 0 0
sp_fill_shopping_cart_clo_res 467040 467040 0 0.00 0 21409 20 0 0
sp_get_clo_for_invoice 5040 5040 0 0.00 0 41447 403 0 0
sp_kill_cost_storno 16575 16575 0 0.00 0 7937 58 0 0
sp_kill_qstorno_ret_qs2qd 1091553 1091553 0 0.00 0 82566 39 0 0
sp_kill_qty_storno 576259 576259 0 0.00 0 82569 83 0 0
sp_lock_selected_doc 20738 20738 0 0.00 0 8842 5 0 0
sp_make_cost_storno 27598 27598 0 0.00 0 18742 51 0 0
sp_multiply_rows_for_pdistr 27598 27598 0 0.00 0 9728 29 0 0
sp_multiply_rows_for_qdistr 20477 20477 0 0.00 0 17739 251 0 0
sp_pay_from_customer 6864 6864 0 0.00 4 9736 106 0 0
sp_pay_to_supplier 5743 5743 0 0.00 5 11956 107 0 0
sp_payment_common 12607 12607 0 0.00 1 5489 16 0 0
sp_qd_handle_on_cancel_clo 1771 1771 0 0.00 0 9392 285 0 0
sp_qd_handle_on_invoice_upd_sts 10478 10478 0 0.00 0 19799 373 0 0
sp_qd_handle_on_reserve_upd_sts 9951 9951 0 0.00 0 14124 43 0 0
sp_reserve_write_off 9951 9951 0 0.00 7 15317 160 0 0
sp_supplier_invoice 5511 5511 0 0.00 6 75339 3472 0 0
sp_supplier_order 5449 5449 0 0.00 6 29360 1368 0 0
srv_aggregate_perf_data 108 108 0 0.00 2783 139087 13552 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1066 153826 46310 0 0
srv_find_qd_qs_mism 1042001 1042001 0 0.00 0 12094 6 0 0
srv_make_invnt_saldo 450 450 0 0.00 61 109631 3425 0 0
srv_make_money_saldo 723 723 0 0.00 1 15657 526 0 0
srv_recalc_idx_stat 4 4 0 0.00 172294 399942 273790 0 0
t$perf-abend:sp_add_invoice_to_stock 99 99 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5043 5043 0 0.00 0 11703 146 0 0
t$perf-norm:sp_cancel_adding_invoice 398 398 0 0.00 0 8036 120 0 0
t$perf-norm:sp_cancel_client_order 1771 1771 0 0.00 0 3357 14 0 0
t$perf-norm:sp_cancel_customer_reserve 681 681 0 0.00 0 747 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 658 658 0 0.00 0 129 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 404 404 0 0.00 0 1047 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 392 392 0 0.00 0 1319 21 0 0
t$perf-norm:sp_cancel_supplier_order 408 408 0 0.00 0 18627 1438 0 0
t$perf-norm:sp_cancel_write_off 1035 1035 0 0.00 0 1748 5 0 0
t$perf-norm:sp_client_order 20477 20477 0 0.00 0 10121 19 0 0
t$perf-norm:sp_customer_reserve 1041 1041 0 0.00 0 1401 5 0 0
t$perf-norm:sp_pay_from_customer 6864 6864 0 0.00 0 4102 10 0 0
t$perf-norm:sp_pay_to_supplier 5743 5743 0 0.00 0 7972 10 0 0
t$perf-norm:sp_reserve_write_off 9951 9951 0 0.00 0 7201 11 0 0
t$perf-norm:sp_supplier_invoice 5511 5511 0 0.00 0 7725 18 0 0
t$perf-norm:sp_supplier_order 5449 5449 0 0.00 0 12874 15 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 2820 35 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 4 1 0 0
t$perf-norm:srv_make_invnt_saldo 450 450 0 0.00 0 775 7 0 0
t$perf-norm:srv_make_money_saldo 723 723 0 0.00 0 683 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 3 0 0
v_add_invoice_to_stock 10278 10080 198 1.92 0 10332 15 0 198
v_all_customers 37088 37088 0 0.00 0 27 1 0 0
v_all_suppliers 30332 30332 0 0.00 0 22 1 0 0
v_all_wares 40954 40954 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 796 796 0 0.00 0 841 8 0 0
v_cancel_client_order 3542 3542 0 0.00 0 2152 4 0 0
v_cancel_customer_prepayment 1316 1316 0 0.00 0 3195 8 0 0
v_cancel_customer_reserve 1362 1362 0 0.00 0 3196 6 0 0
v_cancel_payment_to_supplier 808 808 0 0.00 0 5430 18 0 0
v_cancel_supplier_invoice 798 784 14 1.75 0 2544 15 0 14
v_cancel_supplier_order 816 816 0 0.00 0 311 4 0 0
v_cancel_write_off 2070 2070 0 0.00 0 7865 16 0 0
v_max_id_clo_ord 5445 5445 0 0.00 0 409 1 0 0
v_max_id_clo_res 1041 1041 0 0.00 0 3643 15 0 0
v_max_id_ord_sup 5485 5485 0 0.00 0 5504 3 0 0
v_max_non_paid_invoice 1507 1507 0 0.00 0 399 2 0 0
v_max_non_paid_realizn 3688 3688 0 0.00 0 674 2 0 0
v_min_id_clo_ord 5445 5445 0 0.00 0 2867 5 0 0
v_min_id_clo_res 1041 1041 0 0.00 0 3828 6 0 0
v_min_id_ord_sup 5485 5485 0 0.00 0 6607 9 0 0
v_min_non_paid_invoice 5743 5743 0 0.00 0 1907 6 0 0
v_min_non_paid_realizn 6864 6864 0 0.00 0 5485 13 0 0
v_our_firm 10218 10218 0 0.00 0 14 1 0 0
v_reserve_write_off 19902 19902 0 0.00 0 5432 5 0 0
x_lock_depdocs_on_canc_invoice 11128 11128 0 0.00 0 24497 109 0 0
x_lock_depdocs_on_canc_sup_ord 408 408 0 0.00 0 10180 352 0 0
x_make_qstorno_1000_1200 5449 5449 0 0.00 2 29347 1296 0 0
x_make_qstorno_1200_2000 5511 5511 0 0.00 2 75262 3301 0 0
x_make_qstorno_2100_3300 467043 467043 0 0.00 0 90365 151 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 31-12-2020 04:28:22 to 31-12-2020 04:28:26.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
01:56:59 2 8192000000 8796637808 8807108608 3167696 0.038 1 1 2 0 3265392 61840 35856
01:58:00 26958 8192000000 9733219664 10029826048 806875856 8.966 100 85 170 0 835314000 7756112 20682032
01:59:32 9147 8192000000 9764955744 10071703552 842455488 9.324 100 86 172 1 876235440 13379888 20400064
02:00:46 11912 8192000000 9800399584 10117906432 870121168 9.601 100 83 166 1 908307568 19113088 19073312
02:02:03 10865 8192000000 9862469824 10191122432 906385648 9.962 100 76 152 3 964700080 41126112 17188320
02:03:21 11957 8192000000 9913354144 10254573568 953354256 10.424 100 83 166 3 1031784176 61114528 17315392
02:04:36 6426 8192000000 9975974512 10328190976 985065360 10.733 100 72 144 2 1085409312 86095536 14248416
02:05:46 2772 8192000000 9987255616 10346967040 1012381824 10.998 100 82 164 2 1099666816 70375520 16909472
02:06:50 2053 8192000000 10013162880 10381111296 1039461984 11.259 100 88 177 0 1133835536 76307920 18065632
02:07:53 1513 8192000000 10037536352 10407145472 1055111856 11.410 100 93 187 3 1152775936 79122576 18541504
02:08:55 1485 8192000000 10010191904 10382434304 1065116656 11.505 100 86 176 1 1121264976 37094128 19054192
02:09:58 7696 8192000000 10009493312 10394071040 1077907152 11.628 100 47 96 1 1114525024 26519648 10098224
02:11:05 13388 8192000000 9993988400 10392309760 1079603392 11.644 100 32 62 3 1113253264 25952240 7697632
02:12:19 21163 8192000000 9991782592 10399879168 1083047536 11.677 100 16 33 1 1104613104 18538480 3027088
02:13:43 5592 8192000000 10001991360 10393165824 1088659984 11.730 100 38 71 3 1118003232 21689440 7653808
02:14:52 9714 8192000000 10004877424 10416832512 1086037312 11.705 99 31 60 2 1113400064 20928128 6434624
02:16:05 61241 8192000000 10001434064 10415820800 1076918096 11.618 98 37 71 3 1108734592 23595792 8220704
02:18:06 7702 8192000000 10004341296 10394849280 1072853264 11.579 99 27 54 2 1108243936 29032944 6357728
02:19:18 8075 8192000000 9963696688 10360672256 1037449008 11.240 99 39 79 2 1069919856 23414128 9056720
02:20:29 7601 8192000000 9911705136 10287525888 952167456 10.412 94 26 52 0 966702720 8857936 5677328
02:21:39 42738 8192000000 9935862096 10326798336 1004254048 10.920 98 23 43 2 1036047104 27044032 4749024
02:23:22 7507 8192000000 9936928304 10327363584 1018974144 11.062 99 41 79 1 1047995920 19896384 9125392
02:24:30 6286 8192000000 9957214256 10353586176 1051724192 11.377 100 46 90 2 1074282720 11526672 11031856
02:25:37 4600 8192000000 9989063424 10388918272 1059812128 11.455 100 32 64 1 1080464768 14083984 6568656
02:26:43 56877 8192000000 10000968336 10387021824 1079141408 11.639 100 37 71 2 1094029744 8103600 6784736
02:28:48 6508 8192000000 10062683568 10460233728 1097768192 11.816 100 50 99 1 1168605456 60275840 10561424
02:30:02 10158 8192000000 10102882752 10502201344 1114055472 11.971 100 57 113 0 1211430288 86237984 11136832
02:31:16 19202 8192000000 10115131568 10518990848 1124562272 12.070 100 58 116 4 1224069008 87345968 12160768
02:32:40 2929 8192000000 10182683104 10586882048 1145740464 12.269 100 76 153 3 1286500176 124374544 16385168
02:33:46 33144 8192000000 10173675792 10601041920 1152927776 12.337 100 38 76 1 1298899520 136937360 9034384
02:35:28 9654 8192000000 10236433728 10651738112 1163743808 12.438 100 55 109 3 1342636880 166026736 12866336
02:36:47 3856 8192000000 10261352448 10676576256 1168429232 12.482 100 54 108 0 1366101984 185097344 12575408
02:37:54 11662 8192000000 10324696336 10751713280 1181185984 12.601 100 54 106 0 1423087776 229792608 12109184
02:39:09 3609 8192000000 10334176752 10742120448 1183252240 12.621 100 70 139 0 1440991568 242158672 15580656
02:40:19 2652 8192000000 10345361680 10755629056 1183083392 12.619 100 68 137 1 1443136352 244612064 15440896
02:41:29 2464 8192000000 10372274496 10801147904 1180620240 12.596 100 66 132 2 1478667648 282517664 15529744
02:42:39 1066 8192000000 10404124192 10810150912 1186137424 12.647 100 85 162 2 1503615360 298807792 18670144
02:43:46 4962 8192000000 10423931216 10841403392 1153197920 12.340 98 81 160 2 1514912544 342710672 19003952
02:44:58 3181 8192000000 10466420144 10880933888 1160259344 12.406 100 55 110 1 1572799728 398498016 14042368
02:46:05 2317 8192000000 10464909888 10864340992 1163781584 12.439 100 83 166 2 1561003152 378400992 18820576
02:47:20 7930 8192000000 10417557632 10853117952 1164304960 12.444 100 36 72 2 1522583744 348748448 9530336
02:48:32 6733 8192000000 10449731024 10898169856 1161193440 12.414 100 41 81 0 1547982512 376114096 10674976
02:49:42 14423 8192000000 10534234624 10974740480 1158707136 12.391 100 38 76 0 1631963600 462377776 10878688
02:51:20 18222 8192000000 10493087104 11035172864 1134020320 12.159 100 29 58 0 1628788304 485904000 8863984
02:52:51 9669 8192000000 10330761888 10809303040 1108780928 11.921 98 35 66 3 1427715280 310243344 8691008
02:54:05 69696 8192000000 10080957376 10589888512 1061894656 11.475 95 21 41 1 1145066816 79060464 4111696
02:56:35 55613 8192000000 10056869616 10477645824 1098849616 11.827 99 20 40 2 1135153728 31384944 4919168
02:58:34 153822 8192000000 10056278896 10488893440 1086435568 11.709 96 35 70 0 1131426768 37997952 6993248
03:02:09 31818 8192000000 10063833232 10503720960 1128499808 12.107 100 42 84 0 1160367840 23041904 8826128
03:03:42 26640 8192000000 10098054448 10545930240 1126074560 12.084 100 46 92 4 1199594144 63704192 9815392
03:05:09 30467 8192000000 10145209264 10595835904 1131250368 12.133 100 30 60 2 1243580640 105281392 7048880
03:06:42 54392 8192000000 10118879520 10572230656 1141719472 12.232 100 10 20 0 1252155616 108037952 2398192
03:08:43 75064 8192000000 10146995072 10598096896 1126022704 12.084 99 14 28 0 1247716752 118226112 3467936
03:11:00 38201 8192000000 10091468480 10524594176 1132065056 12.141 99 27 54 2 1166602736 29187136 5350544
03:12:42 31766 8192000000 10090506160 10529517568 1092600624 11.767 96 30 60 1 1142703344 44171344 5931376
03:14:18 44215 8192000000 10098215840 10536202240 1109162080 11.924 98 28 56 0 1177149696 62077616 5910000
03:16:04 57478 8192000000 10093283904 10544001024 1132902032 12.149 100 6 11 1 1186313296 51970000 1441264
03:18:02 54533 8192000000 10114524752 10552930304 1143058688 12.244 100 28 54 2 1190273072 41843776 5370608
03:19:57 33250 8192000000 10077796800 10526212096 1114229632 11.972 98 38 76 2 1163036992 41288272 7519088
03:21:35 102907 8192000000 10100434544 10545627136 1126457440 12.088 99 36 72 1 1206675312 72813216 7404656
03:24:18 57515 8192000000 10048602832 10495696896 1110937456 11.941 99 11 22 0 1162631568 49282512 2411600
03:26:16 55768 8192000000 10052891248 10540568576 1089822832 11.741 97 3 6 0 1132999904 42612496 564576
03:28:17 83306 8192000000 10085676016 10545995776 1060373200 11.460 94 11 22 0 1102037824 39646848 2017776
03:31:28 75367 8192000000 10102952784 10547548160 1073284944 11.583 95 20 40 0 1149135088 72003856 3846288
03:34:07 44429 8192000000 10089336368 10502332416 1087432544 11.718 97 40 80 2 1149103232 53798064 7872624
03:35:54 68148 8192000000 10073082864 10577887232 1082608032 11.672 97 22 44 1 1149581200 62399792 4573376
03:38:02 138505 8192000000 10034048256 10482044928 1028325328 11.152 92 13 26 0 1061186256 30870368 1990560
03:41:21 104185 8192000000 10062611440 10507911168 1080961232 11.657 97 35 68 2 1132035840 44096288 6978320
03:44:14 22275 8192000000 10020568208 10494656512 1089449312 11.737 99 2 4 0 1119626144 29883472 293360
03:46:30 50586 8192000000 10033368976 10482102272 1092299120 11.765 99 8 16 0 1123543648 29702368 1542160
03:48:21 70799 8192000000 10034936608 10509365248 1085144080 11.696 98 8 16 0 1119430960 33001024 1285856
03:50:32 70308 8192000000 10096108048 10525528064 1119648672 12.024 99 13 26 0 1174985712 52524448 2812592
03:52:48 66543 8192000000 10101332880 10580324352 1100680768 11.844 98 24 48 0 1174183504 68166720 5336016
03:54:57 83005 8192000000 10134955632 10586595328 1101501616 11.852 97 20 40 0 1210368400 104483408 4383376
03:57:35 50252 8192000000 10086296496 10512248832 1105364800 11.889 97 20 40 1 1152230064 43048768 3816496
03:59:32 95297 8192000000 10113229168 10582962176 1125032096 12.075 99 26 52 1 1210381152 80100000 5249056
04:02:07 75606 8192000000 10079544672 10518298624 1113224560 11.963 98 6 12 0 1144923872 30765744 933568
04:04:37 48543 8192000000 10079280208 10551156736 1123670448 12.062 98 28 56 2 1174625648 45303744 5651456
04:06:27 35337 8192000000 10100942240 10530926592 1129637216 12.118 99 47 92 1 1153371248 14411024 9323008
04:08:07 45451 8192000000 10103651024 10562596864 1116157968 11.991 98 26 53 1 1187565168 66176928 5230272
04:09:54 34568 8192000000 10080253440 10531815424 1120715856 12.034 98 24 49 4 1156943264 31568496 4658912
04:11:35 81500 8192000000 10083770784 10587103232 1132009232 12.140 99 16 32 2 1198168976 62592560 3567184
04:14:00 90708 8192000000 10083587952 10575204352 1139709424 12.213 100 8 16 1 1192094400 50562032 1822944
04:17:11 58088 8192000000 10048657696 10522238976 1121945344 12.045 99 10 20 0 1178807008 54237808 2623856
04:19:17 68028 8192000000 10060845120 10508443648 1062834400 11.484 94 15 30 0 1101688624 35833392 3020832
04:21:25 40772 8192000000 10089939104 10539167744 1086000528 11.705 96 29 57 2 1146623792 54222896 6400368
04:23:16 62479 8192000000 10091346832 10538950656 1063782496 11.493 94 23 46 2 1144181360 74951744 5447120
04:25:22 95260 8192000000 9944957088 10353401856 875735216 9.657 77 18 36 2 985369888 105405040 4229632

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 99 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 31-12-2020 04:28:44 to 31-12-2020 04:28:46.

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          225681
MON$OLDEST_ACTIVE               234127
MON$OLDEST_SNAPSHOT             234127
MON$NEXT_TRANSACTION            234127
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                       2104768
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             802
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2308 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2308 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2308 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.2308 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 ---
:101: 
:102: mscs-test-161.budzdorov.ru    Thu Dec 31 04:27:02 2020
:103:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:104: 
:105: 
:106: mscs-test-161.budzdorov.ru    Thu Dec 31 04:27:03 2020
:107:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:108: 
--- end of diff output ---

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

31.12.20 04:28:47. 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-31 04:28:50 No severe PSQL-related problems occured
31.12.20 04:28:51. End of report.