Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.11.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 16914296 kB
MemAvailable 31420116 kB
Buffers 20 kB
Cached 14673044 kB
SwapCached 3156 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-23 04:26:55
DTS_END                         2020-11-23 04:26:55.1640
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/47664
TRN_ID                          236309
ATT_ID                          646
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: 8991.65 22000 1078998
customer order: creation 164.72 458 19766
customer order: refuse 14.55 329 1746
order to supplier: creation 44.24 1223 5309
order to supplier: removal 3.32 110393 398
invoice (draft): creation 44.23 3112 5308
invoice (draft): removal 50.35 880 6042
invoice accept: apply 40.87 16517 4904
invoice accept: cancel 45.49 851 5459
customer reserve: creation 3827.82 160 459338
customer reserve: removal 4477.04 65 537245
realization accept: apply 79.68 159 9561
realization accept: cancel 74.19 124 8903
payment from customer: creation 57.49 104 6899
payment from customer: removal 5.53 88 663
payment to supplier: creation 47.44 120 5693
payment to supplier: removal 3.63 62 436
service: total inventory turnovers 4.18 2519 502
service: total monetary turnovers 5.95 363 714
service: aggregate perf. data 0.90 13903 108
service: refresh index statistics 0.03 288567 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 23-11-2020 04:27:35 to 23-11-2020 04:27:37.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5084
TEST_TIME 2 3975
TEST_TIME 3 3822
TEST_TIME 4 3985
TEST_TIME 5 4424
TEST_TIME 6 4290
TEST_TIME 7 4701
TEST_TIME 8 4622
TEST_TIME 9 4858
TEST_TIME 10 4934
TEST_TIME 11 5307
TEST_TIME 12 5316
TEST_TIME 13 5442
TEST_TIME 14 5610
TEST_TIME 15 5841
TEST_TIME 16 6406
TEST_TIME 17 7119
TEST_TIME 18 7142
TEST_TIME 19 7518
TEST_TIME 20 8028
TEST_TIME 21 8631
TEST_TIME 22 9576
TEST_TIME 23 10717
TEST_TIME 24 10957
TEST_TIME 25 10704
TEST_TIME 26 10407
TEST_TIME 27 10437
TEST_TIME 28 10223
TEST_TIME 29 10190
TEST_TIME 30 10060
TEST_TIME 31 9947
TEST_TIME 32 9930
TEST_TIME 33 9804
TEST_TIME 34 9644
TEST_TIME 35 9642
TEST_TIME 36 9756
TEST_TIME 37 9636
TEST_TIME 38 9630
TEST_TIME 39 9545
TEST_TIME 40 9962
TEST_TIME 41 9967
TEST_TIME 42 9869
TEST_TIME 43 9839
TEST_TIME 44 9796
TEST_TIME 45 9989
TEST_TIME 46 10000
TEST_TIME 47 10095
TEST_TIME 48 9898
TEST_TIME 49 9739
TEST_TIME 50 9649
TEST_TIME 51 9529
TEST_TIME 52 9449
TEST_TIME 53 9376
TEST_TIME 54 9281
TEST_TIME 55 9332
TEST_TIME 56 9328
TEST_TIME 57 9282
TEST_TIME 58 9319
TEST_TIME 59 9411
TEST_TIME 60 9388
TEST_TIME 61 9479
TEST_TIME 62 9535
TEST_TIME 63 9597
TEST_TIME 64 9612
TEST_TIME 65 9604
TEST_TIME 66 9555
TEST_TIME 67 9664
TEST_TIME 68 9612
TEST_TIME 69 9482
TEST_TIME 70 9463
TEST_TIME 71 9368
TEST_TIME 72 9278
TEST_TIME 73 9192
TEST_TIME 74 9102
TEST_TIME 75 9095
TEST_TIME 76 9088
TEST_TIME 77 9055
TEST_TIME 78 9046
TEST_TIME 79 9084
TEST_TIME 80 9080
TEST_TIME 81 9064
TEST_TIME 82 9122
TEST_TIME 83 9098
TEST_TIME 84 9160
TEST_TIME 85 9129
TEST_TIME 86 9232
TEST_TIME 87 9211
TEST_TIME 88 9111
TEST_TIME 89 9062
TEST_TIME 90 9014
TEST_TIME 91 8999
TEST_TIME 92 8972
TEST_TIME 93 8999
TEST_TIME 94 8971
TEST_TIME 95 8978
TEST_TIME 96 8949
TEST_TIME 97 9026
TEST_TIME 98 9036
TEST_TIME 99 8987
TEST_TIME 100 8997
TEST_TIME 101 9024
TEST_TIME 102 9105
TEST_TIME 103 9100
TEST_TIME 104 9150
TEST_TIME 105 9117
TEST_TIME 106 9048
TEST_TIME 107 9007
TEST_TIME 108 9042
TEST_TIME 109 9008
TEST_TIME 110 8979
TEST_TIME 111 8951
TEST_TIME 112 8929
TEST_TIME 113 8975
TEST_TIME 114 8993
TEST_TIME 115 8982
TEST_TIME 116 8955
TEST_TIME 117 9000
TEST_TIME 118 9005
TEST_TIME 119 8990
TEST_TIME 120 8995

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 23-11-2020 04:27:39 to 23-11-2020 04:27:42.

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 1502116 1502116 0 0.00 0 11970 6 0 0
doc_list_biud 1502116 1502116 0 0.00 0 38511 29 0 0
sp_add_invoice_to_stock 4958 4904 54 1.08 21 227717 16518 0 54
sp_cancel_adding_invoice 5459 5459 0 0.00 4 78546 851 0 0
sp_cancel_client_order 1746 1746 0 0.00 5 8279 330 0 0
sp_cancel_customer_reserve 537245 537245 0 0.00 1 21961 66 0 0
sp_cancel_pay_from_customer 663 663 0 0.00 3 6058 89 0 0
sp_cancel_pay_to_supplier 436 436 0 0.00 3 4899 62 0 0
sp_cancel_supplier_invoice 6047 6042 5 0.08 1 14268 880 0 5
sp_cancel_supplier_order 398 398 0 0.00 6 1283286 110393 0 0
sp_cancel_write_off 8903 8903 0 0.00 1 10275 125 0 0
sp_client_order 19766 19766 0 0.00 4 22843 459 0 0
sp_customer_reserve 459338 459338 0 0.00 0 71353 160 0 0
sp_fill_shopping_cart 30363 30363 0 0.00 1 25108 48 0 0
sp_fill_shopping_cart_clo_res 459334 459334 0 0.00 0 28700 19 0 0
sp_get_clo_for_invoice 4898 4898 0 0.00 0 69217 473 0 0
sp_kill_cost_storno 15461 15461 0 0.00 0 9243 54 0 0
sp_kill_qstorno_ret_qs2qd 1089833 1089833 0 0.00 0 21952 36 0 0
sp_kill_qty_storno 574252 574252 0 0.00 0 38511 76 0 0
sp_lock_selected_doc 20142 20142 0 0.00 0 6717 4 0 0
sp_make_cost_storno 27051 27051 0 0.00 0 11961 51 0 0
sp_multiply_rows_for_pdistr 27051 27051 0 0.00 0 9526 32 0 0
sp_multiply_rows_for_qdistr 19766 19766 0 0.00 0 22058 282 0 0
sp_pay_from_customer 6899 6899 0 0.00 4 15888 104 0 0
sp_pay_to_supplier 5693 5693 0 0.00 4 11815 121 0 0
sp_payment_common 12592 12592 0 0.00 1 8861 24 0 0
sp_qd_handle_on_cancel_clo 1746 1746 0 0.00 0 7952 301 0 0
sp_qd_handle_on_invoice_upd_sts 10357 10357 0 0.00 0 38510 333 0 0
sp_qd_handle_on_reserve_upd_sts 9561 9561 0 0.00 0 19699 43 0 0
sp_reserve_write_off 9561 9561 0 0.00 7 19755 160 0 0
sp_supplier_invoice 5308 5308 0 0.00 6 80325 3112 0 0
sp_supplier_order 5309 5309 0 0.00 4 28290 1223 0 0
srv_aggregate_perf_data 108 108 0 0.00 2749 119593 13903 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 2064 138669 49432 0 0
srv_find_qd_qs_mism 1031350 1031350 0 0.00 0 11755 5 0 0
srv_make_invnt_saldo 502 502 0 0.00 73 104467 2519 0 0
srv_make_money_saldo 714 714 0 0.00 1 10966 364 0 0
srv_recalc_idx_stat 4 4 0 0.00 58982 394341 288568 0 0
t$perf-abend:sp_add_invoice_to_stock 54 54 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4904 4904 0 0.00 0 11729 156 0 0
t$perf-norm:sp_cancel_adding_invoice 403 403 0 0.00 0 4496 96 0 0
t$perf-norm:sp_cancel_client_order 1746 1746 0 0.00 0 664 6 0 0
t$perf-norm:sp_cancel_customer_reserve 667 667 0 0.00 0 753 5 0 0
t$perf-norm:sp_cancel_pay_from_customer 663 663 0 0.00 0 192 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 436 436 0 0.00 0 69 1 0 0
t$perf-norm:sp_cancel_supplier_invoice 368 368 0 0.00 0 4462 25 0 0
t$perf-norm:sp_cancel_supplier_order 398 398 0 0.00 0 10979 1101 0 0
t$perf-norm:sp_cancel_write_off 1006 1006 0 0.00 0 6928 12 0 0
t$perf-norm:sp_client_order 19766 19766 0 0.00 0 7031 16 0 0
t$perf-norm:sp_customer_reserve 978 978 0 0.00 0 2533 9 0 0
t$perf-norm:sp_pay_from_customer 6899 6899 0 0.00 0 6059 12 0 0
t$perf-norm:sp_pay_to_supplier 5693 5693 0 0.00 0 3855 10 0 0
t$perf-norm:sp_reserve_write_off 9561 9561 0 0.00 0 4403 9 0 0
t$perf-norm:sp_supplier_invoice 5308 5308 0 0.00 0 5835 12 0 0
t$perf-norm:sp_supplier_order 5309 5309 0 0.00 0 7018 14 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 206 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 45 1 0 0
t$perf-norm:srv_make_invnt_saldo 502 502 0 0.00 0 437 4 0 0
t$perf-norm:srv_make_money_saldo 714 714 0 0.00 0 1138 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 14 6 0 0
v_add_invoice_to_stock 9898 9790 108 1.09 0 6591 16 0 108
v_all_customers 36614 36614 0 0.00 0 17 1 0 0
v_all_suppliers 29504 29504 0 0.00 0 45 1 0 0
v_all_wares 39532 39532 0 0.00 0 45 1 0 0
v_cancel_adding_invoice 806 806 0 0.00 0 577 7 0 0
v_cancel_client_order 3492 3492 0 0.00 0 4347 5 0 0
v_cancel_customer_prepayment 1326 1326 0 0.00 0 3470 9 0 0
v_cancel_customer_reserve 1334 1334 0 0.00 0 208 3 0 0
v_cancel_payment_to_supplier 872 872 0 0.00 0 596 4 0 0
v_cancel_supplier_invoice 746 736 10 1.34 0 2062 15 0 10
v_cancel_supplier_order 796 796 0 0.00 0 852 4 0 0
v_cancel_write_off 2012 2012 0 0.00 0 5778 16 0 0
v_max_id_clo_ord 5304 5304 0 0.00 0 246 1 0 0
v_max_id_clo_res 978 978 0 0.00 0 4366 17 0 0
v_max_id_ord_sup 5293 5293 0 0.00 0 4287 7 0 0
v_max_non_paid_invoice 1538 1538 0 0.00 0 1394 4 0 0
v_max_non_paid_realizn 3410 3410 0 0.00 0 2069 3 0 0
v_min_id_clo_ord 5304 5304 0 0.00 0 3244 6 0 0
v_min_id_clo_res 978 978 0 0.00 0 3132 9 0 0
v_min_id_ord_sup 5293 5293 0 0.00 0 9461 16 0 0
v_min_non_paid_invoice 5693 5693 0 0.00 0 7956 17 0 0
v_min_non_paid_realizn 6899 6899 0 0.00 0 8858 16 0 0
v_our_firm 9896 9896 0 0.00 0 38 1 0 0
v_reserve_write_off 19120 19120 0 0.00 0 4787 5 0 0
x_lock_depdocs_on_canc_invoice 11133 11133 0 0.00 0 14734 92 0 0
x_lock_depdocs_on_canc_sup_ord 398 398 0 0.00 0 4590 268 0 0
x_make_qstorno_1000_1200 5309 5309 0 0.00 2 28237 1159 0 0
x_make_qstorno_1200_2000 5308 5308 0 0.00 2 80315 2910 0 0
x_make_qstorno_2100_3300 459338 459338 0 0.00 0 71346 140 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-11-2020 04:27:43 to 23-11-2020 04:27:47.

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 8796641312 8807108608 3168704 0.038 1 1 2 0 3266400 61840 35856
01:57:59 21844 8192000000 9724217232 10020380672 801477408 8.911 100 84 168 0 830590912 8375888 20737616
01:59:26 31015 8192000000 9769058544 10078367744 842889408 9.329 100 92 182 1 879828800 15246592 21692800
02:01:00 8974 8192000000 9815920544 10137137152 876954144 9.669 100 87 174 0 926372736 29797168 19621424
02:02:14 12459 8192000000 9863326464 10195054592 909191680 9.989 100 83 166 3 977235712 50238896 17805136
02:03:30 10952 8192000000 9915686432 10261090304 956864512 10.458 100 73 146 0 1018524736 46857344 14802880
02:04:43 4503 8192000000 9965104960 10316722176 996916480 10.849 100 81 160 0 1071759696 58926064 15917152
02:05:49 2056 8192000000 10004647024 10361540608 1022615600 11.097 100 91 183 0 1117694160 76497184 18581376
02:06:53 1420 8192000000 10013339488 10381000704 1048075904 11.342 100 87 174 0 1122710432 56975856 17658672
02:07:56 2201 8192000000 10050968896 10424561664 1067614896 11.529 100 91 185 0 1157855088 71661168 18579024
02:09:00 5859 8192000000 9987758400 10396409856 1069643152 11.549 100 25 50 1 1106765568 31801888 5320528
02:10:09 7532 8192000000 10001075488 10396811264 1079012880 11.638 100 34 66 4 1109941776 23244912 7683984
02:11:17 7740 8192000000 10006803680 10406379520 1086067696 11.705 100 38 76 1 1116783568 22312976 8402896
02:12:26 60465 8192000000 10029665008 10416607232 1091669536 11.759 100 67 134 0 1127152928 20321552 15161840
02:14:27 5704 8192000000 10021041280 10424131584 1092563056 11.767 100 46 93 1 1127903280 26056912 9283312
02:15:34 5232 8192000000 10027586352 10422054912 1089787664 11.741 100 40 80 2 1135945232 37705968 8451600
02:16:40 4488 8192000000 9995640912 10390822912 1072585984 11.577 99 43 85 2 1104320768 23343280 8391504
02:17:49 43299 8192000000 9970113584 10369277952 1027913328 11.148 96 31 62 1 1053438208 18828608 6696272
02:19:32 5561 8192000000 9961135536 10349219840 1022938384 11.100 98 36 71 2 1052260880 21114064 8208432
02:20:41 7487 8192000000 9951966704 10328735744 1031938368 11.187 100 33 65 0 1061352160 22382272 7031520
02:21:51 5535 8192000000 9963409312 10343821312 1023418912 11.105 99 55 109 3 1051823456 16122832 12281712
02:23:01 34303 8192000000 9930252832 10324959232 1026478272 11.135 99 32 64 4 1048343552 15141424 6723856
02:24:35 6945 8192000000 9965603280 10358947840 1053255888 11.392 100 43 85 0 1074766736 11562288 9948560
02:25:45 4996 8192000000 9979434752 10379493376 1066789440 11.521 100 37 74 1 1091583232 16813888 7979904
02:26:52 7234 8192000000 9998903344 10398154752 1085468912 11.700 100 31 62 1 1109903552 17802544 6632096
02:28:05 63086 8192000000 10044009904 10446233600 1104202016 11.877 100 49 98 3 1144720496 30378336 10140144
02:30:34 12415 8192000000 10116910192 10518360064 1119860752 12.026 100 65 128 2 1227490176 94331472 13297952
02:31:49 22790 8192000000 10143526400 10558468096 1137928080 12.196 100 50 98 3 1241466048 91493840 12044128
02:33:20 26999 8192000000 10167876448 10585833472 1148607088 12.296 100 41 82 1 1283517840 124371680 10539072
02:34:52 17690 8192000000 10262439360 10673741824 1166263920 12.462 100 57 112 0 1363265056 184019008 12982128
02:36:24 2409 8192000000 10321146672 10721218560 1182510960 12.614 100 84 165 3 1415877616 214593216 18773440
02:37:30 8947 8192000000 10328792944 10741272576 1189485712 12.679 100 57 114 0 1431113760 227867632 13760416
02:38:43 5773 8192000000 10350740384 10756452352 1174631424 12.540 100 67 134 0 1456797792 266222880 15943488
02:39:57 5039 8192000000 10413186192 10821849088 1182986272 12.618 100 72 144 0 1515523856 315478224 17059360
02:41:07 2147 8192000000 10471711856 10886205440 1176287120 12.556 99 65 130 1 1566474976 374424576 15763280
02:42:14 2059 8192000000 10470117376 10890842112 1166651152 12.466 100 83 166 2 1579448080 392978240 19818688
02:43:19 14878 8192000000 10418222672 10861371392 1144935472 12.262 99 36 72 0 1539937216 385742256 9259488
02:44:35 10638 8192000000 10412024496 10831552512 1120026816 12.027 98 44 88 1 1504330784 373527472 10776496
02:45:48 17963 8192000000 10402692480 10842759168 1103563616 11.871 99 35 70 1 1499264048 386579904 9120528
02:47:15 29055 8192000000 10387597408 10821582848 1099268656 11.831 99 29 58 0 1523960432 416673872 8017904
02:48:45 21251 8192000000 10204991008 10756063232 1108310864 11.916 100 23 46 2 1392442464 278058336 6073264
02:50:19 59051 8192000000 10060760496 10531495936 1066818288 11.522 97 11 21 0 1130835792 61801376 2216128
02:52:18 79650 8192000000 10076378352 10504654848 1086012224 11.705 99 42 82 0 1167389792 72332208 9045360
02:54:41 90111 8192000000 10057235824 10485747712 1089304144 11.736 98 32 63 2 1133416768 37603024 6509600
02:57:12 39417 8192000000 10082431344 10528247808 1097601616 11.815 97 14 28 1 1175470560 74998384 2870560
02:58:51 26276 8192000000 10072304800 10547150848 1113441872 11.965 100 19 38 0 1195079920 77782400 3855648
03:00:18 42979 8192000000 10104317920 10562936832 1122295856 12.049 100 19 38 1 1196722064 70321712 4104496
03:02:03 63870 8192000000 10078594288 10546413568 1117376064 12.002 99 15 30 1 1213455792 92321808 3757920
03:04:08 62251 8192000000 10118049152 10578755584 1115657520 11.986 99 37 74 0 1213965344 89951392 8356432
03:06:11 73886 8192000000 10093063904 10630373376 1122268944 12.048 99 21 42 1 1248696016 121594176 4832896
03:08:32 71994 8192000000 10111384944 10554740736 1133837904 12.158 100 28 56 2 1173394576 33473280 6083392
03:10:48 74352 8192000000 10132925328 10668347392 1140597984 12.221 100 15 30 1 1277838688 133854080 3386624
03:13:03 73696 8192000000 10087441712 10582437888 1140490976 12.220 99 11 22 0 1195458992 52509184 2458832
03:15:23 66049 8192000000 10076184048 10531393536 1145475648 12.267 99 23 46 1 1177573264 27108816 4988800
03:17:36 57349 8192000000 10103634256 10550640640 1132438608 12.144 98 10 20 0 1180651904 45887600 2325696
03:19:35 50200 8192000000 10120398448 10592149504 1149803184 12.308 100 13 26 2 1214848880 61988272 3057424
03:21:25 52016 8192000000 10080727952 10567507968 1138782064 12.204 99 13 27 0 1210203808 68443200 2978544
03:23:18 81943 8192000000 10137392624 10638925824 1118518704 12.013 97 24 48 1 1175721648 51456640 5746304
03:25:40 29214 8192000000 10089508928 10532876288 1125032464 12.075 99 30 60 1 1184175920 52965168 6178288
03:27:13 40178 8192000000 10113043936 10608582656 1142310752 12.237 100 16 32 2 1198971504 53572320 3088432
03:28:56 76378 8192000000 10090378448 10578927616 1112117888 11.952 98 18 36 1 1171235200 55650816 3466496
03:31:21 65792 8192000000 10107596560 10531115008 1085165296 11.697 96 22 44 0 1118093456 28679408 4248752
03:33:28 35854 8192000000 10087337344 10552168448 1102618624 11.862 98 17 35 0 1179663312 73275152 3769536
03:37:28 47968 8192000000 10062797312 10518134784 1130757072 12.128 100 16 30 0 1160681584 26899920 3024592
03:39:17 54283 8192000000 10079500448 10532274176 1111103232 11.943 98 8 16 0 1167616288 54755392 1757664
03:41:12 52732 8192000000 10067034176 10555793408 1108466496 11.918 98 7 14 1 1164615728 54907936 1241296
03:43:21 56393 8192000000 10051361024 10481483776 1043709232 11.300 94 17 34 0 1091299312 44005312 3584768
03:45:17 60259 8192000000 10025033632 10491277312 1053428144 11.394 95 19 38 1 1109682048 52312384 3941520
03:47:25 29345 8192000000 10118348128 10552348672 1101110608 11.848 98 43 86 3 1168067088 57782272 9174208
03:49:06 31708 8192000000 10119897728 10545942528 1123122208 12.056 100 37 72 0 1189503824 58587280 7794336
03:50:38 80980 8192000000 10107672784 10543448064 1080178496 11.649 96 18 36 2 1146997136 63249600 3569040
03:53:09 77563 8192000000 10058238048 10528305152 1112249040 11.954 99 20 39 1 1147954816 31911216 3794560
03:55:54 58648 8192000000 10075355504 10542669824 1124131728 12.066 100 10 20 0 1183604848 57424592 2048528
03:57:56 44749 8192000000 10072627072 10532855808 1136341136 12.181 100 8 16 0 1161781280 24171632 1268512
03:59:54 39961 8192000000 10057047904 10497835008 1119843392 12.026 99 17 34 2 1152910720 29901776 3165552
04:01:40 66266 8192000000 10100076016 10538737664 1135518096 12.173 99 21 42 0 1196362496 55907328 4937072
04:03:49 107613 8192000000 10085536368 10520883200 1102973344 11.866 97 18 36 1 1162157856 55557952 3626560
04:06:37 21261 8192000000 10131538256 10562097152 1132091920 12.141 100 48 96 1 1200826352 59311872 9422560
04:08:00 137707 8192000000 10116848672 10575290368 1076707968 11.616 94 11 22 0 1153252608 74069808 2474832
04:11:56 138669 8192000000 10102933216 10583724032 1134974144 12.168 100 24 48 1 1228560912 89009200 4577568
04:15:21 33571 8192000000 10066411024 10559647744 1123677168 12.062 99 2 4 0 1168820960 45005072 138720
04:17:07 49910 8192000000 10093459872 10536738816 1144779296 12.260 100 29 57 1 1197323280 46345504 6198480
04:19:07 94041 8192000000 10099606672 10563092480 1140429200 12.220 99 22 44 3 1238782928 93483632 4870096
04:21:45 52937 8192000000 10105038256 10545291264 1107272864 11.907 97 21 42 1 1159406096 47639584 4493648
04:23:38 109685 8192000000 10068618352 10514653184 1069335472 11.546 94 21 42 0 1119681760 45906528 4439760
04:26:30 35577 8192000000 8926508032 9104310272 86153040 1.040 8 1 2 0 91528592 5340064 35488

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 23-11-2020 04:27:49 to 23-11-2020 04:28:04.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 54 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-11-2020 04:28:05 to 23-11-2020 04:28:07.

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          236783
MON$OLDEST_ACTIVE               236784
MON$OLDEST_SNAPSHOT             236784
MON$NEXT_TRANSACTION            236784
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                       2080992
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             797
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2272 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2272 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2272 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.2272 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'

23.11.20 04:28:08. 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-23 04:28:10 No severe PSQL-related problems occured
23.11.20 04:28:12. End of report.