Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 01.01.2021 04: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 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 16694688 kB
MemAvailable 31327172 kB
Buffers 20 kB
Cached 14865028 kB
SwapCached 4844 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-01-01 04:26:55
DTS_END                         2021-01-01 04:26:55.0840
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_TO_SUPPLIER(26:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/46164
TRN_ID                          222111
ATT_ID                          600
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: 8776.00 25346 1053120
customer order: creation 160.63 427 19275
customer order: refuse 13.57 413 1628
order to supplier: creation 42.20 1417 5064
order to supplier: removal 3.30 156089 396
invoice (draft): creation 42.83 3879 5140
invoice (draft): removal 51.60 1043 6192
invoice accept: apply 38.58 19318 4630
invoice accept: cancel 46.53 1133 5584
customer reserve: creation 3571.76 185 428611
customer reserve: removal 4529.02 90 543482
realization accept: apply 79.18 211 9502
realization accept: cancel 76.89 161 9227
payment from customer: creation 54.87 121 6584
payment from customer: removal 5.33 112 640
payment to supplier: creation 46.16 158 5539
payment to supplier: removal 3.46 110 415
service: total inventory turnovers 3.50 4338 420
service: total monetary turnovers 5.83 716 699
service: aggregate perf. data 0.74 20863 89
service: refresh index statistics 0.03 296129 3

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 01-01-2021 04:29:11 to 01-01-2021 04:29:13.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3747
TEST_TIME 2 2886
TEST_TIME 3 3743
TEST_TIME 4 3808
TEST_TIME 5 3886
TEST_TIME 6 3995
TEST_TIME 7 4461
TEST_TIME 8 4788
TEST_TIME 9 5034
TEST_TIME 10 5546
TEST_TIME 11 5536
TEST_TIME 12 5647
TEST_TIME 13 5917
TEST_TIME 14 6069
TEST_TIME 15 6266
TEST_TIME 16 6454
TEST_TIME 17 6582
TEST_TIME 18 6776
TEST_TIME 19 6779
TEST_TIME 20 6864
TEST_TIME 21 7296
TEST_TIME 22 7586
TEST_TIME 23 7631
TEST_TIME 24 7651
TEST_TIME 25 8134
TEST_TIME 26 8240
TEST_TIME 27 8450
TEST_TIME 28 8535
TEST_TIME 29 8738
TEST_TIME 30 8819
TEST_TIME 31 9325
TEST_TIME 32 10188
TEST_TIME 33 10871
TEST_TIME 34 10838
TEST_TIME 35 10966
TEST_TIME 36 10731
TEST_TIME 37 10519
TEST_TIME 38 10403
TEST_TIME 39 10365
TEST_TIME 40 10212
TEST_TIME 41 10148
TEST_TIME 42 10096
TEST_TIME 43 9989
TEST_TIME 44 9903
TEST_TIME 45 9802
TEST_TIME 46 9734
TEST_TIME 47 9752
TEST_TIME 48 9631
TEST_TIME 49 9562
TEST_TIME 50 9397
TEST_TIME 51 9660
TEST_TIME 52 9641
TEST_TIME 53 9593
TEST_TIME 54 9493
TEST_TIME 55 9650
TEST_TIME 56 9649
TEST_TIME 57 9551
TEST_TIME 58 9577
TEST_TIME 59 9542
TEST_TIME 60 9664
TEST_TIME 61 9609
TEST_TIME 62 9583
TEST_TIME 63 9566
TEST_TIME 64 9430
TEST_TIME 65 9345
TEST_TIME 66 9257
TEST_TIME 67 9189
TEST_TIME 68 9201
TEST_TIME 69 9171
TEST_TIME 70 9120
TEST_TIME 71 9187
TEST_TIME 72 9125
TEST_TIME 73 9114
TEST_TIME 74 9094
TEST_TIME 75 9091
TEST_TIME 76 9053
TEST_TIME 77 9116
TEST_TIME 78 9084
TEST_TIME 79 9110
TEST_TIME 80 9158
TEST_TIME 81 9152
TEST_TIME 82 9065
TEST_TIME 83 8970
TEST_TIME 84 9017
TEST_TIME 85 8960
TEST_TIME 86 8933
TEST_TIME 87 8881
TEST_TIME 88 8949
TEST_TIME 89 8905
TEST_TIME 90 8912
TEST_TIME 91 8957
TEST_TIME 92 8965
TEST_TIME 93 8975
TEST_TIME 94 8978
TEST_TIME 95 9013
TEST_TIME 96 8988
TEST_TIME 97 9074
TEST_TIME 98 9083
TEST_TIME 99 9066
TEST_TIME 100 8986
TEST_TIME 101 8932
TEST_TIME 102 8885
TEST_TIME 103 8925
TEST_TIME 104 8879
TEST_TIME 105 8850
TEST_TIME 106 8843
TEST_TIME 107 8840
TEST_TIME 108 8837
TEST_TIME 109 8818
TEST_TIME 110 8848
TEST_TIME 111 8910
TEST_TIME 112 8896
TEST_TIME 113 8895
TEST_TIME 114 8941
TEST_TIME 115 8967
TEST_TIME 116 8960
TEST_TIME 117 8898
TEST_TIME 118 8864
TEST_TIME 119 8815
TEST_TIME 120 8782

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 01-01-2021 04:29:15 to 01-01-2021 04:29:18.

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 1450719 1450719 0 0.00 0 22694 8 0 0
doc_list_biud 1450718 1450718 0 0.00 0 71871 41 0 0
sp_add_invoice_to_stock 4709 4630 79 1.67 28 199312 19319 0 79
sp_cancel_adding_invoice 5584 5584 0 0.00 4 81304 1133 0 0
sp_cancel_client_order 1628 1628 0 0.00 5 40291 413 0 0
sp_cancel_customer_reserve 543482 543482 0 0.00 1 71872 90 0 0
sp_cancel_pay_from_customer 640 640 0 0.00 3 5440 113 0 0
sp_cancel_pay_to_supplier 415 415 0 0.00 4 6642 110 0 0
sp_cancel_supplier_invoice 6202 6192 10 0.16 2 42830 1043 0 10
sp_cancel_supplier_order 396 396 0 0.00 5 1517358 156090 0 0
sp_cancel_write_off 9227 9227 0 0.00 1 9206 162 0 0
sp_client_order 19275 19275 0 0.00 4 39844 427 0 0
sp_customer_reserve 428611 428611 0 0.00 0 152868 186 0 0
sp_fill_shopping_cart 29460 29460 0 0.00 1 24515 51 0 0
sp_fill_shopping_cart_clo_res 428601 428601 0 0.00 0 24903 19 0 0
sp_get_clo_for_invoice 4620 4620 0 0.00 0 56072 517 0 0
sp_kill_cost_storno 15866 15866 0 0.00 0 8383 88 0 0
sp_kill_qstorno_ret_qs2qd 1102779 1102779 0 0.00 0 71869 48 0 0
sp_kill_qty_storno 580631 580631 0 0.00 0 71871 102 0 0
sp_lock_selected_doc 19509 19509 0 0.00 0 8319 5 0 0
sp_make_cost_storno 26245 26245 0 0.00 0 10383 64 0 0
sp_multiply_rows_for_pdistr 26245 26245 0 0.00 0 9392 42 0 0
sp_multiply_rows_for_qdistr 19275 19275 0 0.00 0 20105 235 0 0
sp_pay_from_customer 6584 6584 0 0.00 4 13949 122 0 0
sp_pay_to_supplier 5539 5539 0 0.00 5 14106 159 0 0
sp_payment_common 12123 12123 0 0.00 1 7205 26 0 0
sp_qd_handle_on_cancel_clo 1628 1628 0 0.00 0 40282 387 0 0
sp_qd_handle_on_invoice_upd_sts 10204 10204 0 0.00 0 35614 478 0 0
sp_qd_handle_on_reserve_upd_sts 9502 9502 0 0.00 0 21674 62 0 0
sp_reserve_write_off 9502 9502 0 0.00 7 21765 211 0 0
sp_supplier_invoice 5140 5140 0 0.00 8 88170 3880 0 0
sp_supplier_order 5064 5064 0 0.00 5 54092 1417 0 0
srv_aggregate_perf_data 89 89 0 0.00 2600 361991 20863 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1298 185411 46499 0 0
srv_find_qd_qs_mism 1009099 1009099 0 0.00 0 22693 6 0 0
srv_make_invnt_saldo 420 420 0 0.00 75 128973 4338 0 0
srv_make_money_saldo 699 699 0 0.00 1 16809 717 0 0
srv_recalc_idx_stat 3 3 0 0.00 155207 374379 296129 0 0
t$perf-abend:sp_add_invoice_to_stock 79 79 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4630 4630 0 0.00 0 13828 218 0 0
t$perf-norm:sp_cancel_adding_invoice 420 420 0 0.00 0 3975 99 0 0
t$perf-norm:sp_cancel_client_order 1628 1628 0 0.00 0 2364 15 0 0
t$perf-norm:sp_cancel_customer_reserve 598 598 0 0.00 0 419 6 0 0
t$perf-norm:sp_cancel_pay_from_customer 640 640 0 0.00 0 1501 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 415 415 0 0.00 0 895 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 400 400 0 0.00 0 834 15 0 0
t$perf-norm:sp_cancel_supplier_order 396 396 0 0.00 0 31421 1974 0 0
t$perf-norm:sp_cancel_write_off 890 890 0 0.00 0 4377 20 0 0
t$perf-norm:sp_client_order 19275 19275 0 0.00 0 8187 23 0 0
t$perf-norm:sp_customer_reserve 938 938 0 0.00 0 2357 10 0 0
t$perf-norm:sp_pay_from_customer 6584 6584 0 0.00 0 5638 13 0 0
t$perf-norm:sp_pay_to_supplier 5539 5539 0 0.00 0 7875 13 0 0
t$perf-norm:sp_reserve_write_off 9502 9502 0 0.00 0 8612 12 0 0
t$perf-norm:sp_supplier_invoice 5140 5140 0 0.00 0 5262 21 0 0
t$perf-norm:sp_supplier_order 5064 5064 0 0.00 0 4218 18 0 0
t$perf-norm:srv_aggregate_perf_data 89 89 0 0.00 0 388 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 84 2 0 0
t$perf-norm:srv_make_invnt_saldo 420 420 0 0.00 0 1663 13 0 0
t$perf-norm:srv_make_money_saldo 699 699 0 0.00 0 1421 10 0 0
t$perf-norm:srv_recalc_idx_stat 3 3 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 9396 9238 158 1.68 0 5548 18 0 158
v_all_customers 35576 35576 0 0.00 0 22 1 0 0
v_all_suppliers 28536 28536 0 0.00 0 56 1 0 0
v_all_wares 38550 38550 0 0.00 0 51 1 0 0
v_cancel_adding_invoice 840 840 0 0.00 0 838 9 0 0
v_cancel_client_order 3256 3256 0 0.00 0 788 3 0 0
v_cancel_customer_prepayment 1280 1280 0 0.00 0 4194 9 0 0
v_cancel_customer_reserve 1196 1196 0 0.00 0 5153 13 0 0
v_cancel_payment_to_supplier 830 830 0 0.00 0 3532 14 0 0
v_cancel_supplier_invoice 820 800 20 2.43 0 1609 17 0 20
v_cancel_supplier_order 792 792 0 0.00 0 2236 9 0 0
v_cancel_write_off 1780 1780 0 0.00 0 3663 16 0 0
v_max_id_clo_ord 5058 5058 0 0.00 0 2327 2 0 0
v_max_id_clo_res 938 938 0 0.00 0 1968 15 0 0
v_max_id_ord_sup 5126 5126 0 0.00 0 6649 9 0 0
v_max_non_paid_invoice 1455 1455 0 0.00 0 1161 4 0 0
v_max_non_paid_realizn 3381 3381 0 0.00 0 3573 4 0 0
v_min_id_clo_ord 5058 5058 0 0.00 0 3187 9 0 0
v_min_id_clo_res 938 938 0 0.00 0 12835 37 0 0
v_min_id_ord_sup 5126 5126 0 0.00 0 12078 14 0 0
v_min_non_paid_invoice 5539 5539 0 0.00 0 7196 21 0 0
v_min_non_paid_realizn 6584 6584 0 0.00 0 5199 17 0 0
v_our_firm 9380 9380 0 0.00 0 13 1 0 0
v_reserve_write_off 19004 19004 0 0.00 0 5703 7 0 0
x_lock_depdocs_on_canc_invoice 11376 11376 0 0.00 0 35833 144 0 0
x_lock_depdocs_on_canc_sup_ord 396 396 0 0.00 0 6908 431 0 0
x_make_qstorno_1000_1200 5064 5064 0 0.00 2 54084 1320 0 0
x_make_qstorno_1200_2000 5140 5140 0 0.00 3 76286 3684 0 0
x_make_qstorno_2100_3300 428611 428611 0 0.00 0 152866 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 01-01-2021 04:29:19 to 01-01-2021 04:29:23.

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:56:58 2 8192000000 8796637808 8807108608 3167696 0.038 1 1 2 0 3265392 61840 35856
01:58:00 17912 8192000000 9707370240 10003111936 790504560 8.800 100 82 164 0 818365872 7607200 20254112
01:59:22 9473 8192000000 9753608960 10060193792 833844800 9.238 100 83 167 0 864118384 10646928 19626656
02:00:44 12648 8192000000 9792016576 10104651776 861843616 9.519 100 89 178 0 899067632 16757120 20466896
02:02:00 14598 8192000000 9849506624 10172780544 895234496 9.851 100 81 162 0 948789216 35665584 17889136
02:03:17 15060 8192000000 9893243472 10227896320 933324528 10.227 100 80 161 1 996640304 46345664 16970112
02:04:38 7924 8192000000 9935725552 10278977536 967170672 10.559 100 91 182 1 1051175488 65540320 18464496
02:05:47 4863 8192000000 9987429936 10345406464 995466528 10.835 100 82 164 0 1096929856 84722880 16740448
02:06:54 7561 8192000000 10000625856 10366386176 1022658960 11.098 100 74 146 0 1120049840 83228752 14162128
02:08:04 1617 8192000000 10031070896 10396250112 1048797696 11.349 100 88 174 0 1150815776 84229632 17788448
02:09:07 38676 8192000000 9999213712 10386178048 1066687072 11.520 100 53 108 2 1135008432 56678784 11642576
02:10:47 6834 8192000000 9990069808 10389086208 1077455072 11.623 100 34 68 0 1101342816 16542672 7345072
02:11:56 4783 8192000000 10001956400 10399387648 1086898208 11.713 100 46 91 3 1118543232 22495552 9149472
02:13:03 8983 8192000000 10006737280 10417844224 1090953680 11.752 100 27 54 3 1117274880 20390800 5930400
02:14:15 54197 8192000000 10015595248 10420043776 1096270048 11.802 100 39 78 1 1123406704 19180704 7955952
02:16:12 10232 8192000000 10005743088 10405888000 1087565728 11.720 99 32 64 2 1116437824 21715648 7156448
02:17:26 4334 8192000000 9994049440 10384871424 1067962912 11.533 98 35 68 1 1097672560 21660864 8048784
02:18:31 3944 8192000000 10007045760 10399936512 1073865904 11.589 99 50 100 1 1115747296 31373600 10507792
02:19:35 46493 8192000000 9944152032 10347560960 1020691264 11.079 96 35 69 1 1056406640 27891056 7824320
02:21:22 8590 8192000000 9945063840 10335551488 1011813616 10.993 99 37 74 4 1057250512 37057280 8379616
02:22:31 7287 8192000000 9940050640 10324844544 1010916896 10.984 99 28 56 2 1039959776 23509216 5533664
02:23:43 13317 8192000000 9957084240 10348945408 1029763808 11.166 99 37 73 3 1061567968 23155872 8648288
02:25:01 32817 8192000000 9967242752 10379190272 1049422896 11.355 100 22 45 2 1084459680 30527296 4509488
02:26:36 5812 8192000000 9995581792 10396024832 1077891072 11.627 100 35 64 2 1112967200 27904736 7171392
02:27:47 56664 8192000000 10043749616 10443767808 1103898000 11.875 100 54 106 5 1135799104 20309696 11591408
02:29:53 74645 8192000000 10118153456 10536480768 1123494016 12.060 100 40 80 2 1197160240 64934144 8732080
02:32:14 22267 8192000000 10171859776 10586030080 1144311728 12.256 100 44 87 2 1269648464 115840144 9496592
02:33:40 5420 8192000000 10189867344 10594136064 1152179712 12.330 100 63 126 1 1291738016 125217904 14340400
02:34:49 7573 8192000000 10224612224 10636021760 1161651808 12.419 100 63 126 1 1332859456 157014224 14193424
02:36:07 2020 8192000000 10240063440 10638864384 1163993328 12.441 100 66 129 1 1349573344 172317072 13262944
02:37:14 6937 8192000000 10279332720 10689724416 1174511056 12.539 100 64 128 1 1380333216 191965792 13856368
02:38:29 3721 8192000000 10328573504 10733707264 1164963744 12.450 99 82 161 2 1426445328 243077664 18403920
02:39:41 12520 8192000000 10312338592 10721955840 1172485024 12.520 100 67 132 2 1414201184 226325936 15390224
02:40:57 5259 8192000000 10342200992 10766548992 1185284848 12.639 100 68 133 1 1452452752 251564352 15603552
02:42:08 1399 8192000000 10368337504 10778980352 1187729504 12.662 100 80 158 2 1465374208 259463264 18181440
02:43:17 4272 8192000000 10391024416 10805796864 1194582144 12.726 100 71 142 4 1490412032 279437088 16392800
02:44:28 2695 8192000000 10388675760 10791960576 1180445632 12.594 99 90 180 0 1486584368 285091824 21046912
02:45:39 2251 8192000000 10439442656 10842300416 1180781088 12.597 100 89 178 0 1534179424 331277552 22120784
02:46:47 2459 8192000000 10461557120 10907541504 1187818032 12.663 100 87 171 2 1552401616 343983232 20600352
02:48:01 4332 8192000000 10484164096 10892607488 1179517856 12.586 100 79 158 0 1579586448 381203728 18864864
02:49:09 1296 8192000000 10496782688 10912780288 1195721088 12.737 100 88 174 0 1592034736 375844240 20469408
02:50:17 2549 8192000000 10539854624 10948317184 1184714368 12.634 99 84 168 0 1630707040 425586416 20406256
02:51:26 5475 8192000000 10536472544 10976858112 1191029040 12.693 100 64 129 1 1634313120 427633808 15650272
02:52:41 3201 8192000000 10553056000 11008712704 1194791552 12.728 100 53 106 1 1655937776 447808768 13337456
02:53:58 11052 8192000000 10544456816 10986975232 1194251600 12.723 100 44 87 1 1648806768 443194848 11360320
02:55:13 18134 8192000000 10560321664 11032514560 1178835920 12.579 100 37 74 0 1649514848 460700624 9978304
02:56:33 13748 8192000000 10513479296 10984804352 1150307936 12.312 99 25 50 1 1601270208 444361040 6601232
02:58:04 26976 8192000000 10330944448 10870177792 1155012240 12.357 100 23 46 0 1516486592 355320896 6153456
02:59:39 20027 8192000000 10094516128 10522054656 1136385744 12.182 99 31 66 1 1176365328 33106496 6873088
03:01:01 74186 8192000000 10046276784 10479624192 1096755168 11.807 96 7 14 0 1139461920 41217216 1489536
03:03:15 87555 8192000000 10091419264 10525913088 1114830800 11.978 98 32 63 0 1178137280 56583520 6722960
03:07:04 42020 8192000000 10084934560 10536202240 1126214752 12.086 99 20 40 0 1182723824 51931760 4577312
03:09:00 90705 8192000000 10116338032 10555871232 1119765760 12.025 98 27 54 2 1208717248 82365968 6585520
03:11:31 185410 8192000000 10148546656 10610495488 1102282576 11.859 96 23 47 0 1203295728 95113408 5899744
03:16:24 73076 8192000000 10115367584 10649751552 1136834064 12.186 100 15 29 1 1232228320 92143440 3250816
03:18:38 76640 8192000000 10085005728 10528927744 1093499488 11.776 96 20 40 1 1136171456 38753232 3918736
03:20:59 58566 8192000000 10084422832 10597867520 1116757056 11.996 99 21 42 2 1205190912 84222720 4211136
03:23:03 21713 8192000000 10065111232 10489815040 1120438496 12.031 100 46 90 2 1144102288 13021248 10642544
03:24:26 50119 8192000000 10141174656 10617614336 1130995248 12.131 100 33 66 1 1238170160 100415456 6759456
03:26:19 73489 8192000000 10043468960 10472284160 1050802688 11.368 93 17 34 1 1089121216 35051728 3266800
03:28:33 44399 8192000000 10095011952 10524827648 1133716736 12.156 100 28 56 0 1193133424 54033872 5382816
03:30:30 60969 8192000000 10083768752 10523475968 1135264368 12.171 100 16 32 1 1162923536 24255952 3403216
03:32:54 65814 8192000000 10092786480 10570461184 1120075392 12.028 99 11 22 0 1205661760 83278144 2308224
03:35:02 46689 8192000000 10084578240 10526658560 1115834144 11.988 99 13 27 1 1178480416 59619504 3026768
03:36:50 64975 8192000000 10048009264 10489085952 1084662144 11.692 96 16 32 1 1129083072 39926368 4494560
03:39:09 80439 8192000000 10075328592 10512797696 1065887456 11.513 95 13 26 1 1124118144 55487920 2742768
03:41:41 26668 8192000000 10061208528 10500169728 1088891200 11.732 99 20 40 1 1145727248 52659072 4176976
03:43:11 54047 8192000000 10050234032 10471915520 1100127952 11.839 99 15 30 0 1137837232 34418976 3290304
03:45:07 69730 8192000000 10065787728 10575638528 1127404976 12.097 100 12 24 1 1215290144 84940112 2945056
03:47:19 73153 8192000000 10117129792 10570522624 1110856320 11.941 98 34 68 0 1207927088 90114944 6955824
03:49:44 67305 8192000000 10038788128 10542329856 1114838624 11.978 99 13 26 2 1177838176 59722224 3277328
03:52:04 63816 8192000000 10064695312 10521128960 1126550528 12.089 100 10 20 1 1166131648 37708288 1872832
03:54:23 53434 8192000000 10067878928 10551435264 1093351968 11.775 97 17 34 2 1134890864 37715200 3823696
03:56:17 98262 8192000000 10090145728 10583748608 1110928048 11.941 98 17 34 2 1194462976 79928480 3606448
03:58:59 74768 8192000000 10069312432 10537029632 1107793632 11.912 98 34 66 1 1171573808 57294960 6485216
04:01:16 80635 8192000000 10099363696 10527825920 1088395072 11.727 96 23 46 2 1142888016 49658912 4834032
04:03:59 91093 8192000000 10073435904 10552807424 1110345840 11.936 98 7 14 0 1154369952 42669856 1354256
04:06:31 116309 8192000000 10106179136 10578673664 1144302672 12.256 100 17 34 2 1217305520 69505712 3497136
04:09:38 55597 8192000000 10050575696 10546659328 1102450848 11.861 97 4 7 0 1135842512 32883168 508496
04:11:42 43304 8192000000 10049662576 10491904000 1094188608 11.782 97 18 35 0 1125288736 28052880 3047248
04:13:32 34858 8192000000 10087941184 10543394816 1125886912 12.083 100 38 75 3 1181613040 47641456 8084672
04:15:15 62429 8192000000 10135601152 10584133632 1126394992 12.087 99 19 38 1 1212091344 81754384 3941968
04:17:18 87579 8192000000 10084920976 10555572224 1087976240 11.723 96 18 36 2 1140371232 48127776 4267216
04:19:58 61246 8192000000 10094752128 10582093824 1100319248 11.841 97 14 28 1 1186019536 82631008 3069280
04:21:59 101380 8192000000 10077061648 10538594304 1124504608 12.070 100 24 48 1 1175813136 46512560 4795968
04:25:06 53626 8192000000 10053565216 10514141184 1123586880 12.061 99 9 17 1 1155950272 30444480 1918912

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 01-01-2021 04:29:25 to 01-01-2021 04:29:40.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 01-01-2021 04:29:41 to 01-01-2021 04:29:43.

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          219686
MON$OLDEST_ACTIVE               222230
MON$OLDEST_SNAPSHOT             222230
MON$NEXT_TRANSACTION            222230
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                       2093120
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                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             772
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2313 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2313 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2313 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.2313 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 ---
:41: 
:42: mscs-test-161.budzdorov.ru    Fri Jan  1 04:27:20 2021
:43:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:44: 
:45: 
:46: mscs-test-161.budzdorov.ru    Fri Jan  1 04:27:21 2021
:47:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:48: 
--- end of diff output ---

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

01.01.21 04:29:44. 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-01-01 04:29:46 No severe PSQL-related problems occured
01.01.21 04:29:48. End of report.