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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 16748032 kB
MemAvailable 31432024 kB
Buffers 20 kB
Cached 14824192 kB
SwapCached 0 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.0
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-03-27 04:26:57
DTS_END                         2021-03-27 04:26:57.1560
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/39398
TRN_ID                          219971
ATT_ID                          593
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: 8699.33 25410 1043920
customer order: creation 151.16 424 18139
customer order: refuse 13.48 319 1617
order to supplier: creation 41.17 1492 4940
order to supplier: removal 3.13 128056 375
invoice (draft): creation 41.33 3720 4959
invoice (draft): removal 50.99 896 6119
invoice accept: apply 38.24 16293 4589
invoice accept: cancel 45.90 801 5508
customer reserve: creation 3576.36 156 429163
customer reserve: removal 4465.87 71 535904
realization accept: apply 74.25 155 8910
realization accept: cancel 84.11 112 10093
payment from customer: creation 52.04 110 6245
payment from customer: removal 5.00 64 600
payment to supplier: creation 43.36 116 5203
payment to supplier: removal 3.24 55 389
service: total inventory turnovers 3.48 3295 417
service: total monetary turnovers 5.47 481 656
service: aggregate perf. data 0.75 16413 90
service: refresh index statistics 0.03 335176 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-03-2021 04:28:07 to 27-03-2021 04:28:09.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2766
TEST_TIME 2 3929
TEST_TIME 3 4534
TEST_TIME 4 4935
TEST_TIME 5 4297
TEST_TIME 6 4600
TEST_TIME 7 4299
TEST_TIME 8 4289
TEST_TIME 9 4703
TEST_TIME 10 4736
TEST_TIME 11 4974
TEST_TIME 12 5167
TEST_TIME 13 5803
TEST_TIME 14 6032
TEST_TIME 15 6223
TEST_TIME 16 6234
TEST_TIME 17 6407
TEST_TIME 18 6907
TEST_TIME 19 7196
TEST_TIME 20 7347
TEST_TIME 21 8231
TEST_TIME 22 8429
TEST_TIME 23 8750
TEST_TIME 24 9137
TEST_TIME 25 9192
TEST_TIME 26 10201
TEST_TIME 27 11145
TEST_TIME 28 10851
TEST_TIME 29 10547
TEST_TIME 30 10261
TEST_TIME 31 10517
TEST_TIME 32 10411
TEST_TIME 33 10205
TEST_TIME 34 10008
TEST_TIME 35 9825
TEST_TIME 36 9752
TEST_TIME 37 9685
TEST_TIME 38 9904
TEST_TIME 39 9723
TEST_TIME 40 9597
TEST_TIME 41 9494
TEST_TIME 42 9599
TEST_TIME 43 9514
TEST_TIME 44 9545
TEST_TIME 45 9522
TEST_TIME 46 9484
TEST_TIME 47 9413
TEST_TIME 48 9426
TEST_TIME 49 9430
TEST_TIME 50 9567
TEST_TIME 51 9507
TEST_TIME 52 9427
TEST_TIME 53 9466
TEST_TIME 54 9512
TEST_TIME 55 9531
TEST_TIME 56 9367
TEST_TIME 57 9228
TEST_TIME 58 9246
TEST_TIME 59 9123
TEST_TIME 60 9123
TEST_TIME 61 9017
TEST_TIME 62 9005
TEST_TIME 63 8955
TEST_TIME 64 8967
TEST_TIME 65 8957
TEST_TIME 66 8970
TEST_TIME 67 8919
TEST_TIME 68 8961
TEST_TIME 69 8957
TEST_TIME 70 9133
TEST_TIME 71 9160
TEST_TIME 72 9204
TEST_TIME 73 9252
TEST_TIME 74 9218
TEST_TIME 75 9162
TEST_TIME 76 9219
TEST_TIME 77 9130
TEST_TIME 78 9049
TEST_TIME 79 9002
TEST_TIME 80 8926
TEST_TIME 81 8851
TEST_TIME 82 8835
TEST_TIME 83 8817
TEST_TIME 84 8843
TEST_TIME 85 8811
TEST_TIME 86 8791
TEST_TIME 87 8794
TEST_TIME 88 8777
TEST_TIME 89 8910
TEST_TIME 90 8936
TEST_TIME 91 8925
TEST_TIME 92 8943
TEST_TIME 93 8961
TEST_TIME 94 9017
TEST_TIME 95 8976
TEST_TIME 96 8924
TEST_TIME 97 8874
TEST_TIME 98 8858
TEST_TIME 99 8825
TEST_TIME 100 8837
TEST_TIME 101 8825
TEST_TIME 102 8786
TEST_TIME 103 8764
TEST_TIME 104 8757
TEST_TIME 105 8760
TEST_TIME 106 8774
TEST_TIME 107 8791
TEST_TIME 108 8808
TEST_TIME 109 8801
TEST_TIME 110 8935
TEST_TIME 111 8924
TEST_TIME 112 8915
TEST_TIME 113 8868
TEST_TIME 114 8818
TEST_TIME 115 8778
TEST_TIME 116 8741
TEST_TIME 117 8717
TEST_TIME 118 8725
TEST_TIME 119 8732
TEST_TIME 120 8701

