Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.04.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 17192448 kB
MemAvailable 31481936 kB
Buffers 0 kB
Cached 14504452 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-04-03 13:26:58
DTS_END                         2021-04-03 13:26:58.6960
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/41358
TRN_ID                          223895
ATT_ID                          634
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: 8326.61 20144 999193
customer order: creation 168.90 441 20268
customer order: refuse 13.83 349 1660
order to supplier: creation 43.73 1466 5248
order to supplier: removal 3.26 120870 391
invoice (draft): creation 44.59 3937 5351
invoice (draft): removal 45.27 1085 5432
invoice accept: apply 40.38 20206 4845
invoice accept: cancel 40.64 1112 4877
customer reserve: creation 3676.55 200 441186
customer reserve: removal 3973.37 79 476804
realization accept: apply 80.47 180 9656
realization accept: cancel 72.32 143 8678
payment from customer: creation 56.63 107 6795
payment from customer: removal 5.39 76 647
payment to supplier: creation 47.38 127 5686
payment to supplier: removal 3.31 95 397
service: total inventory turnovers 3.56 3671 427
service: total monetary turnovers 6.08 597 730
service: aggregate perf. data 0.93 14916 111
service: refresh index statistics 0.03 233221 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-04-2021 13:28:39 to 03-04-2021 13:28:41.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4454
TEST_TIME 2 4544
TEST_TIME 3 5153
TEST_TIME 4 6036
TEST_TIME 5 5751
TEST_TIME 6 5733
TEST_TIME 7 5568
TEST_TIME 8 5270
TEST_TIME 9 5240
TEST_TIME 10 5314
TEST_TIME 11 5304
TEST_TIME 12 5730
TEST_TIME 13 5825
TEST_TIME 14 6130
TEST_TIME 15 6221
TEST_TIME 16 6591
TEST_TIME 17 7280
TEST_TIME 18 7347
TEST_TIME 19 7320
TEST_TIME 20 7376
TEST_TIME 21 7956
TEST_TIME 22 7969
TEST_TIME 23 8015
TEST_TIME 24 8214
TEST_TIME 25 8144
TEST_TIME 26 8657
TEST_TIME 27 9009
TEST_TIME 28 9896
TEST_TIME 29 10271
TEST_TIME 30 9946
TEST_TIME 31 9843
TEST_TIME 32 9724
TEST_TIME 33 9620
TEST_TIME 34 9540
TEST_TIME 35 9511
TEST_TIME 36 9324
TEST_TIME 37 9218
TEST_TIME 38 9107
TEST_TIME 39 8984
TEST_TIME 40 8995
TEST_TIME 41 8878
TEST_TIME 42 8744
TEST_TIME 43 8705
TEST_TIME 44 8684
TEST_TIME 45 8620
TEST_TIME 46 8508
TEST_TIME 47 8520
TEST_TIME 48 8558
TEST_TIME 49 8548
TEST_TIME 50 8564
TEST_TIME 51 8711
TEST_TIME 52 8745
TEST_TIME 53 8673
TEST_TIME 54 8665
TEST_TIME 55 8847
TEST_TIME 56 8827
TEST_TIME 57 8752
TEST_TIME 58 8957
TEST_TIME 59 8931
TEST_TIME 60 8790
TEST_TIME 61 8719
TEST_TIME 62 8747
TEST_TIME 63 8705
TEST_TIME 64 8650
TEST_TIME 65 8579
TEST_TIME 66 8569
TEST_TIME 67 8637
TEST_TIME 68 8618
TEST_TIME 69 8699
TEST_TIME 70 8734
TEST_TIME 71 8720
TEST_TIME 72 8717
TEST_TIME 73 8808
TEST_TIME 74 8766
TEST_TIME 75 8768
TEST_TIME 76 8811
TEST_TIME 77 8820
TEST_TIME 78 8788
TEST_TIME 79 8745
TEST_TIME 80 8692
TEST_TIME 81 8660
TEST_TIME 82 8674
TEST_TIME 83 8609
TEST_TIME 84 8560
TEST_TIME 85 8561
TEST_TIME 86 8531
TEST_TIME 87 8549
TEST_TIME 88 8521
TEST_TIME 89 8557
TEST_TIME 90 8519
TEST_TIME 91 8541
TEST_TIME 92 8615
TEST_TIME 93 8653
TEST_TIME 94 8677
TEST_TIME 95 8652
TEST_TIME 96 8631
TEST_TIME 97 8566
TEST_TIME 98 8532
TEST_TIME 99 8500
TEST_TIME 100 8447
TEST_TIME 101 8400
TEST_TIME 102 8409
TEST_TIME 103 8424
TEST_TIME 104 8402
TEST_TIME 105 8402
TEST_TIME 106 8403
TEST_TIME 107 8391
TEST_TIME 108 8428
TEST_TIME 109 8413
TEST_TIME 110 8450
TEST_TIME 111 8423
TEST_TIME 112 8493
TEST_TIME 113 8476
TEST_TIME 114 8521
TEST_TIME 115 8521
TEST_TIME 116 8449
TEST_TIME 117 8411
TEST_TIME 118 8364
TEST_TIME 119 8357
TEST_TIME 120 8340

