Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.11.2020 07:29
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 16617672 kB
MemAvailable 31522016 kB
Buffers 0 kB
Cached 15079156 kB
SwapCached 3300 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-11-22 07:26:51
DTS_END                         2020-11-22 07:26:51.5190
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/33856
TRN_ID                          230818
ATT_ID                          602
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: 9350.78 15552 1122094
customer order: creation 163.24 395 19589
customer order: refuse 13.38 328 1605
order to supplier: creation 43.42 1298 5210
order to supplier: removal 3.60 110278 432
invoice (draft): creation 43.78 3448 5254
invoice (draft): removal 55.53 942 6663
invoice accept: apply 40.06 16275 4807
invoice accept: cancel 49.71 773 5965
customer reserve: creation 3745.48 157 449457
customer reserve: removal 4902.79 63 588335
realization accept: apply 79.93 179 9592
realization accept: cancel 87.06 98 10447
payment from customer: creation 57.38 114 6886
payment from customer: removal 5.61 84 673
payment to supplier: creation 46.18 127 5541
payment to supplier: removal 3.43 64 412
service: total inventory turnovers 3.69 3294 443
service: total monetary turnovers 5.57 550 668
service: aggregate perf. data 0.93 12822 111
service: refresh index statistics 0.03 159750 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 22-11-2020 07:29:36 to 22-11-2020 07:29:39.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2797
TEST_TIME 2 2429
TEST_TIME 3 2898
TEST_TIME 4 3207
TEST_TIME 5 3799
TEST_TIME 6 3977
TEST_TIME 7 4132
TEST_TIME 8 4375
TEST_TIME 9 4563
TEST_TIME 10 4990
TEST_TIME 11 5518
TEST_TIME 12 5902
TEST_TIME 13 6133
TEST_TIME 14 6528
TEST_TIME 15 6955
TEST_TIME 16 7088
TEST_TIME 17 7338
TEST_TIME 18 7405
TEST_TIME 19 7312
TEST_TIME 20 7677
TEST_TIME 21 7761
TEST_TIME 22 7956
TEST_TIME 23 8859
TEST_TIME 24 9546
TEST_TIME 25 11252
TEST_TIME 26 11198
TEST_TIME 27 11226
TEST_TIME 28 11019
TEST_TIME 29 11119
TEST_TIME 30 10977
TEST_TIME 31 10862
TEST_TIME 32 10954
TEST_TIME 33 10990
TEST_TIME 34 10945
TEST_TIME 35 11045
TEST_TIME 36 10957
TEST_TIME 37 11080
TEST_TIME 38 10839
TEST_TIME 39 10630
TEST_TIME 40 10447
TEST_TIME 41 10395
TEST_TIME 42 10308
TEST_TIME 43 10207
TEST_TIME 44 10289
TEST_TIME 45 10431
TEST_TIME 46 10336
TEST_TIME 47 10228
TEST_TIME 48 10127
TEST_TIME 49 10091
TEST_TIME 50 10005
TEST_TIME 51 9980
TEST_TIME 52 9935
TEST_TIME 53 9885
TEST_TIME 54 9989
TEST_TIME 55 10003
TEST_TIME 56 9979
TEST_TIME 57 10083
TEST_TIME 58 10049
TEST_TIME 59 10085
TEST_TIME 60 10087
TEST_TIME 61 10145
TEST_TIME 62 10143
TEST_TIME 63 10107
TEST_TIME 64 10008
TEST_TIME 65 9991
TEST_TIME 66 9900
TEST_TIME 67 9797
TEST_TIME 68 9736
TEST_TIME 69 9741
TEST_TIME 70 9690
TEST_TIME 71 9755
TEST_TIME 72 9688
TEST_TIME 73 9630
TEST_TIME 74 9670
TEST_TIME 75 9791
TEST_TIME 76 9829
TEST_TIME 77 9828
TEST_TIME 78 9785
TEST_TIME 79 9912
TEST_TIME 80 9935
TEST_TIME 81 9917
TEST_TIME 82 9816
TEST_TIME 83 9746
TEST_TIME 84 9689
TEST_TIME 85 9656
TEST_TIME 86 9613
TEST_TIME 87 9600
TEST_TIME 88 9596
TEST_TIME 89 9590
TEST_TIME 90 9572
TEST_TIME 91 9618
TEST_TIME 92 9639
TEST_TIME 93 9608
TEST_TIME 94 9609
TEST_TIME 95 9646
TEST_TIME 96 9639
TEST_TIME 97 9736
TEST_TIME 98 9699
TEST_TIME 99 9637
TEST_TIME 100 9563
TEST_TIME 101 9507
TEST_TIME 102 9479
TEST_TIME 103 9427
TEST_TIME 104 9397
TEST_TIME 105 9410
TEST_TIME 106 9387
TEST_TIME 107 9358
TEST_TIME 108 9333
TEST_TIME 109 9332
TEST_TIME 110 9332
TEST_TIME 111 9356
TEST_TIME 112 9409
TEST_TIME 113 9390
TEST_TIME 114 9412
TEST_TIME 115 9398
TEST_TIME 116 9404
TEST_TIME 117 9474
TEST_TIME 118 9461
TEST_TIME 119 9391
TEST_TIME 120 9353

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 22-11-2020 07:29:40 to 22-11-2020 07:29:43.

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 1538093 1538093 0 0.00 0 44322 6 0 0
doc_list_biud 1538076 1538076 0 0.00 0 25742 31 0 0
sp_add_invoice_to_stock 4902 4807 95 1.93 19 202925 16276 0 95
sp_cancel_adding_invoice 5965 5965 0 0.00 5 58163 774 0 0
sp_cancel_client_order 1605 1605 0 0.00 5 11724 329 0 0
sp_cancel_customer_reserve 588335 588335 0 0.00 1 24264 63 0 0
sp_cancel_pay_from_customer 673 673 0 0.00 3 8149 85 0 0
sp_cancel_pay_to_supplier 412 412 0 0.00 3 1673 65 0 0
sp_cancel_supplier_invoice 6665 6663 2 0.03 2 21407 943 0 2
sp_cancel_supplier_order 432 432 0 0.00 8 1282716 110279 0 0
sp_cancel_write_off 10447 10447 0 0.00 1 7142 98 0 0
sp_client_order 19589 19589 0 0.00 4 23766 396 0 0
sp_customer_reserve 449457 449457 0 0.00 0 121800 158 0 0
sp_fill_shopping_cart 30043 30043 0 0.00 1 35441 45 0 0
sp_fill_shopping_cart_clo_res 449453 449453 0 0.00 0 24905 15 0 0
sp_get_clo_for_invoice 4803 4803 0 0.00 0 27745 367 0 0
sp_kill_cost_storno 17497 17497 0 0.00 0 6972 51 0 0
sp_kill_qstorno_ret_qs2qd 1194212 1194212 0 0.00 0 24234 35 0 0
sp_kill_qty_storno 627830 627830 0 0.00 0 25741 74 0 0
sp_lock_selected_doc 19854 19854 0 0.00 0 9123 6 0 0
sp_make_cost_storno 26822 26822 0 0.00 0 16896 56 0 0
sp_multiply_rows_for_pdistr 26810 26810 0 0.00 0 12242 33 0 0
sp_multiply_rows_for_qdistr 19589 19589 0 0.00 0 14216 223 0 0
sp_pay_from_customer 6886 6886 0 0.00 4 16155 115 0 0
sp_pay_to_supplier 5541 5541 0 0.00 4 8959 128 0 0
sp_payment_common 12422 12422 0 0.00 1 12596 24 0 0
sp_qd_handle_on_cancel_clo 1605 1605 0 0.00 0 11715 304 0 0
sp_qd_handle_on_invoice_upd_sts 10762 10762 0 0.00 0 25741 359 0 0
sp_qd_handle_on_reserve_upd_sts 9586 9586 0 0.00 0 13598 49 0 0
sp_reserve_write_off 9592 9592 0 0.00 6 22969 180 0 0
sp_supplier_invoice 5254 5254 0 0.00 8 123314 3448 0 0
sp_supplier_order 5210 5210 0 0.00 6 29159 1299 0 0
srv_aggregate_perf_data 111 111 0 0.00 2799 127143 12822 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 1341 140434 49882 0 0
srv_find_qd_qs_mism 1076206 1076206 0 0.00 0 44321 5 0 0
srv_make_invnt_saldo 443 443 0 0.00 29 87497 3295 0 0
srv_make_money_saldo 668 668 0 0.00 1 45791 551 0 0
srv_recalc_idx_stat 4 4 0 0.00 107466 232561 159750 0 0
t$perf-abend:sp_add_invoice_to_stock 95 95 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 2 2 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4807 4807 0 0.00 0 16330 143 0 0
t$perf-norm:sp_cancel_adding_invoice 385 385 0 0.00 0 16035 122 0 0
t$perf-norm:sp_cancel_client_order 1605 1605 0 0.00 0 8503 18 0 0
t$perf-norm:sp_cancel_customer_reserve 602 602 0 0.00 0 941 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 673 673 0 0.00 0 4772 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 412 412 0 0.00 0 1830 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 400 400 0 0.00 0 7537 31 0 0
t$perf-norm:sp_cancel_supplier_order 432 432 0 0.00 0 16322 1664 0 0
t$perf-norm:sp_cancel_write_off 962 962 0 0.00 0 2150 8 0 0
t$perf-norm:sp_client_order 19589 19589 0 0.00 0 12428 16 0 0
t$perf-norm:sp_customer_reserve 984 984 0 0.00 0 2203 9 0 0
t$perf-norm:sp_pay_from_customer 6886 6886 0 0.00 0 12103 12 0 0
t$perf-norm:sp_pay_to_supplier 5541 5541 0 0.00 0 11542 12 0 0
t$perf-norm:sp_reserve_write_off 9592 9592 0 0.00 0 5577 11 0 0
t$perf-norm:sp_supplier_invoice 5254 5254 0 0.00 0 10922 21 0 0
t$perf-norm:sp_supplier_order 5210 5210 0 0.00 0 11945 16 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 570 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 9 1 0 0
t$perf-norm:srv_make_invnt_saldo 443 443 0 0.00 0 962 6 0 0
t$perf-norm:srv_make_money_saldo 668 668 0 0.00 0 707 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 9784 9594 190 1.94 0 4881 16 0 190
v_all_customers 35958 35958 0 0.00 0 91 1 0 0
v_all_suppliers 29034 29034 0 0.00 0 17 1 0 0
v_all_wares 39176 39176 0 0.00 0 41 1 0 0
v_cancel_adding_invoice 770 770 0 0.00 0 2537 9 0 0
v_cancel_client_order 3210 3210 0 0.00 0 3776 4 0 0
v_cancel_customer_prepayment 1346 1346 0 0.00 0 1407 8 0 0
v_cancel_customer_reserve 1204 1204 0 0.00 0 644 3 0 0
v_cancel_payment_to_supplier 824 824 0 0.00 0 978 5 0 0
v_cancel_supplier_invoice 804 800 4 0.49 0 3798 18 0 4
v_cancel_supplier_order 864 864 0 0.00 0 1957 10 0 0
v_cancel_write_off 1924 1924 0 0.00 0 3567 16 0 0
v_max_id_clo_ord 5209 5209 0 0.00 0 448 1 0 0
v_max_id_clo_res 984 984 0 0.00 0 1675 15 0 0
v_max_id_ord_sup 5246 5246 0 0.00 0 8770 7 0 0
v_max_non_paid_invoice 1476 1476 0 0.00 0 1529 3 0 0
v_max_non_paid_realizn 3582 3582 0 0.00 0 3503 4 0 0
v_min_id_clo_ord 5209 5209 0 0.00 0 3769 5 0 0
v_min_id_clo_res 984 984 0 0.00 0 883 2 0 0
v_min_id_ord_sup 5246 5246 0 0.00 0 3728 7 0 0
v_min_non_paid_invoice 5539 5539 0 0.00 0 8942 15 0 0
v_min_non_paid_realizn 6881 6881 0 0.00 0 6088 15 0 0
v_our_firm 9818 9818 0 0.00 0 24 1 0 0
v_reserve_write_off 19172 19172 0 0.00 0 5831 7 0 0
x_lock_depdocs_on_canc_invoice 12228 12228 0 0.00 0 17154 106 0 0
x_lock_depdocs_on_canc_sup_ord 432 432 0 0.00 0 17461 303 0 0
x_make_qstorno_1000_1200 5210 5210 0 0.00 2 29138 1235 0 0
x_make_qstorno_1200_2000 5254 5254 0 0.00 4 123297 3264 0 0
x_make_qstorno_2100_3300 449457 449457 0 0.00 0 121768 141 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-11-2020 07:29:44 to 22-11-2020 07:29:48.

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
04:56:55 1 8192000000 8796641312 8807108608 3168704 0.038 1 1 2 0 3266400 61840 35856
04:57:56 31221 8192000000 9716597840 10011774976 788161216 8.776 100 90 180 2 818716832 8182464 22373152
04:59:33 18071 8192000000 9764378032 10071531520 835767184 9.257 100 91 182 0 870421776 12753824 21900768
05:00:57 14638 8192000000 9799576992 10118144000 871765440 9.618 100 88 176 0 912879424 20708960 20405024
05:02:14 16271 8192000000 9865698224 10201026560 919992992 10.096 100 74 148 0 976917168 40739904 16184272
05:03:33 8568 8192000000 9927492800 10271531008 959968560 10.489 100 83 167 1 1035014320 57916864 17128896
05:04:44 6397 8192000000 9956263680 10314199040 993131152 10.812 100 80 160 0 1076502448 66905648 16465648
05:05:52 3534 8192000000 9993251440 10353827840 1019799424 11.070 100 80 161 1 1106684752 70842352 16042976
05:06:57 2240 8192000000 10003733520 10371567616 1048032768 11.342 100 93 186 1 1123170816 55947344 19190704
05:08:00 1781 8192000000 10037056992 10406817792 1063407232 11.489 100 85 171 1 1145404864 64459872 17537760
05:09:03 9139 8192000000 9992917040 10404634624 1064804768 11.502 100 35 70 0 1122636736 50620496 7211472
05:10:13 6073 8192000000 10004142064 10399793152 1075853520 11.608 100 58 115 1 1130649104 42506672 12288912
05:11:23 3672 8192000000 9992694896 10389069824 1077161872 11.620 100 59 117 2 1111813120 22486816 12164432
05:12:28 66941 8192000000 9993547104 10398642176 1085304784 11.698 100 29 57 0 1125602976 33162736 7135456
05:14:36 8571 8192000000 10005718416 10417766400 1089534816 11.738 100 37 73 1 1116749072 19347952 7866304
05:15:45 7189 8192000000 10031733328 10446307328 1098735968 11.826 100 39 78 0 1132375280 25496576 8142736
05:16:58 12291 8192000000 9973271104 10367406080 1049724928 11.358 97 31 62 3 1074445296 17248768 7471600
05:18:11 26516 8192000000 9947381088 10350751744 976598672 10.651 91 30 60 2 996528816 13613120 6317024
05:19:41 5255 8192000000 9954680160 10337693696 1002871840 10.906 96 42 81 1 1031333968 19372400 9089728
05:20:52 4257 8192000000 9960395504 10339692544 1017686080 11.050 99 55 111 0 1051888592 21735392 12467120
05:22:00 17589 8192000000 9946804672 10333773824 1019324656 11.065 99 36 70 3 1045176944 18218448 7633840
05:24:10 10127 8192000000 9967796352 10357141504 1057428080 11.432 100 36 71 1 1086724592 21412416 7884096
05:25:21 5121 8192000000 9987442704 10374418432 1066954624 11.523 100 60 118 3 1105436384 24322464 14159296
05:26:29 16354 8192000000 9987952272 10395181056 1088386656 11.727 100 21 42 0 1119576160 27338048 3851456
05:27:46 38967 8192000000 10023847136 10425192448 1102799904 11.864 100 53 105 4 1132849776 18748720 11301152
05:29:31 2999 8192000000 10048405888 10449408000 1120806272 12.035 100 52 104 2 1163772784 31934896 11031616
05:30:39 30098 8192000000 10144805536 10553520128 1139551424 12.211 100 52 103 2 1240764848 90864912 10348512
05:32:11 13165 8192000000 10161164848 10574286848 1151336128 12.322 100 48 96 1 1262484224 100615200 10532896
05:33:30 9289 8192000000 10218533456 10622922752 1161574608 12.418 100 51 102 1 1312811168 140103760 11132800
05:34:44 13565 8192000000 10253730304 10667433984 1160807104 12.411 99 55 110 1 1347422000 173826784 12788112
05:36:01 6393 8192000000 10281834592 10696650752 1175912928 12.552 100 56 111 2 1391194208 202353888 12927392
05:37:14 4146 8192000000 10332644368 10747097088 1174927808 12.543 100 69 137 1 1438368624 247125552 16315264
05:38:21 1339 8192000000 10363089696 10781130752 1189314960 12.677 100 80 160 1 1464429952 257234208 17880784
05:39:28 1383 8192000000 10355583984 10762944512 1177507616 12.567 100 84 167 3 1455061488 258177376 19376496
05:40:35 4066 8192000000 10367957712 10775011328 1158187360 12.386 99 75 149 2 1459293104 284896112 16209632
05:41:47 1513 8192000000 10385623840 10800713728 1178422848 12.575 100 74 144 4 1484197024 289127568 16646608
05:42:55 14741 8192000000 10369820752 10818629632 1160940512 12.412 99 42 82 2 1489445968 317639728 10865728
05:44:16 8729 8192000000 10434787408 10857897984 1161047056 12.413 100 47 94 1 1533094704 360593792 11453856
05:45:32 17929 8192000000 10471936992 10892615680 1122800672 12.053 97 36 72 0 1555931152 423303008 9827472
05:46:52 10085 8192000000 10485776416 10936672256 1146576592 12.277 99 50 98 1 1597942256 438058272 13307392
05:48:19 14645 8192000000 10515099888 10967326720 1117665584 12.005 99 37 74 0 1632403456 504344448 10393424
05:49:40 11235 8192000000 10445544528 10903470080 1111926464 11.951 100 32 64 1 1557073376 435999680 9147232
05:51:01 33417 8192000000 10232958784 10762637312 1107921488 11.913 99 22 43 1 1455429280 341680144 5827648
05:52:39 72004 8192000000 10040251152 10516836352 1100211808 11.840 98 18 36 0 1150908912 46708288 3988816
05:54:54 54025 8192000000 10045405120 10553040896 1061357664 11.469 97 6 12 0 1099199168 36552880 1288624
05:56:49 58949 8192000000 10057658048 10474909696 1095598672 11.796 98 30 60 0 1134418432 32180528 6639232
05:58:53 140434 8192000000 10102045360 10588811264 1101974704 11.856 98 7 14 0 1164990224 62274640 740880
06:02:15 124094 8192000000 10053745920 10498768896 1102118592 11.858 98 14 28 0 1186774880 81878224 2778064
06:05:19 99628 8192000000 10093393248 10540240896 1115302512 11.983 99 50 100 2 1175645808 49092272 11251024
06:08:06 44309 8192000000 10130182000 10586435584 1123656288 12.062 99 38 76 2 1222656768 90680032 8320448
06:09:57 60295 8192000000 10114310560 10600144896 1126213856 12.086 100 18 37 0 1233102016 102671328 4216832
06:11:59 30549 8192000000 10058132048 10502897664 1128344544 12.106 100 21 41 1 1162126464 29404384 4377536
06:13:33 102259 8192000000 10080321216 10558705664 1126537792 12.089 99 10 20 0 1183302464 54567616 2197056
06:16:16 80764 8192000000 10084629392 10551984128 1129538288 12.117 99 13 26 0 1179517888 47600736 2378864
06:18:56 47184 8192000000 10101919616 10559725568 1140264576 12.218 99 18 37 0 1183780112 40027584 3487952
06:20:50 43352 8192000000 10101978848 10554044416 1140700640 12.222 99 16 32 0 1191993536 47730528 3562368
06:22:40 53617 8192000000 10114935632 10589282304 1124785200 12.072 98 23 46 0 1197445648 67034656 5625792
06:25:42 39833 8192000000 10092331840 10604228608 1136662816 12.184 100 13 26 0 1209496848 70304304 2529728
06:27:24 47448 8192000000 10091049216 10555813888 1107835952 11.912 97 15 30 2 1179335840 68527216 2972672
06:29:11 81495 8192000000 10093177184 10559528960 1106052480 11.895 97 35 69 1 1168955312 55073152 7829680
06:32:37 49393 8192000000 10073917824 10539524096 1121214448 12.038 99 12 23 0 1168916128 45092304 2609376
06:34:55 61142 8192000000 10086683040 10568822784 1123350128 12.059 98 15 30 1 1186308432 59424096 3534208
06:36:57 59512 8192000000 10059868064 10514771968 1099182032 11.830 97 17 35 1 1139220928 36566688 3472208
06:38:59 96021 8192000000 10158835520 10636754944 1107685024 11.910 97 21 42 0 1227352752 115212944 4454784
06:41:35 101160 8192000000 10056366944 10550480896 1062364016 11.479 94 17 34 1 1136810000 70552240 3893744
06:44:17 49106 8192000000 10082631328 10515570688 1053823568 11.397 95 41 80 0 1124599888 62901696 7874624
06:46:07 84299 8192000000 10056091536 10530115584 1087194560 11.716 98 12 24 0 1172989568 83354752 2440256
06:49:36 59643 8192000000 10041855376 10488446976 1120289216 12.030 100 14 28 1 1140040928 16364080 3387632
06:52:18 59810 8192000000 10123758048 10599714816 1115788560 11.987 98 20 41 1 1213573888 92920928 4864400
06:54:23 46541 8192000000 10115342848 10561974272 1118830912 12.016 98 20 40 0 1203348720 79945216 4572592
06:56:20 54498 8192000000 10122443248 10560778240 1104301616 11.878 97 27 53 3 1184115040 73992416 5821008
06:58:19 56004 8192000000 10057407744 10479820800 1078002432 11.628 95 19 38 1 1096332208 14865488 3464288
07:00:17 57725 8192000000 10154819488 10608906240 1137878176 12.196 100 51 102 0 1196203376 48551328 9773872
07:02:32 61982 8192000000 10107683824 10578759680 1136159696 12.179 99 20 40 1 1212050048 71651152 4239200
07:04:37 139546 8192000000 10083167568 10550050816 1151084192 12.320 100 19 39 1 1185944544 30628992 4231360
07:08:09 52613 8192000000 10087275040 10549641216 1143658528 12.250 99 10 20 1 1178795552 32726960 2410064
07:10:02 68854 8192000000 10102996048 10579783680 1140985408 12.225 99 15 30 0 1213631472 69472368 3173696
07:12:49 26692 8192000000 10090787456 10533535744 1137682352 12.194 98 32 64 1 1169064736 24439936 6942448
07:14:26 33802 8192000000 10156726032 10608877568 1158815072 12.392 99 22 44 0 1233219328 69480288 4923968
07:16:02 64326 8192000000 10155329248 10628399104 1158549040 12.390 99 24 48 1 1254107200 90559600 4998560
07:18:06 41977 8192000000 10077225056 10539892736 1119180544 12.019 97 10 20 0 1173542048 51867600 2493904
07:19:49 37693 8192000000 10111716480 10552864768 1132243168 12.143 98 52 104 2 1186681600 44017968 10420464
07:21:35 127407 8192000000 10151486528 10642022400 1136421008 12.182 98 23 46 2 1239915488 98378224 5116256
07:24:42 85172 8192000000 10062517824 10514305024 1141115280 12.226 100 12 24 0 1195053808 50954960 2983568

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 22-11-2020 07:29:50 to 22-11-2020 07:30:05.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 95 v_add_invoice_to_stock 335544517
except 2 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-11-2020 07:30:06 to 22-11-2020 07:30:08.

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          230217
MON$OLDEST_ACTIVE               231353
MON$OLDEST_SNAPSHOT             231353
MON$NEXT_TRANSACTION            231353
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                       2130560
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             786
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2272 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2272 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2272 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.2272 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 ---
:46: 
:47: mscs-test-161.budzdorov.ru    Sun Nov 22 07:26:52 2020
:48:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:49: 
:50: 
:51: mscs-test-161.budzdorov.ru    Sun Nov 22 07:27:09 2020
:52:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:53: 
:54: 
:55: mscs-test-161.budzdorov.ru    Sun Nov 22 07:27:09 2020
:56:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:57: 
:58: 
:59: mscs-test-161.budzdorov.ru    Sun Nov 22 07:27:09 2020
:60:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:61: 
--- end of diff output ---

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

22.11.20 07:30:09. 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-11-22 07:30:11 No severe PSQL-related problems occured
22.11.20 07:30:13. End of report.