Performance per minute, chart


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

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 1440371 1440371 0 0.00 0 18879 6 0 0
doc_list_biud 1440369 1440369 0 0.00 0 48980 33 0 0
sp_add_invoice_to_stock 4704 4589 115 2.44 27 191080 16293 0 115
sp_cancel_adding_invoice 5508 5508 0 0.00 6 76171 802 0 0
sp_cancel_client_order 1617 1617 0 0.00 5 12206 320 0 0
sp_cancel_customer_reserve 535904 535904 0 0.00 1 49006 72 0 0
sp_cancel_pay_from_customer 600 600 0 0.00 2 948 65 0 0
sp_cancel_pay_to_supplier 389 389 0 0.00 3 1656 56 0 0
sp_cancel_supplier_invoice 6131 6119 12 0.19 3 19102 897 0 12
sp_cancel_supplier_order 375 375 0 0.00 8 1253534 128057 0 0
sp_cancel_write_off 10093 10093 0 0.00 1 13497 112 0 0
sp_client_order 18139 18139 0 0.00 4 67176 424 0 0
sp_customer_reserve 429163 429163 0 0.00 0 53032 156 0 0
sp_fill_shopping_cart 28027 28027 0 0.00 1 14338 50 0 0
sp_fill_shopping_cart_clo_res 429159 429159 0 0.00 0 21724 19 0 0
sp_get_clo_for_invoice 4585 4585 0 0.00 0 45487 545 0 0
sp_kill_cost_storno 16590 16590 0 0.00 0 13464 53 0 0
sp_kill_qstorno_ret_qs2qd 1088395 1088395 0 0.00 0 48570 38 0 0
sp_kill_qty_storno 573110 573110 0 0.00 0 48980 82 0 0
sp_lock_selected_doc 18719 18719 0 0.00 0 7485 4 0 0
sp_make_cost_storno 24942 24942 0 0.00 0 14644 48 0 0
sp_multiply_rows_for_pdistr 24942 24942 0 0.00 0 10819 31 0 0
sp_multiply_rows_for_qdistr 18139 18139 0 0.00 0 67166 260 0 0
sp_pay_from_customer 6245 6245 0 0.00 4 10831 111 0 0
sp_pay_to_supplier 5203 5203 0 0.00 5 7612 116 0 0
sp_payment_common 11448 11448 0 0.00 1 5715 22 0 0
sp_qd_handle_on_cancel_clo 1617 1617 0 0.00 0 12199 293 0 0
sp_qd_handle_on_invoice_upd_sts 10092 10092 0 0.00 0 26095 371 0 0
sp_qd_handle_on_reserve_upd_sts 8910 8910 0 0.00 0 10718 47 0 0
sp_reserve_write_off 8910 8910 0 0.00 7 14669 155 0 0
sp_supplier_invoice 4959 4959 0 0.00 5 83521 3721 0 0
sp_supplier_order 4940 4940 0 0.00 5 72829 1493 0 0
srv_aggregate_perf_data 90 90 0 0.00 2358 164777 16414 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 1301 168612 51947 0 0
srv_find_qd_qs_mism 1000529 1000529 0 0.00 0 18877 4 0 0
srv_make_invnt_saldo 417 417 0 0.00 48 106288 3296 0 0
srv_make_money_saldo 656 656 0 0.00 1 20135 482 0 0
srv_recalc_idx_stat 4 4 0 0.00 167731 561647 335176 0 0
t$perf-abend:sp_add_invoice_to_stock 115 115 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4589 4589 0 0.00 0 17399 164 0 0
t$perf-norm:sp_cancel_adding_invoice 374 374 0 0.00 0 1995 95 0 0
t$perf-norm:sp_cancel_client_order 1617 1617 0 0.00 0 5221 19 0 0
t$perf-norm:sp_cancel_customer_reserve 586 586 0 0.00 0 388 4 0 0
t$perf-norm:sp_cancel_pay_from_customer 600 600 0 0.00 0 81 1 0 0
t$perf-norm:sp_cancel_pay_to_supplier 389 389 0 0.00 0 2448 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 369 369 0 0.00 0 2911 26 0 0
t$perf-norm:sp_cancel_supplier_order 375 375 0 0.00 0 19394 1465 0 0
t$perf-norm:sp_cancel_write_off 915 915 0 0.00 0 737 3 0 0
t$perf-norm:sp_client_order 18139 18139 0 0.00 0 8635 18 0 0
t$perf-norm:sp_customer_reserve 899 899 0 0.00 0 16604 27 0 0
t$perf-norm:sp_pay_from_customer 6245 6245 0 0.00 0 4554 9 0 0
t$perf-norm:sp_pay_to_supplier 5203 5203 0 0.00 0 5656 9 0 0
t$perf-norm:sp_reserve_write_off 8910 8910 0 0.00 0 6031 11 0 0
t$perf-norm:sp_supplier_invoice 4959 4959 0 0.00 0 17737 26 0 0
t$perf-norm:sp_supplier_order 4940 4940 0 0.00 0 1898 12 0 0
t$perf-norm:srv_aggregate_perf_data 90 90 0 0.00 0 271 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 632 11 0 0
t$perf-norm:srv_make_invnt_saldo 417 417 0 0.00 0 3012 17 0 0
t$perf-norm:srv_make_money_saldo 656 656 0 0.00 0 281 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 9398 9168 230 2.44 0 5948 16 0 230
v_all_customers 32878 32878 0 0.00 0 17 1 0 0
v_all_suppliers 27536 27536 0 0.00 0 15 1 0 0
v_all_wares 36276 36276 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 748 748 0 0.00 0 3094 13 0 0
v_cancel_client_order 3234 3234 0 0.00 0 2869 4 0 0
v_cancel_customer_prepayment 1200 1200 0 0.00 0 460 5 0 0
v_cancel_customer_reserve 1172 1172 0 0.00 0 1482 4 0 0
v_cancel_payment_to_supplier 778 778 0 0.00 0 647 5 0 0
v_cancel_supplier_invoice 762 738 24 3.14 0 1758 15 0 24
v_cancel_supplier_order 750 750 0 0.00 0 171 3 0 0
v_cancel_write_off 1830 1830 0 0.00 0 1255 14 0 0
v_max_id_clo_ord 4940 4940 0 0.00 0 4163 2 0 0
v_max_id_clo_res 899 899 0 0.00 0 2033 15 0 0
v_max_id_ord_sup 4949 4949 0 0.00 0 6282 5 0 0
v_max_non_paid_invoice 1324 1324 0 0.00 0 937 3 0 0
v_max_non_paid_realizn 3382 3382 0 0.00 0 2252 4 0 0
v_min_id_clo_ord 4940 4940 0 0.00 0 4496 11 0 0
v_min_id_clo_res 899 899 0 0.00 0 3195 10 0 0
v_min_id_ord_sup 4949 4949 0 0.00 0 5896 11 0 0
v_min_non_paid_invoice 5203 5203 0 0.00 0 4695 12 0 0
v_min_non_paid_realizn 6245 6245 0 0.00 0 5666 18 0 0
v_our_firm 9124 9124 0 0.00 0 19 1 0 0
v_reserve_write_off 17820 17820 0 0.00 0 6699 5 0 0
x_lock_depdocs_on_canc_invoice 11258 11258 0 0.00 0 14476 113 0 0
x_lock_depdocs_on_canc_sup_ord 375 375 0 0.00 0 7383 388 0 0
x_make_qstorno_1000_1200 4940 4940 0 0.00 2 71016 1411 0 0
x_make_qstorno_1200_2000 4959 4959 0 0.00 2 81738 3527 0 0
x_make_qstorno_2100_3300 429163 429163 0 0.00 0 53031 135 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-03-2021 04:28:15 to 27-03-2021 04:28:19.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
01:57:00 2 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
01:58:01 24785 8192000000 9723440656 10023288832 800930064 8.906 100 83 166 0 829085520 7970208 20185248
01:59:30 22324 8192000000 9768755664 10076405760 842246816 9.322 100 93 186 0 876538272 11985472 22305984
02:01:02 19375 8192000000 9802621280 10120175616 867140352 9.571 100 84 167 1 908318208 21287088 19890768
02:02:27 14306 8192000000 9868832928 10201378816 925847520 10.154 100 83 168 0 977516704 33336800 18332384
02:03:43 7863 8192000000 9915449904 10261106688 966818816 10.556 100 77 155 0 1029336656 45473760 17044080
02:04:54 4417 8192000000 9965736752 10317119488 1003393232 10.911 100 86 171 2 1074446816 53123232 17930352
02:06:02 3242 8192000000 10009441856 10372325376 1028626016 11.155 100 81 161 2 1119353488 74461536 16265936
02:07:07 1954 8192000000 10018541216 10387927040 1053609680 11.395 100 90 179 1 1140138288 68537232 17991376
02:08:10 2811 8192000000 10032845312 10408304640 1064425888 11.499 100 84 167 2 1144178688 62571296 17181504
02:09:14 3708 8192000000 9998985168 10393800704 1075447936 11.604 100 43 85 2 1118210672 33876768 8885968
02:10:21 40411 8192000000 9989537200 10391568384 1082323696 11.670 100 41 81 1 1106429184 14901888 9203600
02:12:01 6399 8192000000 10001966864 10407878656 1088833040 11.732 100 25 51 1 1117015552 22694048 5488464
02:13:08 6129 8192000000 10005813120 10408714240 1091212512 11.754 100 27 54 0 1128056608 30931136 5912960
02:14:15 6686 8192000000 10008790816 10412425216 1097643216 11.815 100 32 61 4 1125426384 21507136 6276032
02:15:24 24668 8192000000 10005435360 10418999296 1097365680 11.813 100 38 76 3 1119281488 13703936 8211872
02:16:51 3312 8192000000 10028800128 10430664704 1093858608 11.779 100 49 96 0 1161353408 56444096 11050704
02:17:55 6225 8192000000 10011273152 10397532160 1080118368 11.649 100 45 91 1 1109672288 18817424 10736496
02:19:03 11633 8192000000 9957102560 10352783360 989857504 10.780 94 32 64 0 1009603872 12149952 7596416
02:20:16 14301 8192000000 9927802560 10318041088 972418368 10.610 94 30 58 2 1017785152 39940816 5425968
02:21:30 9855 8192000000 9937360336 10328211456 1002635008 10.904 98 42 84 0 1026600048 13512048 10452992
02:22:41 10182 8192000000 9975809584 10372661248 1040267360 11.267 100 34 68 0 1067632192 19719024 7645808
02:23:54 61388 8192000000 9972762672 10376007680 1045170608 11.314 99 44 88 0 1072073568 17794896 9108064
02:25:59 3638 8192000000 9993200768 10378219520 1066816848 11.522 100 48 96 1 1098459712 19571136 12071728
02:27:06 7678 8192000000 10006603376 10398617600 1087600656 11.720 100 41 80 3 1118194880 22239456 8354768
02:28:37 7457 8192000000 10055607136 10455396352 1112337024 11.955 100 59 119 0 1168479104 43915776 12226304
02:30:00 4739 8192000000 10082749376 10485710848 1126870720 12.092 100 58 114 1 1192715232 53551200 12293312
02:31:07 30171 8192000000 10088416944 10500808704 1123826192 12.063 99 47 93 2 1191294896 57679072 9789632
02:32:44 20602 8192000000 10125280928 10542354432 1145397728 12.266 100 31 62 0 1248627328 96472368 6757232
02:34:12 19075 8192000000 10201435488 10616803328 1164018432 12.441 100 43 84 0 1311031440 137299968 9713040
02:35:35 5711 8192000000 10255787664 10672762880 1181301120 12.602 100 66 132 0 1356480688 160835232 14344336
02:36:44 8275 8192000000 10319354960 10733867008 1190012848 12.683 100 65 128 1 1427315952 222373760 14929344
02:38:10 4900 8192000000 10358579936 10776256512 1182757104 12.616 99 60 120 2 1454415920 257528048 14130768
02:39:25 7443 8192000000 10348549440 10789236736 1192338592 12.705 100 54 108 1 1454985168 249484368 13162208
02:40:43 3251 8192000000 10400332944 10809483264 1206688704 12.838 100 92 181 0 1496858112 269493616 20675792
02:41:49 1299 8192000000 10482056800 10889621504 1206522592 12.837 100 81 162 0 1578634480 353578720 18533168
02:42:54 5259 8192000000 10490167872 10918461440 1190915200 12.692 99 68 133 3 1589236176 381778784 16542192
02:44:05 2981 8192000000 10528365360 10939457536 1188391264 12.668 100 87 174 0 1621802848 413454368 19957216
02:45:11 6077 8192000000 10515337200 10946154496 1183776240 12.625 100 64 128 0 1615537648 416186656 15574752
02:46:20 12086 8192000000 10506794336 10948538368 1162913168 12.431 100 44 85 4 1622754432 448389984 11451280
02:47:38 6193 8192000000 10503422736 10975076352 1166334384 12.463 100 43 86 0 1603611120 425399632 11877104
02:48:57 28717 8192000000 10414289312 10853171200 1156783712 12.373 100 31 60 1 1520542816 355692880 8066224
02:50:31 19836 8192000000 10386136592 10853363712 1145724976 12.269 100 24 48 2 1491945328 339648688 6571664
02:51:52 32368 8192000000 10293192896 10797912064 1088778336 11.731 97 23 46 1 1460440464 365513136 6148992
02:53:29 165138 8192000000 10085664736 10519871488 1074790368 11.598 96 24 48 0 1129378656 49261616 5326672
02:57:25 34216 8192000000 10086209760 10613727232 1121976640 12.046 100 16 32 0 1231508800 106121600 3410560
02:59:15 168612 8192000000 10136882624 10586644480 1106183040 11.896 97 22 44 0 1187188000 76890208 4114752
03:03:04 58377 8192000000 10135159328 10570350592 1108732016 11.920 98 16 32 0 1246576096 134078480 3765600
03:06:07 76382 8192000000 10064920048 10530426880 1127428304 12.097 100 12 25 1 1155061312 25254896 2378112
03:08:29 80505 8192000000 10087320768 10557440000 1128453808 12.107 99 13 26 1 1174116704 42921680 2741216
03:10:50 94836 8192000000 10067202384 10532978688 1106067664 11.895 97 15 30 0 1141673264 32564976 3040624
03:13:39 50054 8192000000 10061976592 10505113600 1109359712 11.926 98 28 55 0 1150547680 35717344 5470624
03:15:30 97752 8192000000 10092925312 10618097664 1137157760 12.189 100 12 24 0 1224857328 85405152 2294416
03:19:05 100843 8192000000 10096963600 10553122816 1117993168 12.008 98 15 30 1 1212657664 91399888 3264608
03:21:46 127085 8192000000 10084139648 10536579072 1128238336 12.105 99 15 29 1 1194324112 63119680 2966096
03:24:55 19743 8192000000 10059445808 10524827648 1128617152 12.108 99 4 8 0 1168714176 39001264 1095760
03:26:15 60249 8192000000 10058103872 10543693824 1104401488 11.879 97 7 14 0 1129501904 23592288 1508128
03:28:32 71828 8192000000 10049978960 10519007232 1119990976 12.027 98 15 30 0 1148093776 25305216 2797584
03:30:44 46209 8192000000 10107211744 10574532608 1151968672 12.328 100 19 38 0 1194748224 39229408 3550144
03:32:31 48492 8192000000 10129436544 10574827520 1127928048 12.102 98 17 34 0 1180326720 48986992 3411680
03:34:20 59896 8192000000 10169929568 10638761984 1124926992 12.074 98 18 36 1 1213396464 84533344 3936128
03:36:29 80003 8192000000 10061719392 10515042304 1096126752 11.801 96 12 22 1 1192235008 93522912 2585344
03:38:49 103632 8192000000 10080630224 10522259456 1113374432 11.964 98 14 28 0 1164766112 48513008 2878672
03:41:34 38344 8192000000 10093546144 10554408960 1138229488 12.199 100 36 72 0 1202439520 57007216 7202816
03:43:17 63103 8192000000 10067374480 10532737024 1130800112 12.129 100 15 30 0 1173967488 40102064 3065312
03:45:42 50440 8192000000 10056999184 10523848704 1115759888 11.987 98 6 12 0 1152965920 36160560 1045472
03:47:39 68998 8192000000 10074889232 10550677504 1071010128 11.562 94 17 34 0 1143019968 68164384 3845456
03:49:49 53747 8192000000 10038565296 10541232128 1069413168 11.546 96 10 20 0 1117098928 45413872 2271888
03:51:52 27025 8192000000 10082069056 10516594688 1096839488 11.808 98 27 53 0 1152603152 50611152 5152512
03:53:19 48413 8192000000 10111267872 10550173696 1105155376 11.887 98 23 46 0 1183344992 73145552 5044064
03:55:14 70051 8192000000 10086092560 10551521280 1079040704 11.638 96 26 52 0 1158586640 73534528 6011408
03:57:29 54479 8192000000 10086351328 10584580096 1104960304 11.885 99 14 28 0 1173060528 65347856 2752368
03:59:28 90579 8192000000 10064436368 10548363264 1086784496 11.712 96 9 18 0 1153446256 65132896 1528864
04:02:07 79675 8192000000 10085085264 10534821888 1125747984 12.081 100 25 50 0 1197675184 66707968 5219232
04:04:37 63936 8192000000 10066578432 10562170880 1120612480 12.033 100 5 10 0 1161196000 39811184 772336
04:06:48 67123 8192000000 10070660528 10509758464 1107398080 11.908 98 9 18 1 1135970320 26610448 1961792
04:09:01 39013 8192000000 10066323200 10506260480 1081096256 11.658 96 19 38 1 1130414256 45439552 3878448
04:10:54 83249 8192000000 10114555344 10554683392 1097663104 11.815 97 18 36 0 1168580096 67609104 3307888
04:13:17 50455 8192000000 10131226192 10572849152 1121793664 12.044 99 24 48 2 1205961296 78988832 5178800
04:15:18 115652 8192000000 10088687232 10622300160 1120267136 12.030 99 13 26 0 1195055536 72222176 2566224
04:18:14 132501 8192000000 10038891776 10498293760 1118175536 12.010 99 15 30 1 1172946832 51658096 3113200
04:21:29 67028 8192000000 10053026688 10510860288 1127600176 12.099 100 20 39 1 1158287168 26856384 3830608
04:23:41 29811 8192000000 10074035936 10544320512 1113793488 11.968 99 4 8 1 1169864512 54888736 1182288
04:25:11 45137 8192000000 10051525488 10568175616 1100000608 11.838 98 10 20 1 1143464016 41401520 2061888

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 27-03-2021 04:28:21 to 27-03-2021 04:28:35.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 115 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

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

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          219043
MON$OLDEST_ACTIVE               220360
MON$OLDEST_SNAPSHOT             220360
MON$NEXT_TRANSACTION            220360
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                       2097280
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             774
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2390 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2390 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2390 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.2390 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 ---
:389: 
:390: mscs-test-161.budzdorov.ru    Sat Mar 27 04:26:59 2021
:391:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:392: 
:393: 
:394: mscs-test-161.budzdorov.ru    Sat Mar 27 04:27:01 2021
:395:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:396: 
--- end of diff output ---

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

27.03.21 04:28:39. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-03-27 04:28:42 No severe PSQL-related problems occured
27.03.21 04:28:43. End of report.