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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 16713124 kB
MemAvailable 31385840 kB
Buffers 0 kB
Cached 14832108 kB
SwapCached 3712 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-12-17 13:26:54
DTS_END                         2020-12-17 13:26:54.3330
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/40110
TRN_ID                          229515
ATT_ID                          669
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: 9013.28 23699 1081594
customer order: creation 161.51 377 19381
customer order: refuse 13.48 344 1618
order to supplier: creation 41.72 1295 5006
order to supplier: removal 3.39 102806 407
invoice (draft): creation 43.74 3484 5249
invoice (draft): removal 51.95 834 6234
invoice accept: apply 40.12 17346 4814
invoice accept: cancel 47.70 872 5724
customer reserve: creation 3702.42 170 444290
customer reserve: removal 4629.21 59 555505
realization accept: apply 78.72 155 9446
realization accept: cancel 79.03 108 9484
payment from customer: creation 55.63 114 6676
payment from customer: removal 5.75 79 690
payment to supplier: creation 45.08 116 5409
payment to supplier: removal 3.37 114 404
service: total inventory turnovers 3.90 3120 468
service: total monetary turnovers 5.71 474 685
service: aggregate perf. data 0.83 16704 100
service: refresh index statistics 0.03 325405 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-12-2020 13:27:39 to 17-12-2020 13:27:41.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2365
TEST_TIME 2 3713
TEST_TIME 3 4298
TEST_TIME 4 4224
TEST_TIME 5 5148
TEST_TIME 6 5055
TEST_TIME 7 5475
TEST_TIME 8 5756
TEST_TIME 9 5742
TEST_TIME 10 5762
TEST_TIME 11 6248
TEST_TIME 12 6461
TEST_TIME 13 6822
TEST_TIME 14 7066
TEST_TIME 15 7427
TEST_TIME 16 7659
TEST_TIME 17 8209
TEST_TIME 18 8368
TEST_TIME 19 8371
TEST_TIME 20 8542
TEST_TIME 21 8510
TEST_TIME 22 9300
TEST_TIME 23 9813
TEST_TIME 24 10130
TEST_TIME 25 10196
TEST_TIME 26 9895
TEST_TIME 27 10613
TEST_TIME 28 10452
TEST_TIME 29 10225
TEST_TIME 30 10204
TEST_TIME 31 10164
TEST_TIME 32 10019
TEST_TIME 33 10127
TEST_TIME 34 10040
TEST_TIME 35 9992
TEST_TIME 36 10048
TEST_TIME 37 9915
TEST_TIME 38 9848
TEST_TIME 39 9895
TEST_TIME 40 9941
TEST_TIME 41 9957
TEST_TIME 42 10000
TEST_TIME 43 10001
TEST_TIME 44 9927
TEST_TIME 45 9746
TEST_TIME 46 9677
TEST_TIME 47 9666
TEST_TIME 48 9526
TEST_TIME 49 9592
TEST_TIME 50 9572
TEST_TIME 51 9491
TEST_TIME 52 9379
TEST_TIME 53 9317
TEST_TIME 54 9291
TEST_TIME 55 9284
TEST_TIME 56 9246
TEST_TIME 57 9420
TEST_TIME 58 9435
TEST_TIME 59 9452
TEST_TIME 60 9565
TEST_TIME 61 9631
TEST_TIME 62 9734
TEST_TIME 63 9679
TEST_TIME 64 9688
TEST_TIME 65 9686
TEST_TIME 66 9805
TEST_TIME 67 9792
TEST_TIME 68 9686
TEST_TIME 69 9591
TEST_TIME 70 9553
TEST_TIME 71 9440
TEST_TIME 72 9334
TEST_TIME 73 9268
TEST_TIME 74 9272
TEST_TIME 75 9272
TEST_TIME 76 9215
TEST_TIME 77 9136
TEST_TIME 78 9171
TEST_TIME 79 9157
TEST_TIME 80 9215
TEST_TIME 81 9190
TEST_TIME 82 9221
TEST_TIME 83 9229
TEST_TIME 84 9274
TEST_TIME 85 9272
TEST_TIME 86 9323
TEST_TIME 87 9396
TEST_TIME 88 9418
TEST_TIME 89 9375
TEST_TIME 90 9289
TEST_TIME 91 9257
TEST_TIME 92 9204
TEST_TIME 93 9250
TEST_TIME 94 9198
TEST_TIME 95 9191
TEST_TIME 96 9169
TEST_TIME 97 9149
TEST_TIME 98 9214
TEST_TIME 99 9268
TEST_TIME 100 9240
TEST_TIME 101 9229
TEST_TIME 102 9236
TEST_TIME 103 9237
TEST_TIME 104 9276
TEST_TIME 105 9244
TEST_TIME 106 9254
TEST_TIME 107 9257
TEST_TIME 108 9252
TEST_TIME 109 9171
TEST_TIME 110 9133
TEST_TIME 111 9072
TEST_TIME 112 9059
TEST_TIME 113 9015
TEST_TIME 114 8983
TEST_TIME 115 9032
TEST_TIME 116 9023
TEST_TIME 117 8999
TEST_TIME 118 9002
TEST_TIME 119 9027
TEST_TIME 120 9018

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-12-2020 13:27:43 to 17-12-2020 13:27:46.

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 1493761 1493761 0 0.00 0 16228 6 0 0
doc_list_biud 1493760 1493760 0 0.00 0 36493 28 0 0
sp_add_invoice_to_stock 4926 4814 112 2.27 44 179446 17346 0 112
sp_cancel_adding_invoice 5724 5724 0 0.00 4 88826 873 0 0
sp_cancel_client_order 1618 1618 0 0.00 5 10368 344 0 0
sp_cancel_customer_reserve 555505 555505 0 0.00 1 27559 59 0 0
sp_cancel_pay_from_customer 690 690 0 0.00 3 5899 80 0 0
sp_cancel_pay_to_supplier 404 404 0 0.00 3 5939 114 0 0
sp_cancel_supplier_invoice 6248 6234 14 0.22 2 19393 834 0 14
sp_cancel_supplier_order 407 407 0 0.00 7 977408 102807 0 0
sp_cancel_write_off 9484 9484 0 0.00 1 9617 108 0 0
sp_client_order 19381 19381 0 0.00 4 27327 377 0 0
sp_customer_reserve 444290 444290 0 0.00 0 57776 171 0 0
sp_fill_shopping_cart 29629 29629 0 0.00 1 16710 43 0 0
sp_fill_shopping_cart_clo_res 444277 444277 0 0.00 0 20275 19 0 0
sp_get_clo_for_invoice 4800 4800 0 0.00 0 24986 380 0 0
sp_kill_cost_storno 16302 16302 0 0.00 0 10374 55 0 0
sp_kill_qstorno_ret_qs2qd 1127135 1127135 0 0.00 0 26753 32 0 0
sp_kill_qty_storno 593218 593218 0 0.00 0 36493 70 0 0
sp_lock_selected_doc 19747 19747 0 0.00 0 9877 4 0 0
sp_make_cost_storno 26331 26331 0 0.00 0 14991 51 0 0
sp_multiply_rows_for_pdistr 26331 26331 0 0.00 0 8151 26 0 0
sp_multiply_rows_for_qdistr 19380 19380 0 0.00 0 27270 209 0 0
sp_pay_from_customer 6676 6676 0 0.00 4 12669 115 0 0
sp_pay_to_supplier 5409 5409 0 0.00 5 10236 117 0 0
sp_payment_common 12085 12085 0 0.00 1 9463 23 0 0
sp_qd_handle_on_cancel_clo 1618 1618 0 0.00 0 8102 311 0 0
sp_qd_handle_on_invoice_upd_sts 10524 10524 0 0.00 0 36493 369 0 0
sp_qd_handle_on_reserve_upd_sts 9446 9446 0 0.00 0 8110 45 0 0
sp_reserve_write_off 9446 9446 0 0.00 7 13376 155 0 0
sp_supplier_invoice 5249 5249 0 0.00 6 137809 3485 0 0
sp_supplier_order 5006 5006 0 0.00 5 37572 1296 0 0
srv_aggregate_perf_data 100 100 0 0.00 3068 219348 16705 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 1529 120685 50956 0 0
srv_find_qd_qs_mism 1036910 1036910 0 0.00 0 16228 5 0 0
srv_make_invnt_saldo 468 468 0 0.00 53 111774 3121 0 0
srv_make_money_saldo 685 685 0 0.00 1 12493 475 0 0
srv_recalc_idx_stat 4 4 0 0.00 105216 464146 325405 0 0
t$perf-abend:sp_add_invoice_to_stock 112 112 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4815 4815 0 0.00 0 18775 176 0 0
t$perf-norm:sp_cancel_adding_invoice 403 403 0 0.00 0 4704 83 0 0
t$perf-norm:sp_cancel_client_order 1618 1618 0 0.00 0 4895 18 0 0
t$perf-norm:sp_cancel_customer_reserve 704 704 0 0.00 0 350 6 0 0
t$perf-norm:sp_cancel_pay_from_customer 690 690 0 0.00 0 4016 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 404 404 0 0.00 0 244 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 340 340 0 0.00 0 2786 18 0 0
t$perf-norm:sp_cancel_supplier_order 407 407 0 0.00 0 24273 1495 0 0
t$perf-norm:sp_cancel_write_off 935 935 0 0.00 0 684 4 0 0
t$perf-norm:sp_client_order 19381 19381 0 0.00 0 5111 13 0 0
t$perf-norm:sp_customer_reserve 846 846 0 0.00 0 10736 15 0 0
t$perf-norm:sp_pay_from_customer 6676 6676 0 0.00 0 6222 11 0 0
t$perf-norm:sp_pay_to_supplier 5409 5409 0 0.00 0 10485 9 0 0
t$perf-norm:sp_reserve_write_off 9446 9446 0 0.00 0 7326 12 0 0
t$perf-norm:sp_supplier_invoice 5249 5249 0 0.00 0 10103 18 0 0
t$perf-norm:sp_supplier_order 5006 5006 0 0.00 0 10012 15 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 445 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 172 5 0 0
t$perf-norm:srv_make_invnt_saldo 468 468 0 0.00 0 3196 17 0 0
t$perf-norm:srv_make_money_saldo 685 685 0 0.00 0 929 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 4 0 0
v_add_invoice_to_stock 9824 9600 224 2.28 0 6637 18 0 224
v_all_customers 35138 35138 0 0.00 0 110 1 0 0
v_all_suppliers 28126 28126 0 0.00 0 25 1 0 0
v_all_wares 38760 38760 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 806 806 0 0.00 0 664 8 0 0
v_cancel_client_order 3236 3236 0 0.00 0 3153 6 0 0
v_cancel_customer_prepayment 1380 1380 0 0.00 0 1046 6 0 0
v_cancel_customer_reserve 1408 1408 0 0.00 0 1704 6 0 0
v_cancel_payment_to_supplier 808 808 0 0.00 0 3888 11 0 0
v_cancel_supplier_invoice 708 680 28 3.95 0 1933 16 0 28
v_cancel_supplier_order 814 814 0 0.00 0 2778 9 0 0
v_cancel_write_off 1870 1870 0 0.00 0 2731 14 0 0
v_max_id_clo_ord 5006 5006 0 0.00 0 756 1 0 0
v_max_id_clo_res 846 846 0 0.00 0 2625 13 0 0
v_max_id_ord_sup 5243 5243 0 0.00 0 7912 8 0 0
v_max_non_paid_invoice 1595 1595 0 0.00 0 35 2 0 0
v_max_non_paid_realizn 3650 3650 0 0.00 0 3693 5 0 0
v_min_id_clo_ord 5006 5006 0 0.00 0 905 3 0 0
v_min_id_clo_res 846 846 0 0.00 0 3298 9 0 0
v_min_id_ord_sup 5243 5243 0 0.00 0 4964 7 0 0
v_min_non_paid_invoice 5409 5409 0 0.00 0 5968 13 0 0
v_min_non_paid_realizn 6676 6676 0 0.00 0 7594 18 0 0
v_our_firm 9674 9674 0 0.00 0 19 1 0 0
v_reserve_write_off 18892 18892 0 0.00 0 4564 6 0 0
x_lock_depdocs_on_canc_invoice 11618 11618 0 0.00 0 22576 111 0 0
x_lock_depdocs_on_canc_sup_ord 407 407 0 0.00 0 17788 371 0 0
x_make_qstorno_1000_1200 5006 5006 0 0.00 2 37432 1225 0 0
x_make_qstorno_1200_2000 5249 5249 0 0.00 3 137742 3316 0 0
x_make_qstorno_2100_3300 444290 444290 0 0.00 0 57736 150 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-12-2020 13:27:47 to 17-12-2020 13:27:51.

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
10:56:58 8 8192000000 8796641776 8807174144 3168704 0.038 1 1 2 0 3266400 61840 35856
10:57:58 19948 8192000000 9727390336 10023522304 805831584 8.955 100 90 178 1 834865312 7740416 21293312
10:59:21 22460 8192000000 9759067008 10063663104 830316224 9.202 100 86 172 0 863573888 12663360 20594304
11:00:47 8192 8192000000 9783535600 10101346304 856936160 9.470 100 78 156 1 892991136 18322352 17732624
11:02:03 15145 8192000000 9852375648 10178617344 901915168 9.917 100 87 174 1 962808864 41796128 19097568
11:03:21 10239 8192000000 9913765248 10252103680 936483552 10.258 100 86 172 1 1023754976 69181760 18089664
11:04:34 5893 8192000000 9950693024 10303655936 983130912 10.715 100 76 152 1 1061849248 63475888 15242448
11:05:44 3451 8192000000 9981168752 10343731200 1013749984 11.012 100 81 163 2 1097457840 67944256 15763600
11:06:49 2010 8192000000 10012001024 10376355840 1046297168 11.325 100 92 184 3 1127476240 62401264 18777808
11:07:53 1033 8192000000 10046318336 10415783936 1063549808 11.490 100 92 185 1 1161572128 79278720 18743600
11:08:55 3468 8192000000 10004409632 10395619328 1073961504 11.590 100 51 102 2 1120681232 36171968 10547760
11:10:01 42172 8192000000 10013818288 10414837760 1082593968 11.672 100 45 90 1 1128310784 35387664 10329152
11:11:50 6049 8192000000 10016158112 10412732416 1090112016 11.744 100 41 83 1 1136930992 37326416 9492560
11:12:58 8711 8192000000 10018320096 10415939584 1094721632 11.788 100 23 46 0 1133123856 33536512 4865712
11:14:09 63568 8192000000 10007071104 10418302976 1102626160 11.863 100 36 69 4 1125833504 14543008 8664336
11:16:15 3461 8192000000 10015430976 10410037248 1097773504 11.817 100 59 119 4 1130715312 19721760 13220048
11:17:21 4541 8192000000 10002905568 10389528576 1074637680 11.596 98 50 98 3 1097815664 12257936 10920048
11:18:27 6982 8192000000 9962654368 10360799232 1003181728 10.909 94 20 40 1 1022528832 15175056 4172048
11:19:41 22324 8192000000 9969632784 10343464960 1020759552 11.079 97 65 133 0 1048513472 13626080 14127840
11:21:04 3998 8192000000 9927392256 10298798080 1002316896 10.901 98 46 91 1 1026828144 14135840 10375408
11:22:09 9612 8192000000 9959767888 10345054208 1017879776 11.052 98 37 75 1 1051854928 25542368 8432784
11:23:19 8632 8192000000 9966907664 10371137536 1055505248 11.413 100 45 88 1 1088717296 22744288 10467760
11:24:28 14755 8192000000 9964061328 10372268032 1061646048 11.472 100 22 44 3 1090932080 23706944 5579088
11:25:46 3796 8192000000 9987897328 10381180928 1069127760 11.544 100 57 111 2 1098406880 16967440 12311680
11:26:53 6298 8192000000 10017896096 10415050752 1090797616 11.750 100 27 53 3 1118065488 22115168 5152704
11:28:06 9378 8192000000 10058920656 10456084480 1112799600 11.959 100 56 113 0 1172471312 48164720 11506992
11:29:18 47163 8192000000 10085869680 10506117120 1119073328 12.018 100 36 70 2 1197959152 71562352 7323472
11:31:06 5861 8192000000 10128086368 10541731840 1133546752 12.155 100 47 94 1 1226021504 82429120 10045632
11:32:15 5853 8192000000 10145724480 10553040896 1150800128 12.317 100 55 110 0 1245663648 82731472 12132048
11:33:25 4575 8192000000 10184884928 10588041216 1155965936 12.365 100 64 128 1 1290066048 120407472 13692640
11:34:34 14640 8192000000 10236192208 10649370624 1160374224 12.407 100 56 112 0 1334930704 161946304 12610176
11:36:02 16858 8192000000 10255486528 10676867072 1175092656 12.544 100 51 102 3 1360583728 173593776 11897296
11:37:22 4725 8192000000 10306043424 10715164672 1190486624 12.688 100 68 133 0 1394068784 188936752 14645408
11:38:32 1527 8192000000 10327250656 10735185920 1201356272 12.789 100 92 184 1 1426162544 205147952 19658320
11:39:39 7316 8192000000 10367906320 10783076352 1194441568 12.725 100 75 149 0 1469774784 258310992 17022224
11:40:51 6422 8192000000 10384974944 10799534080 1190155952 12.685 100 78 155 1 1481215488 272698352 18361184
11:42:03 7565 8192000000 10350188608 10787098624 1177705648 12.569 99 64 128 0 1450268640 257441712 15121280
11:43:14 11976 8192000000 10345771664 10779074560 1137451872 12.192 99 50 100 2 1457037536 307322864 12262800
11:44:29 21160 8192000000 10321465776 10790547456 1155105968 12.357 100 25 50 2 1417010480 255547456 6357056
11:46:10 19900 8192000000 10372363056 10801471488 1153254576 12.340 100 29 58 1 1456104352 294747824 8101952
11:47:36 13234 8192000000 10341759088 10787008512 1130436896 12.125 99 33 64 0 1449816640 310589712 8790032
11:48:52 22048 8192000000 10291475888 10734821376 1141653456 12.231 100 24 46 2 1408078512 259799376 6625680
11:50:24 37090 8192000000 10226131072 10724524032 1139528384 12.211 99 16 32 2 1316894544 173406784 3959376
11:52:06 83883 8192000000 10147536368 10667765760 1127753824 12.100 99 20 41 1 1280812512 148407024 4651664
11:54:32 59258 8192000000 10073108144 10534285312 1072503104 11.576 96 5 10 0 1135437392 61875744 1058544
11:56:31 44632 8192000000 10069220496 10497650688 1091029776 11.752 97 46 90 1 1162003792 61338848 9635168
11:58:16 120685 8192000000 10029683024 10490810368 1069680752 11.549 96 18 36 0 1124973472 51816720 3476000
12:01:18 88998 8192000000 10053728400 10524123136 1064187968 11.497 95 10 20 0 1098108528 31867648 2052912
12:03:47 22715 8192000000 10060375408 10509295616 1122410000 12.050 100 28 56 2 1174432160 46333968 5688192
12:05:28 63601 8192000000 10076499504 10554839040 1111595856 11.948 98 17 34 0 1200143696 84954304 3593536
12:08:03 83918 8192000000 10068441456 10548621312 1121974400 12.046 99 7 14 0 1162326592 38769120 1583072
12:10:29 114454 8192000000 10135842848 10580348928 1122266880 12.048 99 26 52 2 1199302704 70274800 6761024
12:14:17 40570 8192000000 10102116400 10536411136 1134825008 12.167 100 31 61 1 1191960272 50789536 6345728
12:15:59 64683 8192000000 10064618912 10516496384 1134509232 12.164 99 14 29 1 1161416512 23753168 3154112
12:19:05 80686 8192000000 10171342176 10615259136 1158890224 12.393 100 22 44 1 1261179648 96928928 5360496
12:21:30 67587 8192000000 10194008832 10667761664 1168070928 12.479 100 23 46 1 1306838832 133402688 5365216
12:23:44 104619 8192000000 10103156256 10597543936 1096913808 11.808 95 12 24 0 1197175232 97442464 2818960
12:26:29 75828 8192000000 10117923936 10640158720 1138018880 12.197 98 14 28 2 1242524992 101473376 3032736
12:28:45 96988 8192000000 10100822192 10546339840 1065957968 11.513 93 28 56 0 1104675744 33241568 5476208
12:31:23 54383 8192000000 10085953232 10574266368 1103684224 11.873 97 20 40 2 1159583472 52458624 3440624
12:33:52 72110 8192000000 10079619152 10519932928 1127759472 12.100 100 45 88 2 1170982416 34193664 9029280
12:36:43 73451 8192000000 10056148432 10526326784 1117504272 12.003 100 7 12 2 1159613008 40558048 1550688
12:39:05 72664 8192000000 10086266912 10580471808 1122416352 12.050 100 16 32 1 1211686960 85620880 3649728
12:41:31 49224 8192000000 10069071328 10554802176 1103636192 11.872 98 18 36 1 1147787936 40293920 3857824
12:43:28 81472 8192000000 10111089360 10582142976 1108727760 11.920 98 18 36 2 1204966016 91897056 4341200
12:45:58 55172 8192000000 10049887728 10481324032 1081218496 11.659 96 15 30 2 1120669488 36683344 2767648
12:47:53 53345 8192000000 10107391712 10554077184 1104276080 11.878 98 22 44 0 1186361392 76967008 5118304
12:49:52 37929 8192000000 10060611360 10510557184 1105740912 11.892 99 26 52 1 1165808240 55028304 5039024
12:51:30 104890 8192000000 10086633984 10567856128 1042594000 11.290 93 19 38 0 1104083888 57787840 3702048
12:54:29 74767 8192000000 10059240320 10484604928 1050967856 11.370 94 37 73 0 1090498080 31565248 7964976
12:56:45 111365 8192000000 10081391488 10553413632 1102815792 11.864 99 20 40 2 1183827440 76959376 4052272
12:59:54 51596 8192000000 10062742160 10579730432 1104592016 11.881 100 4 7 0 1148093280 43019072 482192
13:01:46 69399 8192000000 10093101392 10540613632 1098289056 11.821 98 25 51 0 1139221008 36111808 4820144
13:04:00 66379 8192000000 10115278368 10667057152 1131219392 12.133 100 18 36 0 1233406032 98300576 3886064
13:06:07 51190 8192000000 10072939008 10507677696 1116063424 11.990 99 17 34 0 1145187632 25817760 3306448
13:08:02 32459 8192000000 10081617760 10523725824 1107128752 11.905 98 25 50 2 1159078992 46879424 5070816
13:09:35 65986 8192000000 10087133664 10555420672 1124075824 12.065 99 19 38 1 1182912928 55063216 3773888
13:11:43 22361 8192000000 10111643840 10531213312 1135847056 12.176 100 48 97 1 1185093232 39871264 9374912
13:13:06 32721 8192000000 10081649776 10543476736 1136828816 12.186 100 14 28 0 1191093408 51398432 2866160
13:14:40 88013 8192000000 10072431680 10514231296 1140444688 12.220 100 11 22 0 1181680208 39129056 2106464
13:19:20 70135 8192000000 10090452160 10544435200 1123104832 12.056 98 13 27 1 1174484272 48672704 2706736
13:21:31 49162 8192000000 10070669168 10545324032 1124468384 12.069 99 11 22 1 1162322528 35472336 2381808
13:23:39 37664 8192000000 10100140944 10550935552 1116499104 11.994 98 23 46 1 1193558272 71718048 5341120
13:25:17 102687 8192000000 9358522144 9701920768 476790224 5.500 43 3 6 0 495887776 18606064 491488

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 17-12-2020 13:27:53 to 17-12-2020 13:28:08.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 112 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-12-2020 13:28:09 to 17-12-2020 13:28:11.

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          229752
MON$OLDEST_ACTIVE               229753
MON$OLDEST_SNAPSHOT             229753
MON$NEXT_TRANSACTION            229753
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                       2105600
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             789
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2297 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2297 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2297 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.2297 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 ---
:20: 
:21: mscs-test-161.budzdorov.ru    Thu Dec 17 13:27:03 2020
:22:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:23: 
:24: 
:25: mscs-test-161.budzdorov.ru    Thu Dec 17 13:27:06 2020
:26:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:27: 
--- end of diff output ---

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

17.12.20 13:28:12. 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-12-17 13:28:14 No severe PSQL-related problems occured
17.12.20 13:28:16. End of report.