Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.02.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 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 16430876 kB
MemAvailable 31081508 kB
Buffers 0 kB
Cached 15221068 kB
SwapCached 3708 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-25 07:37:15
DTS_END                         2021-02-25 07:37:15.8800
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/48250
TRN_ID                          225498
ATT_ID                          627
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: 8586.91 24711 1030429
customer order: creation 159.57 417 19148
customer order: refuse 13.98 361 1677
order to supplier: creation 41.33 1554 4959
order to supplier: removal 3.05 124434 366
invoice (draft): creation 43.03 3975 5163
invoice (draft): removal 49.95 925 5994
invoice accept: apply 38.24 18496 4589
invoice accept: cancel 45.41 905 5449
customer reserve: creation 3492.59 182 419111
customer reserve: removal 4417.28 68 530074
realization accept: apply 79.18 207 9501
realization accept: cancel 84.25 108 10110
payment from customer: creation 54.38 122 6526
payment from customer: removal 5.39 91 647
payment to supplier: creation 46.38 117 5566
payment to supplier: removal 2.93 88 352
service: total inventory turnovers 3.76 3650 451
service: total monetary turnovers 5.35 643 642
service: aggregate perf. data 0.83 13799 100
service: refresh index statistics 0.03 324087 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 25-02-2021 07:37:54 to 25-02-2021 07:37:56.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3392
TEST_TIME 2 3419
TEST_TIME 3 3123
TEST_TIME 4 3670
TEST_TIME 5 4335
TEST_TIME 6 4346
TEST_TIME 7 4694
TEST_TIME 8 4581
TEST_TIME 9 4785
TEST_TIME 10 4900
TEST_TIME 11 5356
TEST_TIME 12 6057
TEST_TIME 13 6055
TEST_TIME 14 6163
TEST_TIME 15 6126
TEST_TIME 16 6242
TEST_TIME 17 6332
TEST_TIME 18 6806
TEST_TIME 19 7280
TEST_TIME 20 7957
TEST_TIME 21 8328
TEST_TIME 22 8387
TEST_TIME 23 8815
TEST_TIME 24 9005
TEST_TIME 25 8956
TEST_TIME 26 9052
TEST_TIME 27 9383
TEST_TIME 28 9889
TEST_TIME 29 10606
TEST_TIME 30 10448
TEST_TIME 31 10156
TEST_TIME 32 10347
TEST_TIME 33 10134
TEST_TIME 34 9942
TEST_TIME 35 9885
TEST_TIME 36 9768
TEST_TIME 37 9713
TEST_TIME 38 9527
TEST_TIME 39 9512
TEST_TIME 40 9416
TEST_TIME 41 9334
TEST_TIME 42 9236
TEST_TIME 43 9170
TEST_TIME 44 9100
TEST_TIME 45 9034
TEST_TIME 46 8992
TEST_TIME 47 8911
TEST_TIME 48 8836
TEST_TIME 49 8746
TEST_TIME 50 8716
TEST_TIME 51 8709
TEST_TIME 52 8669
TEST_TIME 53 8633
TEST_TIME 54 8610
TEST_TIME 55 8642
TEST_TIME 56 8714
TEST_TIME 57 8682
TEST_TIME 58 8724
TEST_TIME 59 8847
TEST_TIME 60 8836
TEST_TIME 61 8825
TEST_TIME 62 8863
TEST_TIME 63 8991
TEST_TIME 64 9024
TEST_TIME 65 9114
TEST_TIME 66 9168
TEST_TIME 67 9073
TEST_TIME 68 8969
TEST_TIME 69 8909
TEST_TIME 70 8805
TEST_TIME 71 8783
TEST_TIME 72 8781
TEST_TIME 73 8734
TEST_TIME 74 8732
TEST_TIME 75 8722
TEST_TIME 76 8695
TEST_TIME 77 8747
TEST_TIME 78 8853
TEST_TIME 79 8821
TEST_TIME 80 8852
TEST_TIME 81 8885
TEST_TIME 82 8876
TEST_TIME 83 8856
TEST_TIME 84 8788
TEST_TIME 85 8700
TEST_TIME 86 8704
TEST_TIME 87 8675
TEST_TIME 88 8617
TEST_TIME 89 8583
TEST_TIME 90 8598
TEST_TIME 91 8578
TEST_TIME 92 8590
TEST_TIME 93 8616
TEST_TIME 94 8685
TEST_TIME 95 8662
TEST_TIME 96 8680
TEST_TIME 97 8656
TEST_TIME 98 8694
TEST_TIME 99 8698
TEST_TIME 100 8685
TEST_TIME 101 8720
TEST_TIME 102 8748
TEST_TIME 103 8811
TEST_TIME 104 8801
TEST_TIME 105 8720
TEST_TIME 106 8658
TEST_TIME 107 8648
TEST_TIME 108 8622
TEST_TIME 109 8579
TEST_TIME 110 8574
TEST_TIME 111 8584
TEST_TIME 112 8594
TEST_TIME 113 8562
TEST_TIME 114 8554
TEST_TIME 115 8560
TEST_TIME 116 8574
TEST_TIME 117 8604
TEST_TIME 118 8628
TEST_TIME 119 8637
TEST_TIME 120 8591

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 25-02-2021 07:37:58 to 25-02-2021 07:38:01.

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 1419400 1419400 0 0.00 0 12444 7 0 0
doc_list_biud 1419393 1419393 0 0.00 0 95510 33 0 0
sp_add_invoice_to_stock 4681 4589 92 1.96 30 215122 18496 0 92
sp_cancel_adding_invoice 5449 5449 0 0.00 5 82012 906 0 0
sp_cancel_client_order 1677 1677 0 0.00 4 21445 362 0 0
sp_cancel_customer_reserve 530074 530074 0 0.00 1 58902 69 0 0
sp_cancel_pay_from_customer 647 647 0 0.00 3 6047 92 0 0
sp_cancel_pay_to_supplier 352 352 0 0.00 2 3618 88 0 0
sp_cancel_supplier_invoice 6000 5994 6 0.10 2 95564 925 0 6
sp_cancel_supplier_order 366 366 0 0.00 6 1208522 124434 0 0
sp_cancel_write_off 10110 10110 0 0.00 1 7953 109 0 0
sp_client_order 19148 19148 0 0.00 4 13620 418 0 0
sp_customer_reserve 419111 419111 0 0.00 0 57485 183 0 0
sp_fill_shopping_cart 29265 29265 0 0.00 1 24030 49 0 0
sp_fill_shopping_cart_clo_res 419106 419106 0 0.00 0 39515 20 0 0
sp_get_clo_for_invoice 4582 4582 0 0.00 0 52236 430 0 0
sp_kill_cost_storno 16558 16558 0 0.00 0 7761 57 0 0
sp_kill_qstorno_ret_qs2qd 1076618 1076618 0 0.00 0 95509 37 0 0
sp_kill_qty_storno 567748 567748 0 0.00 0 95510 80 0 0
sp_lock_selected_doc 19521 19521 0 0.00 0 9390 6 0 0
sp_make_cost_storno 26175 26175 0 0.00 0 10582 55 0 0
sp_multiply_rows_for_pdistr 26168 26168 0 0.00 0 6301 32 0 0
sp_multiply_rows_for_qdistr 19148 19148 0 0.00 0 11283 225 0 0
sp_pay_from_customer 6526 6526 0 0.00 4 12369 122 0 0
sp_pay_to_supplier 5566 5566 0 0.00 5 11583 118 0 0
sp_payment_common 12090 12090 0 0.00 1 6595 20 0 0
sp_qd_handle_on_cancel_clo 1677 1677 0 0.00 0 21432 334 0 0
sp_qd_handle_on_invoice_upd_sts 10029 10029 0 0.00 0 39980 403 0 0
sp_qd_handle_on_reserve_upd_sts 9498 9498 0 0.00 0 14585 67 0 0
sp_reserve_write_off 9501 9501 0 0.00 6 14607 208 0 0
sp_supplier_invoice 5163 5163 0 0.00 7 99566 3976 0 0
sp_supplier_order 4959 4959 0 0.00 5 33292 1554 0 0
srv_aggregate_perf_data 101 100 1 0.99 2744 80485 13799 0 1
srv_fill_mon_cache_memory 62 62 0 0.00 1202 132836 48579 0 0
srv_find_qd_qs_mism 987030 987030 0 0.00 0 9161 6 0 0
srv_make_invnt_saldo 451 451 0 0.00 55 113990 3651 0 0
srv_make_money_saldo 642 642 0 0.00 1 13217 644 0 0
srv_recalc_idx_stat 4 4 0 0.00 219586 573708 324088 0 0
t$perf-abend:sp_add_invoice_to_stock 92 92 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-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4589 4589 0 0.00 0 17942 174 0 0
t$perf-norm:sp_cancel_adding_invoice 406 406 0 0.00 0 2728 92 0 0
t$perf-norm:sp_cancel_client_order 1677 1677 0 0.00 0 4143 13 0 0
t$perf-norm:sp_cancel_customer_reserve 666 666 0 0.00 0 3265 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 647 647 0 0.00 0 1636 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 352 352 0 0.00 0 2064 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 374 374 0 0.00 0 462 12 0 0
t$perf-norm:sp_cancel_supplier_order 366 366 0 0.00 0 22042 1579 0 0
t$perf-norm:sp_cancel_write_off 955 955 0 0.00 0 2257 7 0 0
t$perf-norm:sp_client_order 19148 19148 0 0.00 0 9653 18 0 0
t$perf-norm:sp_customer_reserve 915 915 0 0.00 0 1319 6 0 0
t$perf-norm:sp_pay_from_customer 6526 6526 0 0.00 0 5410 12 0 0
t$perf-norm:sp_pay_to_supplier 5566 5566 0 0.00 0 5741 10 0 0
t$perf-norm:sp_reserve_write_off 9501 9501 0 0.00 0 4869 9 0 0
t$perf-norm:sp_supplier_invoice 5163 5163 0 0.00 0 2230 15 0 0
t$perf-norm:sp_supplier_order 4959 4959 0 0.00 0 3780 16 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 625 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 2308 48 0 0
t$perf-norm:srv_make_invnt_saldo 451 451 0 0.00 0 685 6 0 0
t$perf-norm:srv_make_money_saldo 642 642 0 0.00 0 900 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 3 0 0
v_add_invoice_to_stock 9344 9160 184 1.96 0 3280 16 0 184
v_all_customers 34408 34408 0 0.00 0 18 1 0 0
v_all_suppliers 28356 28356 0 0.00 0 19 1 0 0
v_all_wares 38296 38296 0 0.00 0 33 1 0 0
v_cancel_adding_invoice 812 812 0 0.00 0 1148 9 0 0
v_cancel_client_order 3354 3354 0 0.00 0 6142 7 0 0
v_cancel_customer_prepayment 1294 1294 0 0.00 0 5947 10 0 0
v_cancel_customer_reserve 1332 1332 0 0.00 0 1517 5 0 0
v_cancel_payment_to_supplier 704 704 0 0.00 0 1635 11 0 0
v_cancel_supplier_invoice 760 748 12 1.57 0 1820 14 0 12
v_cancel_supplier_order 732 732 0 0.00 0 298 4 0 0
v_cancel_write_off 1910 1910 0 0.00 0 1261 14 0 0
v_max_id_clo_ord 4958 4958 0 0.00 0 2385 2 0 0
v_max_id_clo_res 915 915 0 0.00 0 5289 25 0 0
v_max_id_ord_sup 5159 5159 0 0.00 0 3413 6 0 0
v_max_non_paid_invoice 1503 1503 0 0.00 0 499 2 0 0
v_max_non_paid_realizn 3672 3672 0 0.00 0 3719 3 0 0
v_min_id_clo_ord 4958 4958 0 0.00 0 3560 6 0 0
v_min_id_clo_res 915 915 0 0.00 0 4273 14 0 0
v_min_id_ord_sup 5159 5159 0 0.00 0 5536 11 0 0
v_min_non_paid_invoice 5564 5564 0 0.00 0 5136 10 0 0
v_min_non_paid_realizn 6526 6526 0 0.00 0 4986 17 0 0
v_our_firm 9596 9596 0 0.00 0 261 1 0 0
v_reserve_write_off 18996 18996 0 0.00 0 10919 8 0 0
x_lock_depdocs_on_canc_invoice 11069 11069 0 0.00 0 15904 114 0 0
x_lock_depdocs_on_canc_sup_ord 366 366 0 0.00 0 10222 408 0 0
x_make_qstorno_1000_1200 4959 4959 0 0.00 1 33272 1471 0 0
x_make_qstorno_1200_2000 5163 5163 0 0.00 4 94023 3785 0 0
x_make_qstorno_2100_3300 419111 419111 0 0.00 0 57484 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-02-2021 07:38:02 to 25-02-2021 07:38:06.

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:19 8 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
05:08:20 14417 8192000000 9726657904 10025291776 808379216 8.981 100 86 172 0 836396704 7428160 20589328
05:09:41 6479 8192000000 9766922736 10071584768 844954496 9.349 100 94 188 1 878850656 11537504 22358656
05:10:52 27156 8192000000 9793958192 10108874752 864402848 9.544 100 85 170 1 901448160 17151216 19894096
05:12:22 13404 8192000000 9864848112 10193448960 914657456 10.043 100 81 162 0 967290880 34771024 17862400
05:13:39 10780 8192000000 9921357984 10261647360 954174464 10.432 100 79 158 2 1028312480 57387696 16750320
05:14:53 6362 8192000000 9958006496 10309275648 989025984 10.772 100 85 167 0 1065505696 59711792 16767920
05:16:02 2415 8192000000 9996790736 10358296576 1016144864 11.035 100 85 170 2 1113280992 79886560 17249568
05:17:06 3717 8192000000 10029674512 10399768576 1036901152 11.235 100 80 160 1 1151959472 99741456 15316864
05:18:11 1689 8192000000 10027199376 10405109760 1064426320 11.499 100 89 175 0 1143059120 60413680 18219120
05:19:15 2570 8192000000 10024013376 10401275904 1074324640 11.593 100 84 167 2 1144715008 52940112 17450256
05:20:19 1834 8192000000 10025987376 10416238592 1073292656 11.584 100 57 114 0 1142297376 57144288 11860432
05:21:25 5924 8192000000 10015169760 10404282368 1080854160 11.656 100 31 61 0 1119215280 32597136 5763984
05:22:33 6726 8192000000 9998706624 10395308032 1085987488 11.704 100 42 84 0 1119420928 24145824 9287616
05:23:40 32453 8192000000 10009524288 10416033792 1091326672 11.755 100 43 86 1 1118865552 18748512 8790368
05:25:14 6980 8192000000 10029005232 10434076672 1096209632 11.802 100 41 82 0 1138288432 33621120 8457680
05:26:25 5647 8192000000 10015173088 10420305920 1093247536 11.774 100 42 84 1 1112169408 9054016 9867856
05:27:31 6213 8192000000 9998429664 10404286464 1091420240 11.756 100 36 72 1 1117808192 19356336 7031616
05:28:43 17202 8192000000 9979095312 10389872640 1030082592 11.169 95 37 75 1 1056620208 17731152 8806464
05:30:03 6250 8192000000 9959484608 10347196416 1011614528 10.991 97 50 96 3 1051729088 29270608 10843952
05:31:11 9397 8192000000 9957810624 10344017920 1031007776 11.178 99 33 66 2 1053985056 15687424 7289856
05:32:21 45856 8192000000 9919180400 10300878848 989219520 10.774 96 25 50 1 1010201280 15604896 5376864
05:34:07 8826 8192000000 9972934976 10368430080 1041161008 11.276 99 36 72 1 1076250608 27941552 7148048
05:35:20 9971 8192000000 9965758304 10368290816 1063166000 11.487 100 39 79 3 1104141072 32435184 8539888
05:36:30 5826 8192000000 9983209504 10381983744 1064361232 11.498 100 45 91 1 1090762224 16683504 9717488
05:37:40 84084 8192000000 10011584432 10415198208 1092939584 11.771 100 50 98 2 1124688624 21741440 10007600
05:40:08 53176 8192000000 10092257888 10501292032 1117649008 12.005 100 50 99 0 1178271504 50666688 9955808
05:42:05 10739 8192000000 10145746496 10549166080 1146883136 12.280 100 61 122 0 1250704256 90367488 13453632
05:43:25 12623 8192000000 10194489744 10604273664 1156608832 12.371 100 53 106 0 1304538624 136321888 11607904
05:44:44 14726 8192000000 10210256208 10624131072 1168296752 12.481 100 57 112 1 1326288304 145437248 12554304
05:46:04 6992 8192000000 10238015888 10650947584 1170318816 12.500 100 59 117 0 1349203488 165858960 13025712
05:47:16 5117 8192000000 10276227472 10687442944 1175642752 12.550 100 66 130 0 1380438112 190640288 14155072
05:48:26 3434 8192000000 10301993888 10722869248 1176271440 12.555 100 80 160 0 1410072240 215299280 18501520
05:49:33 2077 8192000000 10283958928 10705829888 1178458416 12.576 100 74 144 0 1379972192 185509568 16004208
05:50:40 7123 8192000000 10320343632 10723725312 1175884224 12.552 100 73 145 2 1425131056 233253744 15993088
05:51:53 6255 8192000000 10340047936 10755268608 1167922336 12.477 99 64 125 1 1427137520 244842800 14372384
05:53:05 4677 8192000000 10378430080 10783555584 1171111872 12.507 100 80 158 0 1480039344 290701984 18225488
05:54:16 1200 8192000000 10397698912 10800406528 1161882992 12.421 100 87 172 1 1496046864 313695392 20468480
05:55:20 8656 8192000000 10424017696 10845577216 1140720896 12.222 99 48 95 0 1518971792 366422256 11828640
05:56:33 4545 8192000000 10373023936 10798104576 1116944320 11.998 99 74 145 1 1490539616 356543040 17052256
05:57:40 17137 8192000000 10317468032 10752643072 1115717920 11.987 99 34 67 1 1424623968 300077296 8828752
05:59:02 5076 8192000000 10302649584 10720886784 1123546528 12.060 100 45 85 2 1405881264 271484224 10850512
06:00:10 14318 8192000000 10304422720 10789195776 1124050688 12.065 99 32 64 0 1420164064 287947328 8166048
06:01:30 16114 8192000000 10292694512 10766725120 1127977344 12.102 99 29 58 2 1391828656 256795968 7055344
06:02:49 32405 8192000000 10304377744 10788409344 1092787472 11.769 97 19 38 1 1356607360 258970208 4849680
06:04:33 28005 8192000000 10311538240 10783727616 1127459776 12.097 100 22 44 4 1426600912 293341440 5799696
06:06:02 56300 8192000000 10114112736 10581671936 1104661920 11.882 99 18 35 1 1323510416 214383280 4465216
06:08:07 65726 8192000000 10117656976 10558836736 1130813600 12.129 100 28 54 1 1224461824 87228320 6419904
06:10:18 44671 8192000000 10074869840 10542493696 1105502224 11.890 98 15 30 0 1176400160 67880480 3017456
06:12:10 70646 8192000000 10084906144 10535518208 1118585152 12.014 99 28 56 0 1159863072 35589920 5688000
06:14:25 83106 8192000000 10100863904 10545332224 1134112464 12.160 100 20 40 0 1217116000 77981552 5021984
06:16:50 42107 8192000000 10053981152 10490720256 1105592560 11.891 98 35 67 2 1151084224 38647792 6843872
06:18:36 32789 8192000000 10066885040 10517356544 1127317776 12.096 100 33 66 0 1174030400 39509360 7203264
06:20:11 57853 8192000000 10063616688 10525777920 1120754304 12.034 99 8 17 0 1162190256 39854208 1581744
06:22:20 86469 8192000000 10094223888 10544246784 1129321488 12.115 99 15 28 0 1208098080 75533200 3243392
06:25:17 47848 8192000000 10050057424 10506326016 1131551536 12.136 100 21 42 3 1168621200 32976032 4093632
06:27:07 64922 8192000000 10070655648 10535964672 1115421184 11.984 98 13 26 0 1180392512 62322848 2648480
06:29:19 49834 8192000000 10071838368 10562510848 1135999136 12.178 100 13 26 2 1186577408 47833648 2744624
06:31:37 40663 8192000000 10118632416 10572365824 1142889840 12.243 99 19 38 1 1219581312 72653440 4038032
06:33:23 35271 8192000000 10069926512 10513285120 1140335456 12.219 100 13 26 1 1179137888 35544080 3258352
06:34:58 66663 8192000000 10150614416 10634698752 1152113456 12.329 100 35 70 0 1244187472 84936288 7137728
06:37:12 39354 8192000000 10091409680 10544898048 1139639520 12.212 100 29 58 1 1191064352 45960544 5464288
06:38:52 79676 8192000000 10099581504 10593398784 1081102736 11.658 95 18 36 0 1211353280 126341440 3909104
06:41:13 31566 8192000000 10102288272 10566774784 1126548112 12.089 99 22 44 2 1212457008 81639648 4269248
06:43:07 81669 8192000000 10088957376 10625757184 1128670880 12.109 100 9 18 1 1210851296 80366192 1814224
06:45:57 86682 8192000000 10065845248 10520174592 1076671216 11.616 95 13 26 0 1133698560 53990016 3037328
06:48:26 50583 8192000000 10047091392 10555641856 1116821760 11.997 100 4 8 0 1174834192 57333968 678464
06:50:19 71510 8192000000 10102587312 10539671552 1116799200 11.997 98 20 40 3 1184210256 62691856 4719200
06:52:31 81583 8192000000 10137043184 10563809280 1110606480 11.938 97 26 52 1 1200277280 83814240 5856560
06:54:53 70264 8192000000 10077582256 10559135744 1110734000 11.939 98 12 24 0 1138596544 25216352 2646192
06:57:04 56120 8192000000 10060864160 10488934400 1098717152 11.825 98 31 62 0 1133772784 27956128 7099504
06:59:00 75752 8192000000 10041897216 10461069312 1009431856 10.970 90 24 47 0 1047224784 33131360 4661568
07:01:19 132835 8192000000 10117702736 10566844416 1121307168 12.039 100 36 72 0 1193136960 64575680 7254112
07:05:16 55624 8192000000 10047546880 10498412544 1101054160 11.848 98 12 24 0 1149857296 46482544 2320592
07:07:12 109052 8192000000 10105934336 10559303680 1134289872 12.162 100 15 30 0 1191334032 54100800 2943360
07:10:05 85975 8192000000 10120009328 10567000064 1137637328 12.193 99 19 38 1 1210699664 69543504 3518832
07:12:46 55961 8192000000 10064146496 10523004928 1100846992 11.846 96 26 52 0 1132362016 25918496 5596528
07:14:45 44390 8192000000 10159484288 10618740736 1138814848 12.204 99 32 64 2 1204795456 59446976 6533632
07:16:32 70136 8192000000 10090394080 10535862272 1132784160 12.148 99 19 38 1 1188612304 52035616 3792528
07:18:42 58911 8192000000 10156104768 10632888320 1133485680 12.154 99 19 38 0 1204384464 67181456 3717328
07:20:46 100684 8192000000 10103151408 10593300480 1124977744 12.074 98 18 36 3 1184655024 55817680 3859600
07:24:03 90691 8192000000 10062380432 10539171840 1118894768 12.017 98 12 24 1 1151664752 30415760 2354224
07:27:50 82830 8192000000 10110424576 10576322560 1109238528 11.925 97 10 20 1 1160561840 48965952 2357360
07:30:14 44538 8192000000 10088753776 10525401088 1108349040 11.917 97 29 58 0 1173426640 58799216 6278384
07:32:08 56724 8192000000 10118010384 10597220352 1135244048 12.171 99 25 50 0 1209871088 68949200 5677840
07:34:23 54892 8192000000 10099094288 10543480832 1123810256 12.063 99 21 42 0 1181453504 53263760 4379488
07:36:19 60471 8192000000 9864808576 10273533952 914464144 10.041 80 24 46 2 936842944 16779552 5599248

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 25-02-2021 07:38:08 to 25-02-2021 07:38:23.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 92 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 25-02-2021 07:38:24 to 25-02-2021 07:38:26.

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          225510
MON$OLDEST_ACTIVE               226281
MON$OLDEST_SNAPSHOT             226281
MON$NEXT_TRANSACTION            226281
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                       2098944
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             782
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2374 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2374 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2374 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.2374 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Thu Feb 25 07:37:22 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Thu Feb 25 07:37:25 2021
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
--- end of diff output ---

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

25.02.21 07:38:27. 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-25 07:38:29 No severe PSQL-related problems occured
25.02.21 07:38:31. End of report.