Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.02.2021 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 16481036 kB
MemAvailable 31042400 kB
Buffers 20 kB
Cached 15120788 kB
SwapCached 3272 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-02-27 04:26:57
DTS_END                         2021-02-27 04:26:57.1820
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_MAKE_MONEY_SALDO(33:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/48906
TRN_ID                          221233
ATT_ID                          629
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: 8726.70 25399 1047204
customer order: creation 148.38 476 17806
customer order: refuse 13.54 324 1625
order to supplier: creation 40.70 1395 4884
order to supplier: removal 3.36 121958 403
invoice (draft): creation 41.82 3749 5018
invoice (draft): removal 51.78 1009 6214
invoice accept: apply 37.66 18358 4519
invoice accept: cancel 46.38 950 5566
customer reserve: creation 3537.77 174 424532
customer reserve: removal 4534.93 70 544191
realization accept: apply 72.88 180 8745
realization accept: cancel 81.31 110 9757
payment from customer: creation 54.28 102 6514
payment from customer: removal 5.03 97 603
payment to supplier: creation 43.69 119 5243
payment to supplier: removal 3.17 105 380
service: total inventory turnovers 3.73 3534 447
service: total monetary turnovers 5.49 648 659
service: aggregate perf. data 0.78 16931 94
service: refresh index statistics 0.03 337695 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-02-2021 04:27:56 to 27-02-2021 04:27:58.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1761
TEST_TIME 2 2768
TEST_TIME 3 3524
TEST_TIME 4 4604
TEST_TIME 5 4681
TEST_TIME 6 5129
TEST_TIME 7 5282
TEST_TIME 8 5213
TEST_TIME 9 5350
TEST_TIME 10 5378
TEST_TIME 11 5599
TEST_TIME 12 5730
TEST_TIME 13 5666
TEST_TIME 14 6069
TEST_TIME 15 6482
TEST_TIME 16 6596
TEST_TIME 17 6595
TEST_TIME 18 6599
TEST_TIME 19 6770
TEST_TIME 20 7385
TEST_TIME 21 8143
TEST_TIME 22 8330
TEST_TIME 23 8766
TEST_TIME 24 9125
TEST_TIME 25 10415
TEST_TIME 26 10518
TEST_TIME 27 10245
TEST_TIME 28 10109
TEST_TIME 29 9898
TEST_TIME 30 9698
TEST_TIME 31 9577
TEST_TIME 32 9534
TEST_TIME 33 9440
TEST_TIME 34 9510
TEST_TIME 35 9351
TEST_TIME 36 9250
TEST_TIME 37 9088
TEST_TIME 38 9122
TEST_TIME 39 9196
TEST_TIME 40 9056
TEST_TIME 41 9030
TEST_TIME 42 8955
TEST_TIME 43 8924
TEST_TIME 44 8814
TEST_TIME 45 8855
TEST_TIME 46 9190
TEST_TIME 47 9390
TEST_TIME 48 9275
TEST_TIME 49 9275
TEST_TIME 50 9327
TEST_TIME 51 9424
TEST_TIME 52 9541
TEST_TIME 53 9448
TEST_TIME 54 9336
TEST_TIME 55 9183
TEST_TIME 56 9187
TEST_TIME 57 9082
TEST_TIME 58 8977
TEST_TIME 59 9023
TEST_TIME 60 9062
TEST_TIME 61 9075
TEST_TIME 62 9093
TEST_TIME 63 9098
TEST_TIME 64 9068
TEST_TIME 65 9055
TEST_TIME 66 9083
TEST_TIME 67 9193
TEST_TIME 68 9175
TEST_TIME 69 9175
TEST_TIME 70 9182
TEST_TIME 71 9200
TEST_TIME 72 9113
TEST_TIME 73 9017
TEST_TIME 74 9045
TEST_TIME 75 8956
TEST_TIME 76 8879
TEST_TIME 77 8806
TEST_TIME 78 8823
TEST_TIME 79 8809
TEST_TIME 80 8795
TEST_TIME 81 8801
TEST_TIME 82 8771
TEST_TIME 83 8868
TEST_TIME 84 8817
TEST_TIME 85 8819
TEST_TIME 86 8834
TEST_TIME 87 8932
TEST_TIME 88 8940
TEST_TIME 89 9045
TEST_TIME 90 9039
TEST_TIME 91 8957
TEST_TIME 92 8891
TEST_TIME 93 8857
TEST_TIME 94 8780
TEST_TIME 95 8735
TEST_TIME 96 8758
TEST_TIME 97 8735
TEST_TIME 98 8696
TEST_TIME 99 8679
TEST_TIME 100 8811
TEST_TIME 101 8866
TEST_TIME 102 8853
TEST_TIME 103 8877
TEST_TIME 104 8908
TEST_TIME 105 8900
TEST_TIME 106 8939
TEST_TIME 107 8942
TEST_TIME 108 8932
TEST_TIME 109 8916
TEST_TIME 110 8865
TEST_TIME 111 8846
TEST_TIME 112 8801
TEST_TIME 113 8756
TEST_TIME 114 8747
TEST_TIME 115 8758
TEST_TIME 116 8756
TEST_TIME 117 8762
TEST_TIME 118 8724
TEST_TIME 119 8753
TEST_TIME 120 8729

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 27-02-2021 04:27:59 to 27-02-2021 04:28:03.

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 1439077 1439077 0 0.00 0 14960 6 0 0
doc_list_biud 1439077 1439077 0 0.00 0 82391 34 0 0
sp_add_invoice_to_stock 4602 4519 83 1.80 49 170873 18359 0 83
sp_cancel_adding_invoice 5566 5566 0 0.00 5 89236 950 0 0
sp_cancel_client_order 1625 1625 0 0.00 4 8856 325 0 0
sp_cancel_customer_reserve 544191 544191 0 0.00 1 45394 71 0 0
sp_cancel_pay_from_customer 603 603 0 0.00 3 6642 98 0 0
sp_cancel_pay_to_supplier 380 380 0 0.00 3 4532 105 0 0
sp_cancel_supplier_invoice 6224 6214 10 0.16 1 82400 1009 0 10
sp_cancel_supplier_order 403 403 0 0.00 5 1120099 121959 0 0
sp_cancel_write_off 9757 9757 0 0.00 1 14883 111 0 0
sp_client_order 17806 17806 0 0.00 4 41301 477 0 0
sp_customer_reserve 424532 424532 0 0.00 0 97214 174 0 0
sp_fill_shopping_cart 27700 27700 0 0.00 1 27494 52 0 0
sp_fill_shopping_cart_clo_res 424521 424521 0 0.00 0 19643 19 0 0
sp_get_clo_for_invoice 4508 4508 0 0.00 0 52396 563 0 0
sp_kill_cost_storno 16306 16306 0 0.00 0 6856 57 0 0
sp_kill_qstorno_ret_qs2qd 1104756 1104756 0 0.00 0 82388 39 0 0
sp_kill_qty_storno 581007 581007 0 0.00 0 82390 83 0 0
sp_lock_selected_doc 18553 18553 0 0.00 0 11626 7 0 0
sp_make_cost_storno 25008 25008 0 0.00 0 14224 51 0 0
sp_multiply_rows_for_pdistr 25008 25008 0 0.00 0 7864 31 0 0
sp_multiply_rows_for_qdistr 17806 17806 0 0.00 0 40201 284 0 0
sp_pay_from_customer 6514 6514 0 0.00 4 7683 103 0 0
sp_pay_to_supplier 5243 5243 0 0.00 5 10508 120 0 0
sp_payment_common 11757 11757 0 0.00 2 7675 20 0 0
sp_qd_handle_on_cancel_clo 1625 1625 0 0.00 0 8847 292 0 0
sp_qd_handle_on_invoice_upd_sts 10072 10072 0 0.00 0 28368 393 0 0
sp_qd_handle_on_reserve_upd_sts 8745 8745 0 0.00 0 12546 55 0 0
sp_reserve_write_off 8745 8745 0 0.00 6 26847 181 0 0
sp_supplier_invoice 5018 5018 0 0.00 8 101216 3749 0 0
sp_supplier_order 4884 4884 0 0.00 6 44365 1396 0 0
srv_aggregate_perf_data 94 94 0 0.00 2606 316989 16931 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1510 168509 45320 0 0
srv_find_qd_qs_mism 1003674 1003674 0 0.00 0 14959 5 0 0
srv_make_invnt_saldo 447 447 0 0.00 45 94939 3534 0 0
srv_make_money_saldo 659 659 0 0.00 0 32299 648 0 0
srv_recalc_idx_stat 4 4 0 0.00 231912 573474 337696 0 0
t$perf-abend:sp_add_invoice_to_stock 83 83 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4519 4519 0 0.00 0 17366 197 0 0
t$perf-norm:sp_cancel_adding_invoice 385 385 0 0.00 1 6688 106 0 0
t$perf-norm:sp_cancel_client_order 1625 1625 0 0.00 0 3307 16 0 0
t$perf-norm:sp_cancel_customer_reserve 615 615 0 0.00 0 1324 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 603 603 0 0.00 0 2888 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 380 380 0 0.00 0 3221 21 0 0
t$perf-norm:sp_cancel_supplier_invoice 373 373 0 0.00 0 4214 43 0 0
t$perf-norm:sp_cancel_supplier_order 403 403 0 0.00 0 21828 1558 0 0
t$perf-norm:sp_cancel_write_off 919 919 0 0.00 0 8118 34 0 0
t$perf-norm:sp_client_order 17806 17806 0 0.00 0 14243 18 0 0
t$perf-norm:sp_customer_reserve 917 917 0 0.00 0 866 5 0 0
t$perf-norm:sp_pay_from_customer 6514 6514 0 0.00 0 11299 13 0 0
t$perf-norm:sp_pay_to_supplier 5243 5243 0 0.00 0 7188 14 0 0
t$perf-norm:sp_reserve_write_off 8745 8745 0 0.00 0 5370 11 0 0
t$perf-norm:sp_supplier_invoice 5018 5018 0 0.00 0 12548 25 0 0
t$perf-norm:sp_supplier_order 4884 4884 0 0.00 0 8119 23 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 499 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 117 4 0 0
t$perf-norm:srv_make_invnt_saldo 447 447 0 0.00 0 8101 34 0 0
t$perf-norm:srv_make_money_saldo 659 659 0 0.00 0 3870 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 17 6 0 0
v_add_invoice_to_stock 9176 9010 166 1.80 0 8611 18 0 166
v_all_customers 32834 32834 0 0.00 0 18 1 0 0
v_all_suppliers 27590 27590 0 0.00 0 17 1 0 0
v_all_wares 35612 35612 0 0.00 0 23 1 0 0
v_cancel_adding_invoice 770 770 0 0.00 0 2006 14 0 0
v_cancel_client_order 3250 3250 0 0.00 0 4114 6 0 0
v_cancel_customer_prepayment 1206 1206 0 0.00 0 2819 8 0 0
v_cancel_customer_reserve 1230 1230 0 0.00 0 1918 6 0 0
v_cancel_payment_to_supplier 760 760 0 0.00 0 1800 7 0 0
v_cancel_supplier_invoice 766 746 20 2.61 0 5458 22 0 20
v_cancel_supplier_order 806 806 0 0.00 0 256 3 0 0
v_cancel_write_off 1838 1838 0 0.00 0 4025 16 0 0
v_max_id_clo_ord 4884 4884 0 0.00 0 334 1 0 0
v_max_id_clo_res 917 917 0 0.00 0 7519 52 0 0
v_max_id_ord_sup 5010 5010 0 0.00 0 13095 10 0 0
v_max_non_paid_invoice 1342 1342 0 0.00 0 315 2 0 0
v_max_non_paid_realizn 3309 3309 0 0.00 0 925 3 0 0
v_min_id_clo_ord 4884 4884 0 0.00 0 1922 6 0 0
v_min_id_clo_res 917 917 0 0.00 0 688 3 0 0
v_min_id_ord_sup 5010 5010 0 0.00 0 4972 7 0 0
v_min_non_paid_invoice 5243 5243 0 0.00 0 4832 14 0 0
v_min_non_paid_realizn 6514 6514 0 0.00 0 6960 13 0 0
v_our_firm 9188 9188 0 0.00 0 15 1 0 0
v_reserve_write_off 17490 17490 0 0.00 0 5626 6 0 0
x_lock_depdocs_on_canc_invoice 11407 11407 0 0.00 0 38721 128 0 0
x_lock_depdocs_on_canc_sup_ord 403 403 0 0.00 0 10962 393 0 0
x_make_qstorno_1000_1200 4884 4884 0 0.00 2 44352 1319 0 0
x_make_qstorno_1200_2000 5018 5018 0 0.00 3 101202 3537 0 0
x_make_qstorno_2100_3300 424532 424532 0 0.00 0 97212 153 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-02-2021 04:28:04 to 27-02-2021 04:28:08.

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:57:01 6 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
01:58:01 26127 8192000000 9740289776 10040778752 818597424 9.084 100 85 170 0 847293680 8230592 20465664
01:59:35 10822 8192000000 9768950832 10077515776 845450224 9.354 100 85 169 0 878368208 12807776 20110208
02:00:53 18760 8192000000 9812301680 10132336640 881994768 9.720 100 84 168 1 921581712 20149616 19437328
02:02:24 14570 8192000000 9878288688 10211807232 927081248 10.166 100 84 168 1 989034080 43509200 18443632
02:03:41 9584 8192000000 9943489792 10289758208 968748160 10.574 100 80 160 1 1064803760 78467104 17588496
02:04:55 5466 8192000000 9961925488 10313289728 998901936 10.868 100 84 168 2 1065922960 50022736 16998288
02:06:05 2399 8192000000 10015863232 10375909376 1023563072 11.106 100 87 174 0 1132108368 90432272 18113024
02:07:10 2055 8192000000 10022626880 10391023616 1043649584 11.300 100 90 176 1 1135271264 74120976 17500704
02:08:14 1773 8192000000 10048913120 10420195328 1061200048 11.468 100 95 190 0 1160350000 78347760 20802192
02:09:16 6920 8192000000 9994449856 10390933504 1073248624 11.583 100 43 86 1 1119495984 36840912 9406448
02:10:25 6701 8192000000 9990266784 10388934656 1081788096 11.665 100 43 81 4 1106084656 15802400 8494160
02:11:36 21930 8192000000 10005108784 10397687808 1087749376 11.721 100 68 137 0 1115639152 12594416 15295360
02:12:58 7300 8192000000 10031243152 10430902272 1095486912 11.795 100 45 91 0 1130130224 25061232 9582080
02:14:06 8442 8192000000 10027594224 10431504384 1098269152 11.821 100 41 81 0 1129530368 22891872 8369344
02:15:20 5392 8192000000 10019666560 10419875840 1097692256 11.816 100 33 65 2 1131929120 26768016 7468848
02:16:30 37674 8192000000 10019868736 10419326976 1084942000 11.695 98 51 102 0 1110763344 14776224 11045120
02:18:09 9911 8192000000 10008180816 10417643520 1081337120 11.660 99 35 69 2 1099478528 10362992 7778416
02:19:19 7299 8192000000 9980887168 10378665984 1050552064 11.366 99 36 73 1 1078099968 18783520 8764384
02:20:30 6521 8192000000 9936105808 10319601664 990048128 10.782 96 37 75 1 1011382464 12860688 8473648
02:21:41 53736 8192000000 9919367264 10300686336 953521792 10.426 93 41 81 0 978691232 16076976 9092464
02:23:35 7498 8192000000 9963968272 10364313600 1040923264 11.274 100 39 78 2 1079255936 29924144 8408528
02:24:43 11146 8192000000 9970331760 10365493248 1055532624 11.414 100 38 76 3 1072856432 8086624 9237184
02:26:01 6376 8192000000 9994740000 10396753920 1072156208 11.573 100 44 88 1 1102125504 19892320 10076976
02:27:08 41473 8192000000 10001403008 10402402304 1077975248 11.628 100 45 86 1 1114241872 27287184 8979440
02:29:01 44388 8192000000 10054610832 10459553792 1097478000 11.814 100 44 88 1 1164465232 57669392 9317840
02:30:51 5404 8192000000 10062095968 10469453824 1118042848 12.008 100 54 105 2 1176365152 47769792 10552512
02:32:01 1510 8192000000 10119507728 10514878464 1129188656 12.114 100 78 151 2 1231766000 85813280 16764064
02:33:04 6632 8192000000 10169769024 10566008832 1128983648 12.112 99 63 126 2 1268127776 125472864 13671264
02:34:38 12696 8192000000 10183523312 10605490176 1149641744 12.306 100 52 104 1 1284107872 122502704 11963424
02:35:54 13671 8192000000 10219459328 10639306752 1159833120 12.402 100 57 113 1 1318935136 145927248 13174768
02:37:18 9440 8192000000 10267810960 10692222976 1172730608 12.522 100 65 130 0 1378410720 190786016 14894096
02:38:36 7335 8192000000 10295509648 10705518592 1171676048 12.512 99 65 127 1 1387279568 201544944 14058576
02:39:47 3169 8192000000 10358455824 10765012992 1185243904 12.639 99 83 161 1 1454538240 250634672 18659664
02:40:54 2716 8192000000 10396342512 10836590592 1199315088 12.770 100 88 175 1 1497248272 278056992 19876192
02:42:02 2707 8192000000 10423491712 10827030528 1182177872 12.611 99 91 180 2 1509343552 306925408 20240272
02:43:12 3009 8192000000 10461760064 10875559936 1185323584 12.640 100 75 150 0 1561115344 357973456 17818304
02:44:25 3757 8192000000 10494190944 10909089792 1163939456 12.440 100 70 139 2 1591663712 411302528 16421728
02:45:33 2457 8192000000 10512247104 10925707264 1156858720 12.374 100 92 183 0 1608017312 429035088 22123504
02:46:40 3661 8192000000 10468365680 10892406784 1170016160 12.497 100 62 125 1 1569065584 382828240 16221184
02:47:51 15414 8192000000 10381629888 10851274752 1151147856 12.320 100 42 84 0 1498512304 336839008 10525440
02:49:08 16676 8192000000 10360974848 10832461824 1144336368 12.256 99 29 58 0 1499547664 347567040 7644256
02:50:27 22031 8192000000 10316658064 10793865216 1128358272 12.106 100 21 42 0 1433596640 299100800 6137568
02:52:07 53493 8192000000 10063835360 10523922432 1056703664 11.425 93 12 24 3 1110255440 50973616 2578160
02:54:03 16104 8192000000 10062045104 10505924608 1114203920 11.972 100 27 54 1 1155213424 35103424 5906080
02:55:22 15189 8192000000 10091944736 10522673152 1114512688 11.975 99 44 88 1 1171144624 47228016 9403920
02:56:42 7636 8192000000 10111281520 10534178816 1113952304 11.970 99 53 105 2 1203407120 78725024 10729792
02:59:12 168483 8192000000 10075022448 10515664896 1085117408 11.696 97 16 32 0 1157895440 69348496 3429536
03:03:58 53119 8192000000 10109489968 10608300032 1112460800 11.956 99 8 15 0 1203759984 89554448 1744736
03:05:53 61280 8192000000 10064312672 10505101312 1098610944 11.824 98 11 22 1 1124539984 24122800 1806240
03:07:58 41634 8192000000 10096255408 10555117568 1132086704 12.141 100 12 24 0 1213834768 79294976 2453088
03:09:46 65624 8192000000 10190677152 10621710336 1119201136 12.019 98 27 53 2 1230141632 104490560 6449936
03:12:02 79540 8192000000 10112729696 10678444032 1117171120 12.000 98 16 32 2 1305767520 184500912 4095488
03:14:23 35162 8192000000 10091774624 10525655040 1125116512 12.075 99 29 57 0 1175732336 44494960 6120864
03:15:59 40033 8192000000 10098331520 10552315904 1121127360 12.038 99 8 16 0 1228140064 105056480 1956224
03:17:41 82606 8192000000 10064271296 10555445248 1114932688 11.979 98 11 21 0 1174955712 57872928 2150096
03:20:13 128535 8192000000 10112636832 10594177024 1130396336 12.125 99 32 64 2 1218920128 81165440 7358352
03:23:23 91472 8192000000 10156718784 10607759360 1109675040 11.929 97 14 28 0 1229183536 116271328 3237168
03:26:53 45820 8192000000 10094984128 10591387648 1119172832 12.019 99 10 20 1 1204942368 83527664 2241872
03:28:40 35015 8192000000 10076875616 10523283456 1134767760 12.166 100 19 38 1 1176930736 37795920 4367056
03:30:30 42207 8192000000 10100059536 10536681472 1127003664 12.093 99 21 42 0 1204851840 73020592 4827584
03:32:20 51301 8192000000 10102272704 10562371584 1108081056 11.914 97 11 22 0 1192830112 82245824 2503232
03:34:23 37126 8192000000 10082644688 10528694272 1108862976 11.922 98 20 40 1 1147830048 35034176 3932896
03:36:05 12236 8192000000 10093119280 10512109568 1134826512 12.167 100 61 120 2 1179317456 32600192 11890752
03:37:21 60267 8192000000 10087937504 10557923328 1106996992 11.904 98 29 58 2 1168880656 56534880 5348784
03:39:22 74891 8192000000 10065655456 10544005120 1126004560 12.084 99 17 33 3 1191156928 61689328 3463040
03:43:32 60695 8192000000 10097967440 10526691328 1084919664 11.694 97 25 49 1 1156830528 66953680 4957184
03:45:33 81062 8192000000 10071832880 10526642176 1122006592 12.046 100 21 42 2 1172218512 45660304 4551616
03:47:54 73908 8192000000 10106984768 10571857920 1116960544 11.998 98 33 67 0 1200061008 76175504 6924960
03:50:12 34747 8192000000 10058307872 10484523008 1104109136 11.877 98 28 56 0 1128755424 18347200 6299088
03:51:49 78488 8192000000 10130419440 10574647296 1117351728 12.002 99 9 18 0 1203476912 84287104 1838080
03:54:09 53925 8192000000 10107703440 10548477952 1097585024 11.815 98 15 29 0 1149065136 48691120 2788992
03:56:09 85155 8192000000 10060105424 10538586112 1077890640 11.627 96 17 34 0 1121245856 40067824 3287392
03:59:28 28045 8192000000 10058844240 10520018944 1106531424 11.900 99 7 14 2 1168411776 60385488 1494864
04:01:33 52640 8192000000 10058441472 10562928640 1087423968 11.718 97 6 12 0 1162949024 73544256 1980800
04:03:27 53787 8192000000 10131562816 10578161664 1123865984 12.063 99 28 56 1 1196407104 66227264 6313856
04:05:22 83573 8192000000 10147982592 10711805952 1143534480 12.249 100 27 54 2 1307349312 157688416 6126416
04:07:47 87520 8192000000 10105522160 10562498560 1133834992 12.157 99 14 28 0 1173793712 37201984 2756736
04:10:15 60057 8192000000 10103465632 10562134016 1111444592 11.946 97 13 26 0 1142523200 28763584 2315024
04:12:16 75821 8192000000 10095622480 10525257728 1116105600 11.990 98 27 54 0 1157508400 36136048 5266752
04:14:37 116570 8192000000 10079320208 10531475456 1128344448 12.106 99 30 60 2 1225886464 90889760 6652256
04:20:19 75068 8192000000 10075615072 10542637056 1131009680 12.131 99 7 14 0 1174546784 42029264 1507840
04:22:34 79926 8192000000 10090453616 10558709760 1135439584 12.173 99 18 36 1 1214613616 75151616 4022416
04:24:55 55319 8192000000 10134735840 10597003264 1137521408 12.192 99 33 68 2 1217481408 73222480 6737520
04:26:57 11123 8192000000 9078376464 9358643200 211958832 2.522 19 2 4 0 240209632 27934240 316560

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 27-02-2021 04:28:10 to 27-02-2021 04:28:24.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 27-02-2021 04:28:25 to 27-02-2021 04:28:27.

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          221536
MON$OLDEST_ACTIVE               221537
MON$OLDEST_SNAPSHOT             221537
MON$NEXT_TRANSACTION            221537
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                       2086240
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             772
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2375 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2375 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2375 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.2375 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.02.21 04:28:28. 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-02-27 04:28:31 No severe PSQL-related problems occured
27.02.21 04:28:32. End of report.