Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.12.2020 13:27
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID e0e7b46250ba4f9795ceb55e0face73f
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 2020-09-25 13:42
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.00
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 16676160 kB
MemAvailable 31475440 kB
Buffers 0 kB
Cached 14889844 kB
SwapCached 1980 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.0
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2020-12-07 13:26:51
DTS_END                         2020-12-07 13:26:51.2450
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/53116
TRN_ID                          232238
ATT_ID                          647
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: 9427.34 28293 1131281
customer order: creation 162.18 450 19462
customer order: refuse 13.83 275 1660
order to supplier: creation 43.51 1324 5221
order to supplier: removal 3.68 120658 442
invoice (draft): creation 42.73 3440 5128
invoice (draft): removal 56.73 879 6807
invoice accept: apply 38.59 16735 4631
invoice accept: cancel 51.26 788 6151
customer reserve: creation 3658.15 159 438978
customer reserve: removal 5071.87 71 608624
realization accept: apply 78.19 170 9383
realization accept: cancel 85.13 118 10215
payment from customer: creation 56.00 110 6720
payment from customer: removal 5.30 93 636
payment to supplier: creation 46.42 110 5570
payment to supplier: removal 3.30 93 396
service: total inventory turnovers 3.80 2912 456
service: total monetary turnovers 5.74 477 689
service: aggregate perf. data 0.91 13704 109
service: refresh index statistics 0.03 403287 3

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-12-2020 13:27:31 to 07-12-2020 13:27:33.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1914
TEST_TIME 2 2942
TEST_TIME 3 3240
TEST_TIME 4 3256
TEST_TIME 5 3462
TEST_TIME 6 3853
TEST_TIME 7 4330
TEST_TIME 8 4617
TEST_TIME 9 5138
TEST_TIME 10 5517
TEST_TIME 11 5642
TEST_TIME 12 5748
TEST_TIME 13 6033
TEST_TIME 14 6332
TEST_TIME 15 6787
TEST_TIME 16 7015
TEST_TIME 17 7473
TEST_TIME 18 7799
TEST_TIME 19 8018
TEST_TIME 20 8238
TEST_TIME 21 8616
TEST_TIME 22 9701
TEST_TIME 23 9431
TEST_TIME 24 9476
TEST_TIME 25 10579
TEST_TIME 26 11767
TEST_TIME 27 11749
TEST_TIME 28 11491
TEST_TIME 29 11365
TEST_TIME 30 11151
TEST_TIME 31 10907
TEST_TIME 32 10837
TEST_TIME 33 10663
TEST_TIME 34 10780
TEST_TIME 35 10603
TEST_TIME 36 10400
TEST_TIME 37 10368
TEST_TIME 38 10472
TEST_TIME 39 10373
TEST_TIME 40 10328
TEST_TIME 41 10359
TEST_TIME 42 10365
TEST_TIME 43 10339
TEST_TIME 44 10274
TEST_TIME 45 10206
TEST_TIME 46 10278
TEST_TIME 47 10283
TEST_TIME 48 10176
TEST_TIME 49 10281
TEST_TIME 50 10417
TEST_TIME 51 10439
TEST_TIME 52 10241
TEST_TIME 53 10134
TEST_TIME 54 10025
TEST_TIME 55 9956
TEST_TIME 56 9908
TEST_TIME 57 9855
TEST_TIME 58 9869
TEST_TIME 59 9884
TEST_TIME 60 9838
TEST_TIME 61 9772
TEST_TIME 62 9822
TEST_TIME 63 9779
TEST_TIME 64 9909
TEST_TIME 65 9927
TEST_TIME 66 9999
TEST_TIME 67 9925
TEST_TIME 68 10009
TEST_TIME 69 10004
TEST_TIME 70 9925
TEST_TIME 71 9802
TEST_TIME 72 9729
TEST_TIME 73 9636
TEST_TIME 74 9705
TEST_TIME 75 9686
TEST_TIME 76 9622
TEST_TIME 77 9570
TEST_TIME 78 9576
TEST_TIME 79 9569
TEST_TIME 80 9620
TEST_TIME 81 9646
TEST_TIME 82 9608
TEST_TIME 83 9579
TEST_TIME 84 9669
TEST_TIME 85 9758
TEST_TIME 86 9768
TEST_TIME 87 9712
TEST_TIME 88 9625
TEST_TIME 89 9553
TEST_TIME 90 9512
TEST_TIME 91 9466
TEST_TIME 92 9485
TEST_TIME 93 9444
TEST_TIME 94 9416
TEST_TIME 95 9396
TEST_TIME 96 9394
TEST_TIME 97 9438
TEST_TIME 98 9586
TEST_TIME 99 9564
TEST_TIME 100 9545
TEST_TIME 101 9571
TEST_TIME 102 9592
TEST_TIME 103 9589
TEST_TIME 104 9572
TEST_TIME 105 9545
TEST_TIME 106 9466
TEST_TIME 107 9413
TEST_TIME 108 9365
TEST_TIME 109 9342
TEST_TIME 110 9372
TEST_TIME 111 9347
TEST_TIME 112 9336
TEST_TIME 113 9321
TEST_TIME 114 9289
TEST_TIME 115 9275
TEST_TIME 116 9321
TEST_TIME 117 9304
TEST_TIME 118 9412
TEST_TIME 119 9440
TEST_TIME 120 9429

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 07-12-2020 13:27:34 to 07-12-2020 13:27:38.

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 1538663 1538663 0 0.00 0 26700 6 0 0
doc_list_biud 1538663 1538663 0 0.00 0 91203 34 0 0
sp_add_invoice_to_stock 4739 4631 108 2.27 20 167014 16735 0 108
sp_cancel_adding_invoice 6151 6151 0 0.00 5 55233 788 0 0
sp_cancel_client_order 1660 1660 0 0.00 4 7290 276 0 0
sp_cancel_customer_reserve 608624 608624 0 0.00 1 29132 71 0 0
sp_cancel_pay_from_customer 636 636 0 0.00 3 9511 94 0 0
sp_cancel_pay_to_supplier 396 396 0 0.00 3 7990 93 0 0
sp_cancel_supplier_invoice 6812 6807 5 0.07 2 91285 879 0 5
sp_cancel_supplier_order 442 442 0 0.00 9 1230101 120659 0 0
sp_cancel_write_off 10215 10215 0 0.00 1 9383 119 0 0
sp_client_order 19462 19462 0 0.00 4 70750 451 0 0
sp_customer_reserve 438978 438978 0 0.00 0 38529 160 0 0
sp_fill_shopping_cart 29796 29796 0 0.00 1 16651 47 0 0
sp_fill_shopping_cart_clo_res 438977 438977 0 0.00 0 27928 20 0 0
sp_get_clo_for_invoice 4630 4630 0 0.00 0 20745 460 0 0
sp_kill_cost_storno 17398 17398 0 0.00 0 9381 58 0 0
sp_kill_qstorno_ret_qs2qd 1234712 1234712 0 0.00 0 91202 38 0 0
sp_kill_qty_storno 647912 647912 0 0.00 0 91203 81 0 0
sp_lock_selected_doc 19486 19486 0 0.00 0 5897 4 0 0
sp_make_cost_storno 26303 26303 0 0.00 0 15519 56 0 0
sp_multiply_rows_for_pdistr 26303 26303 0 0.00 0 8496 32 0 0
sp_multiply_rows_for_qdistr 19462 19462 0 0.00 0 67039 299 0 0
sp_pay_from_customer 6720 6720 0 0.00 4 19767 110 0 0
sp_pay_to_supplier 5570 5570 0 0.00 5 11859 110 0 0
sp_payment_common 12290 12290 0 0.00 1 8126 20 0 0
sp_qd_handle_on_cancel_clo 1660 1660 0 0.00 0 7284 255 0 0
sp_qd_handle_on_invoice_upd_sts 10781 10781 0 0.00 0 37820 362 0 0
sp_qd_handle_on_reserve_upd_sts 9383 9383 0 0.00 0 17306 40 0 0
sp_reserve_write_off 9383 9383 0 0.00 7 18691 171 0 0
sp_supplier_invoice 5128 5128 0 0.00 8 107059 3441 0 0
sp_supplier_order 5221 5221 0 0.00 5 25878 1324 0 0
srv_aggregate_perf_data 109 109 0 0.00 2798 111354 13705 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1148 154069 44521 0 0
srv_find_qd_qs_mism 1086634 1086634 0 0.00 0 26699 5 0 0
srv_make_invnt_saldo 456 456 0 0.00 58 98278 2913 0 0
srv_make_money_saldo 689 689 0 0.00 1 13304 477 0 0
srv_recalc_idx_stat 3 3 0 0.00 335846 518043 403287 0 0
t$perf-abend:sp_add_invoice_to_stock 108 108 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4631 4631 0 0.00 0 26260 153 0 0
t$perf-norm:sp_cancel_adding_invoice 385 385 0 0.00 0 11434 119 0 0
t$perf-norm:sp_cancel_client_order 1660 1660 0 0.00 0 4208 10 0 0
t$perf-norm:sp_cancel_customer_reserve 667 667 0 0.00 0 4354 25 0 0
t$perf-norm:sp_cancel_pay_from_customer 636 636 0 0.00 0 6290 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 396 396 0 0.00 0 161 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 378 378 0 0.00 0 967 14 0 0
t$perf-norm:sp_cancel_supplier_order 442 442 0 0.00 0 26803 1407 0 0
t$perf-norm:sp_cancel_write_off 909 909 0 0.00 0 1240 6 0 0
t$perf-norm:sp_client_order 19462 19462 0 0.00 0 8462 15 0 0
t$perf-norm:sp_customer_reserve 1014 1014 0 0.00 0 5718 11 0 0
t$perf-norm:sp_pay_from_customer 6720 6720 0 0.00 0 4521 8 0 0
t$perf-norm:sp_pay_to_supplier 5570 5570 0 0.00 0 3189 7 0 0
t$perf-norm:sp_reserve_write_off 9383 9383 0 0.00 0 8239 9 0 0
t$perf-norm:sp_supplier_invoice 5128 5128 0 0.00 0 8251 16 0 0
t$perf-norm:sp_supplier_order 5221 5221 0 0.00 0 5882 12 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 310 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 756 12 0 0
t$perf-norm:srv_make_invnt_saldo 456 456 0 0.00 0 6723 20 0 0
t$perf-norm:srv_make_money_saldo 689 689 0 0.00 0 1156 5 0 0
t$perf-norm:srv_recalc_idx_stat 3 3 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 9476 9260 216 2.27 0 3993 15 0 216
v_all_customers 35798 35798 0 0.00 0 49 1 0 0
v_all_suppliers 29166 29166 0 0.00 0 58 1 0 0
v_all_wares 38924 38924 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 770 770 0 0.00 0 152 5 0 0
v_cancel_client_order 3320 3320 0 0.00 0 3270 5 0 0
v_cancel_customer_prepayment 1272 1272 0 0.00 0 4507 12 0 0
v_cancel_customer_reserve 1334 1334 0 0.00 0 3294 8 0 0
v_cancel_payment_to_supplier 792 792 0 0.00 0 447 4 0 0
v_cancel_supplier_invoice 766 756 10 1.30 0 1920 18 0 10
v_cancel_supplier_order 884 884 0 0.00 0 509 3 0 0
v_cancel_write_off 1818 1818 0 0.00 0 2002 12 0 0
v_max_id_clo_ord 5221 5221 0 0.00 0 3859 2 0 0
v_max_id_clo_res 1014 1014 0 0.00 0 4723 26 0 0
v_max_id_ord_sup 5113 5113 0 0.00 0 4407 6 0 0
v_max_non_paid_invoice 1321 1321 0 0.00 0 125 2 0 0
v_max_non_paid_realizn 3461 3461 0 0.00 0 2799 4 0 0
v_min_id_clo_ord 5221 5221 0 0.00 0 7261 9 0 0
v_min_id_clo_res 1014 1014 0 0.00 0 6743 14 0 0
v_min_id_ord_sup 5113 5113 0 0.00 0 8526 16 0 0
v_min_non_paid_invoice 5570 5570 0 0.00 0 5872 13 0 0
v_min_non_paid_realizn 6720 6720 0 0.00 0 5355 14 0 0
v_our_firm 9644 9644 0 0.00 0 14 1 0 0
v_reserve_write_off 18766 18766 0 0.00 0 3275 4 0 0
x_lock_depdocs_on_canc_invoice 12580 12580 0 0.00 0 32451 91 0 0
x_lock_depdocs_on_canc_sup_ord 442 442 0 0.00 0 9359 282 0 0
x_make_qstorno_1000_1200 5221 5221 0 0.00 2 25861 1247 0 0
x_make_qstorno_1200_2000 5128 5128 0 0.00 4 106907 3256 0 0
x_make_qstorno_2100_3300 438978 438978 0 0.00 0 38528 138 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-12-2020 13:27:39 to 07-12-2020 13:27:43.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
10:56:54 9 8192000000 8796641392 8807108608 3168704 0.038 1 1 2 0 3266400 61840 35856
10:57:55 26157 8192000000 9709360000 10000908288 786047344 8.755 100 90 180 0 815769808 7559232 22163232
10:59:34 17905 8192000000 9751390688 10056065024 827477744 9.174 100 85 170 0 859337696 11366448 20493504
11:01:02 17520 8192000000 9814500848 10131599360 877941984 9.679 100 90 181 0 924701152 25927296 20831872
11:02:25 11864 8192000000 9888239136 10222858240 929269424 10.187 100 82 164 1 991972048 45324576 17378048
11:03:39 7067 8192000000 9920316192 10265726976 967005184 10.557 100 76 152 0 1041533360 58818816 15709360
11:04:48 4085 8192000000 9974734000 10330079232 1006345744 10.940 100 84 166 2 1087795616 65044832 16405040
11:05:55 8101 8192000000 10011576608 10375323648 1026388800 11.134 100 79 154 2 1118975248 77304896 15281552
11:07:05 2352 8192000000 10039793152 10408595456 1052544032 11.385 100 94 188 1 1160709296 89357072 18808192
11:08:09 3706 8192000000 10024291664 10414485504 1067288720 11.526 100 58 115 0 1147963568 69119024 11555824
11:09:14 7121 8192000000 9980477328 10364534784 1068430064 11.537 100 61 120 2 1089606016 8083248 13092704
11:10:22 7985 8192000000 10013383920 10413690880 1076912992 11.618 100 41 82 2 1112689648 27525520 8251136
11:11:30 5544 8192000000 9994374368 10391519232 1080543792 11.653 100 38 77 1 1117816496 28539648 8733056
11:12:37 3422 8192000000 9994383536 10383527936 1082247744 11.669 100 32 64 3 1108490400 19138128 7104528
11:13:41 45498 8192000000 10004811808 10403127296 1087896592 11.723 100 57 115 3 1124372560 24537376 11938592
11:15:27 7424 8192000000 10010655280 10415603712 1090227056 11.745 100 27 54 4 1114242656 18059056 5956544
11:16:37 8602 8192000000 10007360368 10421686272 1080056608 11.648 99 24 48 4 1109910944 24667600 5186736
11:17:45 10693 8192000000 9966668464 10372329472 1036997888 11.236 97 28 56 0 1072087296 29775136 5314272
11:19:00 23732 8192000000 9961594784 10331705344 1023479328 11.106 98 70 142 3 1055109312 15511632 16118352
11:20:24 9030 8192000000 9931030768 10330963968 1010894832 10.984 98 42 85 1 1040882416 20421392 9566192
11:21:36 5591 8192000000 9918693520 10296860672 997949856 10.859 98 45 91 1 1018536288 10267552 10318880
11:22:42 4484 8192000000 9958166368 10349215744 1039398640 11.259 100 37 72 1 1070937168 23485952 8052576
11:23:48 19768 8192000000 9975524336 10378547200 1049896560 11.360 100 26 52 0 1088670896 33155984 5618352
11:25:09 7512 8192000000 9966424928 10369359872 1059309920 11.450 100 42 84 2 1081649920 12907056 9432944
11:26:21 5653 8192000000 9996374800 10397638656 1081393840 11.661 100 45 90 1 1110228416 19210544 9624032
11:27:35 12789 8192000000 10012549824 10410512384 1099378224 11.832 100 52 100 5 1122686016 11357456 11950336
11:29:01 33286 8192000000 10048245600 10455384064 1100320176 11.841 99 38 74 1 1164869952 55924608 8625168
11:30:45 36640 8192000000 10116160544 10527641600 1132857696 12.148 100 38 76 0 1222379840 81589408 7932736
11:32:27 8334 8192000000 10166681200 10571755520 1149721872 12.307 100 49 98 0 1274991968 114449616 10820480
11:33:41 7422 8192000000 10200513664 10608361472 1166029344 12.460 100 52 104 1 1301995264 124033936 11931984
11:34:52 3593 8192000000 10242916416 10646855680 1144639056 12.259 97 71 140 1 1334646992 174405248 15602688
11:35:58 2664 8192000000 10272694320 10681356288 1171645824 12.512 100 65 128 0 1382467680 195759520 15062336
11:37:06 3653 8192000000 10309286896 10714497024 1173360960 12.528 100 67 135 0 1410418704 221779088 15278656
11:38:13 3188 8192000000 10332437056 10735026176 1169466752 12.492 99 68 135 1 1437307280 251986208 15854320
11:39:20 3352 8192000000 10385814224 10793586688 1178646464 12.578 100 77 154 0 1494836320 298104512 18085344
11:40:30 2192 8192000000 10444573280 10860068864 1180894352 12.599 100 92 185 2 1542492304 339308112 22289840
11:41:38 1141 8192000000 10440997280 10862784512 1177540144 12.567 100 70 139 0 1545939488 352238752 16160592
11:42:44 8232 8192000000 10440691616 10866761728 1144878080 12.261 99 57 110 2 1552436000 393880816 13677104
11:43:54 3124 8192000000 10459365984 10869600256 1145417872 12.266 99 58 114 2 1547373264 387813728 14141664
11:45:00 5375 8192000000 10452422928 10898444288 1147701552 12.288 100 47 94 0 1576416256 416633904 12080800
11:46:11 15713 8192000000 10474965056 10899169280 1147711424 12.288 100 46 92 1 1572874560 413045488 12117648
11:47:38 8506 8192000000 10482276496 10943946752 1162937104 12.431 100 39 76 2 1583535312 410305712 10292496
11:48:47 10883 8192000000 10401242928 10987929600 1162565808 12.427 100 27 54 1 1523058928 353406720 7086400
11:50:15 15097 8192000000 10475529056 10935283712 1125527376 12.079 98 28 56 1 1555637776 422148928 7961472
11:51:49 36594 8192000000 10126806016 10723237888 1106346880 11.898 98 16 32 3 1368039888 257787264 3905744
11:53:30 52077 8192000000 10032205904 10470604800 1040308560 11.268 93 22 44 2 1072816912 28672752 3835600
11:55:23 72324 8192000000 10045404560 10493366272 1113161776 11.962 100 46 90 1 1166712848 43865808 9685264
11:58:13 55060 8192000000 10092116208 10559033344 1121743344 12.043 100 19 38 2 1175868416 50161040 3964032
12:00:13 48691 8192000000 10074883008 10577977344 1104742720 11.883 98 11 22 0 1203700704 96228704 2729280
12:02:02 52050 8192000000 10115824560 10565046272 1120067744 12.028 99 17 34 0 1204414384 80702368 3644272
12:04:02 47272 8192000000 10071766576 10562555904 1098266000 11.821 98 10 20 0 1185919904 85576432 2077472
12:05:51 35731 8192000000 10077603264 10540412928 1112520000 11.956 99 48 91 1 1159524624 36304704 10699920
12:07:33 38819 8192000000 10063222640 10498256896 1110127456 11.934 98 23 46 2 1154070112 38963456 4979200
12:09:13 66963 8192000000 10077634416 10545586176 1135396496 12.172 100 36 73 3 1178807824 35789248 7622080
12:11:20 34248 8192000000 10085071344 10526711808 1098899232 11.827 97 21 42 2 1170156224 67038672 4218320
12:12:55 78769 8192000000 10096939040 10521948160 1133642688 12.156 100 23 46 0 1204364384 65579280 5142416
12:15:15 70279 8192000000 10208125568 10718425088 1131175904 12.132 99 10 20 0 1291342240 157639344 2526992
12:17:40 154069 8192000000 10074782416 10521391104 1120757376 12.034 98 25 51 1 1156137408 30199312 5180720
12:23:12 83557 8192000000 10071359584 10548461568 1107830528 11.912 97 17 34 1 1169134864 57604704 3699632
12:25:41 49497 8192000000 10069484352 10498883584 1088921024 11.732 95 28 55 0 1123887072 29197936 5768112
12:27:35 51584 8192000000 10141389312 10602422272 1127910624 12.102 98 24 48 2 1208352448 75434640 5007184
12:29:30 90723 8192000000 10067929536 10507313152 1070179632 11.554 94 11 22 1 1154383632 82021248 2182752
12:32:01 63050 8192000000 10048112272 10507259904 1091020352 11.752 98 10 20 2 1135881456 43184720 1676384
12:34:04 56379 8192000000 10102029840 10591125504 1085316096 11.698 96 26 52 0 1171079088 80564400 5198592
12:36:04 142056 8192000000 10086531744 10538946560 1108113456 11.915 98 30 61 3 1178770000 63715072 6941472
12:39:27 49721 8192000000 10116541328 10568425472 1111967744 11.951 99 12 24 1 1215389648 100538880 2883024
12:41:16 20680 8192000000 10059972336 10598961152 1096436400 11.804 98 5 10 0 1144005584 46508992 1060192
12:42:37 61677 8192000000 10055512288 10504024064 1089127680 11.734 97 21 38 1 1138367296 45011456 4228160
12:44:39 74785 8192000000 10156736224 10635317248 1135334016 12.172 100 28 56 3 1255278784 113791280 6153488
12:47:04 44551 8192000000 10073644080 10507804672 1114666608 11.977 99 14 27 0 1163189840 45789504 2733728
12:49:00 43259 8192000000 10111576080 10558095360 1114671968 11.977 99 22 43 1 1203908784 84476688 4760128
12:50:44 37517 8192000000 10116611280 10571886592 1101114128 11.848 98 34 68 0 1187623984 79100144 7409712
12:52:22 57017 8192000000 10057639696 10475368448 1079302080 11.641 96 33 66 0 1110046864 23914032 6830752
12:54:20 102244 8192000000 10067991840 10524127232 1117039184 11.999 99 14 28 0 1162776400 42797648 2939568
12:57:14 39388 8192000000 10089749568 10583932928 1119451200 12.022 99 6 12 0 1191943008 71459568 1032240
12:58:54 54998 8192000000 10085386384 10535956480 1133329088 12.153 100 11 23 1 1181616176 46237632 2049456
13:00:49 48658 8192000000 10128514176 10566225920 1098253152 11.821 96 23 46 1 1178049488 74925120 4871216
13:02:42 71102 8192000000 10215008704 10704592896 1146276384 12.275 100 28 56 0 1344929952 192082416 6571152
13:05:17 63813 8192000000 10077298928 10524119040 1089250224 11.736 95 11 22 2 1126875008 35095472 2529312
13:07:25 31327 8192000000 10111055808 10573316096 1130514752 12.126 99 29 58 1 1214248896 77900016 5834128
13:09:03 12400 8192000000 10087498848 10505048064 1126854288 12.092 99 62 123 1 1169233680 29178592 13200800
13:10:18 22944 8192000000 10080409936 10516004864 1120402672 12.031 98 34 67 1 1167458928 40705488 6350768
13:11:41 89260 8192000000 10085123008 10542931968 1142274592 12.237 100 16 32 0 1186083584 40620704 3188288
13:14:41 53739 8192000000 10078015744 10547527680 1090736768 11.750 96 4 8 0 1156746896 65068576 941552
13:16:36 41850 8192000000 10070784592 10531299328 1133088112 12.150 100 12 24 1 1184982560 49225184 2669264
13:18:24 66669 8192000000 10076212736 10539368448 1086271824 11.707 96 23 46 2 1153574992 62255376 5047792
13:20:34 36441 8192000000 10119708464 10570596352 1098303040 11.822 97 28 56 1 1201957632 96917488 6737104
13:22:20 69250 8192000000 10143008848 10623868928 1135911712 12.177 100 15 30 2 1244018352 104832960 3273680
13:24:29 90977 8192000000 10108263104 10630381568 1126746272 12.091 99 8 16 1 1222057664 93569712 1741680

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 07-12-2020 13:27:45 to 07-12-2020 13:28:00.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 07-12-2020 13:28:01 to 07-12-2020 13:28:03.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          231201
MON$OLDEST_ACTIVE               232349
MON$OLDEST_SNAPSHOT             232349
MON$NEXT_TRANSACTION            232349
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-30 07:30:08.6030 GMT
MON$PAGES                       2117824
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             796
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2290 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2290 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2290 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2290 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:108: 
:109: mscs-test-161.budzdorov.ru    Mon Dec  7 13:26:52 2020
:110:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:111: 
:112: 
:113: mscs-test-161.budzdorov.ru    Mon Dec  7 13:26:54 2020
:114:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:115: 
:116: 
:117: mscs-test-161.budzdorov.ru    Mon Dec  7 13:26:59 2020
:118:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:119: 
--- end of diff output ---

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

07.12.20 13:28:04. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2020-12-07 13:28:06 No severe PSQL-related problems occured
07.12.20 13:28:07. End of report.