Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.11.2021 01:38
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 2a7b53d1c5ed44b9bb2712fffac5d8c6
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-11-11 18:28
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.33
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 16902804 kB
MemAvailable 31589664 kB
Buffers 0 kB
Cached 14750076 kB
SwapCached 0 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-11-14 01:37:53
DTS_END                         2021-11-14 01:37:53.3920
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/49308
TRN_ID                          242437
ATT_ID                          720
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: 9144.91 19208 1097389
customer order: creation 180.98 326 21717
customer order: refuse 15.28 349 1833
order to supplier: creation 46.34 1269 5561
order to supplier: removal 3.39 99571 407
invoice (draft): creation 46.43 3347 5572
invoice (draft): removal 51.91 825 6229
invoice accept: apply 41.48 18019 4977
invoice accept: cancel 47.08 811 5649
customer reserve: creation 3855.90 177 462708
customer reserve: removal 4558.48 58 547018
realization accept: apply 85.73 184 10287
realization accept: cancel 78.01 102 9361
payment from customer: creation 60.80 95 7296
payment from customer: removal 6.28 86 753
payment to supplier: creation 50.85 111 6102
payment to supplier: removal 3.61 57 433
service: total inventory turnovers 4.73 1290 568
service: total monetary turnovers 6.69 41 803
service: aggregate perf. data 0.93 18580 111
service: refresh index statistics 0.03 238856 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 14-11-2021 01:38:40 to 14-11-2021 01:38:43.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2589
TEST_TIME 2 3553
TEST_TIME 3 4023
TEST_TIME 4 4072
TEST_TIME 5 4516
TEST_TIME 6 5152
TEST_TIME 7 5445
TEST_TIME 8 6010
TEST_TIME 9 6339
TEST_TIME 10 6256
TEST_TIME 11 6901
TEST_TIME 12 7056
TEST_TIME 13 7486
TEST_TIME 14 7611
TEST_TIME 15 7765
TEST_TIME 16 7913
TEST_TIME 17 7828
TEST_TIME 18 8074
TEST_TIME 19 8488
TEST_TIME 20 8452
TEST_TIME 21 9034
TEST_TIME 22 8935
TEST_TIME 23 8995
TEST_TIME 24 9246
TEST_TIME 25 9356
TEST_TIME 26 9832
TEST_TIME 27 9585
TEST_TIME 28 9881
TEST_TIME 29 10887
TEST_TIME 30 11129
TEST_TIME 31 10824
TEST_TIME 32 10745
TEST_TIME 33 10675
TEST_TIME 34 10417
TEST_TIME 35 10300
TEST_TIME 36 10122
TEST_TIME 37 9929
TEST_TIME 38 9894
TEST_TIME 39 10074
TEST_TIME 40 9932
TEST_TIME 41 9840
TEST_TIME 42 9728
TEST_TIME 43 9633
TEST_TIME 44 9624
TEST_TIME 45 9561
TEST_TIME 46 9490
TEST_TIME 47 9360
TEST_TIME 48 9453
TEST_TIME 49 9361
TEST_TIME 50 9376
TEST_TIME 51 9319
TEST_TIME 52 9182
TEST_TIME 53 9080
TEST_TIME 54 9042
TEST_TIME 55 8996
TEST_TIME 56 8942
TEST_TIME 57 8884
TEST_TIME 58 8914
TEST_TIME 59 8908
TEST_TIME 60 9064
TEST_TIME 61 9043
TEST_TIME 62 9099
TEST_TIME 63 9069
TEST_TIME 64 9196
TEST_TIME 65 9147
TEST_TIME 66 9183
TEST_TIME 67 9383
TEST_TIME 68 9351
TEST_TIME 69 9377
TEST_TIME 70 9460
TEST_TIME 71 9484
TEST_TIME 72 9552
TEST_TIME 73 9501
TEST_TIME 74 9420
TEST_TIME 75 9466
TEST_TIME 76 9386
TEST_TIME 77 9354
TEST_TIME 78 9396
TEST_TIME 79 9412
TEST_TIME 80 9394
TEST_TIME 81 9354
TEST_TIME 82 9358
TEST_TIME 83 9273
TEST_TIME 84 9200
TEST_TIME 85 9169
TEST_TIME 86 9109
TEST_TIME 87 9108
TEST_TIME 88 9113
TEST_TIME 89 9099
TEST_TIME 90 9083
TEST_TIME 91 9110
TEST_TIME 92 9158
TEST_TIME 93 9181
TEST_TIME 94 9188
TEST_TIME 95 9190
TEST_TIME 96 9227
TEST_TIME 97 9221
TEST_TIME 98 9208
TEST_TIME 99 9209
TEST_TIME 100 9218
TEST_TIME 101 9299
TEST_TIME 102 9287
TEST_TIME 103 9307
TEST_TIME 104 9239
TEST_TIME 105 9173
TEST_TIME 106 9156
TEST_TIME 107 9141
TEST_TIME 108 9116
TEST_TIME 109 9089
TEST_TIME 110 9075
TEST_TIME 111 9062
TEST_TIME 112 9079
TEST_TIME 113 9061
TEST_TIME 114 9074
TEST_TIME 115 9052
TEST_TIME 116 9109
TEST_TIME 117 9127
TEST_TIME 118 9121
TEST_TIME 119 9168
TEST_TIME 120 9146

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-11-2021 01:38:44 to 14-11-2021 01:38:47.

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 1526258 1526258 0 0.00 0 30953 6 0 0
doc_list_biud 1526257 1526257 0 0.00 0 49829 27 0 0
sp_add_invoice_to_stock 5076 4977 99 1.95 32 152821 18019 0 99
sp_cancel_adding_invoice 5649 5649 0 0.00 5 69544 811 0 0
sp_cancel_client_order 1833 1833 0 0.00 4 12387 349 0 0
sp_cancel_customer_reserve 547018 547018 0 0.00 1 49830 58 0 0
sp_cancel_pay_from_customer 753 753 0 0.00 2 7194 87 0 0
sp_cancel_pay_to_supplier 433 433 0 0.00 2 898 58 0 0
sp_cancel_supplier_invoice 6238 6229 9 0.14 1 18334 825 0 9
sp_cancel_supplier_order 407 407 0 0.00 12 1061848 99571 0 0
sp_cancel_write_off 9361 9361 0 0.00 1 6154 103 0 0
sp_client_order 21717 21717 0 0.00 4 24493 326 0 0
sp_customer_reserve 462708 462708 0 0.00 0 26846 178 0 0
sp_fill_shopping_cart 32838 32838 0 0.00 1 25680 41 0 0
sp_fill_shopping_cart_clo_res 462705 462705 0 0.00 0 16296 13 0 0
sp_get_clo_for_invoice 4974 4974 0 0.00 0 35315 344 0 0
sp_kill_cost_storno 16196 16196 0 0.00 0 7179 54 0 0
sp_kill_qstorno_ret_qs2qd 1110033 1110033 0 0.00 0 49825 32 0 0
sp_kill_qty_storno 585758 585758 0 0.00 0 49828 69 0 0
sp_lock_selected_doc 21183 21183 0 0.00 0 8571 4 0 0
sp_make_cost_storno 28659 28659 0 0.00 0 30948 45 0 0
sp_multiply_rows_for_pdistr 28659 28659 0 0.00 0 8507 25 0 0
sp_multiply_rows_for_qdistr 21717 21717 0 0.00 0 14412 157 0 0
sp_pay_from_customer 7296 7296 0 0.00 4 16759 95 0 0
sp_pay_to_supplier 6102 6102 0 0.00 4 9018 111 0 0
sp_payment_common 13398 13398 0 0.00 2 16753 22 0 0
sp_qd_handle_on_cancel_clo 1833 1833 0 0.00 0 12377 323 0 0
sp_qd_handle_on_invoice_upd_sts 10623 10623 0 0.00 0 22650 329 0 0
sp_qd_handle_on_reserve_upd_sts 10287 10287 0 0.00 0 23557 65 0 0
sp_reserve_write_off 10287 10287 0 0.00 6 31762 185 0 0
sp_supplier_invoice 5572 5572 0 0.00 6 75329 3348 0 0
sp_supplier_order 5561 5561 0 0.00 5 55864 1269 0 0
srv_aggregate_perf_data 111 111 0 0.00 2499 138007 18581 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 2026 160063 50444 0 0
srv_find_qd_qs_mism 1048722 1048722 0 0.00 0 6747 5 0 0
srv_make_invnt_saldo 568 568 0 0.00 23 126495 1290 0 0
srv_make_money_saldo 803 803 0 0.00 1 6947 42 0 0
srv_recalc_idx_stat 4 4 0 0.00 75700 403335 238857 0 0
t$perf-abend:sp_add_invoice_to_stock 99 99 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 4977 4977 0 0.00 0 15969 181 0 0
t$perf-norm:sp_cancel_adding_invoice 413 413 0 0.00 0 2125 77 0 0
t$perf-norm:sp_cancel_client_order 1833 1833 0 0.00 0 1878 11 0 0
t$perf-norm:sp_cancel_customer_reserve 675 675 0 0.00 0 2404 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 753 753 0 0.00 0 2526 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 433 433 0 0.00 0 732 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 411 411 0 0.00 0 1443 11 0 0
t$perf-norm:sp_cancel_supplier_order 407 407 0 0.00 0 15961 1497 0 0
t$perf-norm:sp_cancel_write_off 997 997 0 0.00 0 732 5 0 0
t$perf-norm:sp_client_order 21717 21717 0 0.00 0 10955 17 0 0
t$perf-norm:sp_customer_reserve 1026 1026 0 0.00 0 3815 18 0 0
t$perf-norm:sp_pay_from_customer 7296 7296 0 0.00 0 15347 12 0 0
t$perf-norm:sp_pay_to_supplier 6102 6102 0 0.00 0 10703 12 0 0
t$perf-norm:sp_reserve_write_off 10287 10287 0 0.00 0 7352 10 0 0
t$perf-norm:sp_supplier_invoice 5572 5572 0 0.00 0 16527 23 0 0
t$perf-norm:sp_supplier_order 5561 5561 0 0.00 0 4027 14 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 2348 25 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 1656 29 0 0
t$perf-norm:srv_make_invnt_saldo 568 568 0 0.00 0 1550 22 0 0
t$perf-norm:srv_make_money_saldo 803 803 0 0.00 0 906 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 4 0 0
v_add_invoice_to_stock 10145 9947 198 1.95 0 5787 17 0 198
v_all_customers 39184 39184 0 0.00 0 19 1 0 0
v_all_suppliers 31384 31384 0 0.00 0 87 1 0 0
v_all_wares 43434 43434 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 826 826 0 0.00 0 4842 12 0 0
v_cancel_client_order 3666 3666 0 0.00 0 3341 6 0 0
v_cancel_customer_prepayment 1506 1506 0 0.00 0 7082 11 0 0
v_cancel_customer_reserve 1350 1350 0 0.00 0 2432 7 0 0
v_cancel_payment_to_supplier 866 866 0 0.00 0 575 4 0 0
v_cancel_supplier_invoice 840 822 18 2.14 0 669 14 0 18
v_cancel_supplier_order 814 814 0 0.00 0 5346 18 0 0
v_cancel_write_off 1994 1994 0 0.00 0 4967 18 0 0
v_max_id_clo_ord 5559 5559 0 0.00 0 1169 2 0 0
v_max_id_clo_res 1026 1026 0 0.00 0 4212 17 0 0
v_max_id_ord_sup 5562 5562 0 0.00 0 4831 5 0 0
v_max_non_paid_invoice 1531 1531 0 0.00 0 913 3 0 0
v_max_non_paid_realizn 3975 3975 0 0.00 0 2326 4 0 0
v_min_id_clo_ord 5559 5559 0 0.00 0 3182 6 0 0
v_min_id_clo_res 1026 1026 0 0.00 0 1336 4 0 0
v_min_id_ord_sup 5562 5562 0 0.00 0 6057 5 0 0
v_min_non_paid_invoice 6102 6102 0 0.00 0 6680 14 0 0
v_min_non_paid_realizn 7296 7296 0 0.00 0 16747 17 0 0
v_our_firm 10892 10892 0 0.00 0 20 1 0 0
v_reserve_write_off 20574 20574 0 0.00 0 8777 7 0 0
x_lock_depdocs_on_canc_invoice 11467 11467 0 0.00 0 18857 132 0 0
x_lock_depdocs_on_canc_sup_ord 407 407 0 0.00 0 2514 314 0 0
x_make_qstorno_1000_1200 5561 5561 0 0.00 1 55800 1185 0 0
x_make_qstorno_1200_2000 5572 5572 0 0.00 3 74560 3198 0 0
x_make_qstorno_2100_3300 462708 462708 0 0.00 0 26845 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-11-2021 01:38:48 to 14-11-2021 01:38:52.

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:57 3 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:08:58 19502 8192000000 9911774480 10225606656 987919616 10.761 100 84 168 0 1015589216 5536624 22132976
23:10:21 34157 8192000000 9966504608 10293239808 1048957792 11.351 100 94 188 0 1080986592 7939824 24088976
23:12:00 14641 8192000000 9996238592 10334638080 1080328000 11.651 100 80 160 1 1109044640 9146416 19570224
23:13:35 12317 8192000000 10066057216 10422231040 1148593664 12.296 100 79 158 0 1177893840 10821680 18478496
23:14:49 10646 8192000000 10104796288 10472189952 1191308576 12.696 100 75 150 2 1220530320 12131344 17090400
23:16:02 6167 8192000000 10142046544 10514866176 1227777328 13.034 100 85 170 2 1260995088 14137968 19079792
23:17:10 3808 8192000000 10164126976 10545197056 1257135088 13.304 100 81 162 1 1287836160 13030976 17670096
23:18:15 3397 8192000000 10182699072 10567487488 1271466160 13.435 100 80 157 1 1300550208 11397024 17687024
23:19:21 20001 8192000000 10196254256 10587484160 1285558608 13.564 100 66 132 0 1311602800 11846096 14198096
23:20:44 2813 8192000000 10202857760 10602151936 1300482688 13.700 100 59 118 1 1323687232 9222592 13981952
23:21:48 5345 8192000000 10207690176 10610601984 1306789152 13.757 100 30 60 1 1319975504 6596816 6589536
23:22:57 4166 8192000000 10208069776 10623217664 1319740896 13.874 100 26 52 0 1332692208 6803952 6147360
23:24:02 14817 8192000000 10209524896 10626887680 1324053312 13.913 100 24 49 2 1334754528 5073088 5628128
23:25:18 11683 8192000000 10232277488 10640236544 1325461760 13.926 100 57 112 2 1348913536 9842880 13608896
23:26:30 1324 8192000000 10238829040 10650435584 1329031152 13.958 100 50 100 2 1352801728 12258464 11512112
23:27:33 8014 8192000000 10203646784 10622480384 1306028976 13.750 99 35 70 1 1324225440 9925120 8271344
23:28:42 28156 8192000000 10224851568 10625478656 1306844528 13.757 99 62 124 1 1328267376 5356224 16066624
23:30:11 3396 8192000000 10163487664 10565918720 1251552448 13.252 98 38 76 2 1266841536 6561920 8727168
23:31:15 23978 8192000000 10097351392 10481541120 1152972928 12.337 92 28 56 0 1165132144 5257568 6901648
23:32:44 8942 8192000000 10150741936 10547580928 1245589520 13.198 99 33 66 1 1259601280 6942608 7069152
23:33:55 3982 8192000000 10168355840 10570874880 1276376320 13.480 100 36 72 1 1291356240 7216832 7763088
23:35:01 8967 8192000000 10193500720 10593927168 1290962432 13.613 100 30 60 1 1303887264 6010896 6913936
23:36:11 30594 8192000000 10216314496 10609946624 1300374864 13.699 100 48 100 2 1316974304 5511888 11087552
23:37:43 4960 8192000000 10227620448 10636623872 1323614640 13.909 100 41 83 2 1339359664 6439424 9305600
23:38:55 24734 8192000000 10257375824 10666659840 1345131568 14.104 100 62 122 1 1369548848 10080928 14336352
23:40:25 11806 8192000000 10280664960 10695032832 1369491824 14.322 100 57 113 0 1394835232 12392224 12951184
23:41:42 52532 8192000000 10291947792 10715566080 1385457280 14.465 100 50 98 2 1410882400 13048128 12376992
23:43:38 6158 8192000000 10313839936 10731065344 1395511920 14.555 100 55 108 2 1423555488 15496128 12547440
23:44:59 5386 8192000000 10321182512 10743574528 1399387088 14.590 100 47 94 3 1428441072 17416912 11637072
23:46:09 6217 8192000000 10327678000 10750103552 1402972800 14.621 100 59 118 1 1438605200 20786016 14846384
23:47:23 27407 8192000000 10303248224 10733953024 1370954448 14.336 98 39 76 1 1401558160 20743264 9860448
23:48:55 15640 8192000000 10330502176 10761117696 1413007456 14.711 100 52 104 1 1446559456 20692896 12859104
23:50:19 7485 8192000000 10356866192 10780413952 1418854048 14.763 100 79 158 1 1464847520 26356624 19636848
23:51:32 2854 8192000000 10349935888 10764054528 1409473504 14.679 100 83 163 0 1452292288 23949168 18869616
23:52:38 2954 8192000000 10370590624 10786545664 1422313840 14.793 100 82 164 2 1473608080 31442256 19851984
23:53:47 2957 8192000000 10359035376 10785468416 1411670240 14.699 99 70 141 3 1460793888 31053584 18070064
23:54:54 5423 8192000000 10369370816 10786811904 1421973040 14.790 100 84 168 0 1481565408 38694432 20897936
23:56:05 7953 8192000000 10347750896 10760073216 1367546128 14.305 97 67 133 1 1422708032 37706656 17455248
23:57:20 4591 8192000000 10339277840 10747793408 1378627200 14.404 99 68 136 2 1439666016 42037360 19001456
23:58:31 2024 8192000000 10380137344 10785693696 1405858624 14.647 100 87 175 0 1476015776 48758112 21399040
23:59:38 10803 8192000000 10359378064 10792161280 1416724384 14.744 100 32 64 1 1467320816 41682768 8913664
00:01:03 12929 8192000000 10364363392 10797834240 1415693584 14.734 100 40 80 4 1471167712 44308352 11165776
00:02:22 14846 8192000000 10365752544 10807029760 1415391120 14.732 100 31 62 1 1466335504 42164768 8779616
00:03:39 32998 8192000000 10321417808 10766704640 1377969488 14.398 98 23 46 3 1424462752 40441760 6051504
00:05:44 26696 8192000000 10323278880 10763366400 1345269456 14.105 98 21 42 0 1399864000 48463216 6131328
00:07:30 92066 8192000000 10261962256 10689851392 1311785952 13.802 96 27 51 0 1328125856 10361072 5978832
00:10:02 51980 8192000000 10274829776 10727317504 1374300784 14.366 100 16 32 0 1386735648 8829760 3605104
00:12:10 90497 8192000000 10305935664 10758246400 1378034640 14.399 99 16 32 1 1403507584 22028512 3444432
00:14:41 113097 8192000000 10299231024 10739040256 1362728160 14.262 98 19 38 0 1382182048 15152960 4300928
00:17:35 103218 8192000000 10287953824 10744393728 1380468768 14.421 99 39 78 1 1397474320 7410464 9595088
00:20:41 82435 8192000000 10296078848 10754207744 1381346624 14.429 99 18 36 0 1395636256 10109888 4179744
00:23:09 91911 8192000000 10284568000 10733395968 1341628064 14.072 96 17 34 0 1353932400 8544624 3759712
00:25:43 20147 8192000000 10292092016 10748301312 1390552784 14.511 100 22 44 0 1404565600 8830944 5181872
00:27:04 60241 8192000000 10280936480 10746454016 1370913808 14.335 98 29 58 0 1389458176 11667744 6876624
00:29:04 95517 8192000000 10288878016 10751004672 1379180272 14.409 99 21 42 1 1392292016 7675184 5436560
00:32:04 29221 8192000000 10293519968 10755846144 1380754704 14.423 99 18 35 1 1396266608 11009584 4502320
00:33:44 67151 8192000000 10280044816 10727518208 1353760448 14.181 98 12 24 0 1368238816 11507584 2970784
00:35:52 49124 8192000000 10287627584 10747924480 1385471424 14.465 100 35 70 0 1405189712 11023488 8694800
00:37:54 44810 8192000000 10291729088 10744049664 1364124896 14.274 98 29 58 2 1380991440 10345072 6521472
00:39:39 55735 8192000000 10296470480 10749833216 1364969248 14.282 98 14 28 0 1381343856 13404288 2970320
00:42:26 121035 8192000000 10285205040 10767032320 1321673088 13.892 96 10 20 0 1352142240 28175152 2294000
00:45:27 33225 8192000000 10272603968 10709987328 1301794496 13.712 95 17 34 0 1315908544 10213504 3900544
00:47:03 86206 8192000000 10287941952 10725605376 1338233920 14.041 98 17 34 0 1357886608 15886832 3765856
00:49:30 160063 8192000000 10274384016 10719707136 1334706272 14.010 98 20 39 0 1359136448 19635200 4794976
00:53:15 48030 8192000000 10267935104 10720825344 1329656080 13.964 97 15 30 1 1354179440 21043744 3479616
00:55:06 77670 8192000000 10281065008 10746802176 1357473760 14.215 99 15 29 0 1382111008 21199888 3437360
00:57:26 44450 8192000000 10236235440 10690871296 1294937696 13.649 95 7 14 0 1309352432 12777440 1637296
00:59:16 63372 8192000000 10237779936 10697728000 1326276768 13.934 98 8 16 1 1339869712 11698176 1894768
01:01:19 68383 8192000000 10264744336 10699628544 1317820608 13.857 97 34 68 0 1331925008 6466048 7638352
01:03:28 100860 8192000000 10242750224 10704314368 1314836432 13.830 97 4 8 0 1327167504 11495792 835280
01:06:53 41760 8192000000 10268737600 10717560832 1353769808 14.181 100 30 61 2 1368303024 7939424 6593792
01:08:35 78193 8192000000 10270692848 10731905024 1349147744 14.140 98 17 34 0 1369375584 16311152 3916688
01:11:12 70062 8192000000 10295951760 10753495040 1388350528 14.491 100 30 60 0 1407675584 12170160 7154896
01:13:24 57502 8192000000 10289859008 10739023872 1333777856 14.001 96 23 46 0 1353559808 14422656 5359296
01:15:24 42189 8192000000 10295071296 10740260864 1376174256 14.382 100 18 36 0 1389531600 9382496 3974848
01:17:07 22844 8192000000 10306359488 10747416576 1375254944 14.374 99 37 74 2 1393589088 10165472 8168672
01:18:33 89079 8192000000 10300966768 10750582784 1371383616 14.339 99 13 26 1 1388938192 14571456 2983120
01:21:02 135834 8192000000 10286163648 10740473856 1351756848 14.163 97 30 61 0 1367730896 9497184 6476864
01:24:40 37575 8192000000 10284145280 10750185472 1394064432 14.542 100 15 30 0 1405229040 7236304 3928304
01:26:40 82084 8192000000 10276296832 10737057792 1337764832 14.037 96 14 29 0 1358058656 17149088 3144736
01:29:55 59628 8192000000 10279398416 10735624192 1365314608 14.285 99 12 24 1 1379031376 10884976 2831792
01:31:58 29520 8192000000 10284058784 10731929600 1379451472 14.412 100 25 50 0 1396523056 11472640 5598944
01:33:32 76096 8192000000 10267732384 10726612992 1293971152 13.640 94 11 22 1 1308160272 11629184 2559936
01:35:58 69213 8192000000 10248504448 10703036416 1290086656 13.605 94 14 28 1 1304645872 11439792 3119424

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 14-11-2021 01:38:54 to 14-11-2021 01:39:09.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 99 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 14-11-2021 01:39:10 to 14-11-2021 01:39:12.

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          240801
MON$OLDEST_ACTIVE               242560
MON$OLDEST_SNAPSHOT             242560
MON$NEXT_TRANSACTION            242560
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                       2021056
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.33533 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33533 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33533 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33533 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 ---
:241: 
:242: mscs-test-161.budzdorov.ru    Sun Nov 14 01:37:53 2021
:243:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:244: 
:245: 
:246: mscs-test-161.budzdorov.ru    Sun Nov 14 01:38:01 2021
:247:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:248: 
:249: 
:250: mscs-test-161.budzdorov.ru    Sun Nov 14 01:38:03 2021
:251:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:252: 
:253: 
:254: mscs-test-161.budzdorov.ru    Sun Nov 14 01:38:03 2021
:255:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:256: 
:257: 
:258: mscs-test-161.budzdorov.ru    Sun Nov 14 01:38:03 2021
:259:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:260: 
:261: 
:262: mscs-test-161.budzdorov.ru    Sun Nov 14 01:38:03 2021
:263:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:264: 
:265: 
:266: mscs-test-161.budzdorov.ru    Sun Nov 14 01:38:03 2021
:267:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:268: 
:269: 
:270: mscs-test-161.budzdorov.ru    Sun Nov 14 01:38:03 2021
:271:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:272: 
:273: 
:274: mscs-test-161.budzdorov.ru    Sun Nov 14 01:38:03 2021
:275:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:276: 
--- end of diff output ---

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

14.11.21 01:39:13. 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-11-14 01:39:15 No severe PSQL-related problems occured
14.11.21 01:39:16. End of report.