Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.09.2021 01:39
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 16089336 kB
MemAvailable 30910748 kB
Buffers 0 kB
Cached 15623300 kB
SwapCached 340 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 3.0.8
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-09-06 01:37:49
DTS_END                         2021-09-06 01:37:49.6380
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/58890
TRN_ID                          245626
ATT_ID                          728
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: 8965.77 22875 1075892
customer order: creation 177.06 351 21247
customer order: refuse 14.73 380 1768
order to supplier: creation 46.74 1316 5609
order to supplier: removal 3.31 100398 397
invoice (draft): creation 46.43 3830 5571
invoice (draft): removal 49.21 821 5905
invoice accept: apply 42.47 19888 5096
invoice accept: cancel 44.44 893 5333
customer reserve: creation 3867.57 200 464108
customer reserve: removal 4388.31 59 526597
realization accept: apply 84.93 184 10191
realization accept: cancel 69.78 112 8374
payment from customer: creation 59.66 133 7159
payment from customer: removal 5.78 94 694
payment to supplier: creation 49.33 120 5920
payment to supplier: removal 3.44 57 413
service: total inventory turnovers 4.68 1234 561
service: total monetary turnovers 6.96 33 835
service: aggregate perf. data 0.92 16710 110
service: refresh index statistics 0.03 310691 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-09-2021 01:40:09 to 06-09-2021 01:40:11.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3646
TEST_TIME 2 4256
TEST_TIME 3 4384
TEST_TIME 4 4459
TEST_TIME 5 4923
TEST_TIME 6 5084
TEST_TIME 7 5404
TEST_TIME 8 5429
TEST_TIME 9 5597
TEST_TIME 10 5706
TEST_TIME 11 6080
TEST_TIME 12 6246
TEST_TIME 13 6450
TEST_TIME 14 6395
TEST_TIME 15 6564
TEST_TIME 16 6664
TEST_TIME 17 6680
TEST_TIME 18 7189
TEST_TIME 19 7445
TEST_TIME 20 7576
TEST_TIME 21 8106
TEST_TIME 22 8475
TEST_TIME 23 8810
TEST_TIME 24 9247
TEST_TIME 25 9477
TEST_TIME 26 9473
TEST_TIME 27 9298
TEST_TIME 28 9617
TEST_TIME 29 9841
TEST_TIME 30 9823
TEST_TIME 31 10453
TEST_TIME 32 10209
TEST_TIME 33 10173
TEST_TIME 34 10188
TEST_TIME 35 10186
TEST_TIME 36 10001
TEST_TIME 37 9898
TEST_TIME 38 9859
TEST_TIME 39 9832
TEST_TIME 40 9839
TEST_TIME 41 9805
TEST_TIME 42 9646
TEST_TIME 43 9615
TEST_TIME 44 9599
TEST_TIME 45 9490
TEST_TIME 46 9367
TEST_TIME 47 9267
TEST_TIME 48 9217
TEST_TIME 49 9156
TEST_TIME 50 9317
TEST_TIME 51 9404
TEST_TIME 52 9357
TEST_TIME 53 9275
TEST_TIME 54 9205
TEST_TIME 55 9153
TEST_TIME 56 9072
TEST_TIME 57 9013
TEST_TIME 58 8949
TEST_TIME 59 8888
TEST_TIME 60 8848
TEST_TIME 61 8938
TEST_TIME 62 8975
TEST_TIME 63 8984
TEST_TIME 64 9051
TEST_TIME 65 9047
TEST_TIME 66 9096
TEST_TIME 67 9031
TEST_TIME 68 9036
TEST_TIME 69 9116
TEST_TIME 70 9056
TEST_TIME 71 9163
TEST_TIME 72 9115
TEST_TIME 73 9120
TEST_TIME 74 9241
TEST_TIME 75 9150
TEST_TIME 76 9102
TEST_TIME 77 9060
TEST_TIME 78 9023
TEST_TIME 79 9030
TEST_TIME 80 8982
TEST_TIME 81 8954
TEST_TIME 82 8893
TEST_TIME 83 8921
TEST_TIME 84 8921
TEST_TIME 85 8938
TEST_TIME 86 8975
TEST_TIME 87 8941
TEST_TIME 88 8946
TEST_TIME 89 9004
TEST_TIME 90 9004
TEST_TIME 91 9067
TEST_TIME 92 9069
TEST_TIME 93 9090
TEST_TIME 94 9092
TEST_TIME 95 9091
TEST_TIME 96 9099
TEST_TIME 97 9134
TEST_TIME 98 9114
TEST_TIME 99 9039
TEST_TIME 100 9029
TEST_TIME 101 9014
TEST_TIME 102 9013
TEST_TIME 103 9005
TEST_TIME 104 8973
TEST_TIME 105 8959
TEST_TIME 106 8943
TEST_TIME 107 8919
TEST_TIME 108 8975
TEST_TIME 109 8956
TEST_TIME 110 8937
TEST_TIME 111 8957
TEST_TIME 112 8970
TEST_TIME 113 8982
TEST_TIME 114 8958
TEST_TIME 115 9012
TEST_TIME 116 9013
TEST_TIME 117 9025
TEST_TIME 118 9037
TEST_TIME 119 9016
TEST_TIME 120 8971
TEST_TIME 121 8897

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 06-09-2021 01:40:13 to 06-09-2021 01:40:16.

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 1507441 1507441 0 0.00 0 12225 7 0 0
doc_list_biud 1507441 1507441 0 0.00 0 25684 27 0 0
sp_add_invoice_to_stock 5185 5096 89 1.71 36 176454 19888 0 89
sp_cancel_adding_invoice 5333 5333 0 0.00 5 83132 893 0 0
sp_cancel_client_order 1768 1768 0 0.00 3 21395 381 0 0
sp_cancel_customer_reserve 526597 526597 0 0.00 1 23023 59 0 0
sp_cancel_pay_from_customer 694 694 0 0.00 3 5455 95 0 0
sp_cancel_pay_to_supplier 413 413 0 0.00 3 2713 58 0 0
sp_cancel_supplier_invoice 5910 5905 5 0.08 1 25688 821 0 5
sp_cancel_supplier_order 397 397 0 0.00 6 936494 100399 0 0
sp_cancel_write_off 8374 8374 0 0.00 1 14609 112 0 0
sp_client_order 21247 21247 0 0.00 4 21817 352 0 0
sp_customer_reserve 464108 464108 0 0.00 0 39479 201 0 0
sp_fill_shopping_cart 32427 32427 0 0.00 1 24324 46 0 0
sp_fill_shopping_cart_clo_res 464098 464098 0 0.00 0 33423 15 0 0
sp_get_clo_for_invoice 5086 5086 0 0.00 0 21729 294 0 0
sp_kill_cost_storno 14814 14814 0 0.00 0 5381 56 0 0
sp_kill_qstorno_ret_qs2qd 1067870 1067870 0 0.00 0 25682 32 0 0
sp_kill_qty_storno 563651 563651 0 0.00 0 25683 70 0 0
sp_lock_selected_doc 21120 21120 0 0.00 0 4385 3 0 0
sp_make_cost_storno 28356 28356 0 0.00 0 10543 53 0 0
sp_multiply_rows_for_pdistr 28356 28356 0 0.00 0 6303 29 0 0
sp_multiply_rows_for_qdistr 21247 21247 0 0.00 0 12316 151 0 0
sp_pay_from_customer 7159 7159 0 0.00 4 13650 133 0 0
sp_pay_to_supplier 5920 5920 0 0.00 4 16182 121 0 0
sp_payment_common 13079 13079 0 0.00 1 16171 25 0 0
sp_qd_handle_on_cancel_clo 1768 1768 0 0.00 0 21387 348 0 0
sp_qd_handle_on_invoice_upd_sts 10419 10419 0 0.00 0 14983 355 0 0
sp_qd_handle_on_reserve_upd_sts 10191 10191 0 0.00 0 12990 59 0 0
sp_reserve_write_off 10191 10191 0 0.00 6 13341 184 0 0
sp_supplier_invoice 5571 5571 0 0.00 7 75740 3830 0 0
sp_supplier_order 5609 5609 0 0.00 5 25385 1317 0 0
srv_aggregate_perf_data 110 110 0 0.00 2653 154878 16711 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1417 126556 43683 0 0
srv_find_qd_qs_mism 1028458 1028458 0 0.00 0 11306 6 0 0
srv_make_invnt_saldo 561 561 0 0.00 32 88845 1234 0 0
srv_make_money_saldo 835 835 0 0.00 0 3151 33 0 0
srv_recalc_idx_stat 4 4 0 0.00 80655 644053 310691 0 0
t$perf-abend:sp_add_invoice_to_stock 89 89 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5096 5096 0 0.00 0 15266 216 0 0
t$perf-norm:sp_cancel_adding_invoice 412 412 0 0.00 0 4681 135 0 0
t$perf-norm:sp_cancel_client_order 1768 1768 0 0.00 0 1376 16 0 0
t$perf-norm:sp_cancel_customer_reserve 704 704 0 0.00 0 822 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 694 694 0 0.00 0 3639 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 413 413 0 0.00 0 2365 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 402 402 0 0.00 0 638 14 0 0
t$perf-norm:sp_cancel_supplier_order 397 397 0 0.00 0 26799 1705 0 0
t$perf-norm:sp_cancel_write_off 1053 1053 0 0.00 0 767 4 0 0
t$perf-norm:sp_client_order 21247 21247 0 0.00 0 13973 20 0 0
t$perf-norm:sp_customer_reserve 1055 1055 0 0.00 0 3080 15 0 0
t$perf-norm:sp_pay_from_customer 7159 7159 0 0.00 0 5285 13 0 0
t$perf-norm:sp_pay_to_supplier 5920 5920 0 0.00 0 5294 10 0 0
t$perf-norm:sp_reserve_write_off 10191 10191 0 0.00 0 9740 15 0 0
t$perf-norm:sp_supplier_invoice 5571 5571 0 0.00 0 13716 28 0 0
t$perf-norm:sp_supplier_order 5609 5609 0 0.00 0 10733 22 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 525 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 19 1 0 0
t$perf-norm:srv_make_invnt_saldo 561 561 0 0.00 0 1778 20 0 0
t$perf-norm:srv_make_money_saldo 835 835 0 0.00 0 1141 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 3 0 0
v_add_invoice_to_stock 10350 10172 178 1.71 0 12038 20 0 178
v_all_customers 38394 38394 0 0.00 0 22 1 0 0
v_all_suppliers 30986 30986 0 0.00 0 17 1 0 0
v_all_wares 42494 42494 0 0.00 0 117 1 0 0
v_cancel_adding_invoice 824 824 0 0.00 0 239 7 0 0
v_cancel_client_order 3536 3536 0 0.00 0 5141 10 0 0
v_cancel_customer_prepayment 1388 1388 0 0.00 0 2924 9 0 0
v_cancel_customer_reserve 1408 1408 0 0.00 0 13862 15 0 0
v_cancel_payment_to_supplier 826 826 0 0.00 0 2696 7 0 0
v_cancel_supplier_invoice 814 804 10 1.22 0 2280 21 0 10
v_cancel_supplier_order 794 794 0 0.00 0 153 3 0 0
v_cancel_write_off 2106 2106 0 0.00 0 4639 23 0 0
v_max_id_clo_ord 5609 5609 0 0.00 0 2511 3 0 0
v_max_id_clo_res 1055 1055 0 0.00 0 9372 19 0 0
v_max_id_ord_sup 5571 5571 0 0.00 0 4781 6 0 0
v_max_non_paid_invoice 1607 1607 0 0.00 0 4165 6 0 0
v_max_non_paid_realizn 4005 4005 0 0.00 0 2200 4 0 0
v_min_id_clo_ord 5609 5609 0 0.00 0 9296 13 0 0
v_min_id_clo_res 1055 1055 0 0.00 0 4899 18 0 0
v_min_id_ord_sup 5571 5571 0 0.00 0 5427 5 0 0
v_min_non_paid_invoice 5920 5920 0 0.00 0 16165 13 0 0
v_min_non_paid_realizn 7159 7159 0 0.00 0 9727 16 0 0
v_our_firm 10408 10408 0 0.00 0 15 1 0 0
v_reserve_write_off 20382 20382 0 0.00 0 12046 12 0 0
x_lock_depdocs_on_canc_invoice 10836 10836 0 0.00 0 15570 141 0 0
x_lock_depdocs_on_canc_sup_ord 397 397 0 0.00 0 8376 379 0 0
x_make_qstorno_1000_1200 5609 5609 0 0.00 1 25354 1217 0 0
x_make_qstorno_1200_2000 5571 5571 0 0.00 3 75699 3668 0 0
x_make_qstorno_2100_3300 464108 464108 0 0.00 0 39066 183 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-09-2021 01:40:17 to 06-09-2021 01:40:21.

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
23:07:52 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
23:08:53 36771 8192000000 10396268032 10719547392 1441803344 14.966 100 90 180 0 1477576416 6531552 29241520
23:10:32 37288 8192000000 10450366512 10785484800 1499069104 15.468 100 88 176 1 1536499184 9172992 28257088
23:12:19 14668 8192000000 10497266576 10838859776 1545340832 15.870 100 81 162 0 1581495936 10360640 25794464
23:13:40 10193 8192000000 10583211424 10943315968 1634136944 16.630 100 71 143 0 1666803184 11553008 21113232
23:14:54 7282 8192000000 10653496048 11023847424 1715065472 17.311 100 84 168 1 1752991200 13595760 24329968
23:16:04 3842 8192000000 10711486112 11091349504 1772778672 17.790 100 84 169 1 1809490960 13103168 23609120
23:17:10 1603 8192000000 10764836112 11148152832 1821559920 18.190 100 87 176 2 1859377472 13563360 24254192
23:18:14 1366 8192000000 10812077824 11200933888 1871168064 18.594 100 93 186 0 1910697616 13790208 25739344
23:19:16 18902 8192000000 10797063360 11217551360 1888565312 18.734 100 49 99 1 1910113312 7714592 13833408
23:20:41 6008 8192000000 10790280608 11224727552 1891799600 18.760 100 21 42 1 1904616624 7067760 5749264
23:21:48 5360 8192000000 10812336080 11245592576 1909449312 18.902 100 36 72 1 1927327760 7673840 10204608
23:22:54 8730 8192000000 10828187968 11257573376 1915422320 18.950 100 22 44 2 1928122464 6850576 5849568
23:24:03 8075 8192000000 10826419472 11260985344 1920368288 18.990 100 18 36 3 1931078640 5315616 5394736
23:25:12 35435 8192000000 10831202560 11273572352 1924269568 19.021 100 23 46 1 1937106672 5551408 7285696
23:26:50 4584 8192000000 10824415040 11258204160 1901124736 18.835 99 33 67 0 1917491312 7155760 9210816
23:27:56 6955 8192000000 10792639104 11215405056 1819242848 18.171 96 24 48 1 1835474592 9699888 6531856
23:29:07 53892 8192000000 10761083920 11182276608 1787652288 17.912 96 38 76 2 1804989952 6995504 10342160
23:31:01 2825 8192000000 10746839744 11157229568 1810522512 18.100 99 45 90 2 1829670128 6328320 12819296
23:32:07 10209 8192000000 10726112944 11144802304 1783074368 17.875 98 29 58 2 1797227040 5191248 8961424
23:33:18 25002 8192000000 10735164128 11168141312 1829081344 18.252 100 23 46 2 1842489024 5922768 7484912
23:34:44 7506 8192000000 10754947440 11179798528 1825872752 18.226 99 24 47 2 1838396704 5662800 6861152
23:35:54 12494 8192000000 10776881184 11201978368 1862750048 18.526 100 35 70 2 1878560336 5536896 10273392
23:37:08 6800 8192000000 10806486832 11233861632 1890482496 18.750 100 34 68 0 1905235824 4740288 10013040
23:38:18 53682 8192000000 10854961008 11280777216 1927531040 19.047 100 63 126 2 1954766672 8255072 18980560
23:40:23 6376 8192000000 10865895936 11292717056 1936901168 19.122 100 55 108 3 1962231056 8181424 17148464
23:41:33 23455 8192000000 10874027984 11311321088 1949279376 19.221 100 44 86 1 1972082704 10283008 12520320
23:43:03 17858 8192000000 10909923856 11340238848 1967746000 19.368 100 46 92 0 1996508720 15284576 13478144
23:44:27 10458 8192000000 10919876336 11354468352 1976640560 19.438 100 38 73 1 2003792032 16078464 11073008
23:45:40 4137 8192000000 10925407824 11347001344 1968569552 19.374 100 70 141 1 2009360640 20837632 19953456
23:46:51 5629 8192000000 10922017232 11351736320 1968320256 19.372 100 56 112 0 2009825648 24458384 17047008
23:48:00 13315 8192000000 10925695088 11355287552 1958838864 19.297 99 47 94 0 2001914976 29369760 13706352
23:49:21 2249 8192000000 10924439344 11340308480 1960499536 19.310 100 74 146 1 2011311648 28626672 22185440
23:50:28 4502 8192000000 10930544320 11354001408 1964437760 19.341 100 53 104 1 2010680464 30668688 15574016
23:51:38 5522 8192000000 10946372784 11365969920 1974090704 19.418 100 71 141 2 2031432832 36624592 20717536
23:52:49 5883 8192000000 10921672304 11332128768 1933829968 19.097 99 78 156 0 1991234928 33354080 24050880
23:54:01 1413 8192000000 10926106800 11323363328 1936747200 19.121 100 92 182 2 2001036640 36497168 27792272
23:55:10 11750 8192000000 10930671120 11348107264 1950583152 19.231 100 73 146 1 2015927264 42447536 22896576
23:56:28 3168 8192000000 10923262656 11329732608 1938184560 19.132 100 83 163 3 2002084672 37822160 26077952
23:57:37 2825 8192000000 10920498800 11335585792 1937072368 19.123 100 76 153 1 2005529536 43625760 24831408
23:58:46 3731 8192000000 10909233104 11327606784 1938047472 19.131 100 62 123 1 1994825312 37338288 19439552
23:59:53 2864 8192000000 10905433056 11325845504 1918238224 18.973 99 56 108 0 1975671648 39385680 18047744
00:01:00 5242 8192000000 10924669808 11364888576 1956376720 19.277 100 42 84 2 2009470384 39607872 13485792
00:02:27 13644 8192000000 10922536224 11360530432 1958828032 19.297 100 42 81 2 2007744848 35821280 13095536
00:03:43 30762 8192000000 10914774752 11361202176 1960492592 19.310 100 27 52 0 1996660016 27920752 8246672
00:05:19 28602 8192000000 10883382000 11338510336 1918283424 18.973 98 18 36 1 1964380240 39680112 6416704
00:07:12 21002 8192000000 10912177792 11359162368 1952326016 19.245 100 18 37 2 1990049552 31972320 5751216
00:08:54 66128 8192000000 10921857840 11362480128 1970867552 19.392 100 32 65 1 1995962128 15061824 10032752
00:11:02 15394 8192000000 10956617232 11388895232 1996754752 19.597 100 62 124 2 2029364432 15324016 17285664
00:12:26 51920 8192000000 10913615840 11383107584 1970039472 19.386 99 8 16 0 1984655472 12552896 2063104
00:14:18 126555 8192000000 10924631568 11399127040 1959423184 19.301 98 5 10 0 1984205472 23758144 1024144
00:17:25 73691 8192000000 10932751808 11392503808 2009064672 19.694 100 32 66 1 2025987504 7703216 9219616
00:19:44 98275 8192000000 10918640288 11380060160 1951975360 19.242 97 18 36 1 1970048208 12887648 5185200
00:22:23 25934 8192000000 10930629680 11382665216 1981074016 19.473 99 44 86 3 2004769568 10362032 13333520
00:24:06 41230 8192000000 10944685488 11403304960 2009562400 19.698 100 29 57 0 2032915328 14846448 8506480
00:25:51 62628 8192000000 10931904000 11402391552 1959019056 19.298 97 22 44 1 1986484592 20121424 7344112
00:28:33 95061 8192000000 10904680784 11393245184 1955690960 19.272 98 7 14 0 1974440496 16534544 2214992
00:31:18 44808 8192000000 10881404960 11348750336 1924824944 19.025 97 21 42 0 1936399888 5842752 5732192
00:33:04 52064 8192000000 10904859696 11385458688 1987744160 19.526 100 9 18 0 2001672608 11947424 1981024
00:34:57 74187 8192000000 10880853056 11368181760 1911300912 18.917 96 14 28 0 1926802912 11571184 3930816
00:37:13 78037 8192000000 10907416624 11391688704 1946363760 19.198 98 17 34 4 1966143424 14853856 4925808
00:39:41 61390 8192000000 10935690624 11404881920 2006584672 19.675 100 23 46 0 2025974672 12793808 6596192
00:41:47 33514 8192000000 10916060064 11378958336 1929386656 19.062 96 37 74 2 1949548304 9542992 10618656
00:43:30 66682 8192000000 10886930992 11337834496 1827359056 18.238 92 20 40 0 1841076224 8230016 5487152
00:45:43 93957 8192000000 10907769840 11386146816 1961223632 19.316 99 11 22 0 1986924528 22388560 3312336
00:48:25 62803 8192000000 10851523568 11301777408 1805102176 18.056 93 17 34 2 1817970736 8601312 4267248
00:50:28 58650 8192000000 10872684288 11336515584 1936110752 19.116 100 30 60 1 1963619840 18479072 9030016
00:52:43 84176 8192000000 10847538944 11313442816 1863426064 18.531 96 21 42 1 1878921520 9238208 6257248
00:55:12 12073 8192000000 10866834224 11321589760 1935707664 19.112 100 41 80 1 1958138912 9347584 13083664
00:56:30 39379 8192000000 10845688448 11317243904 1922905376 19.010 100 12 24 1 1937203680 10908208 3390096
00:58:10 75995 8192000000 10872502400 11337740288 1889398096 18.741 97 11 22 0 1907212480 14616624 3197760
01:00:47 47754 8192000000 10867453360 11331469312 1914590496 18.943 98 26 52 0 1930075232 7410416 8074320
01:02:36 27897 8192000000 10894666208 11358756864 1960534048 19.310 100 22 44 1 1974674000 8273600 5866352
01:04:04 54533 8192000000 10904634224 11373948928 1960152288 19.307 100 7 14 0 1974700944 12395168 2153488
01:05:59 38042 8192000000 10876532912 11359600640 1924972624 19.027 98 19 36 2 1944074272 13173840 5927808
01:07:45 107941 8192000000 10912381120 11391979520 1964067824 19.338 99 15 30 1 1986838416 18860496 3910096
01:11:42 107489 8192000000 10909834944 11392557056 1927862784 19.050 96 25 48 0 1949939536 15704240 6372512
01:14:49 61389 8192000000 10913166208 11382300672 1950590704 19.231 98 14 28 1 1963754384 9679568 3484112
01:16:52 85834 8192000000 10915556784 11390910464 1950020416 19.227 98 17 34 2 1967550560 12862096 4668048
01:19:18 49942 8192000000 10895942096 11384762368 1963603152 19.335 99 12 24 1 1978668560 11714272 3351136
01:21:10 53131 8192000000 10887184768 11373494272 1906272096 18.877 96 9 18 0 1920743872 12059488 2412288
01:23:04 46514 8192000000 10894702336 11369529344 1953271456 19.253 99 12 23 0 1970206576 13509040 3426080
01:24:51 95065 8192000000 10870792128 11348381696 1839586336 18.337 93 15 30 2 1853652896 9294992 4771568
01:27:26 47880 8192000000 10893355840 11362689024 1949138768 19.220 99 31 62 2 1968293568 10571536 8583264
01:29:14 77108 8192000000 10896957904 11363291136 1944391296 19.182 99 22 44 2 1962884320 12068464 6424560
01:31:43 41936 8192000000 10904027104 11362353152 1913848176 18.938 97 32 64 2 1936689552 13475504 9365872
01:33:56 46140 8192000000 10883158256 11350761472 1921912368 19.002 98 19 38 2 1935499440 8247664 5339408
01:35:47 33835 8192000000 10896828640 11352944640 1910466400 18.910 97 42 84 1 1935850752 13503296 11881056
01:37:21 44256 8192000000 9699747600 10041954304 838943936 9.289 44 29 54 2 853738288 6854672 7939680

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 06-09-2021 01:40:23 to 06-09-2021 01:40:38.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 89 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-09-2021 01:40:39 to 06-09-2021 01:40:41.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          245885
MON$OLDEST_ACTIVE               245886
MON$OLDEST_SNAPSHOT             245886
MON$NEXT_TRANSACTION            245886
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-03 16:41:55.3470
MON$PAGES                       2044960
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33491 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33491 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33491 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33491 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:245: 
:246: mscs-test-161.budzdorov.ru    Mon Sep  6 01:38:44 2021
:247:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:248: 
--- end of diff output ---

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

06.09.21 01:40:42. There are 3 logs in /var/tmp/logs-oltp30 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-09-06 01:40:44 No severe PSQL-related problems occured
06.09.21 01:40:46. End of report.