Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.10.2020 10:27
Common Performance Final Results
  1. Hardware and OS info
  2. DB and 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, full
  3. Ratio "Versions / Records" for tables
  4. Database Validation Results
  5. New in firebird.log while test was run
  6. 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 17033908 kB
MemAvailable 31513188 kB
Buffers 0 kB
Cached 14616432 kB
SwapCached 3044 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 2020-10-25 10:26:52
DTS_END                         2020-10-25 10:26:52.7260
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(67:17)
IP                              ::1/54944
TRN_ID                          238356
ATT_ID                          667
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: 8975.74 26844 1077089
customer order: creation 165.57 460 19868
customer order: refuse 14.83 303 1779
order to supplier: creation 45.90 1395 5508
order to supplier: removal 3.41 119678 409
invoice (draft): creation 45.18 3594 5421
invoice (draft): removal 51.52 928 6182
invoice accept: apply 41.11 18654 4933
invoice accept: cancel 46.04 789 5525
customer reserve: creation 3784.24 182 454109
customer reserve: removal 4480.94 71 537713
realization accept: apply 82.58 179 9909
realization accept: cancel 86.16 125 10339
payment from customer: creation 59.03 130 7083
payment from customer: removal 6.08 126 730
payment to supplier: creation 49.13 130 5895
payment to supplier: removal 3.56 85 427
service: total inventory turnovers 3.52 3609 422
service: total monetary turnovers 6.04 522 725
service: aggregate perf. data 0.90 15903 108
service: refresh index statistics 0.03 370007 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 25-10-2020 10:27:44 to 25-10-2020 10:27:46.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2434
TEST_TIME 2 2572
TEST_TIME 3 2999
TEST_TIME 4 3516
TEST_TIME 5 3708
TEST_TIME 6 3653
TEST_TIME 7 3788
TEST_TIME 8 4435
TEST_TIME 9 4683
TEST_TIME 10 5242
TEST_TIME 11 5805
TEST_TIME 12 6267
TEST_TIME 13 7037
TEST_TIME 14 7177
TEST_TIME 15 7461
TEST_TIME 16 7668
TEST_TIME 17 7853
TEST_TIME 18 7926
TEST_TIME 19 7957
TEST_TIME 20 7971
TEST_TIME 21 8736
TEST_TIME 22 8724
TEST_TIME 23 8750
TEST_TIME 24 8632
TEST_TIME 25 8489
TEST_TIME 26 8876
TEST_TIME 27 9706
TEST_TIME 28 10762
TEST_TIME 29 10760
TEST_TIME 30 10734
TEST_TIME 31 10538
TEST_TIME 32 10281
TEST_TIME 33 10106
TEST_TIME 34 9994
TEST_TIME 35 10124
TEST_TIME 36 10006
TEST_TIME 37 9850
TEST_TIME 38 9709
TEST_TIME 39 9550
TEST_TIME 40 9503
TEST_TIME 41 9573
TEST_TIME 42 9526
TEST_TIME 43 9785
TEST_TIME 44 9880
TEST_TIME 45 9932
TEST_TIME 46 9976
TEST_TIME 47 9939
TEST_TIME 48 9950
TEST_TIME 49 9961
TEST_TIME 50 9840
TEST_TIME 51 9844
TEST_TIME 52 10003
TEST_TIME 53 9960
TEST_TIME 54 9870
TEST_TIME 55 9714
TEST_TIME 56 9615
TEST_TIME 57 9496
TEST_TIME 58 9451
TEST_TIME 59 9426
TEST_TIME 60 9459
TEST_TIME 61 9419
TEST_TIME 62 9364
TEST_TIME 63 9368
TEST_TIME 64 9345
TEST_TIME 65 9423
TEST_TIME 66 9413
TEST_TIME 67 9557
TEST_TIME 68 9517
TEST_TIME 69 9517
TEST_TIME 70 9515
TEST_TIME 71 9397
TEST_TIME 72 9397
TEST_TIME 73 9334
TEST_TIME 74 9303
TEST_TIME 75 9243
TEST_TIME 76 9226
TEST_TIME 77 9208
TEST_TIME 78 9211
TEST_TIME 79 9177
TEST_TIME 80 9137
TEST_TIME 81 9236
TEST_TIME 82 9227
TEST_TIME 83 9215
TEST_TIME 84 9219
TEST_TIME 85 9300
TEST_TIME 86 9347
TEST_TIME 87 9337
TEST_TIME 88 9304
TEST_TIME 89 9207
TEST_TIME 90 9191
TEST_TIME 91 9119
TEST_TIME 92 9138
TEST_TIME 93 9115
TEST_TIME 94 9080
TEST_TIME 95 9119
TEST_TIME 96 9145
TEST_TIME 97 9117
TEST_TIME 98 9115
TEST_TIME 99 9105
TEST_TIME 100 9096
TEST_TIME 101 9148
TEST_TIME 102 9215
TEST_TIME 103 9224
TEST_TIME 104 9218
TEST_TIME 105 9151
TEST_TIME 106 9091
TEST_TIME 107 9075
TEST_TIME 108 9042
TEST_TIME 109 8973
TEST_TIME 110 8982
TEST_TIME 111 8971
TEST_TIME 112 8981
TEST_TIME 113 8947
TEST_TIME 114 8953
TEST_TIME 115 8965
TEST_TIME 116 8954
TEST_TIME 117 9006
TEST_TIME 118 8980
TEST_TIME 119 8993
TEST_TIME 120 8980

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 25-10-2020 10:27:47 to 25-10-2020 10:27:51.

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 1496552 1496552 0 0.00 0 22579 7 0 0
doc_list_biud 1496550 1496550 0 0.00 0 55778 32 0 0
sp_add_invoice_to_stock 5128 4933 195 3.80 26 230090 18654 0 195
sp_cancel_adding_invoice 5525 5525 0 0.00 6 91370 790 0 0
sp_cancel_client_order 1779 1779 0 0.00 4 10952 303 0 0
sp_cancel_customer_reserve 537713 537713 0 0.00 1 55779 72 0 0
sp_cancel_pay_from_customer 730 730 0 0.00 3 8768 127 0 0
sp_cancel_pay_to_supplier 427 427 0 0.00 3 5166 86 0 0
sp_cancel_supplier_invoice 6190 6182 8 0.12 2 26023 929 0 8
sp_cancel_supplier_order 409 409 0 0.00 10 876683 119679 0 0
sp_cancel_write_off 10339 10339 0 0.00 1 8248 126 0 0
sp_client_order 19868 19868 0 0.00 3 21609 461 0 0
sp_customer_reserve 454109 454109 0 0.00 0 99314 182 0 0
sp_fill_shopping_cart 30787 30787 0 0.00 1 27009 46 0 0
sp_fill_shopping_cart_clo_res 454104 454104 0 0.00 0 26179 19 0 0
sp_get_clo_for_invoice 4926 4926 0 0.00 0 46329 478 0 0
sp_kill_cost_storno 17021 17021 0 0.00 0 8762 60 0 0
sp_kill_qstorno_ret_qs2qd 1092356 1092356 0 0.00 0 55757 39 0 0
sp_kill_qty_storno 576780 576780 0 0.00 0 55778 82 0 0
sp_lock_selected_doc 20635 20635 0 0.00 0 10244 5 0 0
sp_make_cost_storno 27813 27813 0 0.00 0 15963 58 0 0
sp_multiply_rows_for_pdistr 27812 27812 0 0.00 0 8741 36 0 0
sp_multiply_rows_for_qdistr 19868 19868 0 0.00 0 18905 265 0 0
sp_pay_from_customer 7083 7083 0 0.00 4 14500 130 0 0
sp_pay_to_supplier 5895 5895 0 0.00 4 11115 130 0 0
sp_payment_common 12978 12978 0 0.00 1 8168 22 0 0
sp_qd_handle_on_cancel_clo 1779 1779 0 0.00 0 7342 268 0 0
sp_qd_handle_on_invoice_upd_sts 10449 10449 0 0.00 0 10504 342 0 0
sp_qd_handle_on_reserve_upd_sts 9909 9909 0 0.00 0 19280 47 0 0
sp_reserve_write_off 9909 9909 0 0.00 6 19342 179 0 0
sp_supplier_invoice 5421 5421 0 0.00 6 113201 3594 0 0
sp_supplier_order 5508 5508 0 0.00 5 42774 1395 0 0
srv_aggregate_perf_data 108 108 0 0.00 2589 268465 15904 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1247 122157 44283 0 0
srv_find_qd_qs_mism 1029605 1029605 0 0.00 0 22578 6 0 0
srv_make_invnt_saldo 422 422 0 0.00 59 85366 3610 0 0
srv_make_money_saldo 725 725 0 0.00 1 9515 523 0 0
srv_recalc_idx_stat 4 4 0 0.00 201971 541211 370007 0 0
t$perf-abend:sp_add_invoice_to_stock 195 195 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4933 4933 0 0.00 0 24911 237 0 0
t$perf-norm:sp_cancel_adding_invoice 377 377 0 0.00 0 4972 100 0 0
t$perf-norm:sp_cancel_client_order 1779 1779 0 0.00 0 3128 11 0 0
t$perf-norm:sp_cancel_customer_reserve 696 696 0 0.00 0 2046 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 730 730 0 0.00 0 3093 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 427 427 0 0.00 0 229 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 416 416 0 0.00 0 1848 19 0 0
t$perf-norm:sp_cancel_supplier_order 409 409 0 0.00 0 26353 1663 0 0
t$perf-norm:sp_cancel_write_off 971 971 0 0.00 0 1664 11 0 0
t$perf-norm:sp_client_order 19868 19868 0 0.00 0 7217 19 0 0
t$perf-norm:sp_customer_reserve 1051 1051 0 0.00 0 287 2 0 0
t$perf-norm:sp_pay_from_customer 7083 7083 0 0.00 0 21571 11 0 0
t$perf-norm:sp_pay_to_supplier 5895 5895 0 0.00 0 9525 14 0 0
t$perf-norm:sp_reserve_write_off 9909 9909 0 0.00 0 7003 8 0 0
t$perf-norm:sp_supplier_invoice 5421 5421 0 0.00 0 12408 20 0 0
t$perf-norm:sp_supplier_order 5508 5508 0 0.00 0 5086 18 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 654 21 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 339 6 0 0
t$perf-norm:srv_make_invnt_saldo 422 422 0 0.00 0 3095 28 0 0
t$perf-norm:srv_make_money_saldo 725 725 0 0.00 0 1577 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 614 155 0 0
v_add_invoice_to_stock 10230 9840 390 3.81 0 10351 19 0 390
v_all_customers 36458 36458 0 0.00 0 222 1 0 0
v_all_suppliers 30186 30186 0 0.00 0 222 1 0 0
v_all_wares 39732 39732 0 0.00 0 33 1 0 0
v_cancel_adding_invoice 754 754 0 0.00 0 978 8 0 0
v_cancel_client_order 3558 3558 0 0.00 0 3064 7 0 0
v_cancel_customer_prepayment 1460 1460 0 0.00 0 3875 14 0 0
v_cancel_customer_reserve 1392 1392 0 0.00 0 967 7 0 0
v_cancel_payment_to_supplier 854 854 0 0.00 0 5145 14 0 0
v_cancel_supplier_invoice 848 832 16 1.88 0 4072 24 0 16
v_cancel_supplier_order 818 818 0 0.00 0 3395 13 0 0
v_cancel_write_off 1942 1942 0 0.00 0 4042 17 0 0
v_max_id_clo_ord 5507 5507 0 0.00 0 986 1 0 0
v_max_id_clo_res 1051 1051 0 0.00 0 2868 25 0 0
v_max_id_ord_sup 5414 5414 0 0.00 0 6811 6 0 0
v_max_non_paid_invoice 1723 1723 0 0.00 0 1296 3 0 0
v_max_non_paid_realizn 3761 3761 0 0.00 0 4768 6 0 0
v_min_id_clo_ord 5507 5507 0 0.00 0 3848 5 0 0
v_min_id_clo_res 1051 1051 0 0.00 0 2548 9 0 0
v_min_id_ord_sup 5414 5414 0 0.00 0 7444 12 0 0
v_min_non_paid_invoice 5895 5895 0 0.00 0 8161 15 0 0
v_min_non_paid_realizn 7083 7083 0 0.00 0 5250 14 0 0
v_our_firm 9918 9918 0 0.00 0 14 1 0 0
v_reserve_write_off 19818 19818 0 0.00 0 12611 7 0 0
x_lock_depdocs_on_canc_invoice 11291 11291 0 0.00 0 19809 129 0 0
x_lock_depdocs_on_canc_sup_ord 409 409 0 0.00 0 20605 530 0 0
x_make_qstorno_1000_1200 5508 5508 0 0.00 1 42750 1309 0 0
x_make_qstorno_1200_2000 5421 5421 0 0.00 3 105665 3423 0 0
x_make_qstorno_2100_3300 454109 454109 0 0.00 0 99313 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-10-2020 10:27:52 to 25-10-2020 10:27:56.

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
07:56:56 2 8192000000 8796633056 8807174144 3163600 0.038 1 1 2 0 3261296 61840 35856
07:57:58 21834 8192000000 9724716288 10022719488 801668416 8.913 100 81 162 0 828148736 7126624 19353696
07:59:25 18023 8192000000 9768726912 10075983872 843218672 9.332 100 89 178 1 876805632 13025776 20561184
08:00:48 16521 8192000000 9810123904 10128035840 872287712 9.623 100 83 166 2 912533472 21416496 18829264
08:02:08 14856 8192000000 9860388928 10196701184 911111584 10.008 100 76 152 1 970508672 41972912 17424176
08:03:28 7812 8192000000 9921656800 10269564928 961982544 10.508 100 76 152 0 1027168704 49204336 15981824
08:04:38 4802 8192000000 9985452416 10340929536 998828832 10.867 100 92 181 0 1096542208 79514720 18198656
08:05:45 2246 8192000000 10014900192 10376450048 1025811120 11.128 100 92 185 1 1129334832 83640768 19882944
08:06:49 1641 8192000000 10028357520 10394005504 1046963616 11.332 100 89 178 2 1134333808 68557600 18812592
08:07:51 47120 8192000000 9991380512 10382618624 1059161280 11.448 100 50 101 0 1109265152 39138336 10965536
08:09:44 8407 8192000000 9982244656 10390695936 1072594032 11.577 100 31 62 0 1096969360 17489328 6886000
08:10:54 9701 8192000000 9991084688 10396205056 1077980144 11.628 100 28 55 5 1106642272 22810032 5852096
08:12:07 7905 8192000000 9990799280 10393067520 1083322976 11.679 100 41 80 4 1114073520 21927488 8823056
08:13:16 72679 8192000000 10004203664 10414026752 1087469136 11.719 100 38 74 0 1117462032 22188016 7804880
08:15:28 5765 8192000000 10002503488 10412818432 1094395680 11.784 100 34 67 1 1125224432 23666208 7162544
08:16:35 10005 8192000000 9985127744 10387509248 1033572336 11.203 96 23 47 2 1058551856 20550928 4428592
08:17:48 5841 8192000000 9969483488 10365632512 1035267008 11.219 98 38 77 1 1069880816 26233472 8380336
08:18:55 41603 8192000000 9933576032 10330689536 972826368 10.614 94 22 44 2 994550080 17330928 4392784
08:20:41 8991 8192000000 9939900928 10329296896 1002168800 10.900 97 39 77 3 1032073728 21534576 8370352
08:21:55 8508 8192000000 9941386992 10317418496 1008414768 10.960 98 38 76 1 1034169648 16956128 8798752
08:23:08 68144 8192000000 9977185568 10386829312 1053320720 11.393 100 30 58 2 1087077760 27621328 6135712
08:25:16 6412 8192000000 9982329584 10368135168 1062115376 11.477 100 42 83 0 1096917712 25834560 8967776
08:26:28 6740 8192000000 9989226544 10388336640 1078247136 11.631 100 46 92 5 1097307984 9087872 9972976
08:27:39 31146 8192000000 10009011776 10411085824 1094546384 11.786 100 32 62 1 1124212176 22217584 7448208
08:29:16 19853 8192000000 10080284624 10475499520 1111135616 11.943 100 44 86 2 1191043376 69746592 10161168
08:30:50 32627 8192000000 10088319584 10519072768 1122135248 12.047 100 30 60 4 1205945232 76810032 6999952
08:32:50 7953 8192000000 10131613008 10536787968 1137184976 12.189 100 47 94 2 1242907328 94785584 10936768
08:34:04 19378 8192000000 10158652880 10564952064 1133760688 12.157 99 49 99 1 1263606784 119051392 10794704
08:35:27 3986 8192000000 10205928496 10601054208 1158820080 12.392 100 80 160 2 1312480960 136240752 17420128
08:36:34 2986 8192000000 10266999840 10665271296 1163305936 12.434 100 74 145 0 1366243936 187324720 15613280
08:37:40 3846 8192000000 10259010784 10657845248 1151670928 12.325 99 70 138 1 1354998768 188151696 15176144
08:38:50 3055 8192000000 10273918384 10671009792 1160499904 12.408 100 86 171 2 1375453952 196299088 18654960
08:39:57 5944 8192000000 10246940304 10652258304 1168004592 12.478 100 79 154 0 1350797248 165769072 17023584
08:41:10 5318 8192000000 10293301456 10692710400 1164919680 12.449 100 77 153 1 1391175584 208661632 17594272
08:42:20 1246 8192000000 10283398016 10681282560 1166417952 12.463 100 80 155 3 1379807792 196338848 17050992
08:43:28 1902 8192000000 10298016160 10716028928 1155441472 12.361 98 83 164 2 1389153504 214946000 18766032
08:44:37 3335 8192000000 10316083056 10715353088 1162867664 12.430 100 68 137 0 1418608128 239590624 16149840
08:45:46 4717 8192000000 10375349808 10783318016 1163425024 12.435 100 62 123 0 1475637984 297134768 15078192
08:46:59 5914 8192000000 10405700928 10842468352 1165068384 12.451 100 65 131 2 1510017568 328947008 16002176
08:48:13 15078 8192000000 10358484352 10792579072 1169325376 12.491 100 37 74 3 1472057184 293281952 9449856
08:49:34 12853 8192000000 10390855328 10847236096 1170684208 12.503 100 33 65 2 1493190848 313863616 8643024
08:50:51 24692 8192000000 10450223360 10908090368 1158295408 12.387 100 27 54 0 1546079424 379748912 8035104
08:52:31 15756 8192000000 10414556448 10890493952 1116666368 11.995 99 36 72 1 1512645024 386408880 9569776
08:53:53 40451 8192000000 10212869776 10743906304 1122773632 12.053 100 16 32 2 1397701024 270859440 4067952
08:55:34 53635 8192000000 10066324064 10522841088 1108093824 11.914 98 16 30 1 1153586928 42175920 3317184
08:57:32 122156 8192000000 10079215104 10521321472 1125002736 12.074 99 42 84 0 1180301056 46431680 8866640
09:00:38 44950 8192000000 10088146592 10530758656 1088703536 11.730 97 29 58 0 1168363136 73293760 6365840
09:02:30 28318 8192000000 10040193376 10484621312 1087544544 11.719 98 26 51 1 1134487504 40837232 6105728
09:04:03 73706 8192000000 10114429248 10549231616 1100699040 11.844 98 28 56 1 1186154112 78342656 7112416
09:06:20 63415 8192000000 10178169632 10648129536 1140962576 12.225 100 22 44 0 1295532704 149246320 5323808
09:08:44 61033 8192000000 10140527600 10574483456 1130563120 12.127 99 28 57 0 1246589968 109399648 6627200
09:10:47 43732 8192000000 10112211200 10575953920 1142352224 12.238 100 28 56 0 1195882208 48139120 5390864
09:12:32 65624 8192000000 10108843152 10533994496 1148507648 12.295 100 13 26 0 1200755392 49275792 2971952
09:14:38 19822 8192000000 10099955696 10534641664 1142409440 12.238 100 46 92 2 1196152064 44460320 9282304
09:15:59 44423 8192000000 10086086928 10517377024 1105651856 11.891 96 19 38 1 1146562400 37200400 3710144
09:17:44 63394 8192000000 10106867872 10606288896 1127625120 12.099 99 21 42 1 1228415232 96575504 4214608
09:20:25 83335 8192000000 10087969824 10518544384 1133456128 12.154 99 35 69 3 1170035136 29053584 7525424
09:22:49 42128 8192000000 10099331344 10548011008 1138406304 12.201 99 14 28 0 1197099536 55419776 3273456
09:24:32 45981 8192000000 10124595744 10605019136 1138572352 12.202 99 8 17 1 1208364192 68095312 1696528
09:26:18 38289 8192000000 10078433776 10554425344 1122508800 12.051 98 13 26 1 1152852576 27200624 3143152
09:27:56 52472 8192000000 10159705728 10601070592 1146432608 12.276 99 21 42 0 1212111696 61565056 4114032
09:30:09 45851 8192000000 10157267008 10594410496 1127188112 12.095 98 29 58 0 1228036192 94315168 6532912
09:32:02 75735 8192000000 10126202736 10560864256 1057615904 11.434 93 20 40 0 1138432336 76343792 4472640
09:34:20 32067 8192000000 10061848448 10490769408 1097269776 11.812 98 28 55 1 1131421264 28480864 5670624
09:35:54 36854 8192000000 10092471040 10530263040 1095477392 11.795 99 43 85 1 1150034416 46274544 8282480
09:37:32 83268 8192000000 10057313136 10538168320 1122611968 12.052 100 30 60 1 1194318992 66194016 5513008
09:42:22 86205 8192000000 10075112960 10519977984 1094152832 11.782 97 21 41 1 1134912096 36543376 4215888
09:44:54 25714 8192000000 10051443440 10478956544 1117443056 12.003 99 42 84 1 1148897872 23078096 8376720
09:46:25 44204 8192000000 10114764512 10543050752 1070981056 11.561 95 35 70 0 1146674944 68584976 7108912
09:48:21 120980 8192000000 10087497376 10522963968 1043321200 11.297 93 33 66 3 1094729664 44584224 6824240
09:51:22 60096 8192000000 10118326480 10598150144 1110399200 11.936 99 15 30 1 1173900832 60540752 2960880
09:53:25 62091 8192000000 10074099840 10510917632 1104625984 11.882 98 16 32 1 1157637920 49853184 3158752
09:55:34 122121 8192000000 10090131152 10564386816 1124558592 12.070 100 19 38 0 1174015584 45296576 4160416
09:58:36 30822 8192000000 10084618144 10607095808 1129232144 12.114 100 3 6 0 1182812368 52996000 584224
10:00:07 98757 8192000000 10141269712 10600517632 1130200224 12.123 99 22 44 1 1201333952 66495584 4638144
10:03:18 50784 8192000000 10106251328 10537172992 1112211568 11.953 97 40 78 1 1146420496 26384256 7824672
10:05:09 33139 8192000000 10076193712 10502565888 1138341968 12.200 100 35 71 1 1166341104 20762960 7236176
10:06:43 65534 8192000000 10173040224 10683965440 1147030304 12.282 99 16 32 2 1244421648 94250160 3141184
10:09:02 43487 8192000000 10118698384 10560798720 1135804752 12.176 99 13 26 0 1174657504 36677712 2175040
10:10:52 120507 8192000000 10112763664 10557255680 1137605168 12.193 99 14 28 2 1198035184 57181136 3248880
10:13:53 62075 8192000000 10109821152 10575781888 1155195872 12.358 100 9 18 0 1221798256 64557056 2045328
10:16:17 93355 8192000000 10094150256 10558607360 1119710480 12.024 97 16 33 2 1185410624 62420064 3280080
10:18:58 60490 8192000000 10114007920 10552205312 1128446064 12.107 98 27 54 1 1177754512 43405168 5903280
10:20:58 48185 8192000000 10069727504 10508148736 1075295536 11.603 95 21 42 1 1135471840 55952576 4223728
10:22:55 68348 8192000000 10105962064 10594770944 1113988512 11.970 98 15 30 1 1205791936 88896880 2906544
10:25:03 34754 8192000000 10059193312 10499571712 1099201488 11.830 97 31 62 1 1128891088 23519040 6170560
10:26:45 20133 8192000000 9277926640 9589940224 406246992 4.724 36 9 14 2 428900160 20886128 1767040

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-10-2020 10:27:58 to 25-10-2020 10:28:13.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 195 v_add_invoice_to_stock 335544517
user_exc 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 25-10-2020 10:28:14 to 25-10-2020 10:28:15.

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          239369
MON$OLDEST_ACTIVE               239370
MON$OLDEST_SNAPSHOT             239370
MON$NEXT_TRANSACTION            239370
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                       2081056
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             782
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2235 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2235 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2235 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.2235 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 ---
:47: 
:48: mscs-test-161.budzdorov.ru    Sun Oct 25 10:27:09 2020
:49:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:50: 
--- end of diff output ---

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

25.10.20 10:28:17. 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
2020-10-25 10:28:19 No severe PSQL-related problems occured
25.10.20 10:28:20. End of report.