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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID df9f488d08424df294c459d6c283e9c3
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-03-04 16:07
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.26
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 16860820 kB
MemAvailable 31529112 kB
Buffers 0 kB
Cached 14829232 kB
SwapCached 0 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 2021-03-27 13:26:56
DTS_END                         2021-03-27 13:26:56.3890
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/45356
TRN_ID                          226075
ATT_ID                          640
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: 8766.57 28781 1051988
customer order: creation 155.44 423 18653
customer order: refuse 14.07 337 1688
order to supplier: creation 42.88 1453 5146
order to supplier: removal 3.22 129822 386
invoice (draft): creation 42.58 3982 5109
invoice (draft): removal 49.88 941 5986
invoice accept: apply 38.48 20655 4617
invoice accept: cancel 45.54 950 5465
customer reserve: creation 3575.85 200 429102
customer reserve: removal 4523.92 73 542870
realization accept: apply 77.26 194 9271
realization accept: cancel 80.38 128 9645
payment from customer: creation 53.77 128 6452
payment from customer: removal 5.11 97 613
payment to supplier: creation 45.09 137 5411
payment to supplier: removal 3.18 72 382
service: total inventory turnovers 3.29 4376 395
service: total monetary turnovers 5.86 702 703
service: aggregate perf. data 0.75 21099 90
service: refresh index statistics 0.03 389855 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-03-2021 13:28:38 to 27-03-2021 13:28:40.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1929
TEST_TIME 2 2303
TEST_TIME 3 3008
TEST_TIME 4 3803
TEST_TIME 5 4518
TEST_TIME 6 4756
TEST_TIME 7 5415
TEST_TIME 8 5663
TEST_TIME 9 5785
TEST_TIME 10 5867
TEST_TIME 11 6410
TEST_TIME 12 6371
TEST_TIME 13 6373
TEST_TIME 14 6692
TEST_TIME 15 6729
TEST_TIME 16 6994
TEST_TIME 17 7181
TEST_TIME 18 7379
TEST_TIME 19 7447
TEST_TIME 20 7695
TEST_TIME 21 8210
TEST_TIME 22 8161
TEST_TIME 23 8736
TEST_TIME 24 8840
TEST_TIME 25 9327
TEST_TIME 26 9508
TEST_TIME 27 9692
TEST_TIME 28 10001
TEST_TIME 29 10739
TEST_TIME 30 11239
TEST_TIME 31 11171
TEST_TIME 32 10981
TEST_TIME 33 10840
TEST_TIME 34 10817
TEST_TIME 35 10661
TEST_TIME 36 10439
TEST_TIME 37 10262
TEST_TIME 38 10238
TEST_TIME 39 10065
TEST_TIME 40 9914
TEST_TIME 41 9800
TEST_TIME 42 9745
TEST_TIME 43 9701
TEST_TIME 44 9798
TEST_TIME 45 9626
TEST_TIME 46 9682
TEST_TIME 47 9561
TEST_TIME 48 9570
TEST_TIME 49 9582
TEST_TIME 50 9557
TEST_TIME 51 9455
TEST_TIME 52 9452
TEST_TIME 53 9458
TEST_TIME 54 9463
TEST_TIME 55 9470
TEST_TIME 56 9369
TEST_TIME 57 9594
TEST_TIME 58 9781
TEST_TIME 59 9721
TEST_TIME 60 9687
TEST_TIME 61 9719
TEST_TIME 62 9614
TEST_TIME 63 9468
TEST_TIME 64 9400
TEST_TIME 65 9381
TEST_TIME 66 9369
TEST_TIME 67 9317
TEST_TIME 68 9278
TEST_TIME 69 9212
TEST_TIME 70 9321
TEST_TIME 71 9313
TEST_TIME 72 9241
TEST_TIME 73 9273
TEST_TIME 74 9229
TEST_TIME 75 9312
TEST_TIME 76 9285
TEST_TIME 77 9280
TEST_TIME 78 9289
TEST_TIME 79 9308
TEST_TIME 80 9323
TEST_TIME 81 9291
TEST_TIME 82 9192
TEST_TIME 83 9131
TEST_TIME 84 9043
TEST_TIME 85 9023
TEST_TIME 86 9042
TEST_TIME 87 9000
TEST_TIME 88 8969
TEST_TIME 89 8979
TEST_TIME 90 8947
TEST_TIME 91 9003
TEST_TIME 92 9023
TEST_TIME 93 8995
TEST_TIME 94 9005
TEST_TIME 95 9001
TEST_TIME 96 9058
TEST_TIME 97 9064
TEST_TIME 98 9079
TEST_TIME 99 8996
TEST_TIME 100 8966
TEST_TIME 101 8920
TEST_TIME 102 8916
TEST_TIME 103 8908
TEST_TIME 104 8890
TEST_TIME 105 8855
TEST_TIME 106 8841
TEST_TIME 107 8885
TEST_TIME 108 8870
TEST_TIME 109 8827
TEST_TIME 110 8926
TEST_TIME 111 8929
TEST_TIME 112 8948
TEST_TIME 113 8961
TEST_TIME 114 8998
TEST_TIME 115 8991
TEST_TIME 116 8934
TEST_TIME 117 8880
TEST_TIME 118 8842
TEST_TIME 119 8811
TEST_TIME 120 8775

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 27-03-2021 13:28:42 to 27-03-2021 13:28:45.

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 1450388 1450388 0 0.00 0 17965 7 0 0
doc_list_biud 1450367 1450367 0 0.00 0 124319 34 0 0
sp_add_invoice_to_stock 4775 4617 158 3.30 41 176180 20656 0 158
sp_cancel_adding_invoice 5465 5465 0 0.00 4 83051 950 0 0
sp_cancel_client_order 1688 1688 0 0.00 5 10201 337 0 0
sp_cancel_customer_reserve 542870 542870 0 0.00 1 57504 73 0 0
sp_cancel_pay_from_customer 613 613 0 0.00 3 5764 98 0 0
sp_cancel_pay_to_supplier 382 382 0 0.00 3 1026 72 0 0
sp_cancel_supplier_invoice 5997 5986 11 0.18 2 124332 942 0 11
sp_cancel_supplier_order 386 386 0 0.00 8 1189019 129823 0 0
sp_cancel_write_off 9645 9645 0 0.00 1 15410 129 0 0
sp_client_order 18653 18653 0 0.00 4 25235 423 0 0
sp_customer_reserve 429102 429102 0 0.00 0 105017 200 0 0
sp_fill_shopping_cart 28904 28904 0 0.00 1 26859 51 0 0
sp_fill_shopping_cart_clo_res 429100 429100 0 0.00 0 34398 19 0 0
sp_get_clo_for_invoice 4606 4606 0 0.00 0 61203 447 0 0
sp_kill_cost_storno 16105 16105 0 0.00 0 7767 66 0 0
sp_kill_qstorno_ret_qs2qd 1101757 1101757 0 0.00 0 124316 39 0 0
sp_kill_qty_storno 579908 579908 0 0.00 0 124319 84 0 0
sp_lock_selected_doc 19256 19256 0 0.00 0 9692 7 0 0
sp_make_cost_storno 25740 25740 0 0.00 0 17959 69 0 0
sp_multiply_rows_for_pdistr 25722 25722 0 0.00 0 7339 37 0 0
sp_multiply_rows_for_qdistr 18653 18653 0 0.00 0 22771 207 0 0
sp_pay_from_customer 6452 6452 0 0.00 4 14340 128 0 0
sp_pay_to_supplier 5411 5411 0 0.00 5 11887 138 0 0
sp_payment_common 11851 11851 0 0.00 1 9038 20 0 0
sp_qd_handle_on_cancel_clo 1688 1688 0 0.00 0 10195 307 0 0
sp_qd_handle_on_invoice_upd_sts 10064 10064 0 0.00 0 25687 427 0 0
sp_qd_handle_on_reserve_upd_sts 9269 9269 0 0.00 0 11336 52 0 0
sp_reserve_write_off 9271 9271 0 0.00 7 17974 194 0 0
sp_supplier_invoice 5109 5109 0 0.00 6 110093 3983 0 0
sp_supplier_order 5146 5146 0 0.00 5 35273 1454 0 0
srv_aggregate_perf_data 90 90 0 0.00 2703 210576 21100 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1315 163081 47099 0 0
srv_find_qd_qs_mism 1008717 1008717 0 0.00 0 11918 6 0 0
srv_make_invnt_saldo 395 395 0 0.00 38 89600 4377 0 0
srv_make_money_saldo 703 703 0 0.00 1 14189 702 0 0
srv_recalc_idx_stat 4 4 0 0.00 179882 629528 389855 0 0
t$perf-abend:sp_add_invoice_to_stock 158 158 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4617 4617 0 0.00 0 14333 196 0 0
t$perf-norm:sp_cancel_adding_invoice 362 362 0 0.00 0 4423 118 0 0
t$perf-norm:sp_cancel_client_order 1688 1688 0 0.00 0 5563 14 0 0
t$perf-norm:sp_cancel_customer_reserve 617 617 0 0.00 0 849 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 613 613 0 0.00 0 452 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 382 382 0 0.00 0 634 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 385 385 0 0.00 0 656 18 0 0
t$perf-norm:sp_cancel_supplier_order 386 386 0 0.00 0 45066 1631 0 0
t$perf-norm:sp_cancel_write_off 955 955 0 0.00 0 3966 13 0 0
t$perf-norm:sp_client_order 18653 18653 0 0.00 0 16208 21 0 0
t$perf-norm:sp_customer_reserve 893 893 0 0.00 0 1530 7 0 0
t$perf-norm:sp_pay_from_customer 6452 6452 0 0.00 0 11614 9 0 0
t$perf-norm:sp_pay_to_supplier 5411 5411 0 0.00 0 11993 12 0 0
t$perf-norm:sp_reserve_write_off 9271 9271 0 0.00 0 4609 9 0 0
t$perf-norm:sp_supplier_invoice 5109 5109 0 0.00 0 4263 19 0 0
t$perf-norm:sp_supplier_order 5146 5146 0 0.00 0 8320 21 0 0
t$perf-norm:srv_aggregate_perf_data 90 90 0 0.00 0 801 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 141 7 0 0
t$perf-norm:srv_make_invnt_saldo 395 395 0 0.00 0 2582 14 0 0
t$perf-norm:srv_make_money_saldo 703 703 0 0.00 0 3410 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 3 0 0
v_add_invoice_to_stock 9514 9198 316 3.32 0 8045 23 0 316
v_all_customers 34066 34066 0 0.00 0 27 1 0 0
v_all_suppliers 28036 28036 0 0.00 0 69 1 0 0
v_all_wares 37306 37306 0 0.00 0 49 1 0 0
v_cancel_adding_invoice 724 724 0 0.00 0 1217 7 0 0
v_cancel_client_order 3376 3376 0 0.00 0 845 3 0 0
v_cancel_customer_prepayment 1226 1226 0 0.00 0 2424 8 0 0
v_cancel_customer_reserve 1234 1234 0 0.00 0 475 3 0 0
v_cancel_payment_to_supplier 764 764 0 0.00 0 536 5 0 0
v_cancel_supplier_invoice 792 770 22 2.77 0 816 16 0 22
v_cancel_supplier_order 772 772 0 0.00 0 4712 10 0 0
v_cancel_write_off 1910 1910 0 0.00 0 6475 17 0 0
v_max_id_clo_ord 5146 5146 0 0.00 0 1532 2 0 0
v_max_id_clo_res 893 893 0 0.00 0 1741 9 0 0
v_max_id_ord_sup 5104 5104 0 0.00 0 11281 13 0 0
v_max_non_paid_invoice 1637 1637 0 0.00 0 5161 6 0 0
v_max_non_paid_realizn 3391 3391 0 0.00 0 4119 5 0 0
v_min_id_clo_ord 5146 5146 0 0.00 0 1706 6 0 0
v_min_id_clo_res 893 893 0 0.00 0 4325 13 0 0
v_min_id_ord_sup 5104 5104 0 0.00 0 10055 7 0 0
v_min_non_paid_invoice 5405 5405 0 0.00 0 4058 9 0 0
v_min_non_paid_realizn 6446 6446 0 0.00 0 6138 12 0 0
v_our_firm 9350 9350 0 0.00 0 20 1 0 0
v_reserve_write_off 18538 18538 0 0.00 0 2600 5 0 0
x_lock_depdocs_on_canc_invoice 11066 11066 0 0.00 0 13068 134 0 0
x_lock_depdocs_on_canc_sup_ord 386 386 0 0.00 0 9579 403 0 0
x_make_qstorno_1000_1200 5146 5146 0 0.00 2 35258 1367 0 0
x_make_qstorno_1200_2000 5109 5109 0 0.00 3 110073 3787 0 0
x_make_qstorno_2100_3300 429102 429102 0 0.00 0 105012 179 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-03-2021 13:28:46 to 27-03-2021 13:28:50.

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
10:57:00 2 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
10:58:00 31123 8192000000 9734219024 10032009216 807865648 8.976 100 84 168 0 835552448 7544128 20142672
10:59:38 31149 8192000000 9784178256 10093465600 852832688 9.428 100 88 176 2 886360000 12967040 20560272
11:01:12 16872 8192000000 9819728896 10139807744 884829152 9.748 100 87 174 0 929452160 24905104 19717904
11:02:31 13353 8192000000 9875385488 10210516992 936311408 10.257 100 79 158 3 984432032 31403200 16717424
11:03:48 6323 8192000000 9945867040 10292084736 972764368 10.614 100 84 168 1 1057226816 66964816 17497632
11:04:57 4714 8192000000 9977909104 10330054656 1002489616 10.903 100 83 166 0 1088468576 69420192 16558768
11:06:03 1663 8192000000 10014051456 10376343552 1033426560 11.201 100 91 181 2 1128579712 77419536 17733616
11:07:06 2013 8192000000 10035939664 10405404672 1049228080 11.353 100 89 178 0 1143694800 76202880 18263840
11:08:10 1338 8192000000 10061272464 10433380352 1067771760 11.531 100 94 186 1 1172961040 86091056 19098224
11:09:13 21332 8192000000 9983730160 10383134720 1064923632 11.504 100 23 46 3 1105855968 35966224 4966112
11:10:40 5120 8192000000 9992557856 10384269312 1076589568 11.615 100 37 74 1 1104667264 20228736 7848960
11:11:47 4358 8192000000 9982693568 10382221312 1080304224 11.650 100 44 90 2 1101063696 11116224 9643248
11:12:56 27701 8192000000 10015524368 10433622016 1087772672 11.721 100 34 68 1 1123795952 28799552 7223728
11:14:51 7517 8192000000 10016215968 10419249152 1093643408 11.777 100 37 74 1 1126200544 24385104 8172032
11:16:04 6015 8192000000 10017908032 10416046080 1083187088 11.678 99 38 77 1 1136116912 44724336 8205488
11:17:11 4977 8192000000 9993534240 10393788416 1059948736 11.456 98 44 87 0 1103647264 34501968 9196560
11:18:19 31987 8192000000 9951749984 10359738368 994959344 10.830 92 19 38 1 1017089776 17913600 4216832
11:19:53 8520 8192000000 9965382048 10358583296 1011933808 10.994 97 46 91 3 1053344592 31009232 10401552
11:21:02 5104 8192000000 9920857504 10298470400 994736992 10.827 98 52 102 0 1028440608 22011776 11691840
11:22:15 53564 8192000000 9939325728 10339012608 1010563712 10.981 98 28 55 0 1039866096 22686320 6616064
11:24:25 13627 8192000000 9955609200 10352148480 1045904448 11.321 100 24 48 0 1080600768 29181312 5515008
11:25:39 3361 8192000000 9977648544 10363326464 1055285760 11.411 100 45 86 2 1075494480 10623760 9584960
11:26:43 4511 8192000000 9979105968 10371645440 1065633568 11.510 100 42 87 2 1089633072 14774480 9225024
11:27:54 20864 8192000000 10010765456 10408370176 1083491008 11.681 100 51 100 0 1115592736 21194176 10907552
11:29:21 22642 8192000000 10038655328 10443141120 1101743824 11.854 100 42 84 1 1151531520 41244688 8543008
11:30:47 1474 8192000000 10081668128 10478256128 1114457136 11.975 100 82 164 2 1185007248 53539792 17010320
11:32:02 9757 8192000000 10099526016 10506870784 1123922736 12.064 100 57 114 1 1207337968 71270048 12145184
11:33:14 6337 8192000000 10146472144 10552074240 1135888112 12.177 100 61 119 2 1252771776 103854272 13029392
11:34:25 5386 8192000000 10163328112 10574131200 1156359984 12.369 100 59 117 1 1267423808 98358576 12705248
11:35:36 5302 8192000000 10188913120 10593468416 1158687184 12.391 100 69 139 3 1286258704 112592736 14978784
11:36:50 9410 8192000000 10236533152 10653175808 1165745408 12.457 100 61 119 1 1345119424 166161040 13212976
11:38:07 5581 8192000000 10232961440 10640240640 1157586464 12.381 99 58 116 0 1332061040 161513456 12961120
11:39:15 3559 8192000000 10288734800 10691399680 1164704192 12.447 100 70 139 0 1386521072 206476288 15340592
11:40:21 1721 8192000000 10343710016 10742677504 1161164608 12.414 99 79 159 1 1452912960 274065632 17682720
11:41:25 1314 8192000000 10352797152 10755469312 1157500368 12.380 100 89 177 1 1455700112 278213776 19985968
11:42:32 3179 8192000000 10366917584 10789003264 1154326704 12.350 100 66 132 1 1464613920 295026032 15261184
11:43:41 3351 8192000000 10396055872 10796650496 1132495776 12.145 98 80 160 1 1475869792 325225136 18148880
11:44:59 3768 8192000000 10403551296 10804203520 1148235536 12.293 100 78 157 0 1501130288 334811552 18083200
11:46:05 2675 8192000000 10444665424 10859188224 1151148400 12.320 100 88 175 1 1536891664 365911392 19831872
11:47:15 1968 8192000000 10454128320 10872524800 1150072256 12.310 100 84 167 1 1554108240 384040864 19995120
11:48:20 8837 8192000000 10434933584 10848518144 1137872496 12.196 100 67 135 2 1530454704 375677232 16904976
11:49:35 4333 8192000000 10439092560 10873692160 1136660656 12.184 99 63 125 2 1538802448 386217088 15924704
11:50:44 8094 8192000000 10449643184 10870505472 1154034704 12.347 100 54 108 0 1546729328 378703552 13991072
11:51:53 6704 8192000000 10424585696 10849828864 1165179168 12.452 100 45 90 2 1508600672 332056640 11364864
11:53:09 19994 8192000000 10390681696 10891948032 1144954080 12.262 99 33 66 0 1485875136 332553232 8367824
11:54:35 19972 8192000000 10405444992 10919485440 1130112224 12.122 99 21 40 1 1488519728 352831312 5576192
11:56:04 64289 8192000000 10070640944 10499497984 1101945504 11.856 98 18 34 0 1198970416 93362752 3662160
11:58:09 50948 8192000000 10112342400 10550943744 1122754624 12.053 98 38 76 1 1189501984 59259456 7487904
12:00:19 58333 8192000000 10073253664 10532999168 1138889280 12.205 100 12 24 0 1202537824 61590016 2058528
12:02:19 147626 8192000000 10074003904 10553118720 1127916736 12.102 98 17 34 1 1199583248 68180096 3486416
12:06:18 27410 8192000000 10096413552 10514833408 1121865584 12.045 98 54 105 2 1173377488 39354784 12157120
12:07:46 50249 8192000000 10126557072 10597728256 1119061168 12.018 97 23 46 1 1223896032 99669504 5165360
12:09:52 50248 8192000000 10136844080 10626150400 1139660400 12.212 100 25 50 1 1273784272 128252448 5871424
12:11:50 54265 8192000000 10124059536 10604654592 1123938880 12.064 99 12 24 0 1249345456 122566176 2840400
12:13:49 59126 8192000000 10084957280 10575609856 1120877184 12.035 99 11 22 0 1159523952 36390592 2256176
12:15:48 107697 8192000000 10073428208 10510606336 1118392240 12.012 98 21 41 0 1149037584 26842368 3802976
12:18:58 96937 8192000000 10113343488 10595569664 1113929680 11.970 98 21 42 3 1207068336 88818288 4320368
12:22:35 69770 8192000000 10191679856 10683088896 1143686352 12.250 100 14 28 0 1298828384 151752624 3389408
12:24:45 84465 8192000000 10042606016 10481803264 1092104160 11.763 96 6 12 0 1135217584 42322656 790768
12:27:10 59964 8192000000 10106587712 10577793024 1100490176 11.842 96 25 50 0 1199651872 94202896 4958800
12:29:13 138266 8192000000 10087840544 10564657152 1134119072 12.160 100 21 42 0 1213677568 75115776 4442720
12:32:35 29051 8192000000 10065653696 10585214976 1120834720 12.035 99 5 10 0 1171587840 49800208 952912
12:34:40 86870 8192000000 10145296576 10605060096 1140588416 12.221 99 15 30 2 1220813808 76790336 3435056
12:37:08 42639 8192000000 10063057936 10529202176 1121063904 12.037 99 14 28 1 1153817264 29451248 3302112
12:38:51 29313 8192000000 10100733488 10568155136 1137791600 12.195 99 40 81 0 1187093184 40590304 8711280
12:40:21 71963 8192000000 10115361632 10575667200 1115370832 11.983 98 31 62 2 1177889744 55596848 6922064
12:42:36 97426 8192000000 10106108928 10577174528 1097399952 11.813 96 15 30 1 1165807280 65776288 2631040
12:45:14 68014 8192000000 10091936848 10528911360 1107166560 11.906 98 28 56 0 1182254256 69582592 5505104
12:47:23 112661 8192000000 10089283920 10532868096 1126376800 12.087 100 27 54 0 1169304544 37388608 5539136
12:50:21 27974 8192000000 10080851696 10542649344 1116359104 11.993 99 6 12 0 1173943216 56074064 1510048
12:51:51 60167 8192000000 10057261040 10564448256 1107952320 11.913 98 9 18 0 1160045888 50074400 2019168
12:54:29 30195 8192000000 10073380624 10511937536 1102175328 11.858 99 18 36 0 1153367904 47026720 4165856
12:56:00 39128 8192000000 10075772832 10504298496 1089223856 11.735 97 23 47 1 1138966080 45015920 4726304
12:57:45 56741 8192000000 10069143184 10573733888 1121734256 12.043 100 11 22 0 1200588080 76433072 2420752
12:59:42 72041 8192000000 10083945840 10537213952 1098973408 11.828 97 24 49 0 1152834656 49286064 4575184
13:01:55 66484 8192000000 10071968272 10535432192 1101320608 11.850 98 16 32 1 1173417296 68826720 3269968
13:04:03 50328 8192000000 10075085536 10541842432 1121013712 12.037 99 25 50 2 1174536320 48251168 5271440
13:05:56 99361 8192000000 10084279696 10557632512 1115761600 11.987 98 12 24 0 1158679264 40242736 2674928
13:08:35 74821 8192000000 10088481104 10561339392 1137390800 12.191 100 14 28 2 1189392848 49119088 2882960
13:11:05 59307 8192000000 10105065616 10548776960 1119877952 12.026 98 22 44 0 1176272784 51953184 4441648
13:13:12 115163 8192000000 10117048176 10565959680 1111315264 11.945 97 24 48 0 1180894688 64798880 4780544
13:16:08 90224 8192000000 10117775136 10588155904 1124477776 12.069 99 13 26 0 1231001504 103678192 2845536
13:19:05 24449 8192000000 10088806784 10546880512 1131663040 12.137 99 36 72 1 1173611264 34168544 7779680
13:20:36 163080 8192000000 10081851712 10579836928 1104818512 11.883 97 13 26 0 1161277280 54213840 2244928
13:24:21 63325 8192000000 10045315472 10508808192 1109816432 11.931 98 7 14 0 1137133184 25904128 1412624
13:26:25 40618 8192000000 9653653104 10080055296 749265184 8.379 66 2 4 0 779360496 29782368 312944

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 27-03-2021 13:28:52 to 27-03-2021 13:29:07.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 158 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 27-03-2021 13:29:08 to 27-03-2021 13:29:10.

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          226401
MON$OLDEST_ACTIVE               226402
MON$OLDEST_SNAPSHOT             226402
MON$NEXT_TRANSACTION            226402
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                       2101888
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             785
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

27.03.21 13:29:11. 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-03-27 13:29:13 No severe PSQL-related problems occured
27.03.21 13:29:14. End of report.