Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.06.2021 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 58e1ddde69c44dad93d327777e5becec
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 2021-05-09 08:45
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 4793.92
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 16751584 kB
MemAvailable 31362264 kB
Buffers 0 kB
Cached 14881244 kB
SwapCached 2472 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.1
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-06-17 13:27:00
DTS_END                         2021-06-17 13:27:00.1200
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/38252
TRN_ID                          228803
ATT_ID                          603
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: 8512.29 21724 1021475
customer order: creation 162.55 445 19506
customer order: refuse 13.55 320 1626
order to supplier: creation 42.63 1342 5116
order to supplier: removal 3.25 124025 390
invoice (draft): creation 42.95 3584 5154
invoice (draft): removal 48.87 1031 5864
invoice accept: apply 38.33 17042 4599
invoice accept: cancel 43.73 861 5247
customer reserve: creation 3515.03 166 421803
customer reserve: removal 4325.84 74 519101
realization accept: apply 78.12 169 9374
realization accept: cancel 75.78 127 9093
payment from customer: creation 56.42 101 6770
payment from customer: removal 5.48 72 658
payment to supplier: creation 45.99 126 5519
payment to supplier: removal 3.11 70 373
service: total inventory turnovers 3.79 2941 455
service: total monetary turnovers 6.03 473 724
service: aggregate perf. data 0.83 18053 99
service: refresh index statistics 0.03 263459 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-06-2021 13:28:12 to 17-06-2021 13:28:14.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7315
TEST_TIME 2 7169
TEST_TIME 3 6143
TEST_TIME 4 5956
TEST_TIME 5 6064
TEST_TIME 6 6103
TEST_TIME 7 6213
TEST_TIME 8 6232
TEST_TIME 9 6162
TEST_TIME 10 6357
TEST_TIME 11 6726
TEST_TIME 12 7085
TEST_TIME 13 7071
TEST_TIME 14 7646
TEST_TIME 15 7731
TEST_TIME 16 8125
TEST_TIME 17 8435
TEST_TIME 18 8797
TEST_TIME 19 8981
TEST_TIME 20 9543
TEST_TIME 21 9897
TEST_TIME 22 9952
TEST_TIME 23 10112
TEST_TIME 24 10347
TEST_TIME 25 11558
TEST_TIME 26 11389
TEST_TIME 27 11266
TEST_TIME 28 11132
TEST_TIME 29 10774
TEST_TIME 30 10650
TEST_TIME 31 10339
TEST_TIME 32 10279
TEST_TIME 33 10163
TEST_TIME 34 10073
TEST_TIME 35 9916
TEST_TIME 36 9788
TEST_TIME 37 9643
TEST_TIME 38 9609
TEST_TIME 39 9634
TEST_TIME 40 9593
TEST_TIME 41 9466
TEST_TIME 42 9422
TEST_TIME 43 9349
TEST_TIME 44 9359
TEST_TIME 45 9308
TEST_TIME 46 9212
TEST_TIME 47 9152
TEST_TIME 48 9096
TEST_TIME 49 9248
TEST_TIME 50 9189
TEST_TIME 51 9185
TEST_TIME 52 9245
TEST_TIME 53 9209
TEST_TIME 54 9232
TEST_TIME 55 9335
TEST_TIME 56 9279
TEST_TIME 57 9333
TEST_TIME 58 9287
TEST_TIME 59 9136
TEST_TIME 60 9067
TEST_TIME 61 9029
TEST_TIME 62 8922
TEST_TIME 63 8836
TEST_TIME 64 8834
TEST_TIME 65 8752
TEST_TIME 66 8842
TEST_TIME 67 8925
TEST_TIME 68 8978
TEST_TIME 69 8958
TEST_TIME 70 8940
TEST_TIME 71 8930
TEST_TIME 72 8929
TEST_TIME 73 8887
TEST_TIME 74 9064
TEST_TIME 75 9063
TEST_TIME 76 9075
TEST_TIME 77 9017
TEST_TIME 78 8928
TEST_TIME 79 8967
TEST_TIME 80 8899
TEST_TIME 81 8859
TEST_TIME 82 8825
TEST_TIME 83 8791
TEST_TIME 84 8781
TEST_TIME 85 8777
TEST_TIME 86 8787
TEST_TIME 87 8836
TEST_TIME 88 8927
TEST_TIME 89 8872
TEST_TIME 90 8863
TEST_TIME 91 8873
TEST_TIME 92 8922
TEST_TIME 93 8957
TEST_TIME 94 8956
TEST_TIME 95 8876
TEST_TIME 96 8822
TEST_TIME 97 8770
TEST_TIME 98 8713
TEST_TIME 99 8730
TEST_TIME 100 8687
TEST_TIME 101 8674
TEST_TIME 102 8655
TEST_TIME 103 8645
TEST_TIME 104 8615
TEST_TIME 105 8578
TEST_TIME 106 8663
TEST_TIME 107 8699
TEST_TIME 108 8812
TEST_TIME 109 8807
TEST_TIME 110 8797
TEST_TIME 111 8779
TEST_TIME 112 8717
TEST_TIME 113 8700
TEST_TIME 114 8672
TEST_TIME 115 8630
TEST_TIME 116 8628
TEST_TIME 117 8594
TEST_TIME 118 8591
TEST_TIME 119 8590
TEST_TIME 120 8518

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 17-06-2021 13:28:15 to 17-06-2021 13:28:19.

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 1415775 1415775 0 0.00 0 21029 6 0 0
doc_list_biud 1415771 1415771 0 0.00 0 110436 34 0 0
sp_add_invoice_to_stock 4707 4599 108 2.29 37 219997 17042 0 108
sp_cancel_adding_invoice 5247 5247 0 0.00 5 75839 861 0 0
sp_cancel_client_order 1626 1626 0 0.00 4 11404 321 0 0
sp_cancel_customer_reserve 519101 519101 0 0.00 1 87276 75 0 0
sp_cancel_pay_from_customer 658 658 0 0.00 3 4200 72 0 0
sp_cancel_pay_to_supplier 373 373 0 0.00 3 1412 70 0 0
sp_cancel_supplier_invoice 5873 5864 9 0.15 2 110455 1032 0 9
sp_cancel_supplier_order 390 390 0 0.00 6 914552 124025 0 0
sp_cancel_write_off 9093 9093 0 0.00 1 11505 128 0 0
sp_client_order 19506 19506 0 0.00 4 88280 445 0 0
sp_customer_reserve 421803 421803 0 0.00 0 46430 167 0 0
sp_fill_shopping_cart 29773 29773 0 0.00 1 15425 48 0 0
sp_fill_shopping_cart_clo_res 421798 421798 0 0.00 0 30706 21 0 0
sp_get_clo_for_invoice 4593 4593 0 0.00 0 59518 528 0 0
sp_kill_cost_storno 15371 15371 0 0.00 0 5808 61 0 0
sp_kill_qstorno_ret_qs2qd 1053549 1053549 0 0.00 0 110436 41 0 0
sp_kill_qty_storno 555288 555288 0 0.00 0 110436 86 0 0
sp_lock_selected_doc 19363 19363 0 0.00 0 11046 4 0 0
sp_make_cost_storno 26256 26256 0 0.00 0 20982 55 0 0
sp_multiply_rows_for_pdistr 26254 26254 0 0.00 0 5580 28 0 0
sp_multiply_rows_for_qdistr 19506 19506 0 0.00 0 88272 270 0 0
sp_pay_from_customer 6770 6770 0 0.00 4 13374 101 0 0
sp_pay_to_supplier 5519 5519 0 0.00 5 14502 126 0 0
sp_payment_common 12285 12285 0 0.00 1 8493 20 0 0
sp_qd_handle_on_cancel_clo 1626 1626 0 0.00 0 11389 295 0 0
sp_qd_handle_on_invoice_upd_sts 9840 9840 0 0.00 0 26432 352 0 0
sp_qd_handle_on_reserve_upd_sts 9374 9374 0 0.00 0 18007 45 0 0
sp_reserve_write_off 9374 9374 0 0.00 7 18022 169 0 0
sp_supplier_invoice 5154 5154 0 0.00 7 91857 3584 0 0
sp_supplier_order 5116 5116 0 0.00 5 27757 1342 0 0
srv_aggregate_perf_data 99 99 0 0.00 2652 332986 18054 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1711 147455 48644 0 0
srv_find_qd_qs_mism 978873 978873 0 0.00 0 18634 5 0 0
srv_make_invnt_saldo 455 455 0 0.00 45 93732 2942 0 0
srv_make_money_saldo 724 724 0 0.00 1 13125 474 0 0
srv_recalc_idx_stat 4 4 0 0.00 166998 324344 263460 0 0
t$perf-abend:sp_add_invoice_to_stock 108 108 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4601 4601 0 0.00 0 9777 148 0 0
t$perf-norm:sp_cancel_adding_invoice 387 387 0 0.00 0 1916 55 0 0
t$perf-norm:sp_cancel_client_order 1626 1626 0 0.00 0 2490 12 0 0
t$perf-norm:sp_cancel_customer_reserve 634 634 0 0.00 0 403 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 658 658 0 0.00 0 5012 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 373 373 0 0.00 0 240 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 351 351 0 0.00 0 2520 20 0 0
t$perf-norm:sp_cancel_supplier_order 390 390 0 0.00 0 22484 1670 0 0
t$perf-norm:sp_cancel_write_off 977 977 0 0.00 0 1645 7 0 0
t$perf-norm:sp_client_order 19507 19507 0 0.00 0 8747 15 0 0
t$perf-norm:sp_customer_reserve 887 887 0 0.00 0 586 5 0 0
t$perf-norm:sp_pay_from_customer 6770 6770 0 0.00 0 2481 7 0 0
t$perf-norm:sp_pay_to_supplier 5519 5519 0 0.00 0 5921 9 0 0
t$perf-norm:sp_reserve_write_off 9374 9374 0 0.00 0 2533 7 0 0
t$perf-norm:sp_supplier_invoice 5154 5154 0 0.00 0 4799 16 0 0
t$perf-norm:sp_supplier_order 5116 5116 0 0.00 0 7150 17 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 243 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 85 2 0 0
t$perf-norm:srv_make_invnt_saldo 455 455 0 0.00 0 1387 9 0 0
t$perf-norm:srv_make_money_saldo 724 724 0 0.00 0 2501 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 2 0 0
v_add_invoice_to_stock 9402 9186 216 2.29 0 8480 18 0 216
v_all_customers 35690 35690 0 0.00 0 49 1 0 0
v_all_suppliers 28782 28782 0 0.00 0 17 1 0 0
v_all_wares 39012 39012 0 0.00 0 189 1 0 0
v_cancel_adding_invoice 774 774 0 0.00 0 1902 8 0 0
v_cancel_client_order 3252 3252 0 0.00 0 1736 3 0 0
v_cancel_customer_prepayment 1316 1316 0 0.00 0 661 4 0 0
v_cancel_customer_reserve 1268 1268 0 0.00 0 80 2 0 0
v_cancel_payment_to_supplier 746 746 0 0.00 0 1405 9 0 0
v_cancel_supplier_invoice 720 702 18 2.50 0 280 10 0 18
v_cancel_supplier_order 780 780 0 0.00 0 764 4 0 0
v_cancel_write_off 1954 1954 0 0.00 0 5981 16 0 0
v_max_id_clo_ord 5116 5116 0 0.00 0 3790 2 0 0
v_max_id_clo_res 887 887 0 0.00 0 6026 19 0 0
v_max_id_ord_sup 5151 5151 0 0.00 0 4837 6 0 0
v_max_non_paid_invoice 1392 1392 0 0.00 0 3744 5 0 0
v_max_non_paid_realizn 3585 3585 0 0.00 0 1379 4 0 0
v_min_id_clo_ord 5116 5116 0 0.00 0 3062 6 0 0
v_min_id_clo_res 887 887 0 0.00 0 708 5 0 0
v_min_id_ord_sup 5151 5151 0 0.00 0 3521 5 0 0
v_min_non_paid_invoice 5516 5516 0 0.00 0 6027 11 0 0
v_min_non_paid_realizn 6769 6769 0 0.00 0 8488 15 0 0
v_our_firm 9690 9690 0 0.00 0 14 1 0 0
v_reserve_write_off 18748 18748 0 0.00 0 6682 6 0 0
x_lock_depdocs_on_canc_invoice 10760 10760 0 0.00 0 12534 103 0 0
x_lock_depdocs_on_canc_sup_ord 390 390 0 0.00 0 7324 344 0 0
x_make_qstorno_1000_1200 5116 5116 0 0.00 2 27745 1267 0 0
x_make_qstorno_1200_2000 5154 5154 0 0.00 3 85501 3394 0 0
x_make_qstorno_2100_3300 421803 421803 0 0.00 0 46428 144 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-06-2021 13:28:20 to 17-06-2021 13:28:24.

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:57:03 10 8192000000 8796668864 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
10:58:04 44058 8192000000 9738540704 10039459840 805802896 8.955 100 86 171 0 834753472 7867264 21083312
10:59:52 12025 8192000000 9768665760 10076385280 846140144 9.361 100 86 172 0 879765216 12839024 20786048
11:01:09 22554 8192000000 9829644464 10151952384 889041232 9.790 100 86 172 0 933965248 25516784 19407232
11:02:34 15453 8192000000 9884198848 10220773376 928477968 10.180 100 83 166 1 993961232 47518496 17964768
11:03:52 9501 8192000000 9944767488 10292879360 975330560 10.639 100 74 148 1 1057199264 66509568 15359136
11:05:02 6254 8192000000 9988243472 10343997440 1010405984 10.979 100 85 166 0 1104160544 76867712 16886848
11:06:10 2306 8192000000 10003536544 10370629632 1039052256 11.256 100 77 152 0 1112255664 56890224 16313184
11:07:13 1287 8192000000 10028929840 10400985088 1061810832 11.474 100 90 181 2 1144015744 63701664 18503248
11:08:17 28368 8192000000 9978216096 10373857280 1062195024 11.477 100 48 96 1 1125002320 52169120 10638176
11:09:47 6550 8192000000 9992324784 10380255232 1066136944 11.515 100 47 95 0 1116583472 41118000 9328528
11:10:56 5696 8192000000 9973678656 10378842112 1075273904 11.602 100 27 53 1 1094689760 13406976 6008880
11:12:04 66974 8192000000 10008659104 10400587776 1087245056 11.716 100 42 84 1 1118212656 21929424 9038176
11:14:11 7447 8192000000 10014436752 10415501312 1091657248 11.758 100 28 56 0 1129242288 30648464 6936576
11:15:19 6585 8192000000 10014360000 10419187712 1100341536 11.841 100 28 56 1 1142654688 35809776 6503376
11:16:27 4646 8192000000 10019568112 10416967680 1093950928 11.780 100 46 92 4 1131105296 26925376 10228992
11:17:32 27565 8192000000 9990416256 10393526272 1048345808 11.345 96 23 47 1 1077866176 24552656 4967712
11:19:01 5200 8192000000 9955453184 10336010240 1016069648 11.034 98 42 85 1 1055835216 30440016 9325552
11:20:06 4742 8192000000 9915334160 10293919744 982611552 10.710 97 44 88 0 1002524400 9664256 10248592
11:21:14 65641 8192000000 9952124320 10360467456 1027764416 11.147 99 23 45 1 1058419456 25996560 4658480
11:23:20 8720 8192000000 9961233184 10358870016 1042665616 11.290 99 34 68 0 1065729264 15309632 7754016
11:24:29 7616 8192000000 9994739808 10400747520 1066533808 11.519 100 33 66 0 1114352400 40377264 7441328
11:25:39 64116 8192000000 9975192720 10389544960 1069205344 11.544 100 31 63 1 1095828800 19571104 7052352
11:27:49 11266 8192000000 10030115552 10432503808 1097255632 11.812 100 44 88 0 1134763200 28116992 9390576
11:29:06 39065 8192000000 10071765408 10482483200 1117503744 12.003 100 42 84 1 1200450560 74123376 8823440
11:30:52 24789 8192000000 10109359872 10517217280 1136721968 12.185 100 54 105 1 1207211296 58677840 11811488
11:32:20 7829 8192000000 10154218624 10572382208 1148512176 12.296 100 47 92 3 1266575856 107294912 10768768
11:33:35 16604 8192000000 10208656496 10646827008 1153743600 12.345 100 48 96 0 1312142256 147892624 10506032
11:34:56 3733 8192000000 10198009728 10610262016 1167250128 12.471 100 53 106 2 1305633440 126498096 11885216
11:36:03 6100 8192000000 10249226720 10665992192 1174045184 12.535 100 56 112 0 1350906512 163532384 13328944
11:37:17 15608 8192000000 10285759408 10713620480 1186732496 12.653 100 58 115 3 1385830624 185359936 13738192
11:38:38 1709 8192000000 10332912464 10734518272 1184643136 12.633 100 92 184 1 1434785728 229466992 20675600
11:39:43 1727 8192000000 10351212448 10764886016 1193021456 12.711 100 82 160 0 1446337264 234684560 18631248
11:40:48 4036 8192000000 10390393296 10804948992 1192237136 12.704 100 72 144 1 1492126016 283243712 16645168
11:41:57 3682 8192000000 10361303856 10778112000 1169925424 12.496 99 72 142 0 1455587760 268261056 17401280
11:43:07 5523 8192000000 10364741808 10794524672 1144650768 12.259 100 47 93 0 1464317760 307496272 12170720
11:44:16 17786 8192000000 10346362896 10796191744 1132760800 12.147 100 45 90 0 1457290640 313123008 11406832
11:45:38 16771 8192000000 10308932560 10752155648 1076055120 11.610 97 33 66 1 1397129920 312067696 9007104
11:46:58 12798 8192000000 10329890032 10757013504 1107682160 11.910 100 38 75 0 1421708144 304413296 9612688
11:48:19 15434 8192000000 10267953072 10758459392 1137863584 12.195 100 30 60 2 1427086800 281473296 7749920
11:49:44 17074 8192000000 10316084144 10767106048 1141752976 12.232 100 25 48 1 1404686624 256701504 6232144
11:51:03 20811 8192000000 10324583744 10766856192 1116486496 11.994 99 17 34 0 1405982192 284559200 4936496
11:52:25 23627 8192000000 10068941536 10545508352 1071609712 11.567 97 32 64 0 1178433568 100077824 6746032
11:53:49 70483 8192000000 10081703808 10514632704 1102182000 11.858 99 27 51 2 1186922976 79451568 5289408
11:56:04 99235 8192000000 10040213888 10501771264 1085129232 11.696 97 24 50 0 1129314928 39429120 4756576
11:58:57 8821 8192000000 10080698544 10505179136 1126950960 12.093 100 55 111 0 1180973360 40684640 13337760
12:00:06 48264 8192000000 10054123712 10500116480 1093255984 11.774 97 22 44 2 1138111344 40029440 4825920
12:01:57 63065 8192000000 10085529936 10532704256 1122722080 12.053 99 15 30 0 1176559376 50815392 3021904
12:04:02 66600 8192000000 10142300000 10592821248 1108548704 11.919 97 17 34 0 1217429200 105090816 3789680
12:06:11 65275 8192000000 10129200432 10615799808 1129338320 12.115 100 15 31 1 1210965088 78698496 2928272
12:08:17 107073 8192000000 10096864512 10573570048 1126881648 12.092 99 33 64 2 1166246000 32378432 6985920
12:11:30 105894 8192000000 10057505600 10513330176 1092824464 11.770 96 13 26 0 1130326560 34599104 2902992
12:14:16 52622 8192000000 10096288768 10548199424 1129048560 12.112 99 35 69 1 1198516032 62112944 7354528
12:16:28 84227 8192000000 10081525616 10540347392 1137744144 12.194 99 13 26 0 1168482976 28003728 2735104
12:18:52 91948 8192000000 10074534240 10548547584 1139489424 12.211 99 11 22 0 1196982736 55318448 2174864
12:21:24 112814 8192000000 10098102128 10564698112 1132566880 12.146 98 28 56 1 1218095488 79662800 5865808
12:24:17 67790 8192000000 10084578176 10589843456 1142423264 12.238 99 23 45 0 1191566992 44725488 4418240
12:26:25 77548 8192000000 10067736016 10506485760 1108248608 11.916 96 28 56 1 1136343888 22420816 5674464
12:29:50 60065 8192000000 10100664784 10549714944 1131912592 12.139 98 11 22 0 1172200400 38043216 2244592
12:32:10 35297 8192000000 10145255024 10585141248 1143113312 12.245 99 27 53 2 1237968864 88871424 5984128
12:33:46 71249 8192000000 10082156736 10574049280 1098958416 11.828 97 18 36 1 1196595360 93497120 4139824
12:36:01 54806 8192000000 10053074592 10501926912 1069637280 11.549 96 23 47 1 1125662544 50718096 5307168
12:37:56 34704 8192000000 10078065840 10519891968 1100166656 11.839 98 24 48 1 1161795488 56236528 5392304
12:39:37 35882 8192000000 10116313888 10547826688 1101506960 11.852 97 22 43 0 1180256352 73958784 4790608
12:41:17 53756 8192000000 10066690128 10598711296 1106374064 11.898 98 9 18 0 1239936560 131340288 2222208
12:43:28 14600 8192000000 10069385040 10486038528 1095140464 11.792 98 49 97 1 1139705088 34889840 9674784
12:44:45 147454 8192000000 10064047344 10563657728 1098584464 11.824 98 17 34 0 1182520944 80327680 3608800
12:48:12 60150 8192000000 10040556320 10523045888 1089610544 11.739 97 4 7 1 1125230448 34816512 803392
12:51:18 63060 8192000000 10096631920 10529918976 1098805232 11.826 98 29 58 0 1163232448 58035264 6391952
12:53:49 80408 8192000000 10094134816 10622824448 1114664656 11.977 99 18 36 0 1251696608 132755536 4276416
12:56:40 28821 8192000000 10074005744 10512474112 1104462208 11.880 99 23 45 0 1149251296 39999584 4789504
12:58:13 42868 8192000000 10112266752 10570223616 1123576064 12.061 100 26 52 1 1185935712 57474816 4884832
12:59:56 128197 8192000000 10084618048 10557075456 1110949280 11.941 98 19 38 1 1155374208 40247696 4177232
13:03:24 77252 8192000000 10073173600 10535919616 1124755584 12.072 99 15 30 0 1164862544 36772096 3334864
13:05:45 77427 8192000000 10075113216 10567045120 1130902512 12.130 99 5 10 0 1184641728 52708144 1031072
13:08:02 56764 8192000000 10112149936 10576596992 1115567296 11.985 97 24 48 0 1191006352 70700224 4738832
13:10:00 30347 8192000000 10128855568 10582929408 1121516592 12.041 98 24 48 0 1228202208 100931808 5753808
13:11:32 53520 8192000000 10211607168 10654466048 1158964752 12.394 100 29 56 1 1302705600 136696352 7044496
13:13:32 51587 8192000000 10244263696 10748661760 1142817424 12.242 99 15 30 1 1300266560 153928208 3520928
13:15:24 74470 8192000000 10088555776 10567012352 1115576624 11.985 98 7 15 1 1177991232 61194304 1220304
13:17:39 40836 8192000000 10114154352 10548482048 1136386288 12.182 99 46 92 1 1183296880 36953056 9957536
13:19:21 89313 8192000000 10084722656 10546180096 1141862848 12.233 100 27 54 0 1188693872 41531520 5299504
13:21:59 56239 8192000000 10090536384 10551517184 1148202832 12.293 100 6 12 0 1191696880 42150064 1343984
13:24:06 47599 8192000000 10096893792 10538971136 1095179392 11.792 95 18 35 2 1136469424 37160880 4129152
13:25:59 61428 8192000000 9998127872 10441875456 1039505296 11.260 91 27 54 1 1100770016 55008576 6256144

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 17-06-2021 13:28:26 to 17-06-2021 13:28:40.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 108 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-06-2021 13:28:41 to 17-06-2021 13:28:43.

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          225938
MON$OLDEST_ACTIVE               228947
MON$OLDEST_SNAPSHOT             228947
MON$NEXT_TRANSACTION            228947
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                       2092096
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             783
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2517 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2517 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2517 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2517 Firebird 4.0/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 ---
:132: 
:133: mscs-test-161.budzdorov.ru    Thu Jun 17 13:27:06 2021
:134:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:135: 
:136: 
:137: mscs-test-161.budzdorov.ru    Thu Jun 17 13:27:09 2021
:138:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:139: 
:140: 
:141: mscs-test-161.budzdorov.ru    Thu Jun 17 13:27:11 2021
:142:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:143: 
:144: 
:145: mscs-test-161.budzdorov.ru    Thu Jun 17 13:27:11 2021
:146:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:147: 
:148: 
:149: mscs-test-161.budzdorov.ru    Thu Jun 17 13:27:11 2021
:150:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:151: 
--- end of diff output ---

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

17.06.21 13:28:44. 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-06-17 13:28:47 No severe PSQL-related problems occured
17.06.21 13:28:48. End of report.