Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.08.2021 07:37
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 16169244 kB
MemAvailable 31041976 kB
Buffers 0 kB
Cached 15484628 kB
SwapCached 2572 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-08-04 07:37:49
DTS_END                         2021-08-04 07:37:04.6460
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/53402
TRN_ID                          238412
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: 8756.38 24327 1050766
customer order: creation 180.39 425 21647
customer order: refuse 15.23 367 1828
order to supplier: creation 48.00 1429 5760
order to supplier: removal 3.26 130006 391
invoice (draft): creation 48.98 4054 5878
invoice (draft): removal 47.92 1120 5750
invoice accept: apply 43.38 20378 5205
invoice accept: cancel 42.93 1075 5151
customer reserve: creation 3839.01 208 460681
customer reserve: removal 4189.54 79 502745
realization accept: apply 87.68 195 10521
realization accept: cancel 75.47 137 9057
payment from customer: creation 62.19 126 7463
payment from customer: removal 5.80 114 696
payment to supplier: creation 51.16 143 6139
payment to supplier: removal 3.50 81 420
service: total inventory turnovers 3.96 3861 475
service: total monetary turnovers 6.91 642 829
service: aggregate perf. data 1.05 13042 126
service: refresh index statistics 0.03 309058 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 04-08-2021 07:38:20 to 04-08-2021 07:38:22.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4662
TEST_TIME 2 5360
TEST_TIME 3 6118
TEST_TIME 4 5908
TEST_TIME 5 5394
TEST_TIME 6 5044
TEST_TIME 7 5197
TEST_TIME 8 5312
TEST_TIME 9 5484
TEST_TIME 10 5792
TEST_TIME 11 6551
TEST_TIME 12 6893
TEST_TIME 13 7111
TEST_TIME 14 7130
TEST_TIME 15 7221
TEST_TIME 16 7336
TEST_TIME 17 7239
TEST_TIME 18 7359
TEST_TIME 19 7587
TEST_TIME 20 7738
TEST_TIME 21 7832
TEST_TIME 22 7825
TEST_TIME 23 8017
TEST_TIME 24 8247
TEST_TIME 25 8246
TEST_TIME 26 8609
TEST_TIME 27 8531
TEST_TIME 28 8646
TEST_TIME 29 8700
TEST_TIME 30 8691
TEST_TIME 31 8991
TEST_TIME 32 10350
TEST_TIME 33 10230
TEST_TIME 34 10222
TEST_TIME 35 10267
TEST_TIME 36 10141
TEST_TIME 37 10124
TEST_TIME 38 10027
TEST_TIME 39 10148
TEST_TIME 40 10039
TEST_TIME 41 9842
TEST_TIME 42 9678
TEST_TIME 43 9555
TEST_TIME 44 9530
TEST_TIME 45 9554
TEST_TIME 46 9469
TEST_TIME 47 9486
TEST_TIME 48 9511
TEST_TIME 49 9426
TEST_TIME 50 9423
TEST_TIME 51 9349
TEST_TIME 52 9363
TEST_TIME 53 9266
TEST_TIME 54 9207
TEST_TIME 55 9205
TEST_TIME 56 9095
TEST_TIME 57 9111
TEST_TIME 58 9125
TEST_TIME 59 9069
TEST_TIME 60 9142
TEST_TIME 61 9102
TEST_TIME 62 9100
TEST_TIME 63 9158
TEST_TIME 64 9238
TEST_TIME 65 9271
TEST_TIME 66 9309
TEST_TIME 67 9331
TEST_TIME 68 9309
TEST_TIME 69 9416
TEST_TIME 70 9364
TEST_TIME 71 9347
TEST_TIME 72 9237
TEST_TIME 73 9162
TEST_TIME 74 9118
TEST_TIME 75 9100
TEST_TIME 76 9048
TEST_TIME 77 8986
TEST_TIME 78 8939
TEST_TIME 79 8996
TEST_TIME 80 8954
TEST_TIME 81 8930
TEST_TIME 82 8931
TEST_TIME 83 8971
TEST_TIME 84 8969
TEST_TIME 85 9094
TEST_TIME 86 9075
TEST_TIME 87 9056
TEST_TIME 88 9155
TEST_TIME 89 9122
TEST_TIME 90 9145
TEST_TIME 91 9086
TEST_TIME 92 9042
TEST_TIME 93 8974
TEST_TIME 94 8965
TEST_TIME 95 8948
TEST_TIME 96 8912
TEST_TIME 97 8957
TEST_TIME 98 8952
TEST_TIME 99 8956
TEST_TIME 100 8971
TEST_TIME 101 8943
TEST_TIME 102 8941
TEST_TIME 103 8957
TEST_TIME 104 9061
TEST_TIME 105 9074
TEST_TIME 106 9086
TEST_TIME 107 9076
TEST_TIME 108 9051
TEST_TIME 109 9000
TEST_TIME 110 8958
TEST_TIME 111 8929
TEST_TIME 112 8876
TEST_TIME 113 8864
TEST_TIME 114 8869
TEST_TIME 115 8853
TEST_TIME 116 8858
TEST_TIME 117 8849
TEST_TIME 118 8845
TEST_TIME 119 8813
TEST_TIME 120 8758

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 04-08-2021 07:38:24 to 04-08-2021 07:38:27.

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 1478032 1478032 0 0.00 0 25656 8 0 0
doc_list_biud 1478031 1478031 0 0.00 0 66200 34 0 0
sp_add_invoice_to_stock 5309 5205 104 1.95 45 218865 20378 0 104
sp_cancel_adding_invoice 5151 5151 0 0.00 4 59972 1076 0 0
sp_cancel_client_order 1828 1828 0 0.00 4 14110 368 0 0
sp_cancel_customer_reserve 502745 502745 0 0.00 1 66203 79 0 0
sp_cancel_pay_from_customer 696 696 0 0.00 3 8068 115 0 0
sp_cancel_pay_to_supplier 420 420 0 0.00 3 4779 81 0 0
sp_cancel_supplier_invoice 5756 5750 6 0.10 1 27644 1121 0 6
sp_cancel_supplier_order 391 391 0 0.00 12 1205707 130006 0 0
sp_cancel_write_off 9057 9057 0 0.00 1 11610 138 0 0
sp_client_order 21647 21647 0 0.00 4 27962 426 0 0
sp_customer_reserve 460681 460681 0 0.00 0 157739 209 0 0
sp_fill_shopping_cart 33275 33275 0 0.00 1 17987 43 0 0
sp_fill_shopping_cart_clo_res 460675 460675 0 0.00 0 16571 15 0 0
sp_get_clo_for_invoice 5199 5199 0 0.00 0 32342 333 0 0
sp_kill_cost_storno 15324 15324 0 0.00 0 11357 71 0 0
sp_kill_qstorno_ret_qs2qd 1020688 1020688 0 0.00 0 66190 43 0 0
sp_kill_qty_storno 540641 540641 0 0.00 0 66199 93 0 0
sp_lock_selected_doc 21708 21708 0 0.00 0 7911 5 0 0
sp_make_cost_storno 29322 29322 0 0.00 0 16147 60 0 0
sp_multiply_rows_for_pdistr 29322 29322 0 0.00 0 10929 38 0 0
sp_multiply_rows_for_qdistr 21647 21647 0 0.00 0 26350 227 0 0
sp_pay_from_customer 7463 7463 0 0.00 4 15680 126 0 0
sp_pay_to_supplier 6139 6139 0 0.00 5 13929 143 0 0
sp_payment_common 13602 13602 0 0.00 1 11845 24 0 0
sp_qd_handle_on_cancel_clo 1828 1828 0 0.00 0 12874 338 0 0
sp_qd_handle_on_invoice_upd_sts 10349 10349 0 0.00 0 34415 437 0 0
sp_qd_handle_on_reserve_upd_sts 10521 10521 0 0.00 0 14514 50 0 0
sp_reserve_write_off 10521 10521 0 0.00 6 23096 195 0 0
sp_supplier_invoice 5878 5878 0 0.00 7 103526 4054 0 0
sp_supplier_order 5760 5760 0 0.00 6 33947 1429 0 0
srv_aggregate_perf_data 126 126 0 0.00 2471 119476 13043 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1501 148479 42752 0 0
srv_find_qd_qs_mism 1001984 1001984 0 0.00 0 25655 7 0 0
srv_make_invnt_saldo 475 475 0 0.00 41 106534 3862 0 0
srv_make_money_saldo 829 829 0 0.00 1 13133 642 0 0
srv_recalc_idx_stat 4 4 0 0.00 163848 473361 309059 0 0
t$perf-abend:sp_add_invoice_to_stock 104 104 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5205 5205 0 0.00 0 27124 212 0 0
t$perf-norm:sp_cancel_adding_invoice 455 455 0 0.00 1 3525 103 0 0
t$perf-norm:sp_cancel_client_order 1828 1828 0 0.00 0 17859 22 0 0
t$perf-norm:sp_cancel_customer_reserve 746 746 0 0.00 0 11968 42 0 0
t$perf-norm:sp_cancel_pay_from_customer 696 696 0 0.00 0 1678 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 420 420 0 0.00 0 212 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 429 429 0 0.00 0 1291 25 0 0
t$perf-norm:sp_cancel_supplier_order 391 391 0 0.00 0 20201 1641 0 0
t$perf-norm:sp_cancel_write_off 1028 1028 0 0.00 0 2628 9 0 0
t$perf-norm:sp_client_order 21647 21647 0 0.00 0 21162 26 0 0
t$perf-norm:sp_customer_reserve 1038 1038 0 0.00 0 521 5 0 0
t$perf-norm:sp_pay_from_customer 7463 7463 0 0.00 0 6258 11 0 0
t$perf-norm:sp_pay_to_supplier 6139 6139 0 0.00 0 20774 13 0 0
t$perf-norm:sp_reserve_write_off 10521 10521 0 0.00 0 19266 11 0 0
t$perf-norm:sp_supplier_invoice 5878 5878 0 0.00 0 10810 23 0 0
t$perf-norm:sp_supplier_order 5760 5760 0 0.00 0 21154 25 0 0
t$perf-norm:srv_aggregate_perf_data 126 126 0 0.00 0 963 22 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 12112 187 0 0
t$perf-norm:srv_make_invnt_saldo 475 475 0 0.00 0 984 17 0 0
t$perf-norm:srv_make_money_saldo 829 829 0 0.00 0 1220 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 10596 10388 208 1.96 0 7721 19 0 208
v_all_customers 39438 39438 0 0.00 0 37 1 0 0
v_all_suppliers 31802 31802 0 0.00 0 64 1 0 0
v_all_wares 43294 43294 0 0.00 0 38 1 0 0
v_cancel_adding_invoice 910 910 0 0.00 0 1553 10 0 0
v_cancel_client_order 3656 3656 0 0.00 0 3139 7 0 0
v_cancel_customer_prepayment 1392 1392 0 0.00 0 8042 16 0 0
v_cancel_customer_reserve 1492 1492 0 0.00 0 1163 5 0 0
v_cancel_payment_to_supplier 840 840 0 0.00 0 1739 6 0 0
v_cancel_supplier_invoice 870 858 12 1.37 0 2118 19 0 12
v_cancel_supplier_order 782 782 0 0.00 0 625 4 0 0
v_cancel_write_off 2056 2056 0 0.00 0 2664 17 0 0
v_max_id_clo_ord 5759 5759 0 0.00 0 3660 2 0 0
v_max_id_clo_res 1038 1038 0 0.00 0 6738 24 0 0
v_max_id_ord_sup 5869 5869 0 0.00 0 7039 5 0 0
v_max_non_paid_invoice 1866 1866 0 0.00 0 1499 3 0 0
v_max_non_paid_realizn 3977 3977 0 0.00 0 995 3 0 0
v_min_id_clo_ord 5759 5759 0 0.00 0 3072 8 0 0
v_min_id_clo_res 1038 1038 0 0.00 0 2399 8 0 0
v_min_id_ord_sup 5869 5869 0 0.00 0 4870 9 0 0
v_min_non_paid_invoice 6139 6139 0 0.00 0 11841 19 0 0
v_min_non_paid_realizn 7463 7463 0 0.00 0 10865 17 0 0
v_our_firm 10828 10828 0 0.00 0 36 1 0 0
v_reserve_write_off 21042 21042 0 0.00 0 8534 8 0 0
x_lock_depdocs_on_canc_invoice 10472 10472 0 0.00 0 20223 123 0 0
x_lock_depdocs_on_canc_sup_ord 391 391 0 0.00 0 3880 349 0 0
x_make_qstorno_1000_1200 5760 5760 0 0.00 1 33931 1328 0 0
x_make_qstorno_1200_2000 5878 5878 0 0.00 3 102243 3908 0 0
x_make_qstorno_2100_3300 460681 460681 0 0.00 0 157732 190 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-08-2021 07:38:28 to 04-08-2021 07:38:32.

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:07:08 2 8192000000 8796668944 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:09 25940 8192000000 9731106256 10033385472 814032960 9.038 100 90 180 0 843230032 7876016 21321056
05:09:41 14121 8192000000 9777768176 10090524672 860312640 9.503 100 89 178 2 892909072 11994656 20601776
05:11:00 21571 8192000000 9818907040 10137776128 885833168 9.758 100 84 167 1 921213056 16477920 18901968
05:12:28 20476 8192000000 9870329744 10200989696 914714592 10.044 100 84 168 1 974970256 41792608 18463056
05:13:56 13613 8192000000 9905010144 10250907648 949943216 10.391 100 71 142 1 1020311168 54986864 15381088
05:15:11 7115 8192000000 9941323296 10298609664 984366608 10.727 100 80 160 2 1055809968 55006064 16437296
05:16:20 6036 8192000000 9983992336 10347143168 1009322688 10.969 100 72 144 1 1094568336 70496240 14749408
05:17:28 3363 8192000000 9997431408 10363146240 1043674432 11.300 100 83 166 3 1110152432 49432960 17045040
05:18:33 1965 8192000000 10032260656 10399535104 1058955376 11.446 100 96 192 0 1142214672 62619568 20639728
05:19:37 1966 8192000000 10035986416 10408464384 1068188160 11.535 100 89 178 0 1147789968 60986784 18615024
05:20:40 6699 8192000000 10038734128 10427740160 1070022848 11.552 100 53 106 0 1165341328 84804304 10514176
05:21:49 5812 8192000000 9995740208 10396971008 1081291776 11.660 100 45 92 0 1133112432 42815808 9004848
05:22:56 4857 8192000000 10006814416 10400878592 1085572832 11.701 100 33 63 0 1129554160 37343424 6637904
05:24:05 35720 8192000000 10028125360 10412634112 1097544128 11.814 100 84 169 1 1130746800 13951328 19251344
05:25:42 5059 8192000000 10030788304 10427412480 1093486256 11.776 100 42 83 2 1129135328 26136960 9512112
05:26:47 5695 8192000000 10016287568 10414948352 1094996640 11.790 100 33 64 3 1126669056 24656480 7015936
05:27:55 9602 8192000000 9989282496 10382888960 1059618432 11.453 97 24 48 1 1081926176 16831488 5476256
05:29:38 7990 8192000000 9990121600 10391375872 1062807200 11.483 99 40 80 1 1090293392 18946624 8539568
05:30:46 4152 8192000000 9947892016 10330152960 1017128800 11.044 98 53 105 1 1044113728 14957424 12027504
05:31:54 15333 8192000000 9928716432 10313822208 972310416 10.609 95 34 68 0 1007877328 28355536 7211376
05:33:11 9534 8192000000 9948192704 10344517632 1013149440 11.006 98 38 76 0 1043558640 21618592 8790608
05:34:21 8226 8192000000 9971504528 10375720960 1052455136 11.384 100 33 68 0 1088132672 28236384 7441152
05:35:30 29886 8192000000 9980206640 10363817984 1054441408 11.403 100 65 133 1 1081305216 12045632 14818176
05:37:00 45918 8192000000 9983791168 10383433728 1076784000 11.617 100 41 81 2 1098701904 13136400 8781504
05:38:52 18751 8192000000 10049930128 10448482304 1102879328 11.865 100 64 126 1 1165776832 50327712 12569792
05:40:15 9007 8192000000 10097863392 10508382208 1122587168 12.051 100 66 132 0 1205916112 70208592 13120352
05:41:29 21735 8192000000 10082560480 10508980224 1131167152 12.132 100 31 62 0 1190199840 52254432 6778256
05:42:54 15831 8192000000 10117988192 10532548608 1143730624 12.251 100 39 78 0 1226481856 74167312 8583920
05:44:19 11432 8192000000 10159189504 10581684224 1155951312 12.365 100 50 100 1 1283244592 116570352 10722928
05:45:36 4238 8192000000 10199088432 10608066560 1163174496 12.433 100 57 112 0 1306532208 130612592 12745120
05:46:47 11823 8192000000 10223032432 10638483456 1170543760 12.502 100 58 113 3 1324269712 140781824 12944128
05:48:07 3113 8192000000 10201682896 10617225216 1179180928 12.583 100 69 134 1 1307914496 113998368 14735200
05:49:14 3259 8192000000 10226438800 10644267008 1185434992 12.641 100 62 122 4 1336114656 137253616 13426048
05:50:21 2849 8192000000 10212460768 10626691072 1162070368 12.423 99 73 145 1 1305192928 127179888 15942672
05:51:29 1496 8192000000 10269707280 10677366784 1179435760 12.585 100 90 178 3 1367176384 168449584 19291040
05:52:35 2480 8192000000 10302500752 10717421568 1190179968 12.685 100 79 157 0 1410086016 202689264 17216784
05:53:47 3441 8192000000 10317882592 10737000448 1188382448 12.668 100 70 139 0 1419392064 215675424 15334192
05:54:55 2476 8192000000 10380241776 10796339200 1195939776 12.739 100 81 162 2 1480648128 265796272 18912080
05:56:05 4724 8192000000 10344437152 10756169728 1160935216 12.412 99 81 158 1 1440153888 259943792 19274880
05:57:15 2057 8192000000 10366910576 10780774400 1162085072 12.423 100 92 184 1 1455070032 271764976 21219984
05:58:21 1983 8192000000 10420648912 10821165056 1164338128 12.444 100 94 187 1 1514409632 327877152 22194352
05:59:33 3646 8192000000 10407323296 10814570496 1170617984 12.503 100 81 162 0 1500389312 310713568 19057760
06:00:46 3524 8192000000 10436342000 10846060544 1170898080 12.505 99 72 144 0 1527017376 338477184 17642112
06:01:58 7427 8192000000 10466886848 10897014784 1170245696 12.499 99 50 97 1 1560185760 377790816 12149248
06:03:11 14417 8192000000 10454477056 10904711168 1179659152 12.587 100 39 79 0 1580100272 389856640 10584480
06:04:32 4807 8192000000 10449810512 10874667008 1169392432 12.491 99 59 118 0 1537935328 353535936 15006960
06:05:40 12094 8192000000 10469567776 10961895424 1168238304 12.480 99 35 70 1 1560738208 383779072 8720832
06:07:21 11746 8192000000 10457771168 10924683264 1136793968 12.185 100 27 53 1 1557215984 412816848 7605168
06:09:25 15530 8192000000 10222041504 10801299456 1114316016 11.973 98 23 46 0 1404275248 283711584 6247648
06:10:46 48284 8192000000 10070503632 10529202176 1099921392 11.837 97 24 45 2 1142049632 37668624 4459616
06:12:39 47613 8192000000 10063645152 10515525632 1112041888 11.952 98 11 22 1 1155342848 41126288 2174672
06:14:27 52784 8192000000 10092852384 10524041216 1130129248 12.123 99 38 75 1 1162211984 23990480 8092256
06:16:27 140192 8192000000 10107048928 10541699072 1090728384 11.750 95 11 22 1 1145378464 51436592 3213488
06:20:34 148478 8192000000 10111796640 10542268416 1142310672 12.237 99 51 100 1 1209643536 55860592 11472272
06:24:04 24424 8192000000 10119784848 10597167104 1159223296 12.396 100 33 66 2 1212349824 45374176 7752352
06:25:30 46459 8192000000 10130445840 10603008000 1162473248 12.426 100 15 29 0 1230120160 64421776 3225136
06:27:17 57542 8192000000 10108419328 10564964352 1156456688 12.370 100 12 24 0 1226571376 67331984 2782704
06:29:16 62214 8192000000 10094876240 10590437376 1157106864 12.376 100 14 28 0 1215642640 55419056 3116720
06:31:30 27911 8192000000 10083739824 10560864256 1148921744 12.299 99 20 40 1 1216055728 62750704 4383280
06:33:04 81444 8192000000 10114270704 10608414720 1157372016 12.379 100 23 46 0 1235510512 73171520 4966976
06:35:26 24050 8192000000 10053230992 10487205888 1109269408 11.926 97 40 80 3 1138630464 20589248 8771808
06:36:51 50364 8192000000 10093318736 10549366784 1123690496 12.062 98 18 36 0 1184705648 56912096 4103056
06:38:44 34399 8192000000 10086928400 10543857664 1102670160 11.863 96 28 54 0 1174132688 65393584 6068944
06:40:23 60233 8192000000 10077927728 10519445504 1106464496 11.899 97 14 28 0 1170438368 61132576 2841296
06:42:27 119931 8192000000 10067494448 10543849472 1093357664 11.775 95 16 33 1 1167842912 71467264 3017984
06:45:28 34016 8192000000 10124654240 10560163840 1106276672 11.897 98 29 58 0 1179419328 67296784 5845872
06:47:05 44822 8192000000 10058860672 10481463296 1082560144 11.672 96 33 67 2 1124208384 34665248 6982992
06:48:51 104051 8192000000 10035569792 10481594368 1092831328 11.770 97 30 60 0 1132539904 33295600 6412976
06:51:58 65058 8192000000 10075545984 10529873920 1125239328 12.076 100 24 46 2 1183061040 52851232 4970480
06:54:41 65233 8192000000 10104454080 10553798656 1117409040 12.003 98 10 20 1 1184436112 64774672 2252400
06:56:47 61139 8192000000 10067482848 10513965056 1116216320 11.991 98 13 26 1 1152478720 33184448 3077952
06:58:55 42103 8192000000 10091628016 10550358016 1124495552 12.069 100 24 45 1 1210560944 80528512 5536880
07:00:54 72262 8192000000 10112070640 10578210816 1089797584 11.741 97 17 34 1 1194834560 100984720 4052256
07:03:10 39863 8192000000 10072101360 10501599232 1091235200 11.754 97 26 52 1 1120294336 23752672 5306464
07:04:55 49698 8192000000 10090111968 10558357504 1120262688 12.029 100 32 64 0 1186555776 60163280 6129808
07:06:49 35339 8192000000 10079038192 10519556096 1095913856 11.799 97 49 98 0 1132376832 26880624 9582352
07:08:26 121085 8192000000 10030209168 10456281088 1096908800 11.808 97 30 60 1 1133721984 30938544 5874640
07:11:27 50771 8192000000 10058005872 10538840064 1129130288 12.113 100 9 18 0 1186023824 55092816 1800720
07:13:18 81404 8192000000 10071955424 10578505728 1112657232 11.958 98 11 22 0 1188115712 73313424 2145056
07:15:40 72469 8192000000 10073025296 10551889920 1095084864 11.791 96 15 30 0 1142380784 44300064 2995856
07:17:53 32843 8192000000 10068294000 10498252800 1103872784 11.874 97 30 60 1 1140655504 30435616 6347104
07:19:35 101916 8192000000 10110031264 10639003648 1119120976 12.019 98 17 34 0 1226745472 103639088 3985408
07:22:19 68292 8192000000 10097484544 10562994176 1136280640 12.181 100 14 28 1 1207188800 67867296 3040864
07:24:51 81744 8192000000 10071564320 10529058816 1085553264 11.700 96 38 76 1 1142094928 49374928 7166736
07:28:28 78559 8192000000 10085800000 10552344576 1125810160 12.082 100 12 24 0 1176915584 48366096 2739328
07:31:07 55024 8192000000 10044707360 10546216960 1072198592 11.573 95 10 19 2 1106137952 31824048 2115312
07:33:02 92879 8192000000 10076612736 10542952448 1120437920 12.031 99 21 42 1 1160468080 35718896 4311264
07:35:36 62034 8192000000 10099331984 10543079424 1115403120 11.984 98 20 40 0 1160930864 41345328 4182416

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 04-08-2021 07:38:34 to 04-08-2021 07:38:49.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 104 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 04-08-2021 07:38:50 to 04-08-2021 07:38:52.

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          267469
MON$OLDEST_ACTIVE               267470
MON$OLDEST_SNAPSHOT             267470
MON$NEXT_TRANSACTION            267470
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                       2054176
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             805
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

04.08.21 07:38:53. 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-08-04 07:38:56 No severe PSQL-related problems occured
04.08.21 07:38:57. End of report.