Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 31.01.2021 04: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 16556712 kB
MemAvailable 31181248 kB
Buffers 20 kB
Cached 15037368 kB
SwapCached 4868 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.0
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-01-31 04:26:56
DTS_END                         2021-01-31 04:26:56.7400
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/53218
TRN_ID                          225370
ATT_ID                          614
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: 8730.14 30957 1047617
customer order: creation 151.96 478 18235
customer order: refuse 14.39 327 1727
order to supplier: creation 41.54 1371 4985
order to supplier: removal 3.19 123508 383
invoice (draft): creation 42.63 3612 5115
invoice (draft): removal 50.43 833 6052
invoice accept: apply 38.79 17888 4655
invoice accept: cancel 45.69 808 5483
customer reserve: creation 3607.71 173 432925
customer reserve: removal 4465.68 71 535881
realization accept: apply 76.68 171 9202
realization accept: cancel 72.94 110 8753
payment from customer: creation 54.13 101 6496
payment from customer: removal 5.23 86 628
payment to supplier: creation 45.77 114 5492
payment to supplier: removal 3.39 72 407
service: total inventory turnovers 3.68 3639 441
service: total monetary turnovers 5.34 546 641
service: aggregate perf. data 0.93 13321 112
service: refresh index statistics 0.03 451918 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 31-01-2021 04:28:13 to 31-01-2021 04:28:16.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4354
TEST_TIME 2 5305
TEST_TIME 3 6216
TEST_TIME 4 6590
TEST_TIME 5 6345
TEST_TIME 6 6347
TEST_TIME 7 6656
TEST_TIME 8 6318
TEST_TIME 9 6265
TEST_TIME 10 6733
TEST_TIME 11 6789
TEST_TIME 12 7169
TEST_TIME 13 7065
TEST_TIME 14 7068
TEST_TIME 15 7291
TEST_TIME 16 7206
TEST_TIME 17 7404
TEST_TIME 18 7699
TEST_TIME 19 8431
TEST_TIME 20 8368
TEST_TIME 21 8776
TEST_TIME 22 8734
TEST_TIME 23 8817
TEST_TIME 24 9295
TEST_TIME 25 9945
TEST_TIME 26 9989
TEST_TIME 27 10764
TEST_TIME 28 11252
TEST_TIME 29 11079
TEST_TIME 30 11034
TEST_TIME 31 10898
TEST_TIME 32 10819
TEST_TIME 33 10648
TEST_TIME 34 10422
TEST_TIME 35 10282
TEST_TIME 36 10179
TEST_TIME 37 10115
TEST_TIME 38 10064
TEST_TIME 39 9846
TEST_TIME 40 9749
TEST_TIME 41 9618
TEST_TIME 42 9550
TEST_TIME 43 9420
TEST_TIME 44 9424
TEST_TIME 45 9375
TEST_TIME 46 9336
TEST_TIME 47 9434
TEST_TIME 48 9380
TEST_TIME 49 9355
TEST_TIME 50 9423
TEST_TIME 51 9451
TEST_TIME 52 9443
TEST_TIME 53 9491
TEST_TIME 54 9538
TEST_TIME 55 9656
TEST_TIME 56 9639
TEST_TIME 57 9601
TEST_TIME 58 9656
TEST_TIME 59 9507
TEST_TIME 60 9429
TEST_TIME 61 9335
TEST_TIME 62 9286
TEST_TIME 63 9221
TEST_TIME 64 9232
TEST_TIME 65 9199
TEST_TIME 66 9166
TEST_TIME 67 9093
TEST_TIME 68 9146
TEST_TIME 69 9117
TEST_TIME 70 9138
TEST_TIME 71 9062
TEST_TIME 72 9033
TEST_TIME 73 9153
TEST_TIME 74 9103
TEST_TIME 75 9148
TEST_TIME 76 9136
TEST_TIME 77 9209
TEST_TIME 78 9095
TEST_TIME 79 9009
TEST_TIME 80 8942
TEST_TIME 81 8930
TEST_TIME 82 8938
TEST_TIME 83 8888
TEST_TIME 84 8864
TEST_TIME 85 8866
TEST_TIME 86 8884
TEST_TIME 87 8895
TEST_TIME 88 8900
TEST_TIME 89 8928
TEST_TIME 90 8939
TEST_TIME 91 8953
TEST_TIME 92 8911
TEST_TIME 93 8979
TEST_TIME 94 9087
TEST_TIME 95 9004
TEST_TIME 96 8948
TEST_TIME 97 8894
TEST_TIME 98 8858
TEST_TIME 99 8856
TEST_TIME 100 8851
TEST_TIME 101 8851
TEST_TIME 102 8900
TEST_TIME 103 8864
TEST_TIME 104 8856
TEST_TIME 105 8876
TEST_TIME 106 8845
TEST_TIME 107 8896
TEST_TIME 108 8975
TEST_TIME 109 8973
TEST_TIME 110 8940
TEST_TIME 111 9019
TEST_TIME 112 8944
TEST_TIME 113 8875
TEST_TIME 114 8835
TEST_TIME 115 8806
TEST_TIME 116 8790
TEST_TIME 117 8757
TEST_TIME 118 8747
TEST_TIME 119 8746
TEST_TIME 120 8734

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 31-01-2021 04:28:17 to 31-01-2021 04:28:20.

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 1447035 1447035 0 0.00 0 20123 7 0 0
doc_list_biud 1447034 1447034 0 0.00 0 29527 32 0 0
sp_add_invoice_to_stock 4762 4655 107 2.24 30 200973 17889 0 107
sp_cancel_adding_invoice 5483 5483 0 0.00 3 54391 808 0 0
sp_cancel_client_order 1727 1727 0 0.00 5 22841 328 0 0
sp_cancel_customer_reserve 535881 535881 0 0.00 1 29446 71 0 0
sp_cancel_pay_from_customer 628 628 0 0.00 3 7355 87 0 0
sp_cancel_pay_to_supplier 407 407 0 0.00 3 2340 73 0 0
sp_cancel_supplier_invoice 6060 6052 8 0.13 2 29538 834 0 8
sp_cancel_supplier_order 383 383 0 0.00 11 1059671 123509 0 0
sp_cancel_write_off 8753 8753 0 0.00 1 5711 110 0 0
sp_client_order 18235 18235 0 0.00 4 76631 478 0 0
sp_customer_reserve 432925 432925 0 0.00 0 85435 173 0 0
sp_fill_shopping_cart 28331 28331 0 0.00 1 21600 49 0 0
sp_fill_shopping_cart_clo_res 432922 432922 0 0.00 0 29020 18 0 0
sp_get_clo_for_invoice 4650 4650 0 0.00 0 51553 434 0 0
sp_kill_cost_storno 15271 15271 0 0.00 0 7133 55 0 0
sp_kill_qstorno_ret_qs2qd 1086950 1086950 0 0.00 0 29525 38 0 0
sp_kill_qty_storno 572130 572130 0 0.00 0 29527 81 0 0
sp_lock_selected_doc 19361 19361 0 0.00 0 10987 6 0 0
sp_make_cost_storno 25840 25840 0 0.00 0 17545 55 0 0
sp_multiply_rows_for_pdistr 25839 25839 0 0.00 0 8195 30 0 0
sp_multiply_rows_for_qdistr 18235 18235 0 0.00 0 76614 272 0 0
sp_pay_from_customer 6496 6496 0 0.00 4 15667 102 0 0
sp_pay_to_supplier 5492 5492 0 0.00 5 16236 115 0 0
sp_payment_common 11988 11988 0 0.00 1 10321 21 0 0
sp_qd_handle_on_cancel_clo 1727 1727 0 0.00 0 20127 292 0 0
sp_qd_handle_on_invoice_upd_sts 10132 10132 0 0.00 0 22732 379 0 0
sp_qd_handle_on_reserve_upd_sts 9202 9202 0 0.00 0 17815 53 0 0
sp_reserve_write_off 9202 9202 0 0.00 7 29803 172 0 0
sp_supplier_invoice 5115 5115 0 0.00 6 105048 3613 0 0
sp_supplier_order 4985 4985 0 0.00 6 29996 1371 0 0
srv_aggregate_perf_data 112 112 0 0.00 2573 211011 13321 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1261 141328 49657 0 0
srv_find_qd_qs_mism 1003074 1003074 0 0.00 0 13345 6 0 0
srv_make_invnt_saldo 441 441 0 0.00 45 135853 3640 0 0
srv_make_money_saldo 641 641 0 0.00 1 13247 547 0 0
srv_recalc_idx_stat 4 4 0 0.00 238745 625834 451919 0 0
t$perf-abend:sp_add_invoice_to_stock 107 107 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4656 4656 0 0.00 0 12072 180 0 0
t$perf-norm:sp_cancel_adding_invoice 403 403 0 0.00 1 3509 104 0 0
t$perf-norm:sp_cancel_client_order 1727 1727 0 0.00 0 2691 12 0 0
t$perf-norm:sp_cancel_customer_reserve 651 651 0 0.00 0 824 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 628 628 0 0.00 0 991 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 407 407 0 0.00 0 807 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 386 386 0 0.00 0 4333 24 0 0
t$perf-norm:sp_cancel_supplier_order 383 383 0 0.00 0 18959 1650 0 0
t$perf-norm:sp_cancel_write_off 925 925 0 0.00 0 2314 8 0 0
t$perf-norm:sp_client_order 18235 18235 0 0.00 0 4806 15 0 0
t$perf-norm:sp_customer_reserve 985 985 0 0.00 0 969 3 0 0
t$perf-norm:sp_pay_from_customer 6496 6496 0 0.00 0 4301 8 0 0
t$perf-norm:sp_pay_to_supplier 5492 5492 0 0.00 0 4254 8 0 0
t$perf-norm:sp_reserve_write_off 9202 9202 0 0.00 0 8258 11 0 0
t$perf-norm:sp_supplier_invoice 5115 5115 0 0.00 0 6416 21 0 0
t$perf-norm:sp_supplier_order 4985 4985 0 0.00 0 4228 15 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 1735 22 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 53 2 0 0
t$perf-norm:srv_make_invnt_saldo 441 441 0 0.00 0 1772 17 0 0
t$perf-norm:srv_make_money_saldo 641 641 0 0.00 0 2340 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 11 5 0 0
v_add_invoice_to_stock 9512 9298 214 2.24 0 7176 17 0 214
v_all_customers 33286 33286 0 0.00 0 18 1 0 0
v_all_suppliers 27952 27952 0 0.00 0 18 1 0 0
v_all_wares 36470 36470 0 0.00 0 44 1 0 0
v_cancel_adding_invoice 806 806 0 0.00 0 131 5 0 0
v_cancel_client_order 3454 3454 0 0.00 0 1365 3 0 0
v_cancel_customer_prepayment 1256 1256 0 0.00 0 544 4 0 0
v_cancel_customer_reserve 1302 1302 0 0.00 0 452 4 0 0
v_cancel_payment_to_supplier 814 814 0 0.00 0 812 5 0 0
v_cancel_supplier_invoice 788 772 16 2.03 0 665 13 0 16
v_cancel_supplier_order 766 766 0 0.00 0 385 4 0 0
v_cancel_write_off 1850 1850 0 0.00 0 987 13 0 0
v_max_id_clo_ord 4985 4985 0 0.00 0 3034 2 0 0
v_max_id_clo_res 985 985 0 0.00 0 4661 15 0 0
v_max_id_ord_sup 5111 5111 0 0.00 0 6729 6 0 0
v_max_non_paid_invoice 1612 1612 0 0.00 0 206 2 0 0
v_max_non_paid_realizn 3474 3474 0 0.00 0 3217 4 0 0
v_min_id_clo_ord 4985 4985 0 0.00 0 3412 7 0 0
v_min_id_clo_res 985 985 0 0.00 0 5158 13 0 0
v_min_id_ord_sup 5111 5111 0 0.00 0 5253 7 0 0
v_min_non_paid_invoice 5492 5492 0 0.00 0 5691 13 0 0
v_min_non_paid_realizn 6496 6496 0 0.00 0 6421 16 0 0
v_our_firm 9228 9228 0 0.00 0 11 1 0 0
v_reserve_write_off 18404 18404 0 0.00 0 2019 4 0 0
x_lock_depdocs_on_canc_invoice 11149 11149 0 0.00 0 28402 117 0 0
x_lock_depdocs_on_canc_sup_ord 383 383 0 0.00 0 10766 415 0 0
x_make_qstorno_1000_1200 4985 4985 0 0.00 2 29987 1292 0 0
x_make_qstorno_1200_2000 5115 5115 0 0.00 3 104544 3420 0 0
x_make_qstorno_2100_3300 432925 432925 0 0.00 0 80817 153 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 31-01-2021 04:28:21 to 31-01-2021 04:28:26.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
01:57:00 5 8192000000 8802576624 8815304704 7721136 0.094 3 2 3 0 7867568 85072 61360
01:58:02 35286 8192000000 9741418336 10039087104 817009632 9.068 100 90 180 0 845975360 7763984 21201744
01:59:45 23835 8192000000 9770270032 10075013120 840754112 9.307 100 87 174 0 875293040 13904912 20634016
02:01:12 9183 8192000000 9804499216 10123079680 868517904 9.585 100 78 156 0 913492384 27281808 17692672
02:02:27 17398 8192000000 9881327600 10214092800 918705424 10.083 100 81 162 0 983296992 46294800 18296768
02:03:47 6810 8192000000 9931136720 10275266560 966754208 10.555 100 75 150 0 1043291296 61179296 15357792
02:04:56 5501 8192000000 9982403984 10338365440 1001574768 10.894 100 84 165 1 1096940640 78999616 16366256
02:06:04 2814 8192000000 9995641200 10357575680 1031149280 11.180 100 89 178 0 1110838272 61008112 18680880
02:07:07 2122 8192000000 10040061936 10408075264 1060913456 11.465 100 88 175 2 1148849152 70225888 17709808
02:08:11 2270 8192000000 10066895216 10441216000 1070744496 11.559 100 82 165 2 1177731632 90405904 16581232
02:09:15 4343 8192000000 10014547280 10398990336 1085150288 11.697 100 55 110 1 1140222480 43766592 11305600
02:10:21 42843 8192000000 10028844928 10437382144 1085035488 11.695 100 36 72 0 1105026672 12405760 7585424
02:12:04 3565 8192000000 10012208752 10405621760 1096322304 11.803 100 64 128 0 1126282800 14896000 15064496
02:13:09 7267 8192000000 10014837840 10419318784 1097250800 11.812 100 28 55 2 1130639488 27313280 6075408
02:14:17 9449 8192000000 10028834944 10436378624 1103893504 11.875 100 33 66 1 1136500944 26126176 6481264
02:15:29 35522 8192000000 10050869760 10449248256 1104953184 11.885 100 47 93 4 1146255120 32441728 8860208
02:17:05 5299 8192000000 10003763360 10403147776 1094442960 11.785 99 45 89 1 1120061952 15863104 9755888
02:18:12 5136 8192000000 9991611872 10382020608 1074506128 11.595 100 54 109 4 1102068304 14164624 13397552
02:19:18 8040 8192000000 9944189872 10326253568 981613712 10.700 95 37 74 0 1014449504 24143136 8692656
02:20:29 69843 8192000000 9926096624 10318258176 967062944 10.558 94 37 74 1 994417392 19061408 8293040
02:22:42 5451 8192000000 9943281504 10318737408 1013610896 11.010 99 47 92 2 1039036304 15371376 10054032
02:23:51 7077 8192000000 9955694064 10345828352 1042047728 11.284 100 42 84 1 1065062688 12876656 10138304
02:25:01 6015 8192000000 9971989824 10354188288 1053330912 11.393 100 39 80 1 1076820192 13876528 9612752
02:26:12 41852 8192000000 9975927552 10382401536 1061620272 11.472 100 29 58 1 1089338768 21736176 5982320
02:28:02 24405 8192000000 10043074288 10436632576 1082525744 11.672 100 53 106 0 1150418256 56959808 10932704
02:29:29 3481 8192000000 10057373568 10454077440 1097794032 11.817 100 73 146 0 1162385728 48084032 16507664
02:30:37 12387 8192000000 10052471984 10459807744 1116798528 11.997 100 48 93 0 1175708720 48894480 10015712
02:31:55 6484 8192000000 10123987920 10524864512 1126416848 12.088 100 57 113 2 1227918752 88969248 12532656
02:33:09 14977 8192000000 10158282656 10569052160 1140517280 12.220 100 48 95 1 1264650800 113475424 10658096
02:34:30 8847 8192000000 10168810224 10575908864 1153124864 12.339 100 57 113 3 1272098128 107135216 11838048
02:35:43 6734 8192000000 10216919136 10624843776 1154823280 12.355 100 65 130 2 1325386448 155947296 14615872
02:36:53 1987 8192000000 10268860576 10678140928 1166130704 12.461 100 83 165 1 1364883648 180557072 18195872
02:37:59 4140 8192000000 10302095728 10706194432 1181678736 12.606 100 63 124 1 1405669760 210238368 13752656
02:39:06 4591 8192000000 10300942704 10708668416 1186386592 12.650 100 69 135 2 1398149136 196700064 15062480
02:40:19 3475 8192000000 10334486112 10747994112 1184589904 12.633 100 59 115 1 1441738800 244136512 13012384
02:41:26 1310 8192000000 10434100432 10850136064 1198530352 12.763 100 92 180 1 1526247440 307383200 20333888
02:42:31 1257 8192000000 10390355152 10787102720 1142933392 12.243 97 81 158 2 1489061120 328237408 17890320
02:43:39 6406 8192000000 10445424128 10874703872 1172144496 12.517 100 72 144 1 1543198736 353841936 17212304
02:44:49 3006 8192000000 10460800208 10881359872 1176575840 12.558 100 84 166 0 1560694400 364741280 19377280
02:45:55 11375 8192000000 10388680160 10805600256 1161506944 12.417 100 40 80 1 1486652192 315146176 9999072
02:47:14 8657 8192000000 10412897920 10851475456 1151903728 12.327 100 36 72 0 1512945344 351599216 9442400
02:49:24 8910 8192000000 10419709264 10840154112 1135691184 12.175 98 45 90 1 1508059344 360927440 11440720
02:50:34 17175 8192000000 10423686528 10927230976 1145631392 12.268 100 31 62 1 1560649040 406100768 8916880
02:51:54 23915 8192000000 10305354032 10769334272 1143442800 12.248 100 21 42 0 1414681872 265252496 5986576
02:53:20 23517 8192000000 10338337296 10840240128 1136884528 12.186 100 26 51 1 1465702096 322305408 6512160
02:54:52 34835 8192000000 10088736144 10506653696 1112842400 11.959 99 40 79 1 1167058112 45543200 8672512
02:56:48 35799 8192000000 10058368224 10487250944 1091501760 11.757 98 27 54 0 1149272576 51475264 6295552
02:58:28 66254 8192000000 10064367712 10495905792 1098899952 11.827 98 20 40 1 1147136736 43569200 4667584
03:00:34 90241 8192000000 10088371312 10537127936 1078605552 11.634 96 22 45 0 1142189600 58970256 4613792
03:03:11 141161 8192000000 10025733760 10459811840 1098351376 11.822 98 17 34 1 1144933360 42531328 4050656
03:06:32 44681 8192000000 10071333664 10530942976 1131999648 12.140 100 24 48 0 1184255392 47248688 5007056
03:08:22 53778 8192000000 10061805952 10534809600 1111120400 11.943 98 8 14 0 1157973520 45267792 1585328
03:10:17 82016 8192000000 10080000224 10560217088 1125658832 12.080 99 15 30 0 1187006768 57783200 3564736
03:12:39 63723 8192000000 10079388688 10559922176 1124716256 12.072 99 33 65 0 1179497856 47812816 6968784
03:14:53 32667 8192000000 10121808224 10555387904 1144587376 12.259 100 46 92 0 1194682208 40354560 9740272
03:16:29 79921 8192000000 10102417904 10576023552 1126827680 12.091 98 18 36 1 1218732128 87490288 4414160
03:18:51 71038 8192000000 10124085440 10575585280 1140855568 12.224 99 20 40 1 1213319360 68198640 4265152
03:21:05 93692 8192000000 10093545344 10591477760 1159813840 12.402 100 12 24 0 1229561040 67150128 2597072
03:23:39 109680 8192000000 10081541280 10582470656 1128871056 12.111 99 16 32 0 1220198000 87829200 3497744
03:26:53 34465 8192000000 10055294192 10514989056 1119774208 12.025 99 7 14 0 1152691808 31415184 1502416
03:28:44 55499 8192000000 10071823936 10545258496 1120274672 12.030 99 6 12 0 1169477104 48232752 969680
03:30:40 51001 8192000000 10063512256 10504351744 1115387248 11.983 98 21 41 2 1150359696 30109280 4863168
03:32:38 48316 8192000000 10099589920 10529243136 1119980848 12.027 98 25 50 1 1189303456 64213312 5109296
03:34:45 71388 8192000000 10086942416 10541289472 1116988224 11.999 98 18 37 1 1180526368 59651696 3886448
03:37:02 48492 8192000000 10049039584 10471686144 1080584096 11.653 96 18 34 1 1110219056 26216096 3418864
03:38:51 57765 8192000000 10043100176 10532556800 1083541728 11.681 97 15 30 0 1146237696 59675568 3020400
03:41:23 76055 8192000000 10055541664 10468601856 1087486592 11.719 97 31 62 0 1153221824 59803936 5931296
03:43:41 74596 8192000000 10065479552 10556973056 1123937488 12.064 100 10 20 0 1165934144 39687520 2309136
03:45:58 68113 8192000000 10053950336 10517053440 1128584992 12.108 100 8 16 0 1164395632 34037008 1773632
03:48:06 61564 8192000000 10062248992 10549321728 1099232192 11.830 97 4 8 0 1140110720 40184096 694432
03:50:15 46353 8192000000 10069589104 10503446528 1069844720 11.551 96 25 50 0 1131315872 56193456 5277696
03:52:08 80936 8192000000 10088673392 10559553536 1097731840 11.816 98 20 40 1 1177454320 75812624 3909856
03:54:30 86668 8192000000 10089628992 10537164800 1102738384 11.864 98 15 30 0 1168958592 63102656 3117552
03:56:58 60059 8192000000 10105954032 10565951488 1107539840 11.909 98 26 52 1 1173812256 60500912 5771504
03:59:06 60363 8192000000 10139649712 10589831168 1129310880 12.115 100 13 26 0 1220682176 88550064 2821232
04:01:08 123207 8192000000 10078754192 10526859264 1075053344 11.600 96 17 34 0 1111222864 32641472 3528048
04:04:16 34157 8192000000 10061877712 10526707712 1108948800 11.922 100 5 10 1 1164280144 54376784 954560
04:05:50 121082 8192000000 10077736224 10546057216 1102663904 11.863 98 11 22 0 1170639792 65572992 2402896
04:08:52 141327 8192000000 10113446512 10575560704 1144947568 12.262 100 9 19 1 1182401088 35815632 1637888
04:13:23 58888 8192000000 10113733184 10640175104 1146794528 12.279 99 11 22 0 1231618080 82409392 2414160
04:15:23 74164 8192000000 10134870640 10604093440 1123507824 12.060 98 23 46 0 1162359568 34599744 4252000
04:17:37 116028 8192000000 10088535056 10540990464 1126329968 12.087 98 12 24 1 1198393248 69660640 2402640
04:21:30 52318 8192000000 10056054160 10553872384 1126013696 12.084 100 6 12 0 1169864848 42144336 1706816
04:23:27 62889 8192000000 10077957856 10528354304 1123495392 12.060 99 20 41 0 1168166912 40258208 4413312
04:25:37 66779 8192000000 10133951200 10592616448 1112594304 11.957 97 23 44 1 1185359552 67896208 4869040

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 31-01-2021 04:28:27 to 31-01-2021 04:28:42.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 107 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

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

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          223110
MON$OLDEST_ACTIVE               225679
MON$OLDEST_SNAPSHOT             225679
MON$NEXT_TRANSACTION            225679
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                       2094336
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                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             781
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2353 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2353 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2353 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.2353 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 ---
:33: 
:34: mscs-test-161.budzdorov.ru    Sun Jan 31 04:27:00 2021
:35:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:36: 
:37: 
:38: mscs-test-161.budzdorov.ru    Sun Jan 31 04:27:03 2021
:39:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:40: 
--- end of diff output ---

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

31.01.21 04:28:46. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-01-31 04:28:48 No severe PSQL-related problems occured
31.01.21 04:28:50. End of report.