Performance per minute, chart


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

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 1406985 1406985 0 0.00 0 16561 8 0 0
doc_list_biud 1406982 1406982 0 0.00 0 97736 34 0 0
sp_add_invoice_to_stock 4898 4845 53 1.08 30 211463 20206 0 53
sp_cancel_adding_invoice 4877 4877 0 0.00 4 67838 1112 0 0
sp_cancel_client_order 1660 1660 0 0.00 4 11049 349 0 0
sp_cancel_customer_reserve 476804 476804 0 0.00 1 36404 80 0 0
sp_cancel_pay_from_customer 647 647 0 0.00 3 1863 77 0 0
sp_cancel_pay_to_supplier 397 397 0 0.00 3 10333 96 0 0
sp_cancel_supplier_invoice 5433 5432 1 0.01 2 97746 1085 0 1
sp_cancel_supplier_order 391 391 0 0.00 7 1218917 120871 0 0
sp_cancel_write_off 8678 8678 0 0.00 1 26137 143 0 0
sp_client_order 20268 20268 0 0.00 4 34989 441 0 0
sp_customer_reserve 441186 441186 0 0.00 0 92354 200 0 0
sp_fill_shopping_cart 30849 30849 0 0.00 1 34763 47 0 0
sp_fill_shopping_cart_clo_res 441162 441162 0 0.00 0 23843 21 0 0
sp_get_clo_for_invoice 4816 4816 0 0.00 0 32969 436 0 0
sp_kill_cost_storno 14599 14599 0 0.00 0 7153 70 0 0
sp_kill_qstorno_ret_qs2qd 968109 968109 0 0.00 0 97734 43 0 0
sp_kill_qty_storno 512314 512314 0 0.00 0 97735 92 0 0
sp_lock_selected_doc 19972 19972 0 0.00 0 15039 5 0 0
sp_make_cost_storno 26953 26953 0 0.00 0 15266 56 0 0
sp_multiply_rows_for_pdistr 26953 26953 0 0.00 0 7589 33 0 0
sp_multiply_rows_for_qdistr 20268 20268 0 0.00 0 34912 245 0 0
sp_pay_from_customer 6795 6795 0 0.00 4 17139 107 0 0
sp_pay_to_supplier 5686 5686 0 0.00 5 27943 128 0 0
sp_payment_common 12481 12481 0 0.00 1 27924 20 0 0
sp_qd_handle_on_cancel_clo 1660 1660 0 0.00 0 8924 319 0 0
sp_qd_handle_on_invoice_upd_sts 9693 9693 0 0.00 0 25639 400 0 0
sp_qd_handle_on_reserve_upd_sts 9656 9656 0 0.00 0 26038 56 0 0
sp_reserve_write_off 9656 9656 0 0.00 7 26202 181 0 0
sp_supplier_invoice 5351 5351 0 0.00 6 118161 3938 0 0
sp_supplier_order 5248 5248 0 0.00 6 40347 1466 0 0
srv_aggregate_perf_data 111 111 0 0.00 2671 236401 14917 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1622 151688 46990 0 0
srv_find_qd_qs_mism 952903 952903 0 0.00 0 14200 7 0 0
srv_make_invnt_saldo 427 427 0 0.00 52 97871 3671 0 0
srv_make_money_saldo 730 730 0 0.00 1 15498 598 0 0
srv_recalc_idx_stat 4 4 0 0.00 143940 275454 233222 0 0
t$perf-abend:sp_add_invoice_to_stock 53 53 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4845 4845 0 0.00 0 7280 166 0 0
t$perf-norm:sp_cancel_adding_invoice 414 414 0 0.00 0 2139 104 0 0
t$perf-norm:sp_cancel_client_order 1660 1660 0 0.00 0 7358 17 0 0
t$perf-norm:sp_cancel_customer_reserve 636 636 0 0.00 0 965 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 647 647 0 0.00 0 1995 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 397 397 0 0.00 0 156 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 400 400 0 0.00 0 346 11 0 0
t$perf-norm:sp_cancel_supplier_order 391 391 0 0.00 0 15022 1437 0 0
t$perf-norm:sp_cancel_write_off 955 955 0 0.00 0 2612 6 0 0
t$perf-norm:sp_client_order 20268 20268 0 0.00 0 8048 17 0 0
t$perf-norm:sp_customer_reserve 964 964 0 0.00 0 848 4 0 0
t$perf-norm:sp_pay_from_customer 6795 6795 0 0.00 0 8255 10 0 0
t$perf-norm:sp_pay_to_supplier 5686 5686 0 0.00 0 1668 6 0 0
t$perf-norm:sp_reserve_write_off 9656 9656 0 0.00 0 8212 8 0 0
t$perf-norm:sp_supplier_invoice 5351 5351 0 0.00 0 4325 18 0 0
t$perf-norm:sp_supplier_order 5248 5248 0 0.00 0 1551 14 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 303 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 903 28 0 0
t$perf-norm:srv_make_invnt_saldo 427 427 0 0.00 0 1058 11 0 0
t$perf-norm:srv_make_money_saldo 730 730 0 0.00 0 962 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 3 0 0
v_add_invoice_to_stock 9738 9632 106 1.08 0 8762 15 0 106
v_all_customers 37022 37022 0 0.00 0 91 1 0 0
v_all_suppliers 29494 29494 0 0.00 0 24 1 0 0
v_all_wares 40536 40536 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 828 828 0 0.00 0 1842 10 0 0
v_cancel_client_order 3320 3320 0 0.00 0 2064 5 0 0
v_cancel_customer_prepayment 1294 1294 0 0.00 0 1131 8 0 0
v_cancel_customer_reserve 1272 1272 0 0.00 0 2161 5 0 0
v_cancel_payment_to_supplier 794 794 0 0.00 0 4846 12 0 0
v_cancel_supplier_invoice 802 800 2 0.24 0 1236 13 0 2
v_cancel_supplier_order 782 782 0 0.00 0 2628 8 0 0
v_cancel_write_off 1910 1910 0 0.00 0 2946 13 0 0
v_max_id_clo_ord 5248 5248 0 0.00 0 222 1 0 0
v_max_id_clo_res 964 964 0 0.00 0 4326 19 0 0
v_max_id_ord_sup 5333 5333 0 0.00 0 2110 4 0 0
v_max_non_paid_invoice 1520 1520 0 0.00 0 210 2 0 0
v_max_non_paid_realizn 3563 3563 0 0.00 0 1462 4 0 0
v_min_id_clo_ord 5248 5248 0 0.00 0 6168 9 0 0
v_min_id_clo_res 964 964 0 0.00 0 4101 14 0 0
v_min_id_ord_sup 5333 5333 0 0.00 0 9875 15 0 0
v_min_non_paid_invoice 5686 5686 0 0.00 0 27916 14 0 0
v_min_non_paid_realizn 6795 6795 0 0.00 0 7428 13 0 0
v_our_firm 9978 9978 0 0.00 0 19 1 0 0
v_reserve_write_off 19312 19312 0 0.00 0 2777 5 0 0
x_lock_depdocs_on_canc_invoice 9909 9909 0 0.00 0 23878 132 0 0
x_lock_depdocs_on_canc_sup_ord 391 391 0 0.00 0 12209 362 0 0
x_make_qstorno_1000_1200 5248 5248 0 0.00 1 40030 1382 0 0
x_make_qstorno_1200_2000 5351 5351 0 0.00 3 111980 3751 0 0
x_make_qstorno_2100_3300 441186 441186 0 0.00 0 92353 177 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-04-2021 13:28:47 to 03-04-2021 13:28:51.

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:02 4 8192000000 8799909440 8811700224 5583440 0.068 2 1 2 0 5706368 79136 43792
10:58:03 28111 8192000000 9717238224 10011365376 795241168 8.848 100 88 175 1 824205552 7596848 21367536
10:59:37 36984 8192000000 9765490000 10072055808 837684464 9.277 100 92 184 0 872126240 12514528 21927248
11:01:19 23346 8192000000 9802774416 10123366400 870481872 9.605 100 89 178 1 915994176 24995552 20516752
11:02:52 10705 8192000000 9872808432 10207936512 930630464 10.201 100 82 165 0 981663360 32786368 18246528
11:04:07 8304 8192000000 9920284016 10272071680 959485600 10.484 100 69 137 2 1035373712 61195088 14693024
11:05:23 4623 8192000000 9974230816 10328248320 996282704 10.842 100 76 151 0 1086865376 75416112 15166560
11:06:31 3347 8192000000 9998338064 10358435840 1028463536 11.154 100 83 163 2 1105223120 60602064 16157520
11:07:36 2730 8192000000 10002753568 10368483328 1045406240 11.317 100 82 164 0 1118728336 56928800 16393296
11:08:41 1791 8192000000 10052941888 10424877056 1060068256 11.457 100 93 186 0 1164634240 85711984 18854000
11:09:46 1500 8192000000 10028262768 10404663296 1066952032 11.523 100 79 159 0 1141681024 57779072 16949920
11:10:49 5324 8192000000 9983632288 10380931072 1074100048 11.591 100 28 56 0 1119631072 39394768 6136256
11:11:58 51845 8192000000 10014525760 10428760064 1085658784 11.701 100 36 72 1 1116980768 23945920 7376064
11:13:51 5860 8192000000 10014435088 10405593088 1094805888 11.788 100 52 104 1 1116725344 9976080 11943376
11:14:59 4223 8192000000 10026895248 10418036736 1104037184 11.876 100 51 101 1 1137057296 22219376 10800736
11:16:04 7621 8192000000 10025194528 10432380928 1103656496 11.872 100 41 84 2 1134021680 21878128 8487056
11:17:13 26086 8192000000 10005674688 10410151936 1071596224 11.567 97 18 34 2 1107216336 32268704 3351408
11:18:40 4898 8192000000 10014831584 10414129152 1096834816 11.808 100 35 70 1 1129832976 25818224 7179936
11:19:46 8758 8192000000 9954315424 10345406464 977802128 10.663 92 24 48 1 992051824 8780192 5469504
11:20:58 62968 8192000000 9926051008 10305761280 966885600 10.556 93 39 75 0 992023888 16919520 8218768
11:23:01 8090 8192000000 9955801584 10340524032 998908448 10.868 97 45 91 1 1044526432 35388400 10229584
11:24:11 7091 8192000000 9973472720 10370707456 1041241856 11.277 100 43 86 2 1067468656 17080144 9146656
11:25:20 6008 8192000000 9970383136 10364067840 1060964096 11.466 100 40 78 2 1080383936 10534976 8884864
11:26:29 24723 8192000000 9994507248 10373783552 1068208896 11.535 100 68 136 0 1093264752 9361904 15693952
11:28:02 11970 8192000000 10031229760 10431627264 1091225744 11.754 100 44 88 0 1139323760 39683520 8414496
11:29:20 10334 8192000000 10071011616 10475491328 1108352080 11.917 100 57 111 2 1190529984 70697360 11480544
11:30:34 4155 8192000000 10087236320 10488381440 1124904112 12.073 100 54 105 0 1195261840 58855088 11502640
11:31:47 25794 8192000000 10141179312 10554146816 1145988592 12.272 100 47 94 1 1248506752 91405648 11112512
11:33:19 15060 8192000000 10151301584 10563895296 1157294096 12.378 100 47 94 1 1261206880 92569344 11343440
11:34:37 20206 8192000000 10215372864 10631585792 1171689808 12.513 100 52 103 1 1316637152 133214880 11732464
11:36:05 8330 8192000000 10276977056 10692120576 1181687552 12.606 100 54 107 1 1383242832 189836288 11718992
11:37:24 21716 8192000000 10310909664 10737774592 1183810656 12.626 100 48 95 2 1404351696 208693568 11847472
11:38:50 1821 8192000000 10323447824 10732089344 1189510752 12.679 100 79 158 0 1423729344 215293536 18925056
11:39:59 4093 8192000000 10356415120 10762936320 1187108816 12.656 100 75 146 2 1458428800 255101248 16218736
11:41:07 4566 8192000000 10344178528 10754797568 1182787504 12.616 99 66 133 0 1436377552 238394432 15195616
11:42:15 4600 8192000000 10384752960 10809450496 1183478864 12.623 100 82 164 0 1493197056 290963248 18754944
11:43:23 2203 8192000000 10345738672 10784948224 1190313296 12.686 100 87 176 2 1446589088 235970544 20305248
11:44:32 3338 8192000000 10365720000 10775777280 1194168656 12.722 100 86 171 0 1463312368 249006800 20136912
11:45:41 7680 8192000000 10439851472 10853056512 1198697456 12.764 100 82 163 1 1530493376 312993840 18802080
11:46:54 1621 8192000000 10435596640 10842066944 1177783136 12.570 99 85 169 0 1530904800 333578864 19542800
11:48:08 3042 8192000000 10412388848 10829586432 1173350688 12.528 100 73 147 0 1512130672 321059872 17720112
11:49:18 1707 8192000000 10417588512 10823573504 1153997712 12.347 100 84 164 0 1509111376 336018224 19095440
11:50:24 15722 8192000000 10369574832 10823741440 1122660368 12.052 98 37 74 2 1473427056 341016560 9750128
11:51:45 14676 8192000000 10416864864 10852085760 1141336960 12.228 100 33 66 3 1520800080 370793504 8669616
11:53:14 35012 8192000000 10290719088 10783744000 1102632944 11.863 98 16 32 0 1409123136 301985904 4504288
11:54:52 27057 8192000000 10063159376 10575888384 1107622960 11.910 100 12 24 1 1207830080 97219552 2987568
11:56:19 54720 8192000000 10072549056 10489929728 1098651632 11.825 98 58 118 1 1155888832 43676592 13560608
11:58:15 21279 8192000000 10072886016 10513272832 1131497888 12.135 100 36 71 2 1169660656 31142544 7020224
11:59:52 131841 8192000000 10055188288 10541154304 1113511264 11.966 98 14 28 1 1160696528 44612720 2572544
12:03:06 45670 8192000000 10058622192 10513489920 1119099856 12.018 99 11 22 0 1159161600 37638368 2423376
12:04:52 151654 8192000000 10071279680 10578169856 1121443344 12.041 99 20 40 1 1171853776 46179680 4230752
12:08:28 24481 8192000000 10048877536 10511544320 1120613600 12.033 100 47 95 0 1158048992 27038160 10397232
12:10:14 37445 8192000000 10056915168 10512646144 1116052864 11.990 99 18 36 2 1165048560 45131248 3864448
12:12:43 51499 8192000000 10095794240 10560200704 1117068768 11.999 98 23 46 0 1191887792 69501584 5317440
12:14:35 39478 8192000000 10104058304 10546372608 1135515728 12.173 100 25 47 0 1186556960 45842144 5199088
12:16:20 78513 8192000000 10109792160 10620604416 1126671120 12.090 98 16 32 0 1217818400 87338752 3808528
12:18:48 41171 8192000000 10100550752 10542895104 1111026816 11.942 97 24 48 0 1166925968 50913856 4985296
12:20:34 103872 8192000000 10077470768 10534559744 1102153200 11.858 96 18 36 0 1186341344 80311536 3876608
12:23:19 17878 8192000000 10148372640 10571505664 1146702352 12.279 99 56 111 1 1212624432 54723296 11198784
12:24:38 138043 8192000000 10120763744 10582503424 1139023088 12.206 99 10 20 0 1213337696 72089824 2224784
12:28:27 59131 8192000000 10081380656 10548146176 1143612416 12.249 99 9 18 1 1192403248 47201184 1589648
12:30:30 51743 8192000000 10109813824 10580000768 1154564544 12.352 100 10 19 0 1211429024 54802160 2062320
12:32:22 66306 8192000000 10116313584 10563989504 1147969376 12.290 99 23 47 2 1251652400 98221792 5461232
12:34:33 45449 8192000000 10102002336 10552676352 1130290672 12.124 98 21 42 0 1171022368 36089952 4641744
12:36:25 86024 8192000000 10082051520 10525868032 1117049280 11.999 97 16 32 2 1178189632 57669600 3470752
12:38:52 33789 8192000000 10092510880 10548482048 1071524736 11.567 94 28 55 0 1132798512 55185456 6088320
12:40:41 66987 8192000000 10075401104 10519166976 1075494176 11.605 95 21 42 0 1114948912 35382272 4072464
12:42:57 41270 8192000000 10067932048 10515238912 1090097744 11.744 97 37 74 1 1149814448 52636240 7080464
12:44:41 36178 8192000000 10120086912 10570080256 1098685872 11.825 98 30 60 1 1181400752 76772384 5942496
12:46:29 101982 8192000000 10027438256 10498670592 1095812512 11.798 98 18 36 2 1156112624 56383072 3917040
12:49:34 59818 8192000000 10063364736 10507837440 1109013840 11.923 99 10 18 2 1144008256 32815568 2178848
12:51:34 60378 8192000000 10084022112 10519699456 1101837936 11.855 98 18 36 2 1163753504 58254064 3661504
12:53:41 47699 8192000000 10095626272 10518614016 1111150928 11.943 99 33 66 0 1179027632 60851392 7025312
12:55:31 61265 8192000000 10092914976 10520866816 1075782416 11.607 95 22 44 0 1146438160 65743008 4912736
12:57:33 109648 8192000000 10079672480 10541432832 1100435760 11.842 98 11 22 1 1172185680 69715488 2034432
13:00:32 62517 8192000000 10068959712 10495168512 1068273040 11.536 94 24 46 1 1099807232 27435824 4098368
13:02:35 111178 8192000000 10083978784 10538196992 1137093280 12.188 100 26 52 0 1204398288 62239280 5065728
13:05:27 65979 8192000000 10048313712 10508652544 1132475680 12.145 100 4 8 0 1147430000 14290528 663792
13:07:33 116785 8192000000 10096384784 10592432128 1105214304 11.887 97 12 24 1 1181669024 73702544 2752176
13:10:31 48665 8192000000 10109563008 10545364992 1115069936 11.980 98 33 64 1 1158932336 36629136 7233264
13:12:20 66757 8192000000 10071959472 10519318528 1092416416 11.766 96 15 30 2 1136633968 40937328 3280224
13:14:27 47065 8192000000 10083369088 10508214272 1105548192 11.890 97 38 73 4 1134897568 21875424 7473952
13:16:17 36016 8192000000 10070598288 10502737920 1120750256 12.034 99 12 24 2 1164933120 41945712 2237152
13:17:59 76415 8192000000 10099638784 10562461696 1145308672 12.265 100 17 34 0 1226332256 77407072 3616512
13:20:20 92386 8192000000 10093505728 10552229888 1120908832 12.036 98 11 22 0 1152920848 29651120 2360896
13:23:12 98452 8192000000 10080319760 10534813696 1125279504 12.077 98 16 32 0 1171335936 43243584 2812848
13:26:37 28144 8192000000 9073581296 9331757056 234383024 2.781 21 3 4 1 240634000 5956544 294432

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 03-04-2021 13:28:53 to 03-04-2021 13:29:08.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 53 v_add_invoice_to_stock 335544517
except 1 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 03-04-2021 13:29:08 to 03-04-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          224209
MON$OLDEST_ACTIVE               224210
MON$OLDEST_SNAPSHOT             224210
MON$NEXT_TRANSACTION            224210
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                       2058912
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             779
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2401 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2401 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2401 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.2401 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 ---
:28: 
:29: mscs-test-161.budzdorov.ru    Sat Apr  3 13:27:17 2021
:30:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:31: 
--- end of diff output ---

Final processing ISQL logs in /var/tmp/logs-oltp40 according to config parameter 'remove_isql_logs'

03.04.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-04-03 13:29:14 No severe PSQL-related problems occured
03.04.21 13:29:15. End of report.