Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.08.2021 07:51
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 16196536 kB
MemAvailable 31089452 kB
Buffers 0 kB
Cached 15511748 kB
SwapCached 2580 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.1
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 2021-08-05 07:50:10
DTS_END                         2021-08-05 07:50:10.1640
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/42360
TRN_ID                          233853
ATT_ID                          645
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: 9350.33 22309 1122039
customer order: creation 172.83 415 20740
customer order: refuse 14.75 332 1770
order to supplier: creation 45.49 1463 5459
order to supplier: removal 3.40 118360 408
invoice (draft): creation 45.18 3308 5421
invoice (draft): removal 53.76 1007 6451
invoice accept: apply 41.64 20557 4997
invoice accept: cancel 48.10 853 5772
customer reserve: creation 3882.25 202 465870
customer reserve: removal 4747.42 65 569690
realization accept: apply 83.19 200 9983
realization accept: cancel 83.93 126 10072
payment from customer: creation 59.49 124 7139
payment from customer: removal 5.73 75 687
payment to supplier: creation 48.82 144 5858
payment to supplier: removal 3.39 114 407
service: total inventory turnovers 3.69 3797 443
service: total monetary turnovers 6.46 544 775
service: aggregate perf. data 0.78 16414 93
service: refresh index statistics 0.03 278076 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 05-08-2021 07:51:51 to 05-08-2021 07:51:53.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3662
TEST_TIME 2 5006
TEST_TIME 3 5296
TEST_TIME 4 5403
TEST_TIME 5 5279
TEST_TIME 6 5509
TEST_TIME 7 5698
TEST_TIME 8 6085
TEST_TIME 9 6254
TEST_TIME 10 6692
TEST_TIME 11 6806
TEST_TIME 12 7012
TEST_TIME 13 7332
TEST_TIME 14 7801
TEST_TIME 15 8137
TEST_TIME 16 8215
TEST_TIME 17 8461
TEST_TIME 18 8392
TEST_TIME 19 8848
TEST_TIME 20 9166
TEST_TIME 21 9275
TEST_TIME 22 9715
TEST_TIME 23 9908
TEST_TIME 24 10310
TEST_TIME 25 10642
TEST_TIME 26 11456
TEST_TIME 27 11712
TEST_TIME 28 11702
TEST_TIME 29 11616
TEST_TIME 30 11332
TEST_TIME 31 11000
TEST_TIME 32 10975
TEST_TIME 33 10842
TEST_TIME 34 10797
TEST_TIME 35 10751
TEST_TIME 36 10585
TEST_TIME 37 10503
TEST_TIME 38 10452
TEST_TIME 39 10452
TEST_TIME 40 10377
TEST_TIME 41 10279
TEST_TIME 42 10103
TEST_TIME 43 10054
TEST_TIME 44 10059
TEST_TIME 45 10326
TEST_TIME 46 10332
TEST_TIME 47 10264
TEST_TIME 48 10316
TEST_TIME 49 10338
TEST_TIME 50 10280
TEST_TIME 51 10317
TEST_TIME 52 10346
TEST_TIME 53 10287
TEST_TIME 54 10298
TEST_TIME 55 10367
TEST_TIME 56 10356
TEST_TIME 57 10336
TEST_TIME 58 10203
TEST_TIME 59 10181
TEST_TIME 60 10123
TEST_TIME 61 10057
TEST_TIME 62 10055
TEST_TIME 63 9932
TEST_TIME 64 9865
TEST_TIME 65 9820
TEST_TIME 66 9790
TEST_TIME 67 9822
TEST_TIME 68 9771
TEST_TIME 69 9781
TEST_TIME 70 9764
TEST_TIME 71 9748
TEST_TIME 72 9752
TEST_TIME 73 9849
TEST_TIME 74 9874
TEST_TIME 75 9864
TEST_TIME 76 9955
TEST_TIME 77 9909
TEST_TIME 78 9899
TEST_TIME 79 9818
TEST_TIME 80 9766
TEST_TIME 81 9691
TEST_TIME 82 9708
TEST_TIME 83 9685
TEST_TIME 84 9650
TEST_TIME 85 9602
TEST_TIME 86 9590
TEST_TIME 87 9608
TEST_TIME 88 9555
TEST_TIME 89 9538
TEST_TIME 90 9585
TEST_TIME 91 9606
TEST_TIME 92 9592
TEST_TIME 93 9656
TEST_TIME 94 9656
TEST_TIME 95 9615
TEST_TIME 96 9680
TEST_TIME 97 9659
TEST_TIME 98 9665
TEST_TIME 99 9621
TEST_TIME 100 9539
TEST_TIME 101 9524
TEST_TIME 102 9501
TEST_TIME 103 9453
TEST_TIME 104 9397
TEST_TIME 105 9328
TEST_TIME 106 9295
TEST_TIME 107 9242
TEST_TIME 108 9261
TEST_TIME 109 9311
TEST_TIME 110 9375
TEST_TIME 111 9369
TEST_TIME 112 9390
TEST_TIME 113 9386
TEST_TIME 114 9400
TEST_TIME 115 9404
TEST_TIME 116 9503
TEST_TIME 117 9506
TEST_TIME 118 9504
TEST_TIME 119 9430
TEST_TIME 120 9354

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 05-08-2021 07:51:54 to 05-08-2021 07:51:58.

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 1552268 1552268 0 0.00 0 24555 7 0 0
doc_list_biud 1552258 1552258 0 0.00 0 95654 31 0 0
sp_add_invoice_to_stock 5153 4997 156 3.02 35 195788 20557 0 156
sp_cancel_adding_invoice 5772 5772 0 0.00 5 57334 853 0 0
sp_cancel_client_order 1770 1770 0 0.00 5 9282 332 0 0
sp_cancel_customer_reserve 569690 569690 0 0.00 1 25765 66 0 0
sp_cancel_pay_from_customer 687 687 0 0.00 3 4477 75 0 0
sp_cancel_pay_to_supplier 407 407 0 0.00 3 8190 115 0 0
sp_cancel_supplier_invoice 6463 6451 12 0.18 3 95657 1008 0 12
sp_cancel_supplier_order 408 408 0 0.00 6 1213835 118361 0 0
sp_cancel_write_off 10072 10072 0 0.00 1 14611 127 0 0
sp_client_order 20740 20740 0 0.00 4 61990 416 0 0
sp_customer_reserve 465870 465870 0 0.00 0 84821 202 0 0
sp_fill_shopping_cart 31613 31613 0 0.00 1 20874 42 0 0
sp_fill_shopping_cart_clo_res 465861 465861 0 0.00 0 14293 16 0 0
sp_get_clo_for_invoice 4988 4988 0 0.00 0 16911 355 0 0
sp_kill_cost_storno 16938 16938 0 0.00 0 24538 61 0 0
sp_kill_qstorno_ret_qs2qd 1156311 1156311 0 0.00 0 95652 36 0 0
sp_kill_qty_storno 609134 609134 0 0.00 0 95652 77 0 0
sp_lock_selected_doc 20616 20616 0 0.00 0 9530 9 0 0
sp_make_cost_storno 27968 27968 0 0.00 0 10363 58 0 0
sp_multiply_rows_for_pdistr 27968 27968 0 0.00 0 7408 35 0 0
sp_multiply_rows_for_qdistr 20740 20740 0 0.00 0 59163 221 0 0
sp_pay_from_customer 7139 7139 0 0.00 4 14086 124 0 0
sp_pay_to_supplier 5858 5858 0 0.00 4 13474 144 0 0
sp_payment_common 12995 12995 0 0.00 1 8252 25 0 0
sp_qd_handle_on_cancel_clo 1770 1770 0 0.00 0 9271 296 0 0
sp_qd_handle_on_invoice_upd_sts 10760 10760 0 0.00 0 25027 354 0 0
sp_qd_handle_on_reserve_upd_sts 9983 9983 0 0.00 0 27085 59 0 0
sp_reserve_write_off 9983 9983 0 0.00 7 27096 200 0 0
sp_supplier_invoice 5421 5421 0 0.00 5 82517 3308 0 0
sp_supplier_order 5459 5459 0 0.00 5 34770 1464 0 0
srv_aggregate_perf_data 93 93 0 0.00 3053 308239 16415 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1200 179139 46889 0 0
srv_find_qd_qs_mism 1073659 1073659 0 0.00 0 20365 6 0 0
srv_make_invnt_saldo 443 443 0 0.00 50 92827 3798 0 0
srv_make_money_saldo 775 775 0 0.00 1 27974 544 0 0
srv_recalc_idx_stat 4 4 0 0.00 204596 380334 278077 0 0
t$perf-abend:sp_add_invoice_to_stock 156 156 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 4997 4997 0 0.00 0 19200 163 0 0
t$perf-norm:sp_cancel_adding_invoice 391 391 0 0.00 0 4419 110 0 0
t$perf-norm:sp_cancel_client_order 1770 1770 0 0.00 0 3484 15 0 0
t$perf-norm:sp_cancel_customer_reserve 643 643 0 0.00 0 1308 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 687 687 0 0.00 0 1394 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 407 407 0 0.00 0 238 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 369 369 0 0.00 0 2910 24 0 0
t$perf-norm:sp_cancel_supplier_order 408 408 0 0.00 0 26536 1629 0 0
t$perf-norm:sp_cancel_write_off 971 971 0 0.00 0 11658 25 0 0
t$perf-norm:sp_client_order 20740 20740 0 0.00 0 6026 17 0 0
t$perf-norm:sp_customer_reserve 986 986 0 0.00 0 2101 8 0 0
t$perf-norm:sp_pay_from_customer 7139 7139 0 0.00 0 7630 12 0 0
t$perf-norm:sp_pay_to_supplier 5858 5858 0 0.00 0 7621 12 0 0
t$perf-norm:sp_reserve_write_off 9983 9983 0 0.00 0 11643 11 0 0
t$perf-norm:sp_supplier_invoice 5421 5421 0 0.00 0 3936 15 0 0
t$perf-norm:sp_supplier_order 5459 5459 0 0.00 0 2630 14 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 181 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 100 6 0 0
t$perf-norm:srv_make_invnt_saldo 443 443 0 0.00 0 2346 21 0 0
t$perf-norm:srv_make_money_saldo 775 775 0 0.00 0 2513 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 3 0 0
v_add_invoice_to_stock 10288 9976 312 3.03 0 5247 19 0 312
v_all_customers 37778 37778 0 0.00 0 39 1 0 0
v_all_suppliers 30368 30368 0 0.00 0 18 1 0 0
v_all_wares 41480 41480 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 782 782 0 0.00 0 1309 9 0 0
v_cancel_client_order 3540 3540 0 0.00 0 2655 6 0 0
v_cancel_customer_prepayment 1374 1374 0 0.00 0 4106 8 0 0
v_cancel_customer_reserve 1286 1286 0 0.00 0 3174 10 0 0
v_cancel_payment_to_supplier 814 814 0 0.00 0 4786 18 0 0
v_cancel_supplier_invoice 762 738 24 3.14 0 2679 18 0 24
v_cancel_supplier_order 816 816 0 0.00 0 2361 9 0 0
v_cancel_write_off 1942 1942 0 0.00 0 3779 16 0 0
v_max_id_clo_ord 5459 5459 0 0.00 0 314 1 0 0
v_max_id_clo_res 986 986 0 0.00 0 2123 19 0 0
v_max_id_ord_sup 5414 5414 0 0.00 0 8933 8 0 0
v_max_non_paid_invoice 1547 1547 0 0.00 0 172 2 0 0
v_max_non_paid_realizn 3788 3788 0 0.00 0 6710 6 0 0
v_min_id_clo_ord 5459 5459 0 0.00 0 3208 7 0 0
v_min_id_clo_res 986 986 0 0.00 0 5760 14 0 0
v_min_id_ord_sup 5414 5414 0 0.00 0 8846 9 0 0
v_min_non_paid_invoice 5858 5858 0 0.00 0 6092 17 0 0
v_min_non_paid_realizn 7137 7137 0 0.00 0 5627 16 0 0
v_our_firm 10400 10400 0 0.00 0 21 1 0 0
v_reserve_write_off 19964 19964 0 0.00 0 5115 7 0 0
x_lock_depdocs_on_canc_invoice 11854 11854 0 0.00 0 13662 110 0 0
x_lock_depdocs_on_canc_sup_ord 408 408 0 0.00 0 9952 348 0 0
x_make_qstorno_1000_1200 5459 5459 0 0.00 2 34762 1383 0 0
x_make_qstorno_1200_2000 5421 5421 0 0.00 2 82369 3151 0 0
x_make_qstorno_2100_3300 465870 465870 0 0.00 0 84819 183 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-08-2021 07:51:59 to 05-08-2021 07:52:03.

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
05:20:13 1 8192000000 8796668944 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:21:14 31199 8192000000 9730036400 10028699648 801798976 8.915 100 89 178 0 831243776 7968272 21476528
05:22:48 24153 8192000000 9765133472 10070691840 834976960 9.249 100 89 178 0 870000832 13715248 21308624
05:24:16 18861 8192000000 9812195600 10128977920 864536000 9.545 100 85 170 0 907282160 23137840 19608320
05:25:40 9076 8192000000 9869278272 10203947008 918400880 10.080 100 83 167 1 976239424 39006800 18831744
05:26:52 7782 8192000000 9926984912 10271006720 956412496 10.454 100 76 147 1 1029683040 58016624 15253920
05:28:02 4310 8192000000 9950843536 10304856064 996388128 10.843 100 80 159 0 1063658176 50983280 16286768
05:29:07 3140 8192000000 9999669920 10361749504 1026727680 11.137 100 94 188 0 1118285920 72762640 18795600
05:30:12 2150 8192000000 10037727408 10408820736 1054676000 11.406 100 90 180 1 1144849904 71766656 18407248
05:31:15 1470 8192000000 10035111968 10408058880 1074314896 11.593 100 95 188 1 1142003744 47846144 19842704
05:32:17 5265 8192000000 9992080368 10379505664 1075472912 11.604 100 49 98 2 1097472400 11750208 10249280
05:33:23 3909 8192000000 9995734464 10391425024 1081613264 11.663 100 51 98 1 1111238800 18552784 11072752
05:34:28 6284 8192000000 9993674752 10392154112 1087223760 11.716 100 43 87 1 1115395296 19012016 9159520
05:35:35 4248 8192000000 10007143584 10412920832 1095340384 11.793 100 42 84 1 1128836736 24885072 8611280
05:36:42 49037 8192000000 10017565056 10414850048 1096844960 11.808 100 60 121 1 1121732448 11411184 13476304
05:38:32 6760 8192000000 10026553136 10429423616 1096467328 11.804 100 52 104 1 1132020208 24229888 11322992
05:39:41 7429 8192000000 10020146176 10419040256 1076057712 11.610 98 34 69 1 1111032816 27118960 7856144
05:40:50 6720 8192000000 9987796320 10391228416 1053167952 11.391 98 40 81 1 1088207376 26432896 8606528
05:41:58 31782 8192000000 9984015024 10355367936 1040275664 11.267 98 64 131 2 1068882352 15356000 13250688
05:43:30 4639 8192000000 9987902400 10387537920 1037848784 11.244 99 54 109 0 1095588736 46894640 10845312
05:44:35 9187 8192000000 9944959824 10341367808 1013871744 11.013 98 29 57 1 1039849632 19943776 6034112
05:45:46 13921 8192000000 9963350048 10362826752 1040021424 11.265 100 25 50 2 1062237792 16466176 5750192
05:47:01 68593 8192000000 9960565920 10375528448 1051149632 11.372 100 34 68 0 1079350544 21048112 7152800
05:49:10 4607 8192000000 9998948848 10385375232 1063656384 11.491 100 50 102 2 1106343824 31696256 10991184
05:50:17 46294 8192000000 9998296864 10396037120 1087635024 11.720 100 39 76 1 1113129392 17690592 7803776
05:52:16 8650 8192000000 10070999904 10468909056 1104473120 11.880 100 48 96 0 1166861168 52445472 9942576
05:53:26 51676 8192000000 10124020208 10536681472 1123457392 12.060 100 52 103 1 1211602736 78026080 10119264
05:55:33 16260 8192000000 10120826112 10535206912 1129053920 12.112 100 39 78 0 1221722960 84884656 7784384
05:56:52 7467 8192000000 10142348992 10557325312 1143536560 12.249 100 43 85 2 1251027424 98065136 9425728
05:58:03 3197 8192000000 10187626416 10589229056 1156184784 12.368 100 69 136 0 1290309712 119448400 14676528
05:59:11 3986 8192000000 10239804432 10648735744 1168520144 12.483 100 71 141 1 1355113472 171550864 15042464
06:00:18 7507 8192000000 10224574064 10642345984 1174155088 12.536 100 57 106 1 1338832528 152777456 11899984
06:01:34 3006 8192000000 10284873696 10696953856 1177954800 12.571 100 92 182 2 1389278608 190733232 20590576
06:02:41 1199 8192000000 10298871200 10711814144 1183743408 12.625 100 93 184 2 1410018352 205204016 21070928
06:03:46 5494 8192000000 10359719840 10784919552 1183322368 12.621 100 66 132 1 1455195264 257188912 14683984
06:04:58 3298 8192000000 10337531264 10738765824 1159802864 12.401 100 87 174 0 1423068912 243753792 19512256
06:06:07 3103 8192000000 10349027456 10770063360 1162748640 12.429 99 74 147 0 1438691168 259417664 16524864
06:07:14 2483 8192000000 10394826208 10800877568 1166424864 12.463 100 74 147 0 1492376640 308170800 17780976
06:08:22 2768 8192000000 10384409856 10796634112 1155556560 12.362 100 69 137 0 1478681456 306900128 16224768
06:09:31 1431 8192000000 10431760304 10849009664 1138449408 12.201 99 90 178 3 1519728608 359829296 21449904
06:10:40 7112 8192000000 10412576992 10841776128 1133543456 12.155 99 55 108 1 1505986496 358709792 13733248
06:11:53 8827 8192000000 10395242544 10826895360 1135084416 12.169 99 47 94 1 1492286848 345170400 12032032
06:13:27 14579 8192000000 10322304544 10774777856 1129233552 12.114 100 27 54 1 1432650752 296047104 7370096
06:14:46 30525 8192000000 10275149472 10743271424 1108619712 11.919 99 21 42 1 1368655520 254332736 5703072
06:16:40 35224 8192000000 10080363744 10594426880 1092368080 11.765 98 13 26 1 1233644544 137614736 3661728
06:18:20 49229 8192000000 10062490528 10518511616 1084342128 11.689 97 16 32 1 1121195312 33769456 3083728
06:20:13 115221 8192000000 10078553408 10513338368 1106792176 11.902 98 28 55 0 1137299280 23890256 6616848
06:23:09 51090 8192000000 10071564592 10508042240 1106265088 11.897 98 18 36 0 1179143120 68857456 4020576
06:25:25 58694 8192000000 10113059680 10560647168 1128789808 12.110 99 37 74 2 1190621472 54139984 7691680
06:27:43 16550 8192000000 10134109312 10584301568 1130113632 12.122 100 52 103 1 1235455616 93774080 11567904
06:29:05 179138 8192000000 10198122592 10658590720 1112564208 11.957 97 25 51 0 1237822672 119715808 5542656
06:33:31 21713 8192000000 10229526144 10674040832 1141397760 12.229 99 26 52 0 1321821888 174405440 6018688
06:34:55 57043 8192000000 10126188736 10656874496 1145035664 12.263 100 14 28 1 1288584080 140142608 3405808
06:36:53 60523 8192000000 10138613136 10582335488 1155418880 12.360 100 13 26 1 1229766048 71215712 3131456
06:39:05 49621 8192000000 10078232464 10525876224 1120723648 12.034 97 30 60 0 1178865088 52446208 5695232
06:40:56 66186 8192000000 10120331920 10602979328 1126605792 12.089 98 28 56 0 1194450000 62178944 5665264
06:43:03 48428 8192000000 10086800384 10528223232 1123797168 12.063 98 31 62 0 1167038768 36872288 6369312
06:44:52 39922 8192000000 10114094768 10602991616 1133882384 12.158 99 24 48 1 1226016944 86971360 5163200
06:46:34 49275 8192000000 10110037888 10554597376 1132760848 12.147 99 18 36 0 1185579200 49561472 3256880
06:48:26 137243 8192000000 10073195728 10581327872 1122104896 12.047 98 23 46 1 1216472048 89562208 4804944
06:51:50 75191 8192000000 10066562576 10565935104 1129504800 12.117 99 14 28 0 1192207280 59398896 3303584
06:54:18 35981 8192000000 10081196448 10557689856 1142662624 12.241 100 10 20 2 1171638960 27463472 1512864
06:56:00 58370 8192000000 10058439168 10512117760 1086008640 11.705 95 17 34 0 1133084432 43076112 3999680
06:57:58 35914 8192000000 10046414608 10482405376 1092746400 11.769 97 22 44 0 1132457440 35040064 4670976
06:59:38 66581 8192000000 10105888800 10543292416 1105490720 11.890 97 29 57 1 1164784928 53168368 6125840
07:01:54 74388 8192000000 10127543216 10564665344 1089942304 11.742 97 18 36 1 1230519360 136019888 4557168
07:04:14 42512 8192000000 10077517520 10545770496 1090583600 11.748 97 34 68 0 1151580304 54375296 6621408
07:06:04 25248 8192000000 10097472064 10591215616 1091528656 11.757 99 44 88 0 1190786224 90658992 8598576
07:07:40 18440 8192000000 10050327264 10478678016 1102129408 11.858 99 48 96 0 1133109952 21499376 9481168
07:09:17 130282 8192000000 10043533520 10483048448 1039307152 11.258 93 29 58 1 1080462192 34946160 6208880
07:12:42 48575 8192000000 10059131584 10543349760 1108232016 11.916 100 25 50 1 1184656496 71440752 4983728
07:14:38 56498 8192000000 10055210000 10539884544 1107887312 11.912 99 13 26 0 1161239200 50120128 3231760
07:17:04 55455 8192000000 10050573792 10502168576 1103354080 11.869 98 29 58 0 1151597824 41440928 6802816
07:19:02 41103 8192000000 10098168288 10553708544 1110423632 11.936 99 19 38 0 1197092960 82795680 3873648
07:20:44 61766 8192000000 10105025904 10546315264 1132899616 12.149 100 25 50 0 1201788128 63458816 5429696
07:23:04 61180 8192000000 10080689904 10528067584 1101769744 11.854 97 24 48 2 1154687408 48100384 4817280
07:25:08 48274 8192000000 10119632592 10557919232 1132416640 12.144 99 35 70 1 1210099264 70781920 6900704
07:26:58 43934 8192000000 10122578944 10566037504 1145745136 12.270 100 32 64 0 1202643952 50477696 6421120
07:28:52 125919 8192000000 10059065584 10501283840 1126454832 12.088 99 17 34 0 1175703216 45620816 3627568
07:32:03 41687 8192000000 10049721504 10503258112 1123701152 12.062 99 17 34 2 1162723376 35773840 3248384
07:33:53 67684 8192000000 10091253328 10535903232 1100614736 11.843 96 15 30 0 1145369408 41586080 3168592
07:36:09 101009 8192000000 10194702656 10654605312 1135972112 12.178 99 18 36 1 1290330752 150031376 4327264
07:38:50 72826 8192000000 10160101648 10637750272 1142381408 12.238 100 18 36 0 1285769216 138933920 4453888
07:41:29 61911 8192000000 10140346544 10624880640 1133352944 12.153 98 24 48 0 1206293104 67468624 5471536
07:43:32 37977 8192000000 10134603712 10575122432 1143040608 12.244 99 26 52 1 1226211440 77531904 5638928
07:46:02 38639 8192000000 10064611920 10611269632 1099910272 11.837 97 6 12 0 1178165184 76810080 1444832
07:47:41 162625 8192000000 9071038432 9303179264 222861856 2.648 20 4 5 1 231853104 8345728 645520

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 05-08-2021 07:52:05 to 05-08-2021 07:52:20.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 156 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:01, from 05-08-2021 07:52:21 to 05-08-2021 07:52:22.

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          234159
MON$OLDEST_ACTIVE               234160
MON$OLDEST_SNAPSHOT             234160
MON$NEXT_TRANSACTION            234160
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                       2049312
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                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             799
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2558 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2558 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2558 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2558 Firebird 4.0/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 ---
--- end of diff output ---

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

05.08.21 07:52:23. 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
2021-08-05 07:52:26 No severe PSQL-related problems occured
05.08.21 07:52:27. End of report.