Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.12.2020 07: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 16692624 kB
MemAvailable 31334276 kB
Buffers 0 kB
Cached 14859404 kB
SwapCached 4820 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-27 07:26:53
DTS_END                         2020-12-27 07:26:53.7240
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/54602
TRN_ID                          237718
ATT_ID                          654
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: 9003.74 23198 1080449
customer order: creation 170.60 414 20472
customer order: refuse 14.18 324 1702
order to supplier: creation 44.56 1383 5347
order to supplier: removal 3.38 118177 406
invoice (draft): creation 44.07 3489 5288
invoice (draft): removal 51.37 964 6164
invoice accept: apply 41.55 18171 4986
invoice accept: cancel 46.28 877 5554
customer reserve: creation 3799.42 179 455930
customer reserve: removal 4492.01 70 539041
realization accept: apply 82.24 181 9869
realization accept: cancel 85.14 137 10217
payment from customer: creation 59.63 108 7155
payment from customer: removal 5.78 95 694
payment to supplier: creation 48.43 131 5811
payment to supplier: removal 3.58 99 429
service: total inventory turnovers 4.19 3196 503
service: total monetary turnovers 6.33 533 760
service: aggregate perf. data 0.98 12599 117
service: refresh index statistics 0.03 302840 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-12-2020 07:27:48 to 27-12-2020 07:27:50.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3373
TEST_TIME 2 4216
TEST_TIME 3 4391
TEST_TIME 4 4246
TEST_TIME 5 5185
TEST_TIME 6 5539
TEST_TIME 7 5647
TEST_TIME 8 5820
TEST_TIME 9 5718
TEST_TIME 10 6068
TEST_TIME 11 5962
TEST_TIME 12 6193
TEST_TIME 13 6621
TEST_TIME 14 6782
TEST_TIME 15 6797
TEST_TIME 16 7299
TEST_TIME 17 7494
TEST_TIME 18 7692
TEST_TIME 19 7836
TEST_TIME 20 7893
TEST_TIME 21 8575
TEST_TIME 22 8687
TEST_TIME 23 8561
TEST_TIME 24 9113
TEST_TIME 25 9279
TEST_TIME 26 9568
TEST_TIME 27 9788
TEST_TIME 28 11210
TEST_TIME 29 10973
TEST_TIME 30 11037
TEST_TIME 31 10825
TEST_TIME 32 10697
TEST_TIME 33 10418
TEST_TIME 34 10145
TEST_TIME 35 10073
TEST_TIME 36 10191
TEST_TIME 37 10102
TEST_TIME 38 10083
TEST_TIME 39 9991
TEST_TIME 40 9909
TEST_TIME 41 9876
TEST_TIME 42 9687
TEST_TIME 43 10071
TEST_TIME 44 9937
TEST_TIME 45 10022
TEST_TIME 46 9924
TEST_TIME 47 10062
TEST_TIME 48 10047
TEST_TIME 49 9986
TEST_TIME 50 10006
TEST_TIME 51 10001
TEST_TIME 52 10010
TEST_TIME 53 9921
TEST_TIME 54 9813
TEST_TIME 55 9692
TEST_TIME 56 9683
TEST_TIME 57 9595
TEST_TIME 58 9540
TEST_TIME 59 9520
TEST_TIME 60 9501
TEST_TIME 61 9483
TEST_TIME 62 9442
TEST_TIME 63 9443
TEST_TIME 64 9470
TEST_TIME 65 9572
TEST_TIME 66 9598
TEST_TIME 67 9583
TEST_TIME 68 9686
TEST_TIME 69 9699
TEST_TIME 70 9667
TEST_TIME 71 9630
TEST_TIME 72 9664
TEST_TIME 73 9666
TEST_TIME 74 9607
TEST_TIME 75 9529
TEST_TIME 76 9455
TEST_TIME 77 9416
TEST_TIME 78 9398
TEST_TIME 79 9332
TEST_TIME 80 9272
TEST_TIME 81 9277
TEST_TIME 82 9282
TEST_TIME 83 9259
TEST_TIME 84 9257
TEST_TIME 85 9233
TEST_TIME 86 9232
TEST_TIME 87 9268
TEST_TIME 88 9355
TEST_TIME 89 9335
TEST_TIME 90 9342
TEST_TIME 91 9411
TEST_TIME 92 9427
TEST_TIME 93 9332
TEST_TIME 94 9250
TEST_TIME 95 9194
TEST_TIME 96 9196
TEST_TIME 97 9157
TEST_TIME 98 9155
TEST_TIME 99 9148
TEST_TIME 100 9122
TEST_TIME 101 9147
TEST_TIME 102 9118
TEST_TIME 103 9166
TEST_TIME 104 9153
TEST_TIME 105 9187
TEST_TIME 106 9206
TEST_TIME 107 9267
TEST_TIME 108 9271
TEST_TIME 109 9199
TEST_TIME 110 9134
TEST_TIME 111 9094
TEST_TIME 112 9047
TEST_TIME 113 9035
TEST_TIME 114 9006
TEST_TIME 115 9007
TEST_TIME 116 8993
TEST_TIME 117 8960
TEST_TIME 118 8951
TEST_TIME 119 8972
TEST_TIME 120 9005

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 27-12-2020 07:27:52 to 27-12-2020 07:27:55.

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 1500700 1500700 0 0.00 0 33217 7 0 0
doc_list_biud 1500700 1500700 0 0.00 0 97280 32 0 0
sp_add_invoice_to_stock 5135 4986 149 2.90 43 176877 18171 0 149
sp_cancel_adding_invoice 5554 5554 0 0.00 6 66668 877 0 0
sp_cancel_client_order 1702 1702 0 0.00 4 8008 324 0 0
sp_cancel_customer_reserve 539041 539041 0 0.00 1 29846 71 0 0
sp_cancel_pay_from_customer 694 694 0 0.00 3 3371 95 0 0
sp_cancel_pay_to_supplier 429 429 0 0.00 3 5141 99 0 0
sp_cancel_supplier_invoice 6181 6164 17 0.27 2 97282 964 0 17
sp_cancel_supplier_order 406 406 0 0.00 6 1033109 118178 0 0
sp_cancel_write_off 10217 10217 0 0.00 1 30090 137 0 0
sp_client_order 20472 20472 0 0.00 4 24900 415 0 0
sp_customer_reserve 455930 455930 0 0.00 0 88900 180 0 0
sp_fill_shopping_cart 31093 31093 0 0.00 1 14245 44 0 0
sp_fill_shopping_cart_clo_res 455929 455929 0 0.00 0 27768 19 0 0
sp_get_clo_for_invoice 4985 4985 0 0.00 0 27627 403 0 0
sp_kill_cost_storno 16894 16894 0 0.00 0 8128 63 0 0
sp_kill_qstorno_ret_qs2qd 1094869 1094869 0 0.00 0 97279 38 0 0
sp_kill_qty_storno 577938 577938 0 0.00 0 97280 82 0 0
sp_lock_selected_doc 20579 20579 0 0.00 0 7443 5 0 0
sp_make_cost_storno 27820 27820 0 0.00 0 15370 57 0 0
sp_multiply_rows_for_pdistr 27820 27820 0 0.00 0 7990 31 0 0
sp_multiply_rows_for_qdistr 20472 20472 0 0.00 0 22752 240 0 0
sp_pay_from_customer 7155 7155 0 0.00 4 11837 108 0 0
sp_pay_to_supplier 5811 5811 0 0.00 5 10272 132 0 0
sp_payment_common 12966 12966 0 0.00 1 5877 20 0 0
sp_qd_handle_on_cancel_clo 1702 1702 0 0.00 0 8002 304 0 0
sp_qd_handle_on_invoice_upd_sts 10539 10539 0 0.00 0 33640 376 0 0
sp_qd_handle_on_reserve_upd_sts 9869 9869 0 0.00 0 22079 55 0 0
sp_reserve_write_off 9869 9869 0 0.00 7 22229 182 0 0
sp_supplier_invoice 5288 5288 0 0.00 7 93591 3489 0 0
sp_supplier_order 5347 5347 0 0.00 6 29789 1384 0 0
srv_aggregate_perf_data 117 117 0 0.00 2873 108317 12599 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2165 131139 48096 0 0
srv_find_qd_qs_mism 1032282 1032282 0 0.00 0 33215 6 0 0
srv_make_invnt_saldo 503 503 0 0.00 57 87558 3197 0 0
srv_make_money_saldo 760 760 0 0.00 1 13332 533 0 0
srv_recalc_idx_stat 4 4 0 0.00 66151 510085 302841 0 0
t$perf-abend:sp_add_invoice_to_stock 149 149 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 17 17 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4987 4987 0 0.00 0 9760 158 0 0
t$perf-norm:sp_cancel_adding_invoice 388 388 0 0.00 0 43757 224 0 0
t$perf-norm:sp_cancel_client_order 1702 1702 0 0.00 0 3932 18 0 0
t$perf-norm:sp_cancel_customer_reserve 680 680 0 0.00 0 3540 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 694 694 0 0.00 0 3170 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 429 429 0 0.00 0 5431 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 407 407 0 0.00 0 1753 14 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 20971 1547 0 0
t$perf-norm:sp_cancel_write_off 1019 1019 0 0.00 0 3390 10 0 0
t$perf-norm:sp_client_order 20472 20472 0 0.00 0 9990 20 0 0
t$perf-norm:sp_customer_reserve 965 965 0 0.00 0 5358 14 0 0
t$perf-norm:sp_pay_from_customer 7155 7155 0 0.00 0 3696 8 0 0
t$perf-norm:sp_pay_to_supplier 5811 5811 0 0.00 0 2243 8 0 0
t$perf-norm:sp_reserve_write_off 9869 9869 0 0.00 0 4520 10 0 0
t$perf-norm:sp_supplier_invoice 5288 5288 0 0.00 0 4216 16 0 0
t$perf-norm:sp_supplier_order 5347 5347 0 0.00 0 10018 17 0 0
t$perf-norm:srv_aggregate_perf_data 117 117 0 0.00 0 400 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 60 2 0 0
t$perf-norm:srv_make_invnt_saldo 503 503 0 0.00 0 6938 47 0 0
t$perf-norm:srv_make_money_saldo 760 760 0 0.00 0 4313 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 11 4 0 0
v_add_invoice_to_stock 10268 9970 298 2.90 0 5284 18 0 298
v_all_customers 37924 37924 0 0.00 0 75 1 0 0
v_all_suppliers 29562 29562 0 0.00 0 82 1 0 0
v_all_wares 40938 40938 0 0.00 0 51 1 0 0
v_cancel_adding_invoice 776 776 0 0.00 0 807 7 0 0
v_cancel_client_order 3404 3404 0 0.00 0 1240 4 0 0
v_cancel_customer_prepayment 1388 1388 0 0.00 0 3330 13 0 0
v_cancel_customer_reserve 1360 1360 0 0.00 0 1475 4 0 0
v_cancel_payment_to_supplier 858 858 0 0.00 0 571 6 0 0
v_cancel_supplier_invoice 848 814 34 4.00 0 349 13 0 34
v_cancel_supplier_order 812 812 0 0.00 0 471 4 0 0
v_cancel_write_off 2038 2038 0 0.00 0 2983 18 0 0
v_max_id_clo_ord 5346 5346 0 0.00 0 4611 3 0 0
v_max_id_clo_res 965 965 0 0.00 0 2316 12 0 0
v_max_id_ord_sup 5278 5278 0 0.00 0 7228 5 0 0
v_max_non_paid_invoice 1654 1654 0 0.00 0 1990 3 0 0
v_max_non_paid_realizn 3567 3567 0 0.00 0 896 2 0 0
v_min_id_clo_ord 5346 5346 0 0.00 0 4260 7 0 0
v_min_id_clo_res 965 965 0 0.00 0 2238 16 0 0
v_min_id_ord_sup 5278 5278 0 0.00 0 7752 13 0 0
v_min_non_paid_invoice 5811 5811 0 0.00 0 5037 13 0 0
v_min_non_paid_realizn 7155 7155 0 0.00 0 5873 14 0 0
v_our_firm 10190 10190 0 0.00 0 97 1 0 0
v_reserve_write_off 19738 19738 0 0.00 0 4640 6 0 0
x_lock_depdocs_on_canc_invoice 11311 11311 0 0.00 0 17868 116 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 19837 367 0 0
x_make_qstorno_1000_1200 5347 5347 0 0.00 2 29782 1304 0 0
x_make_qstorno_1200_2000 5288 5288 0 0.00 2 93582 3316 0 0
x_make_qstorno_2100_3300 455930 455930 0 0.00 0 82354 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-12-2020 07:27:56 to 27-12-2020 07:28:00.

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:57 5 8192000000 8796637808 8807108608 3167696 0.038 1 1 2 0 3265392 61840 35856
04:57:58 33108 8192000000 9719235472 10015764480 792575120 8.821 100 86 172 0 821249104 7758768 20915216
04:59:35 23916 8192000000 9761797152 10065960960 834443936 9.244 100 91 181 2 869113696 12832880 21836880
05:01:05 15321 8192000000 9805375232 10121314304 865533264 9.555 100 84 168 1 906362240 21342560 19486416
05:02:25 9810 8192000000 9874322816 10207842304 918227808 10.079 100 79 158 0 985945824 50656640 17061376
05:03:36 8997 8192000000 9916897152 10263162880 962247536 10.511 100 77 155 1 1038844960 60444384 16153040
05:04:48 4641 8192000000 9970430528 10322780160 995262848 10.833 100 85 170 1 1079463824 67614096 16586880
05:05:55 2283 8192000000 10001119584 10359263232 1021280208 11.084 100 87 172 2 1111003200 72453664 17269328
05:06:59 1740 8192000000 10035498544 10403909632 1050550304 11.366 100 87 174 0 1151870992 84274384 17046304
05:08:02 1694 8192000000 10013874480 10403164160 1061440960 11.470 100 53 107 0 1130911264 58220544 11249760
05:09:04 2425 8192000000 9977122512 10359242752 1064787088 11.502 100 57 113 1 1092442224 15272688 12382448
05:10:07 5577 8192000000 9996389216 10403258368 1075577216 11.605 100 31 62 2 1115446880 33541968 6327696
05:11:15 4706 8192000000 9997836448 10390642688 1081031904 11.657 100 49 99 0 1116273920 24751824 10490192
05:12:20 7960 8192000000 10000949584 10406256640 1086659728 11.711 100 37 74 3 1119886848 24534400 8692720
05:13:30 56405 8192000000 10020527488 10407206912 1091120768 11.753 100 53 109 2 1115196896 13192096 10884032
05:15:31 9902 8192000000 9998096560 10406789120 1099585872 11.834 100 31 62 1 1143164256 37405120 6173264
05:16:42 8855 8192000000 10017824464 10427039744 1093121968 11.772 100 42 85 0 1135707392 33424208 9161216
05:17:51 6817 8192000000 9981688944 10372743168 1039908432 11.264 97 45 91 2 1061575040 10848624 10817984
05:19:06 15231 8192000000 9966604080 10337837056 1024190032 11.112 97 67 134 2 1050904720 12412656 14302032
05:20:21 6782 8192000000 9915823616 10297499648 984011920 10.723 96 43 85 1 1008208496 13637136 10559440
05:21:31 8152 8192000000 9952070608 10342588416 1031858736 11.186 100 30 60 3 1064278496 25767392 6652368
05:22:39 52402 8192000000 9938909472 10335117312 1031249952 11.180 99 27 54 3 1053887200 16194288 6442960
05:24:39 5913 8192000000 9976706256 10372501504 1065233376 11.507 100 47 92 2 1095372624 19814256 10324992
05:25:45 7503 8192000000 9997763872 10396626944 1069960400 11.552 100 26 53 1 1086694336 11225840 5508096
05:26:57 28560 8192000000 10012778192 10408075264 1100825712 11.845 100 35 70 1 1119820352 11497296 7497344
05:28:28 81999 8192000000 10067757008 10480734208 1120497008 12.032 100 38 76 2 1198137648 69226688 8413952
05:30:52 25737 8192000000 10095972416 10519015424 1128567408 12.108 100 38 76 0 1196346336 59725424 8053504
05:32:34 13801 8192000000 10146956528 10551754752 1143548976 12.249 100 52 104 0 1249803872 95228592 11026304
05:33:52 8227 8192000000 10169942016 10577776640 1150982016 12.319 100 61 122 0 1285359344 121580848 12796480
05:35:08 24962 8192000000 10208760816 10626609152 1159243696 12.396 100 54 107 1 1306140320 134888336 12008288
05:36:42 12533 8192000000 10203064960 10623537152 1164502864 12.445 100 44 88 1 1319279152 143777536 10998752
05:38:01 6730 8192000000 10270126480 10673700864 1169982064 12.497 100 75 148 1 1371762192 185019232 16760896
05:39:10 9394 8192000000 10285753696 10696884224 1146665792 12.278 99 66 132 0 1385979712 223934128 15379792
05:40:27 5343 8192000000 10278602336 10681999360 1157784800 12.383 100 79 158 3 1380885360 205094816 18005744
05:41:39 6981 8192000000 10289471840 10693582848 1145897600 12.271 100 68 136 1 1387154048 225374624 15881824
05:42:57 2164 8192000000 10281762096 10670530560 1123795248 12.063 99 88 174 0 1378000384 234714752 19490384
05:44:04 2735 8192000000 10338582928 10741805056 1153842608 12.346 100 69 137 0 1436937968 266432016 16663344
05:45:11 6566 8192000000 10316870512 10726715392 1124086832 12.066 98 62 123 3 1413166544 274286528 14793184
05:46:29 2714 8192000000 10372108656 10768044032 1143670352 12.250 100 87 173 3 1464247744 300926432 19650960
05:47:37 6165 8192000000 10364234320 10796531712 1154206304 12.349 100 57 114 1 1461642144 293350896 14084944
05:48:52 14421 8192000000 10366256384 10785652736 1132389280 12.144 98 34 68 1 1454343120 311282032 10671808
05:50:20 13015 8192000000 10389472528 10866503680 1153916288 12.346 100 38 76 2 1514238336 349977072 10344976
05:51:35 15663 8192000000 10402507152 10858090496 1135699392 12.175 99 30 60 4 1498776448 354972880 8104176
05:53:30 33561 8192000000 10341375696 10800676864 1139915920 12.215 100 23 46 1 1445906208 299835984 6154304
05:55:27 43962 8192000000 10088720944 10510450688 1106807008 11.902 98 31 63 3 1133936768 20574096 6555664
05:57:15 33138 8192000000 10096349872 10528120832 1139960368 12.215 100 37 74 0 1176830464 29452080 7418016
05:58:50 131139 8192000000 10141564304 10579005440 1149074688 12.301 100 41 79 3 1233591504 76567056 7949760
06:02:17 37614 8192000000 10113369136 10645831680 1136248048 12.180 99 15 31 1 1218249056 79011888 2989120
06:03:55 57866 8192000000 10134399008 10603618304 1152647040 12.334 100 20 40 1 1230923568 73801408 4475120
06:06:08 54172 8192000000 10169157504 10639208448 1159617888 12.400 100 17 36 0 1275261792 111414208 4229696
06:08:15 41943 8192000000 10192075344 10637115392 1156908240 12.374 100 9 18 0 1293679248 134411984 2359024
06:10:11 16775 8192000000 10117422672 10562060288 1163891104 12.440 100 41 83 0 1201850816 29230912 8728800
06:11:37 22810 8192000000 10091029808 10530992128 1143033520 12.244 98 25 50 0 1183891056 35783984 5073552
06:13:15 91674 8192000000 10115772128 10615484416 1157659264 12.381 100 19 36 1 1220601328 58638336 4303728
06:15:50 87007 8192000000 10107535696 10583375872 1142193168 12.236 99 20 39 3 1209794944 63364080 4237696
06:18:20 42248 8192000000 10099086752 10555355136 1154617936 12.353 100 21 42 1 1214647728 55920064 4109728
06:20:05 123191 8192000000 10100529760 10556116992 1117785296 12.006 97 19 38 2 1159247408 37752832 3709280
06:23:17 62120 8192000000 10124032688 10611470336 1135275200 12.171 100 6 12 0 1221137648 84525904 1336544
06:25:36 43521 8192000000 10066704864 10513063936 1102866528 11.865 97 6 13 0 1152123728 48293312 963888
06:27:20 75410 8192000000 10122083952 10561867776 1091407680 11.756 96 16 32 1 1139943600 45854240 2681680
06:29:36 63042 8192000000 10099430432 10548326400 1074456176 11.595 95 26 52 3 1193160464 112830864 5873424
06:31:40 50279 8192000000 10043272720 10499092480 1071082448 11.562 96 14 28 1 1108624016 34531504 3010064
06:33:32 61608 8192000000 10057635424 10544386048 1082559200 11.672 97 33 66 1 1178149760 89217792 6372768
06:35:39 58534 8192000000 10056611456 10515132416 1076669392 11.616 97 29 57 2 1123273712 40935760 5668560
06:37:41 46039 8192000000 10128639648 10577653760 1089750704 11.740 98 27 54 1 1175764224 81010720 5002800
06:39:38 105816 8192000000 10046990288 10502418432 1096753360 11.807 99 19 38 1 1145184368 43913680 4517328
06:42:31 88503 8192000000 10056618480 10525650944 1104505696 11.880 99 8 16 2 1141496304 35224064 1766544
06:45:00 40577 8192000000 10050851360 10558545920 1098265152 11.821 99 6 12 0 1150518256 50964416 1288688
06:46:46 49027 8192000000 10062413568 10526683136 1115332272 11.983 99 15 29 2 1154868352 36267232 3268848
06:48:35 47607 8192000000 10064233968 10510127104 1075855072 11.608 95 20 40 2 1124341664 44516688 3969904
06:50:25 49544 8192000000 10130875712 10564448256 1136192592 12.180 100 25 50 2 1221126992 79398192 5536208
06:52:20 40404 8192000000 10199226512 10664022016 1124941376 12.074 98 27 54 2 1236412432 105715456 5755600
06:54:01 115306 8192000000 10071179888 10520371200 1090905264 11.751 96 9 18 0 1136332528 43721216 1706048
06:56:58 71568 8192000000 10106979552 10582441984 1147591168 12.287 100 7 14 0 1214366592 65789344 986080
06:59:09 117619 8192000000 10122506688 10606538752 1137476944 12.192 99 20 40 1 1186653328 45055232 4121152
07:02:09 57477 8192000000 10115512592 10614657024 1152052352 12.329 100 8 16 0 1218550640 64703888 1794400
07:04:12 83050 8192000000 10100708528 10565976064 1145123376 12.264 99 21 40 2 1178937568 29048736 4765456
07:06:47 69490 8192000000 10158200656 10621231104 1128647168 12.109 97 21 42 1 1198098656 64720416 4731072
07:09:02 46941 8192000000 10148888560 10610970624 1132736640 12.147 98 23 48 0 1230513808 92531104 5246064
07:10:54 44931 8192000000 10103819280 10592186368 1126534640 12.089 98 32 62 1 1207740112 74565104 6640368
07:12:44 113558 8192000000 10093401552 10596519936 1074337744 11.593 93 8 16 1 1135204272 59152928 1713600
07:15:38 57987 8192000000 10081230768 10561773568 1139433248 12.210 100 12 24 0 1185236016 43064272 2738496
07:17:54 73988 8192000000 10087056688 10555215872 1128403024 12.106 99 9 18 0 1157986784 27702640 1881120
07:20:32 23271 8192000000 10054080688 10495504384 1103217808 11.868 98 29 56 4 1127177728 17606624 6353296
07:22:02 33823 8192000000 10044102864 10501812224 1103046432 11.867 99 15 30 1 1131838704 25918048 2874224
07:23:40 66877 8192000000 10109293632 10560401408 1102202288 11.859 98 25 50 1 1183955392 76764768 4988336
07:25:53 67242 8192000000 9369955424 9745326080 472596704 5.454 42 11 22 1 563391840 88037072 2758064

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 149 v_add_invoice_to_stock 335544517
except 17 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 27-12-2020 07:28:18 to 27-12-2020 07:28:20.

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          237945
MON$OLDEST_ACTIVE               237946
MON$OLDEST_SNAPSHOT             237946
MON$NEXT_TRANSACTION            237946
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                       2097088
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             806
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2307 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2307 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2307 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.2307 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 ---
:42: 
:43: mscs-test-161.budzdorov.ru    Sun Dec 27 07:27:03 2020
:44:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:45: 
:46: 
:47: mscs-test-161.budzdorov.ru    Sun Dec 27 07:27:03 2020
:48:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:49: 
:50: 
:51: mscs-test-161.budzdorov.ru    Sun Dec 27 07:27:13 2020
:52:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:53: 
--- end of diff output ---

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

27.12.20 07:28:21. 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-27 07:28:24 No severe PSQL-related problems occured
27.12.20 07:28:25. End of report.