Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.11.2021 22: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 15972916 kB
MemAvailable 30810308 kB
Buffers 0 kB
Cached 15753652 kB
SwapCached 2456 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-10 22:37:51
DTS_END                         2021-11-10 22:37:51.7020
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/41520
TRN_ID                          244422
ATT_ID                          655
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: 9320.51 21118 1118461
customer order: creation 176.02 360 21122
customer order: refuse 14.62 341 1754
order to supplier: creation 46.95 1293 5634
order to supplier: removal 3.26 104035 391
invoice (draft): creation 46.73 3499 5608
invoice (draft): removal 51.52 842 6182
invoice accept: apply 42.94 17005 5153
invoice accept: cancel 46.92 760 5630
customer reserve: creation 3959.24 167 475109
customer reserve: removal 4636.43 56 556371
realization accept: apply 84.96 180 10195
realization accept: cancel 78.03 110 9363
payment from customer: creation 61.54 119 7385
payment from customer: removal 6.09 62 731
payment to supplier: creation 49.90 147 5988
payment to supplier: removal 3.58 120 429
service: total inventory turnovers 4.38 1322 526
service: total monetary turnovers 6.52 28 782
service: aggregate perf. data 0.87 17070 104
service: refresh index statistics 0.03 274836 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-11-2021 22:40:13 to 10-11-2021 22:40:15.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3768
TEST_TIME 2 5409
TEST_TIME 3 4868
TEST_TIME 4 5738
TEST_TIME 5 5366
TEST_TIME 6 5082
TEST_TIME 7 4737
TEST_TIME 8 5158
TEST_TIME 9 5007
TEST_TIME 10 5017
TEST_TIME 11 5481
TEST_TIME 12 5614
TEST_TIME 13 5760
TEST_TIME 14 6000
TEST_TIME 15 6243
TEST_TIME 16 6445
TEST_TIME 17 6878
TEST_TIME 18 7297
TEST_TIME 19 7379
TEST_TIME 20 7515
TEST_TIME 21 8173
TEST_TIME 22 8197
TEST_TIME 23 9271
TEST_TIME 24 9238
TEST_TIME 25 9405
TEST_TIME 26 9546
TEST_TIME 27 9968
TEST_TIME 28 9922
TEST_TIME 29 10653
TEST_TIME 30 10435
TEST_TIME 31 10752
TEST_TIME 32 10675
TEST_TIME 33 10554
TEST_TIME 34 10364
TEST_TIME 35 10192
TEST_TIME 36 10103
TEST_TIME 37 9984
TEST_TIME 38 9855
TEST_TIME 39 9812
TEST_TIME 40 9827
TEST_TIME 41 9767
TEST_TIME 42 9854
TEST_TIME 43 9776
TEST_TIME 44 9785
TEST_TIME 45 9753
TEST_TIME 46 9695
TEST_TIME 47 9656
TEST_TIME 48 9567
TEST_TIME 49 9540
TEST_TIME 50 9423
TEST_TIME 51 9386
TEST_TIME 52 9336
TEST_TIME 53 9350
TEST_TIME 54 9330
TEST_TIME 55 9291
TEST_TIME 56 9325
TEST_TIME 57 9415
TEST_TIME 58 9386
TEST_TIME 59 9481
TEST_TIME 60 9584
TEST_TIME 61 9578
TEST_TIME 62 9629
TEST_TIME 63 9750
TEST_TIME 64 9767
TEST_TIME 65 9874
TEST_TIME 66 9903
TEST_TIME 67 9857
TEST_TIME 68 9760
TEST_TIME 69 9714
TEST_TIME 70 9679
TEST_TIME 71 9651
TEST_TIME 72 9594
TEST_TIME 73 9535
TEST_TIME 74 9483
TEST_TIME 75 9475
TEST_TIME 76 9400
TEST_TIME 77 9350
TEST_TIME 78 9353
TEST_TIME 79 9286
TEST_TIME 80 9303
TEST_TIME 81 9339
TEST_TIME 82 9324
TEST_TIME 83 9337
TEST_TIME 84 9354
TEST_TIME 85 9360
TEST_TIME 86 9350
TEST_TIME 87 9312
TEST_TIME 88 9438
TEST_TIME 89 9429
TEST_TIME 90 9457
TEST_TIME 91 9492
TEST_TIME 92 9525
TEST_TIME 93 9538
TEST_TIME 94 9552
TEST_TIME 95 9515
TEST_TIME 96 9465
TEST_TIME 97 9432
TEST_TIME 98 9415
TEST_TIME 99 9402
TEST_TIME 100 9344
TEST_TIME 101 9305
TEST_TIME 102 9304
TEST_TIME 103 9279
TEST_TIME 104 9338
TEST_TIME 105 9351
TEST_TIME 106 9344
TEST_TIME 107 9377
TEST_TIME 108 9376
TEST_TIME 109 9378
TEST_TIME 110 9391
TEST_TIME 111 9424
TEST_TIME 112 9396
TEST_TIME 113 9425
TEST_TIME 114 9431
TEST_TIME 115 9429
TEST_TIME 116 9491
TEST_TIME 117 9422
TEST_TIME 118 9376
TEST_TIME 119 9332
TEST_TIME 120 9327

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 10-11-2021 22:40:17 to 10-11-2021 22:40:20.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1557266 1557266 0 0.00 0 23225 6 0 0
doc_list_biud 1557266 1557266 0 0.00 0 79669 26 0 0
sp_add_invoice_to_stock 5243 5153 90 1.71 36 152929 17005 0 90
sp_cancel_adding_invoice 5630 5630 0 0.00 6 61479 760 0 0
sp_cancel_client_order 1754 1754 0 0.00 4 12737 341 0 0
sp_cancel_customer_reserve 556371 556371 0 0.00 1 32015 57 0 0
sp_cancel_pay_from_customer 731 731 0 0.00 3 2708 63 0 0
sp_cancel_pay_to_supplier 429 429 0 0.00 3 5073 121 0 0
sp_cancel_supplier_invoice 6191 6182 9 0.14 2 79671 843 0 9
sp_cancel_supplier_order 391 391 0 0.00 8 1209973 104035 0 0
sp_cancel_write_off 9363 9363 0 0.00 1 23938 110 0 0
sp_client_order 21122 21122 0 0.00 3 29304 361 0 0
sp_customer_reserve 475109 475109 0 0.00 0 54431 168 0 0
sp_fill_shopping_cart 32357 32357 0 0.00 1 19099 39 0 0
sp_fill_shopping_cart_clo_res 475096 475096 0 0.00 0 19349 12 0 0
sp_get_clo_for_invoice 5140 5140 0 0.00 0 34053 307 0 0
sp_kill_cost_storno 16153 16153 0 0.00 0 7390 56 0 0
sp_kill_qstorno_ret_qs2qd 1128678 1128678 0 0.00 0 79667 31 0 0
sp_kill_qty_storno 595026 595026 0 0.00 0 79668 68 0 0
sp_lock_selected_doc 21223 21223 0 0.00 0 9519 5 0 0
sp_make_cost_storno 28708 28708 0 0.00 0 23212 54 0 0
sp_multiply_rows_for_pdistr 28708 28708 0 0.00 0 6705 27 0 0
sp_multiply_rows_for_qdistr 21122 21122 0 0.00 0 29298 142 0 0
sp_pay_from_customer 7385 7385 0 0.00 4 12141 120 0 0
sp_pay_to_supplier 5988 5988 0 0.00 5 11925 148 0 0
sp_payment_common 13373 13373 0 0.00 1 11831 24 0 0
sp_qd_handle_on_cancel_clo 1754 1754 0 0.00 0 12196 296 0 0
sp_qd_handle_on_invoice_upd_sts 10770 10770 0 0.00 0 16454 338 0 0
sp_qd_handle_on_reserve_upd_sts 10195 10195 0 0.00 0 19942 64 0 0
sp_reserve_write_off 10195 10195 0 0.00 6 19960 181 0 0
sp_supplier_invoice 5608 5608 0 0.00 5 84586 3500 0 0
sp_supplier_order 5634 5634 0 0.00 5 75625 1293 0 0
srv_aggregate_perf_data 104 104 0 0.00 2986 101551 17070 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2235 154871 48165 0 0
srv_find_qd_qs_mism 1068884 1068884 0 0.00 0 18549 5 0 0
srv_make_invnt_saldo 526 526 0 0.00 35 62196 1323 0 0
srv_make_money_saldo 782 782 0 0.00 0 4572 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 66953 423304 274836 0 0
t$perf-abend:sp_add_invoice_to_stock 90 90 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 5154 5154 0 0.00 0 10273 156 0 0
t$perf-norm:sp_cancel_adding_invoice 412 412 0 0.00 0 4562 91 0 0
t$perf-norm:sp_cancel_client_order 1754 1754 0 0.00 0 1328 10 0 0
t$perf-norm:sp_cancel_customer_reserve 725 725 0 0.00 0 359 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 731 731 0 0.00 0 904 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 429 429 0 0.00 0 2086 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 390 390 0 0.00 0 1122 20 0 0
t$perf-norm:sp_cancel_supplier_order 391 391 0 0.00 0 31018 1689 0 0
t$perf-norm:sp_cancel_write_off 1058 1058 0 0.00 0 2098 9 0 0
t$perf-norm:sp_client_order 21122 21122 0 0.00 0 10123 19 0 0
t$perf-norm:sp_customer_reserve 1096 1096 0 0.00 0 3707 15 0 0
t$perf-norm:sp_pay_from_customer 7385 7385 0 0.00 0 5293 12 0 0
t$perf-norm:sp_pay_to_supplier 5988 5988 0 0.00 0 14475 16 0 0
t$perf-norm:sp_reserve_write_off 10195 10195 0 0.00 0 3450 9 0 0
t$perf-norm:sp_supplier_invoice 5608 5608 0 0.00 0 12765 21 0 0
t$perf-norm:sp_supplier_order 5634 5634 0 0.00 0 6926 21 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 423 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 312 9 0 0
t$perf-norm:srv_make_invnt_saldo 526 526 0 0.00 0 1746 32 0 0
t$perf-norm:srv_make_money_saldo 782 782 0 0.00 0 3196 21 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10454 10274 180 1.72 0 11918 20 0 180
v_all_customers 38546 38546 0 0.00 0 26 1 0 0
v_all_suppliers 30910 30910 0 0.00 0 28 1 0 0
v_all_wares 42244 42244 0 0.00 0 88 1 0 0
v_cancel_adding_invoice 824 824 0 0.00 0 6748 16 0 0
v_cancel_client_order 3508 3508 0 0.00 0 12485 13 0 0
v_cancel_customer_prepayment 1462 1462 0 0.00 0 2033 6 0 0
v_cancel_customer_reserve 1450 1450 0 0.00 0 11653 23 0 0
v_cancel_payment_to_supplier 858 858 0 0.00 0 3467 12 0 0
v_cancel_supplier_invoice 798 780 18 2.25 0 4844 20 0 18
v_cancel_supplier_order 782 782 0 0.00 0 528 3 0 0
v_cancel_write_off 2116 2116 0 0.00 0 2051 14 0 0
v_max_id_clo_ord 5632 5632 0 0.00 0 828 2 0 0
v_max_id_clo_res 1096 1096 0 0.00 0 1369 11 0 0
v_max_id_ord_sup 5603 5603 0 0.00 0 5131 4 0 0
v_max_non_paid_invoice 1768 1768 0 0.00 0 429 2 0 0
v_max_non_paid_realizn 3880 3880 0 0.00 0 449 3 0 0
v_min_id_clo_ord 5632 5632 0 0.00 0 2400 7 0 0
v_min_id_clo_res 1096 1096 0 0.00 0 3221 13 0 0
v_min_id_ord_sup 5603 5603 0 0.00 0 7424 7 0 0
v_min_non_paid_invoice 5988 5988 0 0.00 0 7429 17 0 0
v_min_non_paid_realizn 7385 7385 0 0.00 0 7351 18 0 0
v_our_firm 10708 10708 0 0.00 0 15 1 0 0
v_reserve_write_off 20390 20390 0 0.00 0 14644 10 0 0
x_lock_depdocs_on_canc_invoice 11422 11422 0 0.00 0 11236 108 0 0
x_lock_depdocs_on_canc_sup_ord 391 391 0 0.00 0 16886 386 0 0
x_make_qstorno_1000_1200 5634 5634 0 0.00 2 75621 1209 0 0
x_make_qstorno_1200_2000 5608 5608 0 0.00 2 78551 3361 0 0
x_make_qstorno_2100_3300 475109 475109 0 0.00 0 54427 153 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-11-2021 22:40:21 to 10-11-2021 22:40:25.

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
20:07:55 40 8192000000 8829451072 8851333120 28563520 0.347 9 7 7 0 28884288 117136 203632
20:08:57 36040 8192000000 9916010112 10230824960 995730000 10.837 100 90 180 0 1025621760 6404944 23486816
20:10:42 27575 8192000000 9955572304 10284892160 1039876912 11.263 100 87 174 0 1071171200 9405456 21888832
20:12:13 21799 8192000000 10001287760 10343546880 1086820240 11.712 100 85 170 0 1118664464 11122112 20722112
20:13:40 12043 8192000000 10054223424 10407075840 1135283136 12.171 100 84 169 3 1167972928 12740128 19949664
20:14:56 8031 8192000000 10096029200 10459734016 1181638944 12.605 100 83 166 1 1214273872 14102480 18532448
20:16:06 4860 8192000000 10139520960 10509819904 1229621824 13.051 100 83 166 0 1261787984 14141040 18025120
20:17:13 3070 8192000000 10164099216 10542100480 1255318288 13.287 100 86 172 1 1288697696 14080320 19299088
20:18:17 1623 8192000000 10204121344 10583908352 1287168704 13.578 100 94 188 0 1320493152 12126768 21197680
20:19:19 15499 8192000000 10196193024 10591875072 1293112976 13.633 100 23 46 0 1304465056 5560640 5791440
20:20:36 5481 8192000000 10195146288 10610589696 1311867248 13.803 100 22 44 1 1322333120 5445056 5020816
20:21:42 8256 8192000000 10211106224 10622775296 1318787888 13.866 100 17 33 1 1328599264 5368304 4443072
20:22:53 64588 8192000000 10246560336 10642956288 1322516816 13.899 100 68 138 1 1345057408 5637280 16903312
20:24:57 2785 8192000000 10229731136 10644004864 1329497136 13.963 100 39 78 1 1345858128 6641472 9719520
20:26:02 4831 8192000000 10222707104 10645573632 1333931552 14.003 100 23 46 3 1346182608 7077744 5173312
20:27:10 20735 8192000000 10223742960 10646499328 1323204576 13.906 99 28 54 1 1340246432 10654224 6387632
20:28:32 8855 8192000000 10199606480 10610053120 1268565696 13.408 96 21 42 0 1279333520 5433456 5334368
20:29:41 4689 8192000000 10171047808 10571550720 1254370832 13.278 97 27 54 1 1266937248 6518304 6048112
20:30:48 34178 8192000000 10119524976 10511392768 1166512320 12.464 93 32 64 1 1178191152 3974560 7704272
20:32:39 5317 8192000000 10143527296 10542866432 1229510720 13.050 98 25 51 2 1240818096 4582864 6724512
20:33:46 10197 8192000000 10173185024 10580246528 1278355488 13.498 100 21 41 1 1288410032 4657392 5397152
20:35:02 14780 8192000000 10178776592 10597261312 1293677920 13.638 100 22 44 3 1304165520 5133056 5354544
20:36:25 24721 8192000000 10186869856 10607267840 1303126400 13.724 100 39 78 2 1318876160 6185120 9564640
20:37:53 15013 8192000000 10241811152 10643607552 1329928784 13.967 100 51 102 1 1347670416 6321616 11420016
20:39:14 8277 8192000000 10253935568 10665472000 1347759328 14.127 100 55 110 1 1370653040 10844032 12049680
20:40:28 9771 8192000000 10264521360 10679611392 1358618080 14.225 100 47 92 0 1378412672 9135024 10659568
20:41:42 40939 8192000000 10283692544 10708180992 1373574256 14.359 100 37 70 1 1394962384 13211344 8176784
20:43:37 20125 8192000000 10301996064 10727030784 1393015872 14.533 100 53 103 2 1423447760 17833296 12598592
20:45:11 26125 8192000000 10318187408 10751877120 1397468160 14.572 100 31 62 0 1433864736 27833824 8562752
20:46:42 17273 8192000000 10320639248 10751221760 1403932320 14.630 100 41 82 2 1437391408 23367008 10092080
20:48:17 5458 8192000000 10349008128 10770575360 1409695392 14.681 100 61 119 0 1452883392 28318128 14869872
20:49:25 4740 8192000000 10330535520 10753429504 1391535952 14.520 99 57 113 0 1436433648 31407472 13490224
20:50:38 10152 8192000000 10355457760 10777071616 1414831856 14.727 100 72 144 0 1463956464 31820576 17304032
20:51:52 4839 8192000000 10334889680 10749104128 1385510320 14.466 99 66 132 2 1440682960 38612608 16560032
20:53:02 2359 8192000000 10342594896 10754105344 1389568080 14.502 99 81 165 1 1447671088 37941472 20161536
20:54:08 3006 8192000000 10349246688 10759712768 1382104320 14.435 99 76 152 1 1443862992 42600272 19158400
20:55:15 2234 8192000000 10342749072 10757181440 1385680944 14.467 100 63 124 1 1447995856 45443328 16871584
20:56:21 4335 8192000000 10356916640 10770980864 1394214000 14.543 100 73 143 2 1460530912 48378160 17938752
20:57:29 9208 8192000000 10331732080 10753867776 1368567760 14.314 99 46 90 0 1429570448 48248368 12754320
20:58:49 17891 8192000000 10327449232 10757165056 1379620416 14.413 100 33 66 0 1438447472 49565328 9261728
21:00:20 3252 8192000000 10353077776 10777989120 1382448768 14.438 100 66 131 0 1457121888 57945328 16727792
21:01:25 26638 8192000000 10339378528 10769948672 1375093424 14.373 99 27 54 2 1421022896 38781152 7148320
21:02:57 42565 8192000000 10317795680 10764009472 1371948160 14.344 99 21 42 1 1423965376 46189120 5828096
21:04:46 38022 8192000000 10295429136 10752028672 1363380816 14.268 100 10 20 0 1403868384 37498768 2988800
21:06:29 24836 8192000000 10277019408 10711805952 1351918960 14.165 99 16 32 0 1369638576 13887568 3832048
21:07:55 32720 8192000000 10275638960 10713677824 1355311920 14.195 99 41 83 0 1385544784 20443664 9789200
21:09:34 66703 8192000000 10271602080 10700865536 1306421680 13.754 96 34 68 2 1323321904 8720736 8179488
21:11:44 88441 8192000000 10240599808 10685124608 1332984480 13.994 98 33 64 3 1347903680 6998832 7920368
21:14:14 68368 8192000000 10280387024 10728529920 1364704400 14.280 99 26 51 1 1381850896 10888640 6257856
21:16:30 25284 8192000000 10299440192 10728464384 1368302688 14.312 99 49 97 0 1392707744 12738848 11666208
21:18:01 70661 8192000000 10310440848 10755571712 1385997808 14.470 100 22 44 1 1406933488 15422608 5513072
21:20:14 76157 8192000000 10291232800 10755342336 1378300336 14.401 99 10 20 0 1392589312 11835968 2453008
21:22:30 46829 8192000000 10295164256 10749747200 1392597504 14.529 100 19 37 1 1405741808 8865808 4278496
21:24:17 61709 8192000000 10283567968 10740785152 1376410096 14.384 99 13 27 0 1386737440 7617664 2709680
21:26:27 44736 8192000000 10294630400 10755502080 1394127872 14.543 100 17 35 0 1409207680 10901136 4178672
21:28:18 59834 8192000000 10277731616 10747940864 1376384048 14.384 99 10 20 1 1388808704 9912704 2511952
21:30:25 55575 8192000000 10285138976 10753822720 1382048352 14.435 99 11 22 2 1392440864 8095456 2297056
21:32:51 54190 8192000000 10311057104 10770067456 1403906784 14.630 100 29 58 0 1425204960 14446624 6851552
21:35:11 36528 8192000000 10292975504 10745364480 1358247840 14.222 98 17 34 0 1373778832 11569072 3961920
21:36:48 82892 8192000000 10269380832 10731479040 1341742208 14.073 98 16 32 1 1363244224 18277200 3224816
21:39:11 89064 8192000000 10272096384 10736529408 1300831312 13.703 94 8 16 0 1321985856 19456576 1697968
21:41:40 65376 8192000000 10249267008 10695798784 1260007136 13.330 93 15 30 2 1274166352 11050688 3108528
21:44:32 115657 8192000000 10259650608 10705326080 1315222752 13.833 97 22 44 0 1330096784 10143456 4730576
21:47:29 77567 8192000000 10234164912 10692161536 1291402112 13.617 95 10 20 0 1303513744 9581952 2529680
21:50:41 25587 8192000000 10266409776 10718535680 1363573344 14.269 100 15 30 0 1374083328 7287248 3222736
21:52:07 54913 8192000000 10258496912 10716823552 1341961744 14.075 98 17 32 0 1352277664 6802032 3513888
21:54:02 75640 8192000000 10273830784 10725117952 1351279872 14.159 98 24 48 1 1367739552 10749872 5709808
21:56:19 65250 8192000000 10283185408 10740056064 1351618768 14.162 98 24 48 0 1374622544 17563152 5440624
21:58:25 75612 8192000000 10284794800 10742444032 1366384848 14.295 99 11 22 0 1379776128 11077728 2313552
22:00:52 49315 8192000000 10266293504 10714947584 1306961008 13.758 95 26 52 1 1320848480 7923776 5963696
22:02:47 47724 8192000000 10288545904 10734288896 1355196736 14.194 99 29 58 0 1373315120 11947696 6170688
22:04:36 40834 8192000000 10290184016 10737774592 1347864256 14.128 98 28 56 2 1369896768 15798640 6233872
22:06:17 47298 8192000000 10296609120 10745499648 1366192144 14.293 99 28 55 2 1381011808 8716528 6103136
22:08:08 98322 8192000000 10293720624 10757246976 1367446192 14.304 98 11 22 0 1379413488 9909504 2057792
22:10:50 154871 8192000000 10303465808 10755076096 1345869776 14.110 96 30 60 2 1361464384 8996000 6598608
22:14:27 38156 8192000000 10303985280 10765758464 1396313008 14.562 100 30 60 1 1415024672 12143520 6568144
22:16:10 52360 8192000000 10280778992 10753064960 1358212128 14.221 97 9 18 1 1375183504 14703520 2267856
22:18:03 37872 8192000000 10285407536 10745692160 1363345792 14.267 98 11 22 1 1378712928 12895184 2471952
22:19:44 80765 8192000000 10295637808 10769670144 1363428224 14.268 98 9 18 0 1386224928 20592512 2204192
22:22:05 78140 8192000000 10286579888 10753208320 1345176016 14.104 97 18 36 0 1366016368 16645872 4194480
22:24:35 50500 8192000000 10260450656 10709561344 1296497936 13.663 94 19 38 0 1307363056 6771264 4093856
22:26:30 47539 8192000000 10284460000 10727845888 1348408384 14.133 98 18 36 0 1365541264 12804096 4328784
22:28:24 94386 8192000000 10283676032 10731552768 1312815216 13.812 96 22 44 1 1332810224 15385248 4609760
22:31:01 81714 8192000000 10262251392 10711830528 1269492048 13.417 93 24 48 0 1286472608 11763376 5217184
22:33:24 124032 8192000000 10255910768 10696912896 1310893184 13.794 97 22 44 0 1328795824 12581424 5321216
22:36:34 58185 8192000000 10227511600 10676752384 1314111952 13.823 97 19 38 0 1327521408 8116144 5293312

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 10-11-2021 22:40:27 to 10-11-2021 22:40:42.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 90 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 10-11-2021 22:40:43 to 10-11-2021 22:40:45.

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          243411
MON$OLDEST_ACTIVE               244549
MON$OLDEST_SNAPSHOT             244549
MON$NEXT_TRANSACTION            244549
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                       2039392
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 ---
:100: 
:101: mscs-test-161.budzdorov.ru    Wed Nov 10 22:38:18 2021
:102:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:103: 
--- end of diff output ---

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

10.11.21 22:40:46. 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-10 22:40:48 No severe PSQL-related problems occured
10.11.21 22:40:49. End of report.