Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.10.2021 07:47
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 58e1ddde69c44dad93d327777e5becec
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 2021-05-09 08:45
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 4793.92
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 15494992 kB
MemAvailable 30685112 kB
Buffers 0 kB
Cached 16180012 kB
SwapCached 3844 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.1
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 2021-10-10 07:47:38
DTS_END                         2021-10-10 07:47:26.0270
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/60064
TRN_ID                          229513
ATT_ID                          259
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: 9370.28 22467 1124434
customer order: creation 171.39 406 20567
customer order: refuse 14.29 380 1715
order to supplier: creation 44.72 1556 5366
order to supplier: removal 3.58 127859 429
invoice (draft): creation 44.87 3675 5384
invoice (draft): removal 53.38 1078 6406
invoice accept: apply 40.80 20783 4896
invoice accept: cancel 48.05 961 5766
customer reserve: creation 3894.99 197 467399
customer reserve: removal 4767.20 74 572064
realization accept: apply 81.65 204 9798
realization accept: cancel 79.86 122 9583
payment from customer: creation 58.48 130 7018
payment from customer: removal 5.64 80 677
payment to supplier: creation 47.62 143 5714
payment to supplier: removal 3.48 68 418
service: total inventory turnovers 3.33 4978 400
service: total monetary turnovers 6.11 708 733
service: aggregate perf. data 0.81 19321 97
service: refresh index statistics 0.03 266622 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-10-2021 07:48:10 to 10-10-2021 07:48:12.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3248
TEST_TIME 2 5554
TEST_TIME 3 5906
TEST_TIME 4 6089
TEST_TIME 5 5460
TEST_TIME 6 5897
TEST_TIME 7 5881
TEST_TIME 8 6116
TEST_TIME 9 6267
TEST_TIME 10 6681
TEST_TIME 11 6851
TEST_TIME 12 6978
TEST_TIME 13 7133
TEST_TIME 14 7179
TEST_TIME 15 7516
TEST_TIME 16 7784
TEST_TIME 17 7750
TEST_TIME 18 8128
TEST_TIME 19 8270
TEST_TIME 20 8090
TEST_TIME 21 8187
TEST_TIME 22 8454
TEST_TIME 23 8682
TEST_TIME 24 8707
TEST_TIME 25 8938
TEST_TIME 26 9089
TEST_TIME 27 9505
TEST_TIME 28 9708
TEST_TIME 29 10731
TEST_TIME 30 11326
TEST_TIME 31 11488
TEST_TIME 32 11559
TEST_TIME 33 11348
TEST_TIME 34 11399
TEST_TIME 35 11376
TEST_TIME 36 11203
TEST_TIME 37 11056
TEST_TIME 38 10992
TEST_TIME 39 11014
TEST_TIME 40 10929
TEST_TIME 41 10745
TEST_TIME 42 10888
TEST_TIME 43 10813
TEST_TIME 44 10797
TEST_TIME 45 10627
TEST_TIME 46 10431
TEST_TIME 47 10434
TEST_TIME 48 10353
TEST_TIME 49 10287
TEST_TIME 50 10261
TEST_TIME 51 10207
TEST_TIME 52 10197
TEST_TIME 53 10178
TEST_TIME 54 10162
TEST_TIME 55 10104
TEST_TIME 56 10171
TEST_TIME 57 10175
TEST_TIME 58 10198
TEST_TIME 59 10115
TEST_TIME 60 10022
TEST_TIME 61 9908
TEST_TIME 62 9963
TEST_TIME 63 10019
TEST_TIME 64 9934
TEST_TIME 65 9853
TEST_TIME 66 9798
TEST_TIME 67 9757
TEST_TIME 68 9855
TEST_TIME 69 9809
TEST_TIME 70 9818
TEST_TIME 71 9882
TEST_TIME 72 9825
TEST_TIME 73 9838
TEST_TIME 74 9900
TEST_TIME 75 9863
TEST_TIME 76 9867
TEST_TIME 77 9832
TEST_TIME 78 9894
TEST_TIME 79 9872
TEST_TIME 80 9762
TEST_TIME 81 9735
TEST_TIME 82 9671
TEST_TIME 83 9636
TEST_TIME 84 9645
TEST_TIME 85 9590
TEST_TIME 86 9531
TEST_TIME 87 9527
TEST_TIME 88 9486
TEST_TIME 89 9473
TEST_TIME 90 9437
TEST_TIME 91 9470
TEST_TIME 92 9468
TEST_TIME 93 9513
TEST_TIME 94 9538
TEST_TIME 95 9516
TEST_TIME 96 9552
TEST_TIME 97 9601
TEST_TIME 98 9642
TEST_TIME 99 9698
TEST_TIME 100 9730
TEST_TIME 101 9714
TEST_TIME 102 9702
TEST_TIME 103 9627
TEST_TIME 104 9598
TEST_TIME 105 9568
TEST_TIME 106 9547
TEST_TIME 107 9501
TEST_TIME 108 9453
TEST_TIME 109 9429
TEST_TIME 110 9414
TEST_TIME 111 9375
TEST_TIME 112 9395
TEST_TIME 113 9380
TEST_TIME 114 9388
TEST_TIME 115 9369
TEST_TIME 116 9430
TEST_TIME 117 9407
TEST_TIME 118 9395
TEST_TIME 119 9425
TEST_TIME 120 9370

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 10-10-2021 07:48:14 to 10-10-2021 07:48:17.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1555706 1555706 0 0.00 0 21706 7 0 0
doc_list_biud 1555704 1555704 0 0.00 0 28754 35 0 0
sp_add_invoice_to_stock 4999 4896 103 2.06 22 217352 20783 0 103
sp_cancel_adding_invoice 5766 5766 0 0.00 6 67069 962 0 0
sp_cancel_client_order 1715 1715 0 0.00 5 14852 381 0 0
sp_cancel_customer_reserve 572064 572064 0 0.00 1 28763 75 0 0
sp_cancel_pay_from_customer 677 677 0 0.00 3 3628 81 0 0
sp_cancel_pay_to_supplier 418 418 0 0.00 3 1685 68 0 0
sp_cancel_supplier_invoice 6414 6406 8 0.12 2 17799 1079 0 8
sp_cancel_supplier_order 429 429 0 0.00 10 1361151 127859 0 0
sp_cancel_write_off 9583 9583 0 0.00 1 18797 123 0 0
sp_client_order 20567 20567 0 0.00 4 27103 407 0 0
sp_customer_reserve 467399 467399 0 0.00 0 112811 198 0 0
sp_fill_shopping_cart 31312 31312 0 0.00 1 11340 42 0 0
sp_fill_shopping_cart_clo_res 467399 467399 0 0.00 0 14051 16 0 0
sp_get_clo_for_invoice 4896 4896 0 0.00 0 26238 367 0 0
sp_kill_cost_storno 16444 16444 0 0.00 0 9995 68 0 0
sp_kill_qstorno_ret_qs2qd 1160546 1160546 0 0.00 0 28743 40 0 0
sp_kill_qty_storno 610656 610656 0 0.00 0 28753 87 0 0
sp_lock_selected_doc 20369 20369 0 0.00 0 9007 7 0 0
sp_make_cost_storno 27426 27426 0 0.00 0 13960 62 0 0
sp_multiply_rows_for_pdistr 27425 27425 0 0.00 0 14962 38 0 0
sp_multiply_rows_for_qdistr 20567 20567 0 0.00 0 27046 203 0 0
sp_pay_from_customer 7018 7018 0 0.00 5 13349 130 0 0
sp_pay_to_supplier 5714 5714 0 0.00 5 14978 144 0 0
sp_payment_common 12732 12732 0 0.00 1 9781 25 0 0
sp_qd_handle_on_cancel_clo 1715 1715 0 0.00 0 14846 345 0 0
sp_qd_handle_on_invoice_upd_sts 10662 10662 0 0.00 0 21238 416 0 0
sp_qd_handle_on_reserve_upd_sts 9797 9797 0 0.00 0 16190 65 0 0
sp_reserve_write_off 9798 9798 0 0.00 7 16205 205 0 0
sp_supplier_invoice 5384 5384 0 0.00 8 82837 3675 0 0
sp_supplier_order 5366 5366 0 0.00 5 69989 1556 0 0
srv_aggregate_perf_data 97 97 0 0.00 3211 160004 19322 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1598 123441 41649 0 0
srv_find_qd_qs_mism 1076269 1076269 0 0.00 0 21705 6 0 0
srv_make_invnt_saldo 400 400 0 0.00 54 213612 4978 0 0
srv_make_money_saldo 733 733 0 0.00 1 25515 708 0 0
srv_recalc_idx_stat 4 4 0 0.00 141523 414015 266622 0 0
t$perf-abend:sp_add_invoice_to_stock 103 103 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4896 4896 0 0.00 0 10769 189 0 0
t$perf-norm:sp_cancel_adding_invoice 410 410 0 0.00 0 5072 130 0 0
t$perf-norm:sp_cancel_client_order 1715 1715 0 0.00 0 1770 8 0 0
t$perf-norm:sp_cancel_customer_reserve 689 689 0 0.00 0 8039 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 677 677 0 0.00 0 6110 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 418 418 0 0.00 0 1104 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 391 391 0 0.00 0 2969 21 0 0
t$perf-norm:sp_cancel_supplier_order 429 429 0 0.00 0 34184 2044 0 0
t$perf-norm:sp_cancel_write_off 947 947 0 0.00 0 3936 13 0 0
t$perf-norm:sp_client_order 20567 20567 0 0.00 0 6316 20 0 0
t$perf-norm:sp_customer_reserve 972 972 0 0.00 0 1035 5 0 0
t$perf-norm:sp_pay_from_customer 7018 7018 0 0.00 0 6105 13 0 0
t$perf-norm:sp_pay_to_supplier 5714 5714 0 0.00 0 2463 8 0 0
t$perf-norm:sp_reserve_write_off 9798 9798 0 0.00 0 11042 16 0 0
t$perf-norm:sp_supplier_invoice 5384 5384 0 0.00 0 2723 17 0 0
t$perf-norm:sp_supplier_order 5366 5366 0 0.00 0 7818 17 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 1028 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 853 17 0 0
t$perf-norm:srv_make_invnt_saldo 400 400 0 0.00 0 1239 12 0 0
t$perf-norm:srv_make_money_saldo 733 733 0 0.00 0 1963 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 9998 9792 206 2.06 0 5870 18 0 206
v_all_customers 37244 37244 0 0.00 0 22 1 0 0
v_all_suppliers 29604 29604 0 0.00 0 255 1 0 0
v_all_wares 41132 41132 0 0.00 0 110 1 0 0
v_cancel_adding_invoice 820 820 0 0.00 0 2062 12 0 0
v_cancel_client_order 3430 3430 0 0.00 0 2163 6 0 0
v_cancel_customer_prepayment 1354 1354 0 0.00 0 3617 8 0 0
v_cancel_customer_reserve 1378 1378 0 0.00 0 2390 5 0 0
v_cancel_payment_to_supplier 836 836 0 0.00 0 1541 6 0 0
v_cancel_supplier_invoice 798 782 16 2.00 0 2117 15 0 16
v_cancel_supplier_order 858 858 0 0.00 0 974 5 0 0
v_cancel_write_off 1894 1894 0 0.00 0 915 15 0 0
v_max_id_clo_ord 5366 5366 0 0.00 0 157 1 0 0
v_max_id_clo_res 972 972 0 0.00 0 13870 23 0 0
v_max_id_ord_sup 5380 5380 0 0.00 0 2335 3 0 0
v_max_non_paid_invoice 1658 1658 0 0.00 0 1429 4 0 0
v_max_non_paid_realizn 3808 3808 0 0.00 0 3006 6 0 0
v_min_id_clo_ord 5366 5366 0 0.00 0 3746 6 0 0
v_min_id_clo_res 972 972 0 0.00 0 2923 8 0 0
v_min_id_ord_sup 5380 5380 0 0.00 0 6982 10 0 0
v_min_non_paid_invoice 5714 5714 0 0.00 0 9501 14 0 0
v_min_non_paid_realizn 7018 7018 0 0.00 0 9769 16 0 0
v_our_firm 10308 10308 0 0.00 0 257 1 0 0
v_reserve_write_off 19594 19594 0 0.00 0 2939 5 0 0
x_lock_depdocs_on_canc_invoice 11781 11781 0 0.00 0 18398 131 0 0
x_lock_depdocs_on_canc_sup_ord 429 429 0 0.00 0 12845 406 0 0
x_make_qstorno_1000_1200 5366 5366 0 0.00 2 69969 1466 0 0
x_make_qstorno_1200_2000 5384 5384 0 0.00 4 82826 3529 0 0
x_make_qstorno_2100_3300 467399 467399 0 0.00 0 112809 179 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-10-2021 07:48:18 to 10-10-2021 07:48:22.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:17:29 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:18:30 25666 8192000000 9724523856 10019840000 797328112 8.869 100 86 172 0 826238224 7615248 21294864
05:20:02 19759 8192000000 9765771056 10069639168 838411616 9.284 100 88 176 0 871121984 11935680 20774688
05:21:26 14408 8192000000 9797653392 10115010560 869689616 9.597 100 87 174 0 909643376 20107856 19845904
05:22:46 13078 8192000000 9870726992 10205409280 925519424 10.151 100 81 162 0 981081616 38574336 16987856
05:24:02 9700 8192000000 9932004064 10277965824 968145728 10.569 100 83 166 0 1040216304 54945328 17125248
05:25:17 5556 8192000000 9951644000 10311004160 1002121216 10.899 100 71 139 0 1069933968 54052256 13760496
05:26:26 4215 8192000000 10000537968 10362884096 1029996288 11.168 100 91 182 1 1120242320 72270368 17975664
05:27:32 2213 8192000000 10020983344 10388312064 1049003840 11.351 100 98 196 1 1134158880 65251632 19903408
05:28:35 1181 8192000000 10044586160 10414727168 1059101312 11.448 100 96 192 1 1154862704 75971552 19789840
05:29:37 6039 8192000000 10018889008 10414809088 1067081104 11.524 100 44 89 0 1127839328 52084176 8674048
05:30:44 4480 8192000000 9986762720 10376974336 1078994352 11.638 100 34 65 2 1107673120 19694656 8984112
05:31:49 25544 8192000000 10003553408 10405896192 1079415568 11.642 100 29 58 0 1110085568 24536832 6133168
05:33:19 3609 8192000000 10011667392 10402603008 1091425712 11.756 100 41 79 1 1118398864 18898016 8075136
05:34:23 6072 8192000000 10013151840 10423742464 1095962880 11.799 100 38 74 2 1133937776 29994512 7980384
05:35:33 45756 8192000000 10004166544 10413625344 1086544160 11.710 99 37 75 3 1115346544 20980976 7821408
05:37:22 3876 8192000000 9999231840 10390896640 1086494720 11.709 99 57 116 0 1113231264 14445616 12290928
05:38:27 3987 8192000000 9981629360 10365075456 1050825424 11.369 99 49 99 1 1091459536 29640144 10993968
05:39:34 7030 8192000000 9959969888 10356408320 1039676496 11.262 98 31 62 0 1061055296 14206368 7172432
05:40:44 44733 8192000000 9950862528 10361233408 966614704 10.554 92 28 56 1 1000251536 28029696 5607136
05:42:37 6072 8192000000 9958289376 10348445696 1036728736 11.233 100 47 96 3 1070952944 23714256 10509952
05:43:46 10057 8192000000 9955435584 10347937792 1017448288 11.047 97 35 69 1 1048862672 22625056 8789328
05:44:57 6212 8192000000 9971912384 10366496768 1049731952 11.358 99 26 52 0 1075088480 19249856 6106672
05:46:04 53093 8192000000 9962577216 10371887104 1065848272 11.512 100 30 58 4 1092008832 19816064 6344496
05:48:06 31632 8192000000 10028049968 10428542976 1095888496 11.799 100 49 98 2 1137672448 31610784 10173168
05:49:45 12451 8192000000 10081236672 10489184256 1112232016 11.954 100 69 137 0 1199426576 73462816 13731744
05:51:03 18127 8192000000 10113378480 10528268288 1130729712 12.128 100 49 96 1 1214168864 73246544 10192608
05:52:24 46016 8192000000 10150074016 10561966080 1140825248 12.223 100 39 77 0 1237230112 88247440 8157424
05:54:16 2665 8192000000 10168871200 10581544960 1156946752 12.375 100 63 122 0 1276668080 106616256 13105072
05:55:28 2773 8192000000 10223132672 10628870144 1161934560 12.421 100 76 152 1 1319379408 140280864 17163984
05:56:34 10261 8192000000 10270253696 10687483904 1174480592 12.539 100 62 124 1 1371573184 183436592 13656000
05:57:47 21764 8192000000 10266209344 10696110080 1172675728 12.522 99 64 126 2 1360842240 174127632 14038880
05:59:20 4533 8192000000 10297843008 10714886144 1189320864 12.677 100 65 130 0 1396978688 192536880 15120944
06:00:31 1597 8192000000 10339566464 10749685760 1195278464 12.732 100 91 180 1 1440370080 224756512 20335104
06:01:38 2694 8192000000 10395155248 10807390208 1185921760 12.645 99 85 171 0 1489587424 284077520 19588144
06:02:45 8040 8192000000 10356272176 10777612288 1156524432 12.371 98 59 117 1 1449518144 278862240 14131472
06:04:06 2621 8192000000 10355065024 10768932864 1167370528 12.472 99 78 155 2 1453735632 268502928 17862176
06:05:15 2556 8192000000 10398311088 10823233536 1190833952 12.691 100 86 172 0 1492635152 281630896 20170304
06:06:26 12666 8192000000 10383055328 10818445312 1183755712 12.625 99 44 88 0 1485477632 290327008 11394912
06:07:46 2953 8192000000 10471776848 10873851904 1194731456 12.727 100 94 187 0 1563232864 347278528 21222880
06:08:54 6415 8192000000 10519309648 10942730240 1184008384 12.628 99 51 101 1 1618352432 420540128 13803920
06:10:09 6466 8192000000 10522608208 10977796096 1176444400 12.557 100 44 88 0 1622176432 433557408 12174624
06:11:18 7385 8192000000 10509340352 10948337664 1163293056 12.434 99 53 106 0 1602871856 425423120 14155680
06:12:35 4478 8192000000 10519551248 10960158720 1163593312 12.437 100 49 99 0 1614290656 437789856 12907488
06:13:44 13091 8192000000 10527224528 10956091392 1154463632 12.351 100 39 78 2 1626344048 461284928 10595488
06:15:00 12403 8192000000 10476662784 10930532352 1137091648 12.188 99 27 54 0 1578196192 433543312 7561232
06:16:14 7978 8192000000 10366378352 10834006016 1147914944 12.290 100 31 62 0 1473489472 317658512 7916016
06:17:23 68278 8192000000 10178003776 10631532544 1136392384 12.182 98 30 60 0 1272927488 129783008 6752096
06:19:33 44919 8192000000 10098593696 10573582336 1134004480 12.159 99 17 33 0 1202531376 64517552 4009344
06:21:19 100931 8192000000 10068007104 10519044096 1091560144 11.757 95 10 20 0 1132852320 39508656 1783520
06:24:02 92089 8192000000 10087042448 10557206528 1107408896 11.908 96 25 50 0 1179099856 66064944 5626016
06:26:34 49670 8192000000 10073197984 10511425536 1130791072 12.129 100 18 36 0 1151663296 17661920 3210304
06:28:27 54506 8192000000 10125495104 10569781248 1128858592 12.111 99 28 56 0 1176179168 41231472 6089104
06:30:31 23528 8192000000 10065922768 10522857472 1133184672 12.151 99 17 35 1 1166355008 30168352 3001984
06:31:59 123440 8192000000 10105502544 10550587392 1131070896 12.131 99 23 46 0 1161210736 25352800 4787040
06:35:18 19651 8192000000 10126958656 10548944896 1150744656 12.316 100 59 119 1 1206302432 42405088 13152688
06:36:39 67634 8192000000 10122503904 10593288192 1154525040 12.352 100 18 36 0 1241278464 82763600 3989824
06:38:52 31950 8192000000 10169340256 10604339200 1139354000 12.209 98 32 64 0 1236429488 89788688 7286800
06:40:27 32408 8192000000 10176134352 10622365696 1167002464 12.469 100 49 100 0 1286375088 107914896 11457728
06:42:04 34043 8192000000 10183554608 10645692416 1156729712 12.373 100 28 56 0 1277015120 113925072 6360336
06:43:45 99060 8192000000 10100137024 10573611008 1116550112 11.994 97 12 24 1 1189702512 70403456 2748944
06:46:28 46542 8192000000 10124552096 10585010176 1129046944 12.112 98 15 30 1 1187517472 54865472 3605056
06:48:22 104006 8192000000 10108562768 10588372992 1134448016 12.163 99 22 44 0 1209905504 70747760 4709728
06:51:15 10088 8192000000 10063732720 10528976896 1136019616 12.178 100 22 45 0 1165845040 24863376 4962048
06:52:30 63583 8192000000 10111539552 10588278784 1133292960 12.152 99 14 29 0 1216929008 80425552 3210496
06:55:21 60774 8192000000 10096619888 10580283392 1115833168 11.988 98 17 35 0 1161523808 42146944 3543696
06:57:23 103664 8192000000 10070354016 10516840448 1104504384 11.880 96 19 38 0 1156216768 48000048 3712336
07:00:13 26228 8192000000 10099177392 10530660352 1108822512 11.921 98 47 94 1 1155002672 37044512 9135648
07:01:41 53415 8192000000 10056653712 10487832576 1043084432 11.294 92 26 52 1 1072228944 24071024 5073488
07:03:35 42766 8192000000 10078886912 10510663680 1083597792 11.682 97 28 56 1 1131850416 42587360 5665264
07:05:18 54461 8192000000 10077223776 10517123072 1090516528 11.748 97 33 66 0 1168629616 71529360 6583728
07:07:15 88540 8192000000 10083080288 10530533376 1127730784 12.100 100 49 98 1 1179122960 41112448 10279728
07:09:44 6879 8192000000 10099157248 10533355520 1112705696 11.958 98 30 58 1 1182592448 63913680 5973072
07:11:35 30007 8192000000 10057862816 10557235200 1116981536 11.998 99 5 10 1 1149152256 31160336 1010384
07:13:05 52744 8192000000 10072780400 10526937088 1109415952 11.927 98 20 40 0 1165068112 51496192 4155968
07:15:40 40697 8192000000 10086764720 10540290048 1130122224 12.123 99 27 54 0 1179504544 43817216 5565104
07:17:27 36087 8192000000 10119049376 10556747776 1122769584 12.053 99 23 46 2 1185339792 57798144 4772064
07:19:17 89989 8192000000 10086265344 10561298432 1111890176 11.950 98 18 36 0 1154360368 38704016 3766176
07:22:01 67864 8192000000 10141104224 10589921280 1139542688 12.211 100 17 34 0 1211539424 68389008 3607728
07:24:44 52631 8192000000 10108016400 10627096576 1125126736 12.075 99 15 30 1 1234463920 105784736 3552448
07:26:42 74973 8192000000 10052408640 10510934016 1097926592 11.818 96 10 20 0 1161418832 61418240 2074000
07:29:04 51346 8192000000 10098458064 10565095424 1129561792 12.117 99 45 90 1 1197636480 59253616 8821072
07:30:56 85957 8192000000 10086040320 10554437632 1119512704 12.022 98 22 44 0 1186465040 62122240 4830096
07:33:23 38489 8192000000 10062020512 10565464064 1134362800 12.162 100 8 16 0 1174485584 38474576 1648208
07:36:26 58463 8192000000 10069702832 10545364992 1131495968 12.135 99 14 28 2 1175877808 41013520 3368320
07:38:33 62799 8192000000 10112281872 10599231488 1149395040 12.304 100 17 34 0 1221186112 68321376 3469696
07:40:38 77655 8192000000 10115545328 10594304000 1133313024 12.153 99 13 26 0 1179664608 43517184 2834400
07:43:07 99520 8192000000 10105782352 10614345728 1104777248 11.883 96 16 32 0 1174923072 66839568 3306256
07:45:47 43242 8192000000 10108406576 10550697984 1119189344 12.019 99 41 82 1 1182451984 55111104 8151536

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 10-10-2021 07:48:24 to 10-10-2021 07:48:39.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 103 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-10-2021 07:48:40 to 10-10-2021 07:48:42.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          233810
MON$OLDEST_ACTIVE               233811
MON$OLDEST_SNAPSHOT             233811
MON$NEXT_TRANSACTION            233811
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                       2084192
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                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             787
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2625 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2625 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2625 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2625 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17: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'

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