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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2020-11-02 07:35:18
DTS_END                         2020-11-02 07:35:18.9210
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/57400
TRN_ID                          236995
ATT_ID                          617
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: 9135.57 20083 1096268
customer order: creation 174.73 356 20967
customer order: refuse 14.96 328 1795
order to supplier: creation 45.15 1314 5418
order to supplier: removal 3.50 116576 420
invoice (draft): creation 45.51 3350 5461
invoice (draft): removal 52.58 971 6310
invoice accept: apply 41.39 15631 4967
invoice accept: cancel 47.66 907 5719
customer reserve: creation 3708.57 157 445028
customer reserve: removal 4710.08 69 565210
realization accept: apply 83.48 152 10017
realization accept: cancel 80.12 120 9614
payment from customer: creation 59.41 94 7129
payment from customer: removal 5.91 97 709
payment to supplier: creation 48.34 106 5801
payment to supplier: removal 3.35 59 402
service: total inventory turnovers 4.01 3316 481
service: total monetary turnovers 5.90 618 708
service: aggregate perf. data 0.90 17496 108
service: refresh index statistics 0.03 239937 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 02-11-2020 07:36:16 to 02-11-2020 07:36:18.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2822
TEST_TIME 2 4616
TEST_TIME 3 5018
TEST_TIME 4 4921
TEST_TIME 5 4961
TEST_TIME 6 5104
TEST_TIME 7 5256
TEST_TIME 8 5363
TEST_TIME 9 5390
TEST_TIME 10 5523
TEST_TIME 11 5736
TEST_TIME 12 5957
TEST_TIME 13 6528
TEST_TIME 14 6946
TEST_TIME 15 6939
TEST_TIME 16 7091
TEST_TIME 17 7393
TEST_TIME 18 7516
TEST_TIME 19 7948
TEST_TIME 20 8087
TEST_TIME 21 8291
TEST_TIME 22 8153
TEST_TIME 23 8658
TEST_TIME 24 8751
TEST_TIME 25 8912
TEST_TIME 26 9258
TEST_TIME 27 11252
TEST_TIME 28 11180
TEST_TIME 29 11119
TEST_TIME 30 11090
TEST_TIME 31 10854
TEST_TIME 32 10764
TEST_TIME 33 10588
TEST_TIME 34 10712
TEST_TIME 35 10557
TEST_TIME 36 10626
TEST_TIME 37 10411
TEST_TIME 38 10399
TEST_TIME 39 10544
TEST_TIME 40 10451
TEST_TIME 41 10338
TEST_TIME 42 10351
TEST_TIME 43 10499
TEST_TIME 44 10556
TEST_TIME 45 10580
TEST_TIME 46 10355
TEST_TIME 47 10151
TEST_TIME 48 10043
TEST_TIME 49 9950
TEST_TIME 50 10013
TEST_TIME 51 9900
TEST_TIME 52 9906
TEST_TIME 53 9890
TEST_TIME 54 9850
TEST_TIME 55 9779
TEST_TIME 56 9664
TEST_TIME 57 9568
TEST_TIME 58 9505
TEST_TIME 59 9605
TEST_TIME 60 9603
TEST_TIME 61 9595
TEST_TIME 62 9646
TEST_TIME 63 9613
TEST_TIME 64 9651
TEST_TIME 65 9609
TEST_TIME 66 9610
TEST_TIME 67 9628
TEST_TIME 68 9633
TEST_TIME 69 9632
TEST_TIME 70 9723
TEST_TIME 71 9758
TEST_TIME 72 9806
TEST_TIME 73 9767
TEST_TIME 74 9674
TEST_TIME 75 9592
TEST_TIME 76 9531
TEST_TIME 77 9493
TEST_TIME 78 9435
TEST_TIME 79 9380
TEST_TIME 80 9358
TEST_TIME 81 9378
TEST_TIME 82 9363
TEST_TIME 83 9370
TEST_TIME 84 9359
TEST_TIME 85 9349
TEST_TIME 86 9298
TEST_TIME 87 9444
TEST_TIME 88 9389
TEST_TIME 89 9341
TEST_TIME 90 9506
TEST_TIME 91 9584
TEST_TIME 92 9635
TEST_TIME 93 9553
TEST_TIME 94 9505
TEST_TIME 95 9452
TEST_TIME 96 9433
TEST_TIME 97 9424
TEST_TIME 98 9419
TEST_TIME 99 9389
TEST_TIME 100 9403
TEST_TIME 101 9379
TEST_TIME 102 9332
TEST_TIME 103 9370
TEST_TIME 104 9427
TEST_TIME 105 9380
TEST_TIME 106 9503
TEST_TIME 107 9512
TEST_TIME 108 9490
TEST_TIME 109 9459
TEST_TIME 110 9382
TEST_TIME 111 9337
TEST_TIME 112 9287
TEST_TIME 113 9219
TEST_TIME 114 9191
TEST_TIME 115 9229
TEST_TIME 116 9204
TEST_TIME 117 9187
TEST_TIME 118 9162
TEST_TIME 119 9191
TEST_TIME 120 9141

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 02-11-2020 07:36:20 to 02-11-2020 07:36:23.

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 1510480 1510480 0 0.00 0 25980 6 0 0
doc_list_biud 1510480 1510480 0 0.00 0 26752 32 0 0
sp_add_invoice_to_stock 5092 4967 125 2.45 29 163486 15631 0 125
sp_cancel_adding_invoice 5719 5719 0 0.00 5 61593 908 0 0
sp_cancel_client_order 1795 1795 0 0.00 4 13113 329 0 0
sp_cancel_customer_reserve 565210 565210 0 0.00 1 20826 69 0 0
sp_cancel_pay_from_customer 709 709 0 0.00 2 9820 97 0 0
sp_cancel_pay_to_supplier 402 402 0 0.00 2 1721 59 0 0
sp_cancel_supplier_invoice 6320 6310 10 0.15 2 21146 971 0 10
sp_cancel_supplier_order 420 420 0 0.00 8 1198167 116577 0 0
sp_cancel_write_off 9614 9614 0 0.00 1 14570 120 0 0
sp_client_order 20967 20967 0 0.00 4 19356 356 0 0
sp_customer_reserve 445028 445028 0 0.00 0 48755 157 0 0
sp_fill_shopping_cart 31829 31829 0 0.00 1 12471 43 0 0
sp_fill_shopping_cart_clo_res 445013 445013 0 0.00 0 25786 16 0 0
sp_get_clo_for_invoice 4952 4952 0 0.00 0 30644 355 0 0
sp_kill_cost_storno 16444 16444 0 0.00 0 7763 57 0 0
sp_kill_qstorno_ret_qs2qd 1146764 1146764 0 0.00 0 21137 37 0 0
sp_kill_qty_storno 604035 604035 0 0.00 0 26752 80 0 0
sp_lock_selected_doc 20761 20761 0 0.00 0 10709 6 0 0
sp_make_cost_storno 27897 27897 0 0.00 0 14185 45 0 0
sp_multiply_rows_for_pdistr 27897 27897 0 0.00 0 7322 26 0 0
sp_multiply_rows_for_qdistr 20967 20967 0 0.00 0 11148 197 0 0
sp_pay_from_customer 7129 7129 0 0.00 4 18445 95 0 0
sp_pay_to_supplier 5801 5801 0 0.00 5 11847 107 0 0
sp_payment_common 12930 12930 0 0.00 1 7643 17 0 0
sp_qd_handle_on_cancel_clo 1795 1795 0 0.00 0 13081 297 0 0
sp_qd_handle_on_invoice_upd_sts 10669 10669 0 0.00 0 26751 363 0 0
sp_qd_handle_on_reserve_upd_sts 10017 10017 0 0.00 0 9739 44 0 0
sp_reserve_write_off 10017 10017 0 0.00 6 15997 152 0 0
sp_supplier_invoice 5461 5461 0 0.00 6 88171 3350 0 0
sp_supplier_order 5418 5418 0 0.00 5 93445 1315 0 0
srv_aggregate_perf_data 108 108 0 0.00 2273 420171 17496 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 2646 139827 47543 0 0
srv_find_qd_qs_mism 1050251 1050251 0 0.00 0 25979 5 0 0
srv_make_invnt_saldo 481 481 0 0.00 49 98355 3316 0 0
srv_make_money_saldo 708 708 0 0.00 1 32718 618 0 0
srv_recalc_idx_stat 4 4 0 0.00 163150 307738 239937 0 0
t$perf-abend:sp_add_invoice_to_stock 125 125 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4968 4968 0 0.00 0 12474 150 0 0
t$perf-norm:sp_cancel_adding_invoice 407 407 0 0.00 0 4926 98 0 0
t$perf-norm:sp_cancel_client_order 1795 1795 0 0.00 0 1483 9 0 0
t$perf-norm:sp_cancel_customer_reserve 660 660 0 0.00 0 1565 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 709 709 0 0.00 0 1990 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 402 402 0 0.00 0 451 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 399 399 0 0.00 0 887 20 0 0
t$perf-norm:sp_cancel_supplier_order 420 420 0 0.00 0 16198 1497 0 0
t$perf-norm:sp_cancel_write_off 1002 1002 0 0.00 0 4059 12 0 0
t$perf-norm:sp_client_order 20967 20967 0 0.00 0 8196 17 0 0
t$perf-norm:sp_customer_reserve 1017 1017 0 0.00 0 5177 9 0 0
t$perf-norm:sp_pay_from_customer 7129 7129 0 0.00 0 6446 9 0 0
t$perf-norm:sp_pay_to_supplier 5801 5801 0 0.00 0 6462 7 0 0
t$perf-norm:sp_reserve_write_off 10017 10017 0 0.00 0 11074 11 0 0
t$perf-norm:sp_supplier_invoice 5461 5461 0 0.00 0 8591 16 0 0
t$perf-norm:sp_supplier_order 5418 5418 0 0.00 0 6232 16 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 1872 32 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 337 9 0 0
t$perf-norm:srv_make_invnt_saldo 481 481 0 0.00 0 1031 7 0 0
t$perf-norm:srv_make_money_saldo 708 708 0 0.00 0 1151 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 10150 9900 250 2.46 0 5466 16 0 250
v_all_customers 38066 38066 0 0.00 0 22 1 0 0
v_all_suppliers 30080 30080 0 0.00 0 94 1 0 0
v_all_wares 41934 41934 0 0.00 0 93 1 0 0
v_cancel_adding_invoice 814 814 0 0.00 0 181 6 0 0
v_cancel_client_order 3590 3590 0 0.00 0 1746 5 0 0
v_cancel_customer_prepayment 1418 1418 0 0.00 0 1844 8 0 0
v_cancel_customer_reserve 1320 1320 0 0.00 0 1555 5 0 0
v_cancel_payment_to_supplier 804 804 0 0.00 0 1715 6 0 0
v_cancel_supplier_invoice 818 798 20 2.44 0 1073 14 0 20
v_cancel_supplier_order 840 840 0 0.00 0 3846 15 0 0
v_cancel_write_off 2004 2004 0 0.00 0 6766 21 0 0
v_max_id_clo_ord 5412 5412 0 0.00 0 1029 1 0 0
v_max_id_clo_res 1017 1017 0 0.00 0 2938 11 0 0
v_max_id_ord_sup 5450 5450 0 0.00 0 7534 7 0 0
v_max_non_paid_invoice 1623 1623 0 0.00 0 407 2 0 0
v_max_non_paid_realizn 3888 3888 0 0.00 0 685 2 0 0
v_min_id_clo_ord 5412 5412 0 0.00 0 2465 6 0 0
v_min_id_clo_res 1017 1017 0 0.00 0 1947 6 0 0
v_min_id_ord_sup 5450 5450 0 0.00 0 8488 12 0 0
v_min_non_paid_invoice 5801 5801 0 0.00 0 3314 11 0 0
v_min_non_paid_realizn 7129 7129 0 0.00 0 5753 11 0 0
v_our_firm 10350 10350 0 0.00 0 85 1 0 0
v_reserve_write_off 20034 20034 0 0.00 0 6292 6 0 0
x_lock_depdocs_on_canc_invoice 11630 11630 0 0.00 0 14663 113 0 0
x_lock_depdocs_on_canc_sup_ord 420 420 0 0.00 0 9543 373 0 0
x_make_qstorno_1000_1200 5418 5418 0 0.00 2 93440 1237 0 0
x_make_qstorno_1200_2000 5461 5461 0 0.00 3 82127 3182 0 0
x_make_qstorno_2100_3300 445028 445028 0 0.00 0 48752 139 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-11-2020 07:36:24 to 02-11-2020 07:36:28.

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:05:22 1 8192000000 8796633152 8807174144 3163616 0.038 1 1 2 0 3261312 61840 35856
05:06:23 32562 8192000000 9731077104 10028736512 808128208 8.979 100 88 176 0 837363584 8149440 21085936
05:07:59 29335 8192000000 9777379232 10084966400 850079888 9.401 100 87 174 1 883168720 13022736 20066096
05:09:31 21525 8192000000 9810283184 10133188608 879365232 9.693 100 87 174 0 922949200 24369808 19214160
05:10:59 14500 8192000000 9901055232 10236141568 923507520 10.131 100 83 166 1 1002980304 61708480 17764304
05:12:15 7503 8192000000 9931190256 10276786176 962395488 10.512 100 78 154 0 1045292576 66661840 16235248
05:13:25 4153 8192000000 9972924640 10328768512 999693056 10.876 100 84 164 1 1080874736 63683040 17498640
05:14:31 4839 8192000000 9999319216 10365124608 1024384368 11.114 100 77 154 2 1117004496 76722096 15898032
05:15:39 3064 8192000000 10012321344 10377535488 1050498976 11.365 100 85 168 0 1124609616 57092224 17018416
05:16:44 1943 8192000000 10029391408 10406973440 1070141280 11.553 100 78 158 1 1149215008 62862512 16211216
05:17:49 980 8192000000 10035397584 10406748160 1074865744 11.599 100 92 187 0 1147415920 53441552 19108624
05:18:50 1790 8192000000 10038565456 10422276096 1078137296 11.630 100 67 133 0 1157064736 65206064 13721376
05:19:54 4567 8192000000 10009150864 10402287616 1082374848 11.670 100 50 100 2 1125872400 32547296 10950256
05:20:59 33097 8192000000 10004446048 10394304512 1088463056 11.728 100 62 121 0 1111205136 8626848 14115232
05:22:32 5165 8192000000 10013795936 10410385408 1093901760 11.780 100 41 81 0 1138034400 35486288 8646352
05:23:39 6782 8192000000 10005927120 10415636480 1093220624 11.773 100 36 71 2 1125952864 24290752 8441488
05:24:48 6584 8192000000 9988039936 10383507456 1082483616 11.671 99 45 89 2 1104606816 11979168 10144032
05:25:57 27569 8192000000 9996585216 10378108928 1050263936 11.363 97 54 107 0 1072366400 10496320 11606144
05:27:25 8478 8192000000 9957806016 10344833024 1006616864 10.943 95 57 115 3 1042632720 23050224 12965632
05:28:35 6282 8192000000 9933471216 10303721472 993522816 10.816 97 45 90 1 1019534160 15367088 10644256
05:29:42 9976 8192000000 9926834672 10309464064 977392112 10.659 96 33 66 1 1008291120 22834944 8064064
05:30:55 21961 8192000000 9944852768 10327453696 1001219328 10.890 98 36 72 2 1036262912 27457552 7586032
05:32:18 8609 8192000000 9955404832 10346233856 1039709504 11.262 100 37 74 1 1072297264 23809152 8778608
05:33:28 4294 8192000000 9971962096 10352340992 1049482688 11.356 100 55 102 5 1072772608 11053296 12236624
05:34:36 9498 8192000000 9983238256 10386096128 1059839184 11.455 100 34 68 2 1086513040 19304336 7369520
05:35:48 87163 8192000000 10025955840 10420740096 1084969824 11.695 100 41 82 0 1126818336 33849152 7999360
05:38:20 17903 8192000000 10082188688 10488877056 1112181408 11.953 100 41 82 1 1190448928 69942496 8325024
05:39:43 40298 8192000000 10093154720 10514714624 1124814096 12.072 100 40 79 3 1209141440 75993168 8334176
05:41:33 5937 8192000000 10162182960 10556346368 1138637520 12.203 100 59 116 3 1257520176 106161968 12720688
05:42:42 5825 8192000000 10171065440 10579296256 1156739504 12.373 100 48 94 3 1281700320 114548576 10412240
05:43:50 3482 8192000000 10194379072 10607550464 1152367536 12.332 100 57 110 4 1307666016 143345104 11953376
05:44:57 3460 8192000000 10256676080 10655854592 1149453840 12.304 100 72 144 1 1367166224 201044768 16667616
05:46:04 8947 8192000000 10277407760 10689396736 1159235232 12.396 100 57 112 0 1388028016 216200960 12591824
05:47:17 9617 8192000000 10303034976 10716975104 1166736208 12.466 100 56 112 0 1412115296 231727376 13651712
05:48:30 8576 8192000000 10309138336 10748022784 1154022752 12.347 99 60 120 1 1397887936 230128704 13736480
05:49:49 2643 8192000000 10284545696 10705092608 1153612096 12.343 98 65 128 1 1385687024 217913168 14161760
05:50:56 4154 8192000000 10343698128 10745757696 1169802736 12.495 100 78 153 1 1444225824 257537728 16885360
05:52:04 4475 8192000000 10359458688 10763165696 1140499888 12.220 100 61 122 1 1459858304 304702992 14655424
05:53:16 3426 8192000000 10365863616 10782298112 1142946336 12.243 100 68 135 1 1463852208 304561312 16344560
05:54:25 2802 8192000000 10395036128 10792390656 1138179456 12.198 99 77 154 1 1492851168 336542048 18129664
05:55:31 12713 8192000000 10383992960 10813931520 1143788144 12.251 99 54 106 1 1497115728 340066816 13260768
05:56:47 5862 8192000000 10427284160 10846027776 1152924592 12.337 100 50 100 1 1523476608 357659888 12892128
05:57:54 8054 8192000000 10521831984 10944114688 1163796064 12.439 100 41 82 0 1623594640 448751040 11047536
05:59:05 5680 8192000000 10480132160 10904776704 1154582192 12.352 100 38 76 2 1579536048 414673904 10279952
06:00:16 27793 8192000000 10490474208 10950230016 1143864784 12.252 100 34 68 0 1589137408 436099392 9173232
06:01:48 31029 8192000000 10277054416 10835722240 1079775136 11.645 97 24 48 1 1481907280 394698288 7433856
06:03:20 31413 8192000000 10070645168 10537005056 1111956032 11.951 100 24 46 1 1197214336 79916640 5341664
06:04:55 94880 8192000000 10057065632 10492964864 1074675504 11.597 95 17 34 0 1126102048 47725104 3701440
06:07:47 26331 8192000000 10104678800 10532216832 1131972384 12.140 100 39 75 4 1179737680 39820448 7944848
06:09:14 81061 8192000000 10100638160 10532900864 1118752624 12.015 98 19 38 0 1178735488 56595616 3387248
06:11:36 92236 8192000000 10131213344 10559164416 1108463984 11.918 97 14 28 0 1187717760 76293472 2960304
06:14:16 139826 8192000000 10084651088 10518667264 1077519136 11.624 95 10 19 0 1097749808 18102224 2128448
06:17:41 45866 8192000000 10107503936 10596052992 1136658016 12.184 100 8 16 2 1210673008 72431520 1583472
06:19:32 37752 8192000000 10105743552 10579169280 1148861872 12.299 100 10 20 0 1200526272 49803984 1860416
06:21:29 119936 8192000000 10081056688 10532102144 1144286624 12.256 100 25 44 0 1172369168 23649520 4433024
06:24:31 22683 8192000000 10117797040 10560974848 1148425536 12.295 100 28 56 1 1203204496 48100112 6678848
06:25:56 90566 8192000000 10106400688 10576891904 1141599296 12.231 99 18 35 0 1214001056 68706704 3695056
06:28:37 33440 8192000000 10063955968 10511646720 1140033648 12.216 99 28 53 0 1171281296 25994544 5253104
06:30:32 60884 8192000000 10123942656 10572529664 1138216096 12.199 98 13 26 0 1177641744 36583920 2841728
06:32:42 84639 8192000000 10130799552 10620194816 1147162656 12.283 99 19 38 0 1245903104 94696512 4043936
06:35:30 42339 8192000000 10044208272 10514907136 1103007008 11.866 96 14 28 0 1139741920 33536048 3198864
06:37:13 44956 8192000000 10061431072 10537017344 1105376832 11.889 98 19 38 1 1154463440 45015664 4070944
06:38:58 26540 8192000000 10083529216 10518265856 1110993232 11.942 99 35 70 0 1167633824 49770704 6869888
06:40:28 11590 8192000000 10076018000 10484338688 1114281808 11.973 100 67 134 3 1164255824 35581664 14392352
06:41:41 40728 8192000000 10075053920 10509578240 1115806624 11.987 100 34 68 0 1174355424 52462192 6086608
06:43:27 34658 8192000000 10112822480 10574073856 1093514048 11.776 98 28 56 2 1178431376 79128944 5788384
06:45:13 61772 8192000000 10079206224 10506874880 1108310944 11.916 99 17 34 1 1181340112 69287728 3741440
06:47:18 65559 8192000000 10049645152 10466254848 1062604528 11.481 96 13 27 2 1088484608 23060624 2819456
06:49:25 128398 8192000000 10016840704 10467151872 1082462480 11.671 98 22 44 1 1129256880 42359872 4434528
06:52:34 42524 8192000000 10014368592 10464468992 1096019712 11.800 99 7 14 0 1112809888 15763808 1026368
06:55:16 66573 8192000000 10075442080 10562838528 1098685184 11.825 98 7 14 0 1172965136 72801024 1478928
06:57:30 60217 8192000000 10049119312 10488315904 1068296368 11.536 96 22 44 1 1119710672 46782976 4631328
06:59:38 87098 8192000000 10114022064 10551447552 1120696864 12.034 100 25 50 2 1202716560 76438880 5580816
07:02:10 71023 8192000000 10087240992 10551189504 1093583600 11.777 97 17 34 1 1172543024 75233264 3726160
07:04:38 77865 8192000000 10160230080 10716270592 1128588192 12.108 100 23 46 0 1341871392 207935392 5347808
07:06:59 72150 8192000000 10048978400 10485002240 1095738352 11.797 97 15 30 0 1142656656 43780416 3137888
07:09:15 99089 8192000000 10089003648 10559336448 1122827632 12.054 99 16 32 0 1172687056 46805408 3054016
07:11:54 79574 8192000000 10100554048 10575044608 1136951840 12.187 100 10 19 0 1184612128 45542112 2118176
07:14:21 81874 8192000000 10082515872 10567737344 1095347328 11.793 96 20 40 0 1152483328 52892864 4243136
07:17:21 44608 8192000000 10136759696 10581315584 1146476544 12.276 100 21 42 1 1226380144 75398512 4505088
07:19:09 55681 8192000000 10107955648 10602663936 1136707024 12.185 99 14 28 0 1225120080 85353936 3059120
07:21:12 83750 8192000000 10105333344 10614947840 1138748048 12.204 99 7 14 0 1194065056 54036480 1280528
07:23:41 51913 8192000000 10107928304 10550591488 1133945984 12.159 98 32 64 1 1181460768 41526672 5988112
07:25:36 105978 8192000000 10088170592 10554277888 1139384624 12.210 99 21 42 1 1205160576 61275600 4500352
07:28:26 47311 8192000000 10096466112 10558459904 1138497584 12.201 99 8 16 0 1197184864 57229728 1457552
07:32:26 55728 8192000000 10076164000 10510671872 1086726672 11.712 96 13 26 0 1120694944 31167520 2800752
07:34:37 46665 8192000000 9366953264 9696325632 477590448 5.508 42 11 22 0 507846256 28383056 1872752

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 02-11-2020 07:36:30 to 02-11-2020 07:36:45.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 125 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 02-11-2020 07:36:46 to 02-11-2020 07:36:48.

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          235357
MON$OLDEST_ACTIVE               237287
MON$OLDEST_SNAPSHOT             237287
MON$NEXT_TRANSACTION            237287
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                       2136832
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             806
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2243 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2243 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2243 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.2243 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 ---
--- end of diff output ---

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

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