Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.03.2021 10:29
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 df9f488d08424df294c459d6c283e9c3
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-03-04 16:07
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.26
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 16932372 kB
MemAvailable 31586964 kB
Buffers 0 kB
Cached 14760120 kB
SwapCached 0 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 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 2021-03-18 10:27:00
DTS_END                         2021-03-18 10:27:00.3480
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_MAKE_MONEY_SALDO(33:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/46454
TRN_ID                          230578
ATT_ID                          683
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: 9231.47 28576 1107776
customer order: creation 157.07 447 18849
customer order: refuse 14.27 320 1712
order to supplier: creation 43.02 1397 5162
order to supplier: removal 3.49 119201 419
invoice (draft): creation 43.76 3822 5251
invoice (draft): removal 53.74 930 6449
invoice accept: apply 39.46 18497 4735
invoice accept: cancel 48.38 863 5805
customer reserve: creation 3759.71 176 451165
customer reserve: removal 4791.98 68 575037
realization accept: apply 78.52 186 9422
realization accept: cancel 77.32 127 9278
payment from customer: creation 55.65 110 6678
payment from customer: removal 4.92 63 590
payment to supplier: creation 46.23 137 5548
payment to supplier: removal 3.08 69 369
service: total inventory turnovers 4.29 2984 515
service: total monetary turnovers 5.72 538 686
service: aggregate perf. data 0.85 16907 102
service: refresh index statistics 0.03 404686 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 18-03-2021 10:29:28 to 18-03-2021 10:29:31.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3896
TEST_TIME 2 3287
TEST_TIME 3 3069
TEST_TIME 4 3580
TEST_TIME 5 3599
TEST_TIME 6 3889
TEST_TIME 7 4554
TEST_TIME 8 4486
TEST_TIME 9 4915
TEST_TIME 10 4915
TEST_TIME 11 5233
TEST_TIME 12 5128
TEST_TIME 13 5395
TEST_TIME 14 5501
TEST_TIME 15 5787
TEST_TIME 16 6027
TEST_TIME 17 6165
TEST_TIME 18 6393
TEST_TIME 19 6948
TEST_TIME 20 7466
TEST_TIME 21 7762
TEST_TIME 22 7811
TEST_TIME 23 8198
TEST_TIME 24 8382
TEST_TIME 25 8725
TEST_TIME 26 9159
TEST_TIME 27 10726
TEST_TIME 28 10856
TEST_TIME 29 10725
TEST_TIME 30 10516
TEST_TIME 31 10283
TEST_TIME 32 10076
TEST_TIME 33 10110
TEST_TIME 34 9973
TEST_TIME 35 9931
TEST_TIME 36 9833
TEST_TIME 37 9794
TEST_TIME 38 9686
TEST_TIME 39 9623
TEST_TIME 40 9575
TEST_TIME 41 9723
TEST_TIME 42 9718
TEST_TIME 43 9662
TEST_TIME 44 9736
TEST_TIME 45 9789
TEST_TIME 46 10013
TEST_TIME 47 9922
TEST_TIME 48 9894
TEST_TIME 49 10021
TEST_TIME 50 10007
TEST_TIME 51 10041
TEST_TIME 52 10038
TEST_TIME 53 10042
TEST_TIME 54 10049
TEST_TIME 55 9872
TEST_TIME 56 9795
TEST_TIME 57 9759
TEST_TIME 58 9654
TEST_TIME 59 9640
TEST_TIME 60 9561
TEST_TIME 61 9564
TEST_TIME 62 9575
TEST_TIME 63 9492
TEST_TIME 64 9516
TEST_TIME 65 9537
TEST_TIME 66 9601
TEST_TIME 67 9596
TEST_TIME 68 9550
TEST_TIME 69 9509
TEST_TIME 70 9653
TEST_TIME 71 9613
TEST_TIME 72 9592
TEST_TIME 73 9464
TEST_TIME 74 9440
TEST_TIME 75 9433
TEST_TIME 76 9344
TEST_TIME 77 9427
TEST_TIME 78 9369
TEST_TIME 79 9341
TEST_TIME 80 9337
TEST_TIME 81 9328
TEST_TIME 82 9304
TEST_TIME 83 9484
TEST_TIME 84 9507
TEST_TIME 85 9531
TEST_TIME 86 9522
TEST_TIME 87 9437
TEST_TIME 88 9346
TEST_TIME 89 9358
TEST_TIME 90 9284
TEST_TIME 91 9242
TEST_TIME 92 9193
TEST_TIME 93 9187
TEST_TIME 94 9205
TEST_TIME 95 9189
TEST_TIME 96 9179
TEST_TIME 97 9163
TEST_TIME 98 9158
TEST_TIME 99 9274
TEST_TIME 100 9268
TEST_TIME 101 9236
TEST_TIME 102 9289
TEST_TIME 103 9397
TEST_TIME 104 9314
TEST_TIME 105 9251
TEST_TIME 106 9212
TEST_TIME 107 9176
TEST_TIME 108 9178
TEST_TIME 109 9220
TEST_TIME 110 9177
TEST_TIME 111 9147
TEST_TIME 112 9187
TEST_TIME 113 9184
TEST_TIME 114 9202
TEST_TIME 115 9188
TEST_TIME 116 9281
TEST_TIME 117 9256
TEST_TIME 118 9278
TEST_TIME 119 9314
TEST_TIME 120 9239
TEST_TIME 121 9163

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 18-03-2021 10:29:32 to 18-03-2021 10:29:35.

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 1524031 1524031 0 0.00 0 17453 6 0 0
doc_list_biud 1524024 1524024 0 0.00 0 28388 32 0 0
sp_add_invoice_to_stock 4849 4735 114 2.35 41 207396 18498 0 114
sp_cancel_adding_invoice 5805 5805 0 0.00 5 78463 864 0 0
sp_cancel_client_order 1712 1712 0 0.00 4 10451 321 0 0
sp_cancel_customer_reserve 575037 575037 0 0.00 1 21025 68 0 0
sp_cancel_pay_from_customer 590 590 0 0.00 2 1293 63 0 0
sp_cancel_pay_to_supplier 369 369 0 0.00 3 3649 69 0 0
sp_cancel_supplier_invoice 6456 6449 7 0.10 2 19198 931 0 7
sp_cancel_supplier_order 419 419 0 0.00 8 1076640 119201 0 0
sp_cancel_write_off 9278 9278 0 0.00 1 11596 128 0 0
sp_client_order 18849 18849 0 0.00 3 81619 447 0 0
sp_customer_reserve 451165 451165 0 0.00 0 110679 177 0 0
sp_fill_shopping_cart 29249 29249 0 0.00 1 17794 46 0 0
sp_fill_shopping_cart_clo_res 451156 451156 0 0.00 0 29317 19 0 0
sp_get_clo_for_invoice 4725 4725 0 0.00 0 61033 391 0 0
sp_kill_cost_storno 16042 16042 0 0.00 0 7651 58 0 0
sp_kill_qstorno_ret_qs2qd 1166220 1166220 0 0.00 0 25451 37 0 0
sp_kill_qty_storno 612846 612846 0 0.00 0 28388 79 0 0
sp_lock_selected_doc 19574 19574 0 0.00 0 9653 6 0 0
sp_make_cost_storno 26373 26373 0 0.00 0 11914 59 0 0
sp_multiply_rows_for_pdistr 26372 26372 0 0.00 0 7637 30 0 0
sp_multiply_rows_for_qdistr 18849 18849 0 0.00 0 81610 253 0 0
sp_pay_from_customer 6678 6678 0 0.00 4 10319 111 0 0
sp_pay_to_supplier 5548 5548 0 0.00 5 10724 137 0 0
sp_payment_common 12225 12225 0 0.00 1 7677 18 0 0
sp_qd_handle_on_cancel_clo 1712 1712 0 0.00 0 10443 295 0 0
sp_qd_handle_on_invoice_upd_sts 10529 10529 0 0.00 0 28388 371 0 0
sp_qd_handle_on_reserve_upd_sts 9422 9422 0 0.00 0 16746 51 0 0
sp_reserve_write_off 9422 9422 0 0.00 7 16760 187 0 0
sp_supplier_invoice 5251 5251 0 0.00 8 109168 3822 0 0
sp_supplier_order 5162 5162 0 0.00 6 44014 1398 0 0
srv_aggregate_perf_data 102 102 0 0.00 2894 180301 16907 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 928 134325 51576 0 0
srv_find_qd_qs_mism 1061853 1061853 0 0.00 0 17453 5 0 0
srv_make_invnt_saldo 515 515 0 0.00 40 107695 2984 0 0
srv_make_money_saldo 686 686 0 0.00 1 25816 538 0 0
srv_recalc_idx_stat 4 4 0 0.00 230517 599602 404687 0 0
t$perf-abend:sp_add_invoice_to_stock 114 114 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4737 4737 0 0.00 0 20822 155 0 0
t$perf-norm:sp_cancel_adding_invoice 367 367 0 0.00 1 2723 89 0 0
t$perf-norm:sp_cancel_client_order 1712 1712 0 0.00 0 5888 16 0 0
t$perf-norm:sp_cancel_customer_reserve 626 626 0 0.00 0 2252 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 590 590 0 0.00 0 81 1 0 0
t$perf-norm:sp_cancel_pay_to_supplier 369 369 0 0.00 0 19919 76 0 0
t$perf-norm:sp_cancel_supplier_invoice 351 351 0 0.00 0 948 26 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 25771 1577 0 0
t$perf-norm:sp_cancel_write_off 994 994 0 0.00 0 538 4 0 0
t$perf-norm:sp_client_order 18849 18849 0 0.00 0 6503 13 0 0
t$perf-norm:sp_customer_reserve 910 910 0 0.00 0 1114 8 0 0
t$perf-norm:sp_pay_from_customer 6678 6678 0 0.00 0 3662 8 0 0
t$perf-norm:sp_pay_to_supplier 5548 5548 0 0.00 0 4264 8 0 0
t$perf-norm:sp_reserve_write_off 9422 9422 0 0.00 0 9737 11 0 0
t$perf-norm:sp_supplier_invoice 5251 5251 0 0.00 0 3509 11 0 0
t$perf-norm:sp_supplier_order 5162 5162 0 0.00 0 8788 17 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 913 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 175 4 0 0
t$perf-norm:srv_make_invnt_saldo 515 515 0 0.00 0 579 4 0 0
t$perf-norm:srv_make_money_saldo 686 686 0 0.00 0 3099 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 22 7 0 0
v_add_invoice_to_stock 9674 9446 228 2.35 0 7212 20 0 228
v_all_customers 34968 34968 0 0.00 0 100 1 0 0
v_all_suppliers 29294 29294 0 0.00 0 21 1 0 0
v_all_wares 37692 37692 0 0.00 0 102 1 0 0
v_cancel_adding_invoice 734 734 0 0.00 0 3026 17 0 0
v_cancel_client_order 3424 3424 0 0.00 0 2531 7 0 0
v_cancel_customer_prepayment 1180 1180 0 0.00 0 1270 8 0 0
v_cancel_customer_reserve 1252 1252 0 0.00 0 2270 5 0 0
v_cancel_payment_to_supplier 738 738 0 0.00 0 1916 8 0 0
v_cancel_supplier_invoice 716 702 14 1.95 0 401 14 0 14
v_cancel_supplier_order 838 838 0 0.00 0 3737 10 0 0
v_cancel_write_off 1988 1988 0 0.00 0 7124 16 0 0
v_max_id_clo_ord 5161 5161 0 0.00 0 4841 3 0 0
v_max_id_clo_res 910 910 0 0.00 0 7028 36 0 0
v_max_id_ord_sup 5242 5242 0 0.00 0 6550 8 0 0
v_max_non_paid_invoice 1303 1303 0 0.00 0 2253 5 0 0
v_max_non_paid_realizn 3393 3393 0 0.00 0 2397 4 0 0
v_min_id_clo_ord 5161 5161 0 0.00 0 3950 9 0 0
v_min_id_clo_res 910 910 0 0.00 0 12777 35 0 0
v_min_id_ord_sup 5242 5242 0 0.00 0 9184 14 0 0
v_min_non_paid_invoice 5547 5547 0 0.00 0 2958 9 0 0
v_min_non_paid_realizn 6678 6678 0 0.00 0 3323 11 0 0
v_our_firm 9294 9294 0 0.00 0 19 1 0 0
v_reserve_write_off 18844 18844 0 0.00 0 6164 9 0 0
x_lock_depdocs_on_canc_invoice 11903 11903 0 0.00 0 29703 109 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 6354 379 0 0
x_make_qstorno_1000_1200 5162 5162 0 0.00 2 44005 1324 0 0
x_make_qstorno_1200_2000 5251 5251 0 0.00 3 109140 3642 0 0
x_make_qstorno_2100_3300 451165 451165 0 0.00 0 110679 155 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 18-03-2021 10:29:36 to 18-03-2021 10:29:41.

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
07:57:02 14 8192000000 8801068224 8813273088 5757040 0.070 3 1 2 0 5858208 65392 35776
07:58:03 26867 8192000000 9715119264 10009477120 795154368 8.847 100 89 178 0 825083232 7642032 22286832
07:59:36 14955 8192000000 9757168960 10062880768 835484736 9.254 100 86 172 0 868879424 11863440 21531248
08:00:54 21226 8192000000 9805551232 10122043392 866657888 9.567 100 84 168 0 915751792 29417360 19676544
08:02:22 11740 8192000000 9869504544 10202877952 920986944 10.106 100 79 158 1 985440080 47388832 17064304
08:03:36 10627 8192000000 9919517232 10264358912 958961312 10.479 100 79 158 0 1027130576 51523744 16645520
08:04:49 5492 8192000000 9969996976 10324434944 1001253920 10.891 100 73 145 1 1079678544 64309056 14115568
08:05:56 3936 8192000000 10000108080 10360045568 1017576560 11.049 100 72 144 1 1106402976 74355152 14471264
08:07:02 1540 8192000000 10021619904 10389663744 1051538912 11.375 100 94 184 2 1132002144 61546160 18917072
08:08:05 1618 8192000000 10047759840 10417410048 1068154096 11.534 100 91 181 1 1155565728 69155824 18255808
08:09:07 7591 8192000000 10027675568 10431737856 1068017840 11.533 100 52 104 1 1150354896 71314384 11022672
08:10:19 7244 8192000000 9994723120 10392272896 1073411440 11.585 100 34 68 3 1111859744 31189952 7258352
08:11:28 6552 8192000000 9989140496 10388033536 1081744960 11.664 100 32 62 3 1101981072 13960048 6276064
08:12:36 13869 8192000000 10005228976 10407845888 1089988304 11.743 100 47 92 5 1115043264 14645008 10409952
08:13:51 28442 8192000000 10017292400 10426814464 1094772288 11.788 100 34 67 1 1126610656 24363728 7474640
08:15:20 4065 8192000000 10001245904 10404777984 1098348896 11.822 100 31 61 2 1122655392 17740768 6565728
08:16:26 7429 8192000000 10011467008 10409738240 1088025072 11.724 99 37 74 0 1112503584 16741296 7737216
08:17:35 9737 8192000000 9995925104 10395541504 1062613712 11.481 99 39 78 0 1089351328 18736640 8000976
08:18:46 31859 8192000000 9933575376 10332745728 971111840 10.598 91 20 40 1 990287440 14693952 4481648
08:20:18 6433 8192000000 9917275056 10294763520 962660592 10.515 94 42 83 0 982396992 10780944 8955456
08:21:25 8922 8192000000 9964057872 10352988160 1031241264 11.180 100 42 84 1 1060188816 19910496 9037056
08:22:38 65678 8192000000 9960984064 10353336320 1031238160 11.180 99 41 82 1 1056365680 15505040 9622480
08:24:44 5360 8192000000 9965284256 10357948416 1058020368 11.438 100 43 87 1 1088993360 20725296 10247696
08:25:52 4256 8192000000 9979491360 10368995328 1061612544 11.472 100 48 99 2 1086434752 14050368 10771840
08:26:57 10658 8192000000 9993272704 10392588288 1070855248 11.560 100 27 54 1 1101471824 24346384 6270192
08:28:19 111987 8192000000 10057033872 10463109120 1094890864 11.789 100 47 94 3 1163989232 59163760 9934608
08:31:15 23758 8192000000 10060529808 10471763968 1110543488 11.938 100 34 68 1 1176579632 58323024 7713120
08:32:52 4803 8192000000 10124955264 10530463744 1114808784 11.978 100 52 104 0 1231771504 105730800 11231920
08:34:05 13324 8192000000 10129796896 10536472576 1130631344 12.127 100 49 96 1 1233059424 91666656 10761424
08:35:23 28910 8192000000 10182967744 10595926016 1143903904 12.252 100 51 100 1 1275105792 119967696 11234192
08:36:57 7096 8192000000 10227669200 10639699968 1153505440 12.342 100 66 129 3 1329353872 161503696 14344736
08:38:12 11157 8192000000 10241757952 10651729920 1166357184 12.463 100 55 110 1 1344201536 165626304 12218048
08:39:28 3699 8192000000 10276227968 10683408384 1175218224 12.546 100 74 145 1 1378481184 186732784 16530176
08:40:37 4115 8192000000 10344804048 10751959040 1189457440 12.678 100 81 162 2 1444070416 235790832 18822144
08:41:47 2321 8192000000 10397455136 10802393088 1184728288 12.634 100 79 158 1 1491005856 287812288 18465280
08:42:54 2495 8192000000 10404276784 10809638912 1167015456 12.469 99 76 152 0 1500677424 315678096 17983872
08:44:01 925 8192000000 10485675968 10889105408 1181970528 12.609 100 94 188 0 1579559392 374976624 22612240
08:45:06 1541 8192000000 10472718848 10913112064 1174528064 12.539 100 82 163 0 1574764160 379751376 20484720
08:46:13 1443 8192000000 10463612864 10893279232 1173378064 12.528 100 91 180 2 1558210288 363006240 21825984
08:47:16 18591 8192000000 10446962864 10921443328 1170814288 12.504 100 37 75 1 1545488912 364659632 10014992
08:49:07 14842 8192000000 10447006544 10878705664 1161560672 12.418 100 37 71 3 1535543696 364987344 8995680
08:50:29 33160 8192000000 10462116288 10944667648 1153922560 12.346 100 32 62 0 1586739760 424169248 8647952
08:52:22 41179 8192000000 10322197248 10817630208 1110958064 11.941 99 19 38 1 1455419088 339112096 5348928
08:54:29 90676 8192000000 10075250064 10505699328 1094547168 11.786 97 16 32 1 1132451792 34823152 3081472
08:57:05 111409 8192000000 10086948608 10536132608 1111288912 11.945 99 36 72 0 1178914496 59810512 7815072
09:00:13 27564 8192000000 10067051232 10486784000 1082615600 11.672 97 54 107 1 1127931952 33512528 11803824
09:01:43 128486 8192000000 10083213632 10542403584 1087967472 11.723 97 32 64 1 1149071600 53871568 7232560
09:05:40 101535 8192000000 10116838784 10595930112 1099285296 11.831 98 15 30 1 1208325312 105707744 3332272
09:08:21 69853 8192000000 10064523632 10525024256 1095772960 11.798 97 14 28 0 1144016544 45595872 2647712
09:10:31 45234 8192000000 10130677760 10583965696 1121016352 12.037 99 41 82 1 1223478240 93433728 9028160
09:12:19 65088 8192000000 10077572544 10549305344 1132950176 12.149 100 12 24 1 1233312304 97172800 3189328
09:14:27 46900 8192000000 10120547632 10569371648 1132784352 12.148 99 27 54 2 1202318432 64317696 5216384
09:16:31 24868 8192000000 10058486000 10503847936 1141156208 12.226 100 25 48 2 1169689328 23444784 5088336
09:18:02 81013 8192000000 10107386448 10554408960 1128864160 12.111 98 39 77 1 1187376336 50896448 7615728
09:20:24 128382 8192000000 10123247088 10605010944 1135443808 12.173 100 26 52 0 1212887056 71709792 5733456
09:23:38 64246 8192000000 10075057776 10546843648 1113096992 11.962 98 12 24 0 1173641664 58029904 2514768
09:25:48 63678 8192000000 10078555152 10568011776 1135093856 12.169 100 5 10 0 1178165728 42020752 1051120
09:27:53 64691 8192000000 10093443472 10568019968 1110383808 11.936 97 15 29 0 1173668576 59881568 3403200
09:29:59 70368 8192000000 10125746736 10605240320 1120070864 12.028 98 25 50 2 1239627728 114195360 5361504
09:33:11 20997 8192000000 10108288048 10539761664 1146802208 12.279 100 39 78 1 1194145024 38498848 8843968
09:34:33 38512 8192000000 10124615136 10550001664 1105243488 11.887 97 40 79 1 1176774320 63171504 8359328
09:36:13 80694 8192000000 10045849696 10471743488 1018274752 11.055 90 16 32 1 1057013744 35784416 2954576
09:38:35 107339 8192000000 10086237840 10545967104 1123864160 12.063 100 17 34 1 1198072848 70701792 3506896
09:41:23 44873 8192000000 10071288192 10551873536 1099180304 11.830 98 5 10 2 1152435760 52437072 818384
09:43:08 46620 8192000000 10062722576 10531303424 1113567088 11.966 99 19 37 0 1158972832 41353952 4051792
09:44:55 20388 8192000000 10085726208 10517069824 1075871936 11.608 96 29 58 1 1139841904 58121552 5848416
09:46:16 46677 8192000000 10129790496 10564890624 1104621840 11.881 98 20 38 1 1188947424 80185696 4139888
09:48:11 50209 8192000000 10193257152 10647339008 1135119216 12.170 100 19 38 0 1285601280 146002384 4479680
09:50:04 47059 8192000000 10105803344 10546601984 1084494512 11.690 97 40 80 2 1144121440 51087824 8539104
09:52:06 24608 8192000000 10072548144 10488500224 1107930784 11.913 99 51 102 2 1148394672 29176752 11287136
09:53:34 134325 8192000000 10050998688 10515730432 1110820032 11.940 99 26 52 2 1188824912 72187184 5817696
09:58:21 59876 8192000000 10040231104 10498269184 1080947808 11.657 96 26 52 3 1118376464 31948896 5479760
10:00:23 27847 8192000000 10042094688 10483601408 1093212640 11.773 98 20 40 1 1139289872 42517472 3559760
10:01:57 32430 8192000000 10076610560 10510778368 1101930880 11.856 98 25 50 2 1157490176 50081680 5477616
10:03:36 68657 8192000000 10167587920 10643664896 1131882480 12.139 100 23 46 0 1277073360 140004016 5186864
10:06:06 35218 8192000000 10079612880 10509361152 1125731536 12.081 99 22 44 1 1167634784 37614944 4288304
10:07:44 79171 8192000000 10151881568 10657542144 1126419552 12.088 98 22 44 2 1239383264 108484896 4478816
10:10:06 90473 8192000000 10062102528 10521300992 1138918976 12.205 100 11 22 0 1172835104 31692688 2223440
10:12:40 67857 8192000000 10087518992 10535641088 1133581536 12.155 99 8 16 1 1163352640 28049120 1721984
10:15:02 102648 8192000000 10104594176 10555375616 1118502912 12.013 97 8 16 0 1181272736 61139184 1630640
10:17:49 73662 8192000000 10103079296 10544148480 1122179904 12.048 98 26 52 1 1185056640 57746656 5130080
10:20:05 77458 8192000000 10103097264 10532270080 1090427376 11.747 95 23 46 1 1159114016 63869280 4817360
10:22:28 84975 8192000000 10070828064 10535833600 1095591072 11.796 97 11 22 2 1138652992 41013168 2048752
10:25:04 111875 8192000000 10066198848 10581762048 1120097408 12.028 99 10 20 1 1198728272 76535264 2095600

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 18-03-2021 10:29:42 to 18-03-2021 10:29:57.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 114 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-03-2021 10:29:58 to 18-03-2021 10:30:00.

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          230803
MON$OLDEST_ACTIVE               230804
MON$OLDEST_SNAPSHOT             230804
MON$NEXT_TRANSACTION            230804
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                       2097536
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             789
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2389 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2389 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2389 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.2389 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 ---
:75: 
:76: mscs-test-161.budzdorov.ru    Thu Mar 18 10:27:23 2021
:77:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:78: 
:79: 
:80: mscs-test-161.budzdorov.ru    Thu Mar 18 10:27:34 2021
:81:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:82: 
--- end of diff output ---

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

18.03.21 10:30:01. 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-03-18 10:30:03 No severe PSQL-related problems occured
18.03.21 10:30:05. End of report.