Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.12.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 16616396 kB
MemAvailable 31443912 kB
Buffers 0 kB
Cached 15025428 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.9
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-12-09 01:38:01
DTS_END                         2021-12-09 01:38:01.3130
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/45618
TRN_ID                          234572
ATT_ID                          597
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: 9176.42 24034 1101170
customer order: creation 165.21 464 19825
customer order: refuse 14.54 321 1745
order to supplier: creation 43.77 1440 5252
order to supplier: removal 3.35 107144 402
invoice (draft): creation 44.34 3446 5321
invoice (draft): removal 54.45 777 6534
invoice accept: apply 40.14 17393 4817
invoice accept: cancel 49.37 822 5924
customer reserve: creation 3658.28 172 438994
customer reserve: removal 4814.53 59 577744
realization accept: apply 80.23 183 9628
realization accept: cancel 83.26 115 9991
payment from customer: creation 57.03 134 6844
payment from customer: removal 5.85 114 702
payment to supplier: creation 46.87 140 5624
payment to supplier: removal 3.51 109 421
service: total inventory turnovers 4.49 1401 539
service: total monetary turnovers 6.42 33 770
service: aggregate perf. data 0.74 25976 89
service: refresh index statistics 0.03 320444 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-12-2021 01:38:30 to 09-12-2021 01:38:32.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7507
TEST_TIME 2 7170
TEST_TIME 3 6213
TEST_TIME 4 5741
TEST_TIME 5 5197
TEST_TIME 6 5106
TEST_TIME 7 5401
TEST_TIME 8 5383
TEST_TIME 9 5689
TEST_TIME 10 5638
TEST_TIME 11 6006
TEST_TIME 12 6423
TEST_TIME 13 6922
TEST_TIME 14 7091
TEST_TIME 15 7492
TEST_TIME 16 7801
TEST_TIME 17 7851
TEST_TIME 18 8003
TEST_TIME 19 8007
TEST_TIME 20 8484
TEST_TIME 21 8276
TEST_TIME 22 8450
TEST_TIME 23 8506
TEST_TIME 24 9234
TEST_TIME 25 9083
TEST_TIME 26 9731
TEST_TIME 27 9796
TEST_TIME 28 10494
TEST_TIME 29 10457
TEST_TIME 30 10407
TEST_TIME 31 10171
TEST_TIME 32 9946
TEST_TIME 33 10058
TEST_TIME 34 10297
TEST_TIME 35 10325
TEST_TIME 36 10154
TEST_TIME 37 10022
TEST_TIME 38 9901
TEST_TIME 39 9826
TEST_TIME 40 9784
TEST_TIME 41 9600
TEST_TIME 42 9631
TEST_TIME 43 9691
TEST_TIME 44 9606
TEST_TIME 45 9457
TEST_TIME 46 9374
TEST_TIME 47 9254
TEST_TIME 48 9215
TEST_TIME 49 9254
TEST_TIME 50 9184
TEST_TIME 51 9280
TEST_TIME 52 9232
TEST_TIME 53 9169
TEST_TIME 54 9041
TEST_TIME 55 8964
TEST_TIME 56 8923
TEST_TIME 57 8858
TEST_TIME 58 8816
TEST_TIME 59 8717
TEST_TIME 60 8791
TEST_TIME 61 8749
TEST_TIME 62 8741
TEST_TIME 63 8746
TEST_TIME 64 8825
TEST_TIME 65 8876
TEST_TIME 66 8885
TEST_TIME 67 8927
TEST_TIME 68 8956
TEST_TIME 69 8953
TEST_TIME 70 9034
TEST_TIME 71 9018
TEST_TIME 72 9054
TEST_TIME 73 9082
TEST_TIME 74 9094
TEST_TIME 75 9074
TEST_TIME 76 9093
TEST_TIME 77 9060
TEST_TIME 78 9045
TEST_TIME 79 9059
TEST_TIME 80 8991
TEST_TIME 81 8945
TEST_TIME 82 8938
TEST_TIME 83 9018
TEST_TIME 84 8982
TEST_TIME 85 8944
TEST_TIME 86 8939
TEST_TIME 87 8976
TEST_TIME 88 8960
TEST_TIME 89 9073
TEST_TIME 90 9057
TEST_TIME 91 9101
TEST_TIME 92 9142
TEST_TIME 93 9138
TEST_TIME 94 9174
TEST_TIME 95 9266
TEST_TIME 96 9280
TEST_TIME 97 9282
TEST_TIME 98 9271
TEST_TIME 99 9218
TEST_TIME 100 9151
TEST_TIME 101 9157
TEST_TIME 102 9114
TEST_TIME 103 9156
TEST_TIME 104 9136
TEST_TIME 105 9109
TEST_TIME 106 9078
TEST_TIME 107 9089
TEST_TIME 108 9060
TEST_TIME 109 9101
TEST_TIME 110 9088
TEST_TIME 111 9076
TEST_TIME 112 9111
TEST_TIME 113 9158
TEST_TIME 114 9167
TEST_TIME 115 9172
TEST_TIME 116 9223
TEST_TIME 117 9169
TEST_TIME 118 9214
TEST_TIME 119 9255
TEST_TIME 120 9179

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 09-12-2021 01:38:34 to 09-12-2021 01:38:37.

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 1509850 1509850 0 0.00 0 21446 6 0 0
doc_list_biud 1509850 1509850 0 0.00 0 75207 28 0 0
sp_add_invoice_to_stock 4930 4817 113 2.29 26 177701 17394 0 113
sp_cancel_adding_invoice 5924 5924 0 0.00 5 71698 823 0 0
sp_cancel_client_order 1745 1745 0 0.00 4 10955 321 0 0
sp_cancel_customer_reserve 577744 577744 0 0.00 1 75208 59 0 0
sp_cancel_pay_from_customer 702 702 0 0.00 3 8121 115 0 0
sp_cancel_pay_to_supplier 421 421 0 0.00 3 9198 110 0 0
sp_cancel_supplier_invoice 6546 6534 12 0.18 1 13896 777 0 12
sp_cancel_supplier_order 402 402 0 0.00 7 1013254 107145 0 0
sp_cancel_write_off 9991 9991 0 0.00 1 12034 115 0 0
sp_client_order 19825 19825 0 0.00 3 34971 464 0 0
sp_customer_reserve 438994 438994 0 0.00 0 49344 173 0 0
sp_fill_shopping_cart 30393 30393 0 0.00 1 22381 46 0 0
sp_fill_shopping_cart_clo_res 438992 438992 0 0.00 0 14390 15 0 0
sp_get_clo_for_invoice 4813 4813 0 0.00 0 65762 388 0 0
sp_kill_cost_storno 17038 17038 0 0.00 0 14748 62 0 0
sp_kill_qstorno_ret_qs2qd 1172415 1172415 0 0.00 0 75206 32 0 0
sp_kill_qty_storno 616781 616781 0 0.00 0 75207 69 0 0
sp_lock_selected_doc 20098 20098 0 0.00 0 7493 7 0 0
sp_make_cost_storno 26909 26909 0 0.00 0 18617 61 0 0
sp_multiply_rows_for_pdistr 26909 26909 0 0.00 0 11457 35 0 0
sp_multiply_rows_for_qdistr 19825 19825 0 0.00 0 16429 204 0 0
sp_pay_from_customer 6844 6844 0 0.00 4 18998 134 0 0
sp_pay_to_supplier 5624 5624 0 0.00 4 12587 140 0 0
sp_payment_common 12468 12468 0 0.00 1 17069 36 0 0
sp_qd_handle_on_cancel_clo 1745 1745 0 0.00 0 10912 298 0 0
sp_qd_handle_on_invoice_upd_sts 10737 10737 0 0.00 0 12038 320 0 0
sp_qd_handle_on_reserve_upd_sts 9628 9628 0 0.00 0 14049 53 0 0
sp_reserve_write_off 9628 9628 0 0.00 6 17976 183 0 0
sp_supplier_invoice 5321 5321 0 0.00 6 90878 3447 0 0
sp_supplier_order 5252 5252 0 0.00 6 38193 1440 0 0
srv_aggregate_perf_data 89 89 0 0.00 2662 392230 25977 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 1549 133628 48659 0 0
srv_find_qd_qs_mism 1056522 1056522 0 0.00 0 21445 5 0 0
srv_make_invnt_saldo 539 539 0 0.00 34 114450 1402 0 0
srv_make_money_saldo 770 770 0 0.00 1 2418 33 0 0
srv_recalc_idx_stat 4 4 0 0.00 138714 553850 320444 0 0
t$perf-abend:sp_add_invoice_to_stock 113 113 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4818 4818 0 0.00 0 15580 177 0 0
t$perf-norm:sp_cancel_adding_invoice 426 426 0 0.00 0 22699 173 0 0
t$perf-norm:sp_cancel_client_order 1745 1745 0 0.00 0 2811 21 0 0
t$perf-norm:sp_cancel_customer_reserve 625 625 0 0.00 0 11927 32 0 0
t$perf-norm:sp_cancel_pay_from_customer 702 702 0 0.00 0 2525 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 421 421 0 0.00 0 9820 27 0 0
t$perf-norm:sp_cancel_supplier_invoice 397 397 0 0.00 0 4135 24 0 0
t$perf-norm:sp_cancel_supplier_order 402 402 0 0.00 0 25728 1699 0 0
t$perf-norm:sp_cancel_write_off 940 940 0 0.00 0 1170 7 0 0
t$perf-norm:sp_client_order 19825 19825 0 0.00 0 14478 24 0 0
t$perf-norm:sp_customer_reserve 1016 1016 0 0.00 0 2035 10 0 0
t$perf-norm:sp_pay_from_customer 6844 6844 0 0.00 0 10163 14 0 0
t$perf-norm:sp_pay_to_supplier 5624 5624 0 0.00 0 10492 14 0 0
t$perf-norm:sp_reserve_write_off 9628 9628 0 0.00 0 12216 14 0 0
t$perf-norm:sp_supplier_invoice 5321 5321 0 0.00 0 10378 24 0 0
t$perf-norm:sp_supplier_order 5252 5252 0 0.00 0 8227 23 0 0
t$perf-norm:srv_aggregate_perf_data 89 89 0 0.00 0 187 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 11278 203 0 0
t$perf-norm:srv_make_invnt_saldo 539 539 0 0.00 0 777 26 0 0
t$perf-norm:srv_make_money_saldo 770 770 0 0.00 0 2237 23 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 7 4 0 0
v_add_invoice_to_stock 9850 9624 226 2.29 0 3976 17 0 226
v_all_customers 35808 35808 0 0.00 0 15 1 0 0
v_all_suppliers 29492 29492 0 0.00 0 31 1 0 0
v_all_wares 39648 39648 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 852 852 0 0.00 0 1799 10 0 0
v_cancel_client_order 3490 3490 0 0.00 0 1659 4 0 0
v_cancel_customer_prepayment 1404 1404 0 0.00 0 1664 5 0 0
v_cancel_customer_reserve 1250 1250 0 0.00 0 2789 9 0 0
v_cancel_payment_to_supplier 842 842 0 0.00 0 3194 7 0 0
v_cancel_supplier_invoice 818 794 24 2.93 0 258 13 0 24
v_cancel_supplier_order 804 804 0 0.00 0 183 3 0 0
v_cancel_write_off 1880 1880 0 0.00 0 5808 19 0 0
v_max_id_clo_ord 5249 5249 0 0.00 0 3859 3 0 0
v_max_id_clo_res 1016 1016 0 0.00 0 1432 12 0 0
v_max_id_ord_sup 5320 5320 0 0.00 0 5773 5 0 0
v_max_non_paid_invoice 1447 1447 0 0.00 0 1090 4 0 0
v_max_non_paid_realizn 3697 3697 0 0.00 0 8900 10 0 0
v_min_id_clo_ord 5249 5249 0 0.00 0 16424 22 0 0
v_min_id_clo_res 1016 1016 0 0.00 0 6639 25 0 0
v_min_id_ord_sup 5320 5320 0 0.00 0 4697 5 0 0
v_min_non_paid_invoice 5624 5624 0 0.00 0 10118 26 0 0
v_min_non_paid_realizn 6844 6844 0 0.00 0 17065 30 0 0
v_our_firm 10134 10134 0 0.00 0 20 1 0 0
v_reserve_write_off 19256 19256 0 0.00 0 4277 5 0 0
x_lock_depdocs_on_canc_invoice 12061 12061 0 0.00 0 10269 111 0 0
x_lock_depdocs_on_canc_sup_ord 402 402 0 0.00 0 4078 327 0 0
x_make_qstorno_1000_1200 5252 5252 0 0.00 2 38185 1332 0 0
x_make_qstorno_1200_2000 5321 5321 0 0.00 2 90856 3294 0 0
x_make_qstorno_2100_3300 438994 438994 0 0.00 0 47229 156 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-12-2021 01:38:38 to 09-12-2021 01:38:42.

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:08:05 3 8192000000 8801016624 8813051904 6613872 0.080 2 1 2 0 6712144 55488 42784
23:09:06 27618 8192000000 9896534368 10210422784 973084192 10.617 100 87 174 0 1003602096 6502848 24015056
23:10:40 16342 8192000000 9952559264 10278678528 1037359696 11.239 100 91 182 0 1069629088 8860752 23408640
23:12:04 27738 8192000000 10006335456 10345275392 1080106880 11.648 100 89 178 0 1112121184 10360832 21653472
23:13:39 15815 8192000000 10043593936 10391158784 1119379296 12.021 100 84 169 2 1151522160 12375376 19767488
23:14:59 9321 8192000000 10075108032 10432880640 1160001616 12.403 100 77 154 0 1190185344 12536608 17647120
23:16:10 4469 8192000000 10118318944 10491351040 1212208176 12.890 100 66 132 2 1239812048 12764576 14839296
23:17:20 7857 8192000000 10172167952 10547335168 1251145328 13.249 100 85 168 1 1283883600 14365920 18372352
23:18:29 1413 8192000000 10201576080 10583080960 1288347952 13.589 100 91 180 1 1322070704 12812016 20910736
23:19:31 6577 8192000000 10215663136 10604281856 1301514256 13.709 100 74 148 3 1326708496 7744112 17450128
23:20:38 6768 8192000000 10204374656 10615140352 1312976560 13.813 100 29 57 3 1325775456 6661040 6137856
23:21:45 6077 8192000000 10209524688 10625642496 1321269856 13.888 100 26 50 2 1333528320 6601104 5657360
23:22:52 35449 8192000000 10209544752 10633764864 1324210800 13.915 100 11 22 1 1331565168 5262432 2091936
23:24:31 8290 8192000000 10222387664 10646040576 1333690400 14.000 100 27 54 0 1345588320 5563504 6334416
23:25:42 9760 8192000000 10234600896 10652688384 1339334208 14.051 100 19 38 0 1349840192 5889904 4616080
23:26:54 52038 8192000000 10228059664 10650918912 1327148048 13.941 99 40 80 0 1343476448 7001504 9326896
23:28:47 2773 8192000000 10206342688 10618986496 1309008688 13.777 99 32 64 1 1323831904 7726080 7097136
23:29:51 8481 8192000000 10164211984 10579173376 1269483568 13.417 99 17 34 1 1279335472 5991296 3860608
23:30:59 11700 8192000000 10106464864 10495623168 1173788528 12.532 93 17 34 1 1182897248 4896640 4212080
23:32:12 22427 8192000000 10117739952 10517127168 1178142192 12.573 94 17 33 1 1187635648 5174848 4318608
23:33:39 11157 8192000000 10166742448 10571120640 1271481968 13.435 100 24 46 1 1282495840 5191600 5822272
23:34:52 14957 8192000000 10183670288 10596306944 1290862880 13.612 100 13 26 1 1300055680 6178512 3014288
23:36:11 56721 8192000000 10195707104 10611941376 1307664768 13.765 100 17 34 0 1316382400 5106864 3610768
23:38:10 15064 8192000000 10237876864 10647015424 1332230128 13.987 100 51 102 3 1350691888 6555520 11906240
23:39:54 16058 8192000000 10264932448 10681995264 1361646192 14.252 100 46 90 0 1385534672 13921200 9967280
23:41:24 39643 8192000000 10299625136 10723368960 1381982912 14.434 100 39 78 0 1404655536 13173808 9498816
23:43:08 48968 8192000000 10310903200 10742353920 1404424736 14.634 100 41 80 2 1429378768 14413728 10540304
23:45:23 17741 8192000000 10318458272 10751229952 1413097904 14.711 100 29 58 0 1437194592 16391312 7705376
23:46:51 9693 8192000000 10338486960 10763972608 1418762640 14.762 100 49 96 1 1449817216 19615200 11439376
23:48:05 7032 8192000000 10351694944 10772934656 1422751824 14.797 100 65 130 1 1463124976 24711376 15661776
23:49:23 9066 8192000000 10345703568 10773028864 1418195152 14.757 100 58 116 0 1457660160 25416128 14048880
23:50:35 1547 8192000000 10370833648 10792710144 1431747328 14.877 100 75 146 0 1479852864 30900224 17205312
23:51:40 4924 8192000000 10352968528 10776780800 1412861568 14.709 99 60 119 2 1459013664 31953552 14198544
23:52:53 4122 8192000000 10356034064 10777214976 1411796720 14.700 99 68 135 0 1459992432 31823360 16372352
23:54:05 4488 8192000000 10317349520 10725560320 1368389792 14.313 99 74 148 1 1420355440 32911568 19054080
23:55:16 2211 8192000000 10309060832 10709032960 1353654160 14.180 98 91 182 4 1406738864 30225168 22859536
23:56:22 4322 8192000000 10326858688 10733740032 1382014208 14.435 100 77 155 0 1430935936 29928272 18993456
23:57:33 2447 8192000000 10335699728 10740256768 1381197616 14.427 100 86 171 0 1436568176 34033424 21337136
23:58:37 7723 8192000000 10311824256 10738941952 1371528592 14.341 99 35 70 3 1408315808 27207056 9580160
23:59:55 6726 8192000000 10342229344 10755473408 1387812064 14.486 100 62 124 1 1442634800 38217232 16605504
00:01:05 15917 8192000000 10337935632 10761363456 1387346512 14.482 100 40 79 2 1440563040 42108544 11107984
00:02:25 8922 8192000000 10328729664 10768781312 1379099456 14.408 99 29 58 1 1435678304 47738576 8840272
00:03:38 19481 8192000000 10305520208 10741035008 1371885456 14.344 100 26 52 1 1420043248 40441904 7715888
00:04:59 32042 8192000000 10292911200 10737160192 1349996400 14.147 99 21 40 2 1393598784 37881712 5720672
00:06:37 36246 8192000000 10268968608 10728677376 1339614944 14.054 99 10 20 2 1370360688 28595072 2150672
00:09:32 86389 8192000000 10312960000 10752991232 1385437776 14.465 100 27 54 1 1417137392 24939856 6759760
00:12:00 49447 8192000000 10275682800 10744791040 1347559328 14.126 98 9 18 1 1376681568 27008592 2113648
00:13:52 65125 8192000000 10271226288 10715422720 1368600336 14.315 100 30 54 0 1380978384 6227904 6150144
00:16:04 27022 8192000000 10289730368 10735697920 1382793184 14.442 100 20 40 0 1397200544 10028704 4378656
00:17:44 92685 8192000000 10305277712 10750775296 1392249456 14.526 100 30 56 1 1414220336 14805088 7165792
00:20:19 69284 8192000000 10304714112 10775224320 1396209856 14.561 100 13 26 1 1416014400 16443552 3360992
00:22:50 83965 8192000000 10313504528 10765070336 1403983808 14.630 100 25 49 0 1421387840 12249088 5154944
00:25:14 26840 8192000000 10317556176 10762547200 1392071184 14.524 99 48 95 2 1415328752 12569024 10688544
00:26:43 42179 8192000000 10314693824 10761768960 1394909440 14.550 100 34 66 2 1419140608 16105312 8125856
00:28:29 79441 8192000000 10293459008 10762199040 1378991856 14.408 99 11 22 0 1397172688 15344624 2836208
00:30:53 82206 8192000000 10285202688 10750697472 1394593792 14.547 100 11 22 0 1410307152 13344672 2368688
00:33:27 79089 8192000000 10279627840 10744438784 1380831728 14.424 99 9 18 0 1388919712 6067840 2020144
00:35:48 79297 8192000000 10294010992 10765709312 1402797168 14.620 100 12 24 2 1414512256 9284768 2430320
00:38:50 63127 8192000000 10296494048 10755743744 1376703168 14.387 98 14 28 0 1393742848 14279232 2760448
00:40:55 67613 8192000000 10302622096 10766483456 1377151584 14.391 98 24 48 0 1396026544 13441968 5432992
00:43:42 56950 8192000000 10304043808 10751193088 1359294416 14.231 97 28 56 0 1377658128 12099184 6264528
00:45:40 32992 8192000000 10284422880 10733072384 1359880656 14.236 99 24 48 0 1374193968 9107360 5205952
00:47:16 73325 8192000000 10286347344 10730115072 1295031600 13.650 94 27 54 1 1312845168 12148384 5665184
00:49:29 71717 8192000000 10248805600 10698792960 1263229520 13.360 93 26 52 0 1283635568 14852976 5553072
00:51:42 47065 8192000000 10260245328 10710650880 1314344144 13.825 97 16 32 1 1327762640 9959424 3459072
00:53:36 118815 8192000000 10243693104 10682347520 1300532848 13.700 97 30 60 1 1326391504 18401424 7457232
00:56:50 64032 8192000000 10237490768 10689179648 1313852288 13.821 98 13 27 1 1325377168 8226832 3298048
00:59:15 68441 8192000000 10246338544 10699997184 1306548800 13.755 96 18 36 0 1323431792 12580160 4302832
01:02:13 68779 8192000000 10235435232 10685124608 1311005648 13.795 97 9 18 0 1319762352 6884704 1872000
01:04:27 47493 8192000000 10247880320 10704330752 1316245408 13.843 97 21 42 0 1331675584 10540720 4889456
01:06:18 89889 8192000000 10248772624 10706427904 1298298128 13.680 96 18 36 1 1315768096 13455040 4014928
01:08:49 64365 8192000000 10288909232 10738364416 1375460160 14.376 100 18 36 0 1394119568 14857664 3801744
01:10:57 133628 8192000000 10285737552 10752917504 1288006880 13.586 93 10 20 1 1307859600 17710864 2141856
01:14:53 83330 8192000000 10291561584 10737061888 1347352512 14.124 97 18 36 0 1361694944 10508240 3834192
01:17:17 91538 8192000000 10308011600 10752237568 1392969152 14.532 100 42 84 0 1409809120 7493552 9346416
01:20:00 42355 8192000000 10298927840 10760441856 1393580096 14.538 100 20 40 1 1415694400 18014336 4099968
01:21:43 47437 8192000000 10284175872 10751148032 1392112272 14.525 100 4 8 0 1401244576 8303840 828464
01:24:00 73897 8192000000 10307025904 10767241216 1390350272 14.509 99 12 24 2 1405762048 12469440 2942336
01:26:43 85831 8192000000 10282694240 10756034560 1363562800 14.269 97 16 33 1 1381958592 14299344 4096448
01:29:11 55615 8192000000 10316378160 10765881344 1396785152 14.566 99 33 66 1 1416626704 12290640 7550912
01:31:14 87736 8192000000 10275735088 10719264768 1282612816 13.537 92 14 27 1 1294138960 8841424 2684720
01:33:57 57394 8192000000 10286614160 10745860096 1359976656 14.237 98 17 34 0 1376005968 12392224 3637088
01:36:15 48587 8192000000 10306971584 10755133440 1378598752 14.404 99 28 56 1 1399780032 14970944 6210336

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 09-12-2021 01:38:44 to 09-12-2021 01:38:59.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 113 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

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

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          233787
MON$OLDEST_ACTIVE               234779
MON$OLDEST_SNAPSHOT             234779
MON$NEXT_TRANSACTION            234779
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                       2034592
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33542 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33542 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33542 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33542 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 ---
:141: 
:142: mscs-test-161.budzdorov.ru    Thu Dec  9 01:38:02 2021
:143:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:144: 
--- end of diff output ---

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

09.12.21 01:39:03. 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-12-09 01:39:05 No severe PSQL-related problems occured
09.12.21 01:39:06. End of report.