builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-017 starttime: 1447939955.29 results: success (0) buildid: 20151119030404 builduid: 501e3dd1b6c343c983c59c1c7961f024 revision: 1d6155d7e6c91fa5ec1ef6927f3d3a044187896d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-19 05:32:35.285781) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-19 05:32:35.286196) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-19 05:32:35.286479) ========= bash -c pwd in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'pwd'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=6587686cb2da90a5f91b3170000001be-1447939594.172589-1818896900 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006493 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-19 05:32:35.312414) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-19 05:32:35.312705) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-19 05:32:35.359088) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 05:32:35.359345) ========= rm -rf properties in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=6587686cb2da90a5f91b3170000001be-1447939594.172589-1818896900 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004591 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 05:32:35.408821) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-19 05:32:35.409160) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-19 05:32:35.409518) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 05:32:35.409954) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=6587686cb2da90a5f91b3170000001be-1447939594.172589-1818896900 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-19 05:32:35-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.9M=0s 2015-11-19 05:32:35 (46.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.228726 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 05:32:35.657922) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 05:32:35.658244) ========= rm -rf scripts in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=6587686cb2da90a5f91b3170000001be-1447939594.172589-1818896900 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017741 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 05:32:35.726373) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-11-19 05:32:35.726681) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1d6155d7e6c91fa5ec1ef6927f3d3a044187896d --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 1d6155d7e6c91fa5ec1ef6927f3d3a044187896d --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=6587686cb2da90a5f91b3170000001be-1447939594.172589-1818896900 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-19 05:32:35,831 truncating revision to first 12 chars 2015-11-19 05:32:35,831 Setting DEBUG logging. 2015-11-19 05:32:35,831 attempt 1/10 2015-11-19 05:32:35,831 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1d6155d7e6c9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-19 05:32:36,837 unpacking tar archive at: mozilla-central-1d6155d7e6c9/testing/mozharness/ program finished with exit code 0 elapsedTime=1.289299 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-11-19 05:32:37.037771) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-19 05:32:37.038097) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-19 05:32:37.057406) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-19 05:32:37.057668) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-19 05:32:37.058023) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 30 secs) (at 2015-11-19 05:32:37.058313) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test-pgo/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test-pgo/buildprops.json PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=6587686cb2da90a5f91b3170000001be-1447939594.172589-1818896900 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:32:37 INFO - MultiFileLogger online at 20151119 05:32:37 in /builds/slave/test-pgo 05:32:37 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:32:37 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 05:32:37 INFO - {'append_to_log': False, 05:32:37 INFO - 'base_work_dir': '/builds/slave/test-pgo', 05:32:37 INFO - 'blob_upload_branch': 'Firefox', 05:32:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 05:32:37 INFO - 'branch': 'Firefox', 05:32:37 INFO - 'buildbot_json_path': 'buildprops.json', 05:32:37 INFO - 'config_files': ('talos/linux_config.py',), 05:32:37 INFO - 'default_actions': ('clobber', 05:32:37 INFO - 'read-buildbot-config', 05:32:37 INFO - 'download-and-extract', 05:32:37 INFO - 'populate-webroot', 05:32:37 INFO - 'create-virtualenv', 05:32:37 INFO - 'install', 05:32:37 INFO - 'run-tests'), 05:32:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:32:37 INFO - 'download_minidump_stackwalk': True, 05:32:37 INFO - 'download_symbols': 'ondemand', 05:32:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:32:37 INFO - 'tooltool.py': '/tools/tooltool.py', 05:32:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:32:37 INFO - '/tools/misc-python/virtualenv.py')}, 05:32:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:32:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:32:37 INFO - 'installer_path': 'installer.exe', 05:32:37 INFO - 'log_level': 'info', 05:32:37 INFO - 'log_name': 'talos', 05:32:37 INFO - 'log_to_console': True, 05:32:37 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:32:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:32:37 INFO - 'opt_config_files': (), 05:32:37 INFO - 'pip_index': False, 05:32:37 INFO - 'sps_profile': False, 05:32:37 INFO - 'sps_profile_interval': 0, 05:32:37 INFO - 'suite': 'dromaeojs-e10s', 05:32:37 INFO - 'system_bits': '64', 05:32:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:32:37 INFO - 'title': 'talos-linux64-ix-017', 05:32:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:32:37 INFO - 'use_talos_json': True, 05:32:37 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 05:32:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:32:37 INFO - 'work_dir': 'build'} 05:32:37 INFO - ##### 05:32:37 INFO - ##### Running clobber step. 05:32:37 INFO - ##### 05:32:37 INFO - Running pre-action listener: _resource_record_pre_action 05:32:37 INFO - Running main action method: clobber 05:32:37 INFO - rmtree: /builds/slave/test-pgo/build 05:32:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 05:32:37 INFO - Running post-action listener: _resource_record_post_action 05:32:37 INFO - ##### 05:32:37 INFO - ##### Running read-buildbot-config step. 05:32:37 INFO - ##### 05:32:37 INFO - Running pre-action listener: _resource_record_pre_action 05:32:37 INFO - Running main action method: read_buildbot_config 05:32:37 INFO - Using buildbot properties: 05:32:37 INFO - { 05:32:37 INFO - "properties": { 05:32:37 INFO - "buildnumber": 549, 05:32:37 INFO - "product": "firefox", 05:32:37 INFO - "script_repo_revision": "production", 05:32:37 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 05:32:37 INFO - "repository": "", 05:32:37 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 05:32:37 INFO - "buildid": "20151119030404", 05:32:37 INFO - "slavename": "talos-linux64-ix-017", 05:32:37 INFO - "stage_platform": "linux64-pgo", 05:32:37 INFO - "basedir": "/builds/slave/test-pgo", 05:32:37 INFO - "nightly_build": "True", 05:32:37 INFO - "project": "", 05:32:37 INFO - "platform": "ubuntu64_hw", 05:32:37 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 05:32:37 INFO - "slavebuilddir": "test", 05:32:37 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 05:32:37 INFO - "branch": "mozilla-central", 05:32:37 INFO - "repo_path": "mozilla-central", 05:32:37 INFO - "pgo_build": "True", 05:32:37 INFO - "builduid": "501e3dd1b6c343c983c59c1c7961f024", 05:32:37 INFO - "revision": "1d6155d7e6c91fa5ec1ef6927f3d3a044187896d" 05:32:37 INFO - }, 05:32:37 INFO - "sourcestamp": { 05:32:37 INFO - "repository": "", 05:32:37 INFO - "hasPatch": false, 05:32:37 INFO - "project": "", 05:32:37 INFO - "branch": "mozilla-central-linux64-pgo-talos", 05:32:37 INFO - "changes": [ 05:32:37 INFO - { 05:32:37 INFO - "category": null, 05:32:37 INFO - "files": [ 05:32:37 INFO - { 05:32:37 INFO - "url": null, 05:32:37 INFO - "name": "https://queue.taskcluster.net/v1/task/t4BCKR2TQICXWQQunOtFDQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 05:32:37 INFO - } 05:32:37 INFO - ], 05:32:37 INFO - "repository": "", 05:32:37 INFO - "rev": "1d6155d7e6c91fa5ec1ef6927f3d3a044187896d", 05:32:37 INFO - "who": "sendchange", 05:32:37 INFO - "when": 1447939915, 05:32:37 INFO - "number": 6682425, 05:32:37 INFO - "comments": "", 05:32:37 INFO - "project": "", 05:32:37 INFO - "at": "Thu 19 Nov 2015 05:31:55", 05:32:37 INFO - "branch": "mozilla-central-linux64-pgo-talos", 05:32:37 INFO - "revlink": "", 05:32:37 INFO - "properties": [ 05:32:37 INFO - [ 05:32:37 INFO - "buildid", 05:32:37 INFO - "20151119030404", 05:32:37 INFO - "Change" 05:32:37 INFO - ], 05:32:37 INFO - [ 05:32:37 INFO - "builduid", 05:32:37 INFO - "501e3dd1b6c343c983c59c1c7961f024", 05:32:37 INFO - "Change" 05:32:37 INFO - ], 05:32:37 INFO - [ 05:32:37 INFO - "nightly_build", 05:32:37 INFO - "True", 05:32:37 INFO - "Change" 05:32:37 INFO - ], 05:32:37 INFO - [ 05:32:37 INFO - "pgo_build", 05:32:37 INFO - "True", 05:32:37 INFO - "Change" 05:32:37 INFO - ] 05:32:37 INFO - ], 05:32:37 INFO - "revision": "1d6155d7e6c91fa5ec1ef6927f3d3a044187896d" 05:32:37 INFO - } 05:32:37 INFO - ], 05:32:37 INFO - "revision": "1d6155d7e6c91fa5ec1ef6927f3d3a044187896d" 05:32:37 INFO - } 05:32:37 INFO - } 05:32:37 INFO - Found installer url https://queue.taskcluster.net/v1/task/t4BCKR2TQICXWQQunOtFDQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 05:32:37 INFO - Running post-action listener: _resource_record_post_action 05:32:37 INFO - ##### 05:32:37 INFO - ##### Running download-and-extract step. 05:32:37 INFO - ##### 05:32:37 INFO - Running pre-action listener: _resource_record_pre_action 05:32:37 INFO - Running main action method: download_and_extract 05:32:37 INFO - mkdir: /builds/slave/test-pgo/build/tests 05:32:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:32:37 INFO - https://queue.taskcluster.net/v1/task/t4BCKR2TQICXWQQunOtFDQ/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 05:32:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/t4BCKR2TQICXWQQunOtFDQ/artifacts/public/build/test_packages.json 05:32:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/t4BCKR2TQICXWQQunOtFDQ/artifacts/public/build/test_packages.json 05:32:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/t4BCKR2TQICXWQQunOtFDQ/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 05:32:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/t4BCKR2TQICXWQQunOtFDQ/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 05:32:52 INFO - Downloaded 1302 bytes. 05:32:52 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 05:32:52 INFO - Using the following test package requirements: 05:32:52 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 05:32:52 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:32:52 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:32:52 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:32:52 INFO - u'jsshell-linux-x86_64.zip'], 05:32:52 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:32:52 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:32:52 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 05:32:52 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:32:52 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:32:52 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:32:52 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:32:52 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:32:52 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:32:52 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 05:32:52 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:32:52 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:32:52 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:32:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:32:52 INFO - https://queue.taskcluster.net/v1/task/t4BCKR2TQICXWQQunOtFDQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:32:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/t4BCKR2TQICXWQQunOtFDQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:32:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/t4BCKR2TQICXWQQunOtFDQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:32:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/t4BCKR2TQICXWQQunOtFDQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:32:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/t4BCKR2TQICXWQQunOtFDQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:32:59 INFO - Downloaded 22461635 bytes. 05:32:59 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 05:32:59 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:32:59 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 05:33:00 INFO - Return code: 0 05:33:00 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 05:33:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:33:00 INFO - https://queue.taskcluster.net/v1/task/t4BCKR2TQICXWQQunOtFDQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:33:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/t4BCKR2TQICXWQQunOtFDQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:33:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/t4BCKR2TQICXWQQunOtFDQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:33:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/t4BCKR2TQICXWQQunOtFDQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:33:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/t4BCKR2TQICXWQQunOtFDQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:33:12 INFO - Downloaded 22461635 bytes. 05:33:12 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 05:33:12 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:33:12 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 05:33:13 INFO - Return code: 0 05:33:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:33:13 INFO - https://queue.taskcluster.net/v1/task/t4BCKR2TQICXWQQunOtFDQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:33:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/t4BCKR2TQICXWQQunOtFDQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 05:33:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/t4BCKR2TQICXWQQunOtFDQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 05:33:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/t4BCKR2TQICXWQQunOtFDQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 05:33:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/t4BCKR2TQICXWQQunOtFDQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 05:33:22 INFO - Downloaded 11130997 bytes. 05:33:22 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 05:33:22 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 05:33:22 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 05:33:22 INFO - Return code: 0 05:33:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:33:22 INFO - https://queue.taskcluster.net/v1/task/t4BCKR2TQICXWQQunOtFDQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:33:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/t4BCKR2TQICXWQQunOtFDQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 05:33:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/t4BCKR2TQICXWQQunOtFDQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 05:33:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/t4BCKR2TQICXWQQunOtFDQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 05:33:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/t4BCKR2TQICXWQQunOtFDQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 05:33:24 INFO - Downloaded 57200719 bytes. 05:33:24 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/t4BCKR2TQICXWQQunOtFDQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 05:33:24 INFO - mkdir: /builds/slave/test-pgo/properties 05:33:24 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 05:33:24 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 05:33:24 INFO - Contents: 05:33:24 INFO - build_url:https://queue.taskcluster.net/v1/task/t4BCKR2TQICXWQQunOtFDQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 05:33:24 INFO - Running post-action listener: _resource_record_post_action 05:33:24 INFO - Running post-action listener: set_extra_try_arguments 05:33:24 INFO - ##### 05:33:24 INFO - ##### Running populate-webroot step. 05:33:24 INFO - ##### 05:33:24 INFO - Running pre-action listener: _resource_record_pre_action 05:33:24 INFO - Running main action method: populate_webroot 05:33:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:33:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:33:24 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 05:33:24 INFO - '../fennec_ids.txt'], 05:33:24 INFO - 'tests': ['tcheck2']}, 05:33:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:33:24 INFO - '--tppagecycles', 05:33:24 INFO - '7'], 05:33:24 INFO - 'tests': ['tsvgm']}}, 05:33:24 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 05:33:24 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 05:33:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:33:24 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:33:24 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 05:33:24 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:33:24 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:33:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:33:24 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:33:24 INFO - 'talos_options': ['--e10s'], 05:33:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:33:24 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:33:24 INFO - 'tests': ['damp', 'tps']}, 05:33:24 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:33:24 INFO - 'talos_options': ['--e10s'], 05:33:24 INFO - 'tests': ['damp', 'tps']}, 05:33:24 INFO - 'g3': {'tests': ['dromaeo_dom']}, 05:33:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:33:24 INFO - 'other': {'tests': ['a11yr', 05:33:24 INFO - 'ts_paint', 05:33:24 INFO - 'tpaint', 05:33:24 INFO - 'sessionrestore', 05:33:24 INFO - 'sessionrestore_no_auto_restore']}, 05:33:24 INFO - 'other-e10s': {'talos_options': ['--e10s'], 05:33:24 INFO - 'tests': ['a11yr', 05:33:24 INFO - 'ts_paint', 05:33:24 INFO - 'tpaint', 05:33:24 INFO - 'sessionrestore', 05:33:24 INFO - 'sessionrestore_no_auto_restore']}, 05:33:24 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 05:33:24 INFO - 'tests': ['a11yr', 05:33:24 INFO - 'ts_paint', 05:33:24 INFO - 'tpaint', 05:33:24 INFO - 'sessionrestore', 05:33:24 INFO - 'sessionrestore_no_auto_restore']}, 05:33:24 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 05:33:24 INFO - 'tests': ['a11yr', 05:33:24 INFO - 'ts_paint', 05:33:24 INFO - 'tpaint', 05:33:24 INFO - 'sessionrestore', 05:33:24 INFO - 'sessionrestore_no_auto_restore']}, 05:33:24 INFO - 'other_l64': {'tests': ['a11yr', 05:33:24 INFO - 'ts_paint', 05:33:24 INFO - 'tpaint', 05:33:24 INFO - 'sessionrestore', 05:33:24 INFO - 'sessionrestore_no_auto_restore']}, 05:33:24 INFO - 'other_nol64': {'tests': ['a11yr', 05:33:24 INFO - 'ts_paint', 05:33:24 INFO - 'tpaint', 05:33:24 INFO - 'sessionrestore', 05:33:24 INFO - 'sessionrestore_no_auto_restore']}, 05:33:24 INFO - 'svgr': {'tests': ['tsvgx', 05:33:24 INFO - 'tsvgr_opacity', 05:33:24 INFO - 'tart', 05:33:24 INFO - 'tscrollx', 05:33:24 INFO - 'cart']}, 05:33:24 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 05:33:24 INFO - 'tests': ['tsvgx', 05:33:24 INFO - 'tsvgr_opacity', 05:33:24 INFO - 'tart', 05:33:24 INFO - 'tscrollx', 05:33:24 INFO - 'cart']}, 05:33:24 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:33:24 INFO - 'tests': ['tp5o']}, 05:33:24 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:33:24 INFO - 'talos_options': ['--e10s'], 05:33:24 INFO - 'tests': ['tp5o']}, 05:33:24 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:33:24 INFO - 'talos_options': ['--xperf_path', 05:33:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:33:24 INFO - 'tests': ['tp5n']}, 05:33:24 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:33:24 INFO - 'talos_options': ['--e10s', 05:33:24 INFO - '--xperf_path', 05:33:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:33:24 INFO - 'tests': ['tp5n']}}, 05:33:24 INFO - 'talos.zip': {'path': '', 05:33:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:33:24 INFO - Running post-action listener: _resource_record_post_action 05:33:24 INFO - ##### 05:33:24 INFO - ##### Running create-virtualenv step. 05:33:24 INFO - ##### 05:33:24 INFO - Running pre-action listener: _resource_record_pre_action 05:33:24 INFO - Running main action method: create_virtualenv 05:33:24 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 05:33:24 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test-pgo/build/venv'] in /builds/slave/test-pgo/build 05:33:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 05:33:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:33:24 INFO - Using real prefix '/usr' 05:33:24 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 05:33:25 INFO - Installing distribute.............................................................................................................................................................................................done. 05:33:27 INFO - Installing pip.................done. 05:33:27 INFO - Return code: 0 05:33:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 05:33:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:33:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:33:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:33:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:33:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:27 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd657aa1c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20fb6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21bc6e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f2a90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2244f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22452e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '6587686cb2da90a5f91b3170000001be-1447939594.172589-1818896900', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:33:27 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 05:33:27 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:33:27 INFO - Using env: {'DISPLAY': ':0', 05:33:27 INFO - 'HOME': '/home/cltbld', 05:33:27 INFO - 'LANG': 'en_US.UTF-8', 05:33:27 INFO - 'LANGUAGE': 'en_US:en', 05:33:27 INFO - 'LOGNAME': 'cltbld', 05:33:27 INFO - 'MAIL': '/var/mail/cltbld', 05:33:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:27 INFO - 'MOZ_NO_REMOTE': '1', 05:33:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:33:27 INFO - 'NO_EM_RESTART': '1', 05:33:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:33:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:33:27 INFO - 'PWD': '/builds/slave/test-pgo', 05:33:27 INFO - 'SHELL': '/bin/bash', 05:33:27 INFO - 'SHLVL': '1', 05:33:27 INFO - 'TERM': 'linux', 05:33:27 INFO - 'TMOUT': '86400', 05:33:27 INFO - 'USER': 'cltbld', 05:33:27 INFO - 'XDG_SESSION_COOKIE': '6587686cb2da90a5f91b3170000001be-1447939594.172589-1818896900', 05:33:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:27 INFO - '_': '/tools/buildbot/bin/python'} 05:33:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:33:27 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:33:27 INFO - Cleaning up... 05:33:27 INFO - Return code: 0 05:33:27 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 05:33:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:33:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:33:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:33:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:33:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:27 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd657aa1c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20fb6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21bc6e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f2a90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2244f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22452e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '6587686cb2da90a5f91b3170000001be-1447939594.172589-1818896900', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:33:27 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 05:33:27 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 05:33:27 INFO - Using env: {'DISPLAY': ':0', 05:33:27 INFO - 'HOME': '/home/cltbld', 05:33:27 INFO - 'LANG': 'en_US.UTF-8', 05:33:27 INFO - 'LANGUAGE': 'en_US:en', 05:33:27 INFO - 'LOGNAME': 'cltbld', 05:33:27 INFO - 'MAIL': '/var/mail/cltbld', 05:33:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:27 INFO - 'MOZ_NO_REMOTE': '1', 05:33:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:33:27 INFO - 'NO_EM_RESTART': '1', 05:33:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:33:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:33:27 INFO - 'PWD': '/builds/slave/test-pgo', 05:33:27 INFO - 'SHELL': '/bin/bash', 05:33:27 INFO - 'SHLVL': '1', 05:33:27 INFO - 'TERM': 'linux', 05:33:27 INFO - 'TMOUT': '86400', 05:33:27 INFO - 'USER': 'cltbld', 05:33:27 INFO - 'XDG_SESSION_COOKIE': '6587686cb2da90a5f91b3170000001be-1447939594.172589-1818896900', 05:33:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:27 INFO - '_': '/tools/buildbot/bin/python'} 05:33:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:33:27 INFO - Downloading/unpacking psutil>=0.7.1 05:33:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:33:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:33:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:29 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 05:33:29 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 05:33:29 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 05:33:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:33:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:33:29 INFO - Installing collected packages: psutil 05:33:29 INFO - Running setup.py install for psutil 05:33:29 INFO - building 'psutil._psutil_linux' extension 05:33:29 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 05:33:29 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 05:33:29 INFO - building 'psutil._psutil_posix' extension 05:33:29 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 05:33:29 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 05:33:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:33:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:33:30 INFO - Successfully installed psutil 05:33:30 INFO - Cleaning up... 05:33:30 INFO - Return code: 0 05:33:30 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 05:33:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:33:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:33:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:33:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:33:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:30 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd657aa1c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20fb6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21bc6e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f2a90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2244f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22452e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '6587686cb2da90a5f91b3170000001be-1447939594.172589-1818896900', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:33:30 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 05:33:30 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 05:33:30 INFO - Using env: {'DISPLAY': ':0', 05:33:30 INFO - 'HOME': '/home/cltbld', 05:33:30 INFO - 'LANG': 'en_US.UTF-8', 05:33:30 INFO - 'LANGUAGE': 'en_US:en', 05:33:30 INFO - 'LOGNAME': 'cltbld', 05:33:30 INFO - 'MAIL': '/var/mail/cltbld', 05:33:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:30 INFO - 'MOZ_NO_REMOTE': '1', 05:33:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:33:30 INFO - 'NO_EM_RESTART': '1', 05:33:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:33:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:33:30 INFO - 'PWD': '/builds/slave/test-pgo', 05:33:30 INFO - 'SHELL': '/bin/bash', 05:33:30 INFO - 'SHLVL': '1', 05:33:30 INFO - 'TERM': 'linux', 05:33:30 INFO - 'TMOUT': '86400', 05:33:30 INFO - 'USER': 'cltbld', 05:33:30 INFO - 'XDG_SESSION_COOKIE': '6587686cb2da90a5f91b3170000001be-1447939594.172589-1818896900', 05:33:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:30 INFO - '_': '/tools/buildbot/bin/python'} 05:33:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:33:30 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:33:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:33:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:33:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:31 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:33:31 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 05:33:31 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:33:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:33:32 INFO - Installing collected packages: mozsystemmonitor 05:33:32 INFO - Running setup.py install for mozsystemmonitor 05:33:32 INFO - Successfully installed mozsystemmonitor 05:33:32 INFO - Cleaning up... 05:33:32 INFO - Return code: 0 05:33:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 05:33:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:33:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:33:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:33:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:33:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:32 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd657aa1c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20fb6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21bc6e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f2a90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2244f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22452e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '6587686cb2da90a5f91b3170000001be-1447939594.172589-1818896900', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:33:32 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 05:33:32 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:33:32 INFO - Using env: {'DISPLAY': ':0', 05:33:32 INFO - 'HOME': '/home/cltbld', 05:33:32 INFO - 'LANG': 'en_US.UTF-8', 05:33:32 INFO - 'LANGUAGE': 'en_US:en', 05:33:32 INFO - 'LOGNAME': 'cltbld', 05:33:32 INFO - 'MAIL': '/var/mail/cltbld', 05:33:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:32 INFO - 'MOZ_NO_REMOTE': '1', 05:33:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:33:32 INFO - 'NO_EM_RESTART': '1', 05:33:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:33:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:33:32 INFO - 'PWD': '/builds/slave/test-pgo', 05:33:32 INFO - 'SHELL': '/bin/bash', 05:33:32 INFO - 'SHLVL': '1', 05:33:32 INFO - 'TERM': 'linux', 05:33:32 INFO - 'TMOUT': '86400', 05:33:32 INFO - 'USER': 'cltbld', 05:33:32 INFO - 'XDG_SESSION_COOKIE': '6587686cb2da90a5f91b3170000001be-1447939594.172589-1818896900', 05:33:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:32 INFO - '_': '/tools/buildbot/bin/python'} 05:33:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:33:32 INFO - Downloading/unpacking blobuploader==1.2.4 05:33:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:33:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:33:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:34 INFO - Downloading blobuploader-1.2.4.tar.gz 05:33:34 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 05:33:34 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:33:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:33:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:33:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:33:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:34 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 05:33:34 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 05:33:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:33:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:33:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:33:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:34 INFO - Downloading docopt-0.6.1.tar.gz 05:33:34 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 05:33:34 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 05:33:34 INFO - Installing collected packages: blobuploader, requests, docopt 05:33:34 INFO - Running setup.py install for blobuploader 05:33:34 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:33:34 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 05:33:34 INFO - Running setup.py install for requests 05:33:35 INFO - Running setup.py install for docopt 05:33:35 INFO - Successfully installed blobuploader requests docopt 05:33:35 INFO - Cleaning up... 05:33:35 INFO - Return code: 0 05:33:35 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:33:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:33:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:33:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:33:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:33:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:35 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd657aa1c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20fb6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21bc6e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f2a90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2244f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22452e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '6587686cb2da90a5f91b3170000001be-1447939594.172589-1818896900', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:33:35 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 05:33:35 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:33:35 INFO - Using env: {'DISPLAY': ':0', 05:33:35 INFO - 'HOME': '/home/cltbld', 05:33:35 INFO - 'LANG': 'en_US.UTF-8', 05:33:35 INFO - 'LANGUAGE': 'en_US:en', 05:33:35 INFO - 'LOGNAME': 'cltbld', 05:33:35 INFO - 'MAIL': '/var/mail/cltbld', 05:33:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:35 INFO - 'MOZ_NO_REMOTE': '1', 05:33:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:33:35 INFO - 'NO_EM_RESTART': '1', 05:33:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:33:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:33:35 INFO - 'PWD': '/builds/slave/test-pgo', 05:33:35 INFO - 'SHELL': '/bin/bash', 05:33:35 INFO - 'SHLVL': '1', 05:33:35 INFO - 'TERM': 'linux', 05:33:35 INFO - 'TMOUT': '86400', 05:33:35 INFO - 'USER': 'cltbld', 05:33:35 INFO - 'XDG_SESSION_COOKIE': '6587686cb2da90a5f91b3170000001be-1447939594.172589-1818896900', 05:33:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:35 INFO - '_': '/tools/buildbot/bin/python'} 05:33:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:33:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:33:35 INFO - Running setup.py (path:/tmp/pip-SVq2tm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:33:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:33:35 INFO - Running setup.py (path:/tmp/pip-QWHOtv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:33:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:33:35 INFO - Running setup.py (path:/tmp/pip-jX8xpZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:33:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:33:35 INFO - Running setup.py (path:/tmp/pip-eXeWSd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:33:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 05:33:35 INFO - Running setup.py (path:/tmp/pip-aXC9HT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 05:33:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:33:35 INFO - Running setup.py (path:/tmp/pip-LYvPLq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:33:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:33:35 INFO - Running setup.py (path:/tmp/pip-l5cWIf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:33:36 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:33:36 INFO - Running setup.py (path:/tmp/pip-hmDP1I-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:33:36 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 05:33:36 INFO - Running setup.py (path:/tmp/pip-AKrkc0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 05:33:36 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 05:33:36 INFO - Running setup.py (path:/tmp/pip-4SmP1s-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 05:33:36 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:33:36 INFO - Running setup.py (path:/tmp/pip-OkUXKd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:33:36 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:33:36 INFO - Running setup.py (path:/tmp/pip-ixt7MV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:33:36 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:33:36 INFO - Running setup.py (path:/tmp/pip-I3WMSx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:33:36 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:33:36 INFO - Running setup.py (path:/tmp/pip-bBcm9g-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:33:36 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:33:36 INFO - Running setup.py (path:/tmp/pip-mvhdQz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:33:36 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 05:33:36 INFO - Running setup.py (path:/tmp/pip-njLeMi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 05:33:36 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 05:33:36 INFO - Running setup.py (path:/tmp/pip-XCmTa_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 05:33:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:33:36 INFO - Running setup.py install for manifestparser 05:33:36 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 05:33:36 INFO - Running setup.py install for mozcrash 05:33:36 INFO - Running setup.py install for mozdebug 05:33:37 INFO - Running setup.py install for mozdevice 05:33:37 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 05:33:37 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 05:33:37 INFO - Running setup.py install for mozfile 05:33:37 INFO - Running setup.py install for mozhttpd 05:33:37 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 05:33:37 INFO - Running setup.py install for mozinfo 05:33:37 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 05:33:37 INFO - Running setup.py install for mozInstall 05:33:37 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 05:33:37 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 05:33:37 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 05:33:37 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 05:33:37 INFO - Running setup.py install for mozleak 05:33:37 INFO - Running setup.py install for mozlog 05:33:37 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 05:33:37 INFO - Running setup.py install for moznetwork 05:33:37 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 05:33:37 INFO - Running setup.py install for mozprocess 05:33:38 INFO - Running setup.py install for mozprofile 05:33:38 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 05:33:38 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 05:33:38 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 05:33:38 INFO - Running setup.py install for mozrunner 05:33:38 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 05:33:38 INFO - Running setup.py install for mozscreenshot 05:33:38 INFO - Running setup.py install for moztest 05:33:38 INFO - Running setup.py install for mozversion 05:33:38 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 05:33:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:33:38 INFO - Cleaning up... 05:33:38 INFO - Return code: 0 05:33:38 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:33:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:33:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:33:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:33:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:33:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:38 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd657aa1c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20fb6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21bc6e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f2a90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2244f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22452e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '6587686cb2da90a5f91b3170000001be-1447939594.172589-1818896900', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:33:38 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 05:33:38 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:33:38 INFO - Using env: {'DISPLAY': ':0', 05:33:38 INFO - 'HOME': '/home/cltbld', 05:33:38 INFO - 'LANG': 'en_US.UTF-8', 05:33:38 INFO - 'LANGUAGE': 'en_US:en', 05:33:38 INFO - 'LOGNAME': 'cltbld', 05:33:38 INFO - 'MAIL': '/var/mail/cltbld', 05:33:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:38 INFO - 'MOZ_NO_REMOTE': '1', 05:33:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:33:38 INFO - 'NO_EM_RESTART': '1', 05:33:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:33:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:33:38 INFO - 'PWD': '/builds/slave/test-pgo', 05:33:38 INFO - 'SHELL': '/bin/bash', 05:33:38 INFO - 'SHLVL': '1', 05:33:38 INFO - 'TERM': 'linux', 05:33:38 INFO - 'TMOUT': '86400', 05:33:38 INFO - 'USER': 'cltbld', 05:33:38 INFO - 'XDG_SESSION_COOKIE': '6587686cb2da90a5f91b3170000001be-1447939594.172589-1818896900', 05:33:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:38 INFO - '_': '/tools/buildbot/bin/python'} 05:33:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:33:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:33:38 INFO - Running setup.py (path:/tmp/pip-tBLQnO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:33:38 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 1)) 05:33:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:33:38 INFO - Running setup.py (path:/tmp/pip-6VbmNx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:33:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 05:33:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:33:38 INFO - Running setup.py (path:/tmp/pip-lfX38f-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:33:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 05:33:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:33:38 INFO - Running setup.py (path:/tmp/pip-F1KcCf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:33:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 05:33:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 05:33:39 INFO - Running setup.py (path:/tmp/pip-u5pvXg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 05:33:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 5)) 05:33:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:33:39 INFO - Running setup.py (path:/tmp/pip-GUr8hH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:33:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 6)) 05:33:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:33:39 INFO - Running setup.py (path:/tmp/pip-LkcqVg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:33:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 7)) 05:33:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:33:39 INFO - Running setup.py (path:/tmp/pip-3_gXNa-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:33:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 8)) 05:33:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 05:33:39 INFO - Running setup.py (path:/tmp/pip-iafJGh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 05:33:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 9)) 05:33:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 05:33:39 INFO - Running setup.py (path:/tmp/pip-YbHLaV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 05:33:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.0 from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 05:33:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:33:39 INFO - Running setup.py (path:/tmp/pip-dRLcXz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:33:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 11)) 05:33:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:33:39 INFO - Running setup.py (path:/tmp/pip-NmLnrG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:33:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 12)) 05:33:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:33:39 INFO - Running setup.py (path:/tmp/pip-bCcZCt-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:33:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 13)) 05:33:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:33:39 INFO - Running setup.py (path:/tmp/pip-fKGzo3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:33:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 14)) 05:33:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:33:39 INFO - Running setup.py (path:/tmp/pip-Zdo2KC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:33:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 15)) 05:33:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 05:33:39 INFO - Running setup.py (path:/tmp/pip-fN2cx5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 05:33:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/moztest in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 16)) 05:33:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 05:33:39 INFO - Running setup.py (path:/tmp/pip-NTALuc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 05:33:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 17)) 05:33:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 05:33:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 05:33:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 05:33:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 05:33:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 05:33:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 05:33:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:33:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:33:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:41 INFO - Downloading blessings-1.5.1.tar.gz 05:33:41 INFO - Storing download in cache at /builds/slave/test-pgo/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 05:33:41 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 05:33:41 INFO - Installing collected packages: blessings 05:33:41 INFO - Running setup.py install for blessings 05:33:41 INFO - Successfully installed blessings 05:33:41 INFO - Cleaning up... 05:33:41 INFO - Return code: 0 05:33:41 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 05:33:41 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 05:33:41 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 05:33:42 INFO - Reading from file tmpfile_stdout 05:33:42 INFO - Current package versions: 05:33:42 INFO - argparse == 1.2.1 05:33:42 INFO - blessings == 1.5.1 05:33:42 INFO - blobuploader == 1.2.4 05:33:42 INFO - docopt == 0.6.1 05:33:42 INFO - manifestparser == 1.1 05:33:42 INFO - mozInstall == 1.12 05:33:42 INFO - mozcrash == 0.16 05:33:42 INFO - mozdebug == 0.1 05:33:42 INFO - mozdevice == 0.47 05:33:42 INFO - mozfile == 1.2 05:33:42 INFO - mozhttpd == 0.7 05:33:42 INFO - mozinfo == 0.9 05:33:42 INFO - mozleak == 0.1 05:33:42 INFO - mozlog == 3.0 05:33:42 INFO - moznetwork == 0.27 05:33:42 INFO - mozprocess == 0.22 05:33:42 INFO - mozprofile == 0.27 05:33:42 INFO - mozrunner == 6.11 05:33:42 INFO - mozscreenshot == 0.1 05:33:42 INFO - mozsystemmonitor == 0.0 05:33:42 INFO - moztest == 0.7 05:33:42 INFO - mozversion == 1.4 05:33:42 INFO - psutil == 3.1.1 05:33:42 INFO - requests == 1.2.3 05:33:42 INFO - wsgiref == 0.1.2 05:33:42 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:33:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:33:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:33:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:33:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:33:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:42 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd657aa1c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20fb6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21bc6e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f2a90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2244f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22452e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '6587686cb2da90a5f91b3170000001be-1447939594.172589-1818896900', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:33:42 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/talos 05:33:42 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:33:42 INFO - Using env: {'DISPLAY': ':0', 05:33:42 INFO - 'HOME': '/home/cltbld', 05:33:42 INFO - 'LANG': 'en_US.UTF-8', 05:33:42 INFO - 'LANGUAGE': 'en_US:en', 05:33:42 INFO - 'LOGNAME': 'cltbld', 05:33:42 INFO - 'MAIL': '/var/mail/cltbld', 05:33:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:42 INFO - 'MOZ_NO_REMOTE': '1', 05:33:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:33:42 INFO - 'NO_EM_RESTART': '1', 05:33:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:33:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:33:42 INFO - 'PWD': '/builds/slave/test-pgo', 05:33:42 INFO - 'SHELL': '/bin/bash', 05:33:42 INFO - 'SHLVL': '1', 05:33:42 INFO - 'TERM': 'linux', 05:33:42 INFO - 'TMOUT': '86400', 05:33:42 INFO - 'USER': 'cltbld', 05:33:42 INFO - 'XDG_SESSION_COOKIE': '6587686cb2da90a5f91b3170000001be-1447939594.172589-1818896900', 05:33:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:42 INFO - '_': '/tools/buildbot/bin/python'} 05:33:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:33:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 05:33:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 2)) 05:33:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 05:33:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 4)) 05:33:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 5)) 05:33:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 6)) 05:33:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 7)) 05:33:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 8)) 05:33:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 05:33:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 05:33:42 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozlog>=3.0->mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 05:33:42 INFO - Cleaning up... 05:33:42 INFO - Return code: 0 05:33:42 INFO - Running post-action listener: _resource_record_post_action 05:33:42 INFO - Running post-action listener: _start_resource_monitoring 05:33:42 INFO - Starting resource monitoring. 05:33:42 INFO - ##### 05:33:42 INFO - ##### Running install step. 05:33:42 INFO - ##### 05:33:42 INFO - Running pre-action listener: _resource_record_pre_action 05:33:42 INFO - Running main action method: install 05:33:42 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 05:33:42 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 05:33:42 INFO - Reading from file tmpfile_stdout 05:33:42 INFO - Detecting whether we're running mozinstall >=1.0... 05:33:42 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 05:33:42 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 05:33:42 INFO - Reading from file tmpfile_stdout 05:33:42 INFO - Output received: 05:33:42 INFO - Usage: mozinstall [options] installer 05:33:42 INFO - Options: 05:33:42 INFO - -h, --help show this help message and exit 05:33:42 INFO - -d DEST, --destination=DEST 05:33:42 INFO - Directory to install application into. [default: 05:33:42 INFO - "/builds/slave/test-pgo"] 05:33:42 INFO - --app=APP Application being installed. [default: firefox] 05:33:42 INFO - mkdir: /builds/slave/test-pgo/build/application 05:33:42 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 05:33:42 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 05:33:57 INFO - Reading from file tmpfile_stdout 05:33:57 INFO - Output received: 05:33:57 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 05:33:57 INFO - Running post-action listener: _resource_record_post_action 05:33:57 INFO - ##### 05:33:57 INFO - ##### Running run-tests step. 05:33:57 INFO - ##### 05:33:57 INFO - Running pre-action listener: _resource_record_pre_action 05:33:57 INFO - Running main action method: run_tests 05:33:57 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 05:33:57 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 05:33:57 INFO - Python 2.7.3 05:33:57 INFO - Return code: 0 05:33:57 INFO - grabbing minidump binary from tooltool 05:33:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:33:57 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f2a90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2244f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22452e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 05:33:57 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test-pgo/build 05:33:57 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 05:33:57 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:33:57 INFO - Return code: 0 05:33:57 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 05:33:57 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 05:33:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 05:33:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 05:33:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 05:33:57 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/t4BCKR2TQICXWQQunOtFDQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-017', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 05:33:57 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/t4BCKR2TQICXWQQunOtFDQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-017 --webServer localhost 05:33:57 INFO - Using env: {'DISPLAY': ':0', 05:33:57 INFO - 'HOME': '/home/cltbld', 05:33:57 INFO - 'LANG': 'en_US.UTF-8', 05:33:57 INFO - 'LANGUAGE': 'en_US:en', 05:33:57 INFO - 'LOGNAME': 'cltbld', 05:33:57 INFO - 'MAIL': '/var/mail/cltbld', 05:33:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 05:33:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 05:33:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:57 INFO - 'MOZ_NO_REMOTE': '1', 05:33:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 05:33:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:33:57 INFO - 'NO_EM_RESTART': '1', 05:33:57 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:33:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:33:57 INFO - 'PWD': '/builds/slave/test-pgo', 05:33:57 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 05:33:57 INFO - 'SHELL': '/bin/bash', 05:33:57 INFO - 'SHLVL': '1', 05:33:57 INFO - 'TERM': 'linux', 05:33:57 INFO - 'TMOUT': '86400', 05:33:57 INFO - 'USER': 'cltbld', 05:33:57 INFO - 'XDG_SESSION_COOKIE': '6587686cb2da90a5f91b3170000001be-1447939594.172589-1818896900', 05:33:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:57 INFO - '_': '/tools/buildbot/bin/python'} 05:33:57 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/t4BCKR2TQICXWQQunOtFDQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-017', '--webServer', 'localhost'] with output_timeout 3600 05:33:57 INFO - mozversion INFO | application_buildid: 20151119030404 05:33:57 INFO - mozversion INFO | application_changeset: 1d6155d7e6c91fa5ec1ef6927f3d3a044187896d 05:33:57 INFO - mozversion INFO | application_display_name: Nightly 05:33:57 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:33:57 INFO - mozversion INFO | application_name: Firefox 05:33:57 INFO - mozversion INFO | application_remotingname: firefox 05:33:57 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/mozilla-central 05:33:57 INFO - mozversion INFO | application_vendor: Mozilla 05:33:57 INFO - mozversion INFO | application_version: 45.0a1 05:33:57 INFO - mozversion INFO | platform_buildid: 20151119030404 05:33:57 INFO - mozversion INFO | platform_changeset: 1d6155d7e6c91fa5ec1ef6927f3d3a044187896d 05:33:57 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/mozilla-central 05:33:57 INFO - mozversion INFO | platform_version: 45.0a1 05:33:57 INFO - 2015-11-19 05:33:57,314 DEBUG : using testdate: 1447940037 05:33:57 INFO - 2015-11-19 05:33:57,314 DEBUG : actual date: 1447940037 05:33:57 INFO - 2015-11-19 05:33:57,322 INFO : starting webserver on 'localhost:35227' 05:33:57 INFO - 2015-11-19 05:33:57,323 INFO : Starting test suite talos-linux64-ix-017.e 05:33:57 INFO - 2015-11-19 05:33:57,323 INFO : Starting test dromaeo_css 05:33:57 INFO - 2015-11-19 05:33:57,323 DEBUG : operating with platform_type : linux_ 05:33:57 INFO - 2015-11-19 05:33:57,324 INFO : Initialising browser for dromaeo_css test... 05:33:57 INFO - 2015-11-19 05:33:57,339 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpOVRDvU/profile http://localhost:35227/getInfo.html 05:34:01 INFO - 2015-11-19 05:34:01,717 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:34:01 INFO - 2015-11-19 05:34:01,717 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:34:01 INFO - 2015-11-19 05:34:01,719 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/668 05:34:01 INFO - 2015-11-19 05:34:01,719 DEBUG : BROWSER_OUTPUT: __metrics 05:34:02 INFO - 2015-11-19 05:34:02,761 INFO : Browser initialized. 05:34:02 INFO - 2015-11-19 05:34:02,762 INFO : Running cycle 1/1 for dromaeo_css test... 05:34:02 INFO - 2015-11-19 05:34:02,762 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpOVRDvU/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 05:34:14 INFO - 2015-11-19 05:34:14,545 DEBUG : BROWSER_OUTPUT: RSS: Main: 141754368 05:34:14 INFO - 2015-11-19 05:34:14,545 DEBUG : BROWSER_OUTPUT: 05:35:04 INFO - 2015-11-19 05:35:04,163 DEBUG : BROWSER_OUTPUT: 1447940104162 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:37:13 INFO - 2015-11-19 05:37:13,132 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35227/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35227/tests/dromaeo/cssquery-ext.html) 05:37:14 INFO - 2015-11-19 05:37:14,330 DEBUG : BROWSER_OUTPUT: RSS: Main: 146657280 05:37:14 INFO - 2015-11-19 05:37:14,330 DEBUG : BROWSER_OUTPUT: 05:40:16 INFO - 2015-11-19 05:40:16,253 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35227/tests/dromaeo/cssquery-ext.html (next: http://localhost:35227/tests/dromaeo/cssquery-jquery.html) 05:40:16 INFO - 2015-11-19 05:40:16,834 DEBUG : BROWSER_OUTPUT: RSS: Main: 141557760 05:40:16 INFO - 2015-11-19 05:40:16,835 DEBUG : BROWSER_OUTPUT: 05:43:18 INFO - 2015-11-19 05:43:18,477 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35227/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35227/tests/dromaeo/cssquery-mootools.html) 05:43:19 INFO - 2015-11-19 05:43:19,058 DEBUG : BROWSER_OUTPUT: RSS: Main: 141492224 05:43:19 INFO - 2015-11-19 05:43:19,058 DEBUG : BROWSER_OUTPUT: 05:46:20 INFO - 2015-11-19 05:46:20,752 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35227/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35227/tests/dromaeo/cssquery-prototype.html) 05:46:21 INFO - 2015-11-19 05:46:21,354 DEBUG : BROWSER_OUTPUT: RSS: Main: 140054528 05:46:21 INFO - 2015-11-19 05:46:21,354 DEBUG : BROWSER_OUTPUT: 05:49:23 INFO - 2015-11-19 05:49:23,692 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35227/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35227/tests/dromaeo/cssquery-yui.html) 05:49:24 INFO - 2015-11-19 05:49:24,314 DEBUG : BROWSER_OUTPUT: RSS: Main: 142016512 05:49:24 INFO - 2015-11-19 05:49:24,315 DEBUG : BROWSER_OUTPUT: 05:52:26 INFO - 2015-11-19 05:52:26,657 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35227/tests/dromaeo/cssquery-yui.html (next: http://localhost:35227/tests/dromaeo/cssquery-dojo.html) 05:52:26 INFO - 2015-11-19 05:52:26,916 DEBUG : BROWSER_OUTPUT: RSS: Main: 143212544 05:52:26 INFO - 2015-11-19 05:52:26,916 DEBUG : BROWSER_OUTPUT: 05:52:26 INFO - 2015-11-19 05:52:26,916 DEBUG : BROWSER_OUTPUT: __start_tp_report 05:52:26 INFO - 2015-11-19 05:52:26,916 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 05:52:26 INFO - 2015-11-19 05:52:26,917 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 05:52:26 INFO - 2015-11-19 05:52:26,917 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 05:52:26 INFO - 2015-11-19 05:52:26,917 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3188.5057816038516;3262.314913868089;3288.358770039843;3305.5372247885302;3308.5037244413334;5308.5487733542695;5422.566194704419;5437.483439073281;5441.891162176754;5486.3690675572325;5329.626926115174;5396.217548455472;5422.132458806592;5423.078076726956;5432.9185062224;4943.975280123604;5046.596272298208;5095.312132862054;5130.666506677054;5146.382434107913;5083.847484575469;5088.4148322942965;5167.7932882684645;5171.818986335479;5232.686038837663;4900.509949005107;4965.950340496621;5017.824376146854;5084.51697088776;5102.413222479407;5057.317262169634;5060.139776238048;5061.063703214917;5068.011737711147;5093.388793344822;4998.3502144721315;5044.747762611892;5058.0895438821;5063.316452278932;5103.974480127613;5175.223716442564;5205.895882082337;5232.2674825524455;5290.656107353048;5373.892522149574;5937.376575459558;5944.10838374243;5959.195508606369;5962.403759624046;5968.373320801296;5989.341172471069;5996.100584912255;6010.978133717234;6016.037434010581;6017.217761690996;5370.5435038021815;5469.453054694499;5499.972500137514;5553.666779993173;5623;5973.551983601247;6020.096985452217;6029.065494848305;6042.848928776816;6045.486133678686;5824.592278540546;5825.155352473867;5830.76676932918;5831.183634291203;5835;5729.598928075078;5802.738876750556;5838.883222335615;5909.674967876808;5959.165716799652;5850.94149058504;5991.281046274475;5991.430814072656;5994.940050599525;5995.130706047598;5691.886162276731;5989.2812862456785;5994;6013.579049466583;6014.578979471483;4453.599176074169;4557.635389168859;4559.886002849956;4567.725936443824;4570.7257564546235;4014.698897582693;4039.6970227233073;4047.615476529725;4052;4076.5108187017154;5595.468430499096;5615.129654903415;5641.746121424546;5652.378238393773;5684.77260909559;5807.912881306784;5863.355030946593;5920.171176035358;5935.139404786281;5942.910856337158;5545.972270138624;5610.410906854721;5653.745581448845;5681.630694004876;5689.231953686282;5028.19548872186;5211.791528338824;5220.425753167148;5226.738663066832;5265.447128051499;5937.287525496968;6039.184710064085;6049.8487537811025;6066.666333351708;6107.704691583745;5951.315598706031;5971.343152253162;5991.820245392645;5992.520598352208;6003.969980150246;5944.613600115978;6120.326764056039;6138.693065346804;6148.538859585459;6161.199044124235;5811.4479124483105;5815.185873977731;5832.387599302182;5874.412558744262;5918.348981611933;5955.225820643289;5969.791057312973;5973.163757073926;5989.520838332835;5992.880142397041;5738.9139162913425;5846.152307915413;5855.502282306052;5883.941160588511;5914.467697907209;5722.971385143048;5826.621269617462;5862.912056319244;5887.8233652990475;5895.793647222326;5710.600257982068;5720.942790572041;5751.338596061507;5760.481556659753;5779.942200577941;5716.485516303553;5834.854128646648;5868.2664666916635;5887.20522729426;5889;5627.774889004394;5677.630953987978;5768.307803063659;5782.392848750821;5799.188113664175 05:52:26 INFO - 2015-11-19 05:52:26,917 DEBUG : BROWSER_OUTPUT: |1;ext.html;537.9004884096443;601.314501468326;602.4638072115815;608.0271565495208;612.9111278864565;39184.99999999996;39906.20187596246;40336.798316008455;40515.797421012925;40541.39187912184;42141;42616.360754588706;42693.573064269345;42868.785656071675;43114.35328470068;8651.74044778656;8827.867581986275;8828.426152300111;8836.248918841906;8840.646374145059;8561.1866872647;8624;8639.39524233304;8688.56557172142;8690.652373905068;8619.1811777881;8662.30701543878;8689.087645797195;8699.95650021749;8739.912600874006;8574.228319451247;8593.312534997185;8641;8692.304615630734;8695.695650652196;8476.449030813039;8520.275776559;8590.527520986343;8601.5699215039;8607.225349718556;39494.605053949665;39909.20181596352;39959.8002009991;39995;40085.79957100225;3215.9678403215903;3320.5019247112887;3342.74929380295;3456.9308613827834;3612.0066981580276;29282.24306541593;29391.2652183695;29561.26096847596;29922.40155196884;29956.101316960525;3529.2235708144167;3568.518250036238;3585.46218067292;3607.4408466687705;3671.724620653435;272299;272767;273013;273743;274752;49736.25395619069;51027.23459148116;55721.442785572435;57637.71181144068;58938.51676315602;55882.44117558854;55911.720441397534;55948.440515594324;55991;56178.71910640502;14867.851321486647;15131;15141.848581514043;15233.23833808305;15261.9236903817;11074.280171788809;11250.831237531444;11255.099592032782;11322.716932076764;11410.885891141148;19285.61428771445;19364.322248721226;19381.321653742052;19414.12636431364;19440.125194366006;7542.208068152875;7721.729739459092;7736.381089512825;7796.4932279401655;7801.9609901950125;950.6815216902415;1020.3316827478013;1043.4000449741277;1049.7795462952865;1058.8200005999;27974.580381294298;28065.859670701517;28693;28699;28893.133206003855;1004.1866088468364;1009.2380252909154;1059.210887888528;1117.6479408986143;1134.5178299222962;13816.861831381759;13858.861411385757;13876.375563099482;14050.789238161437;14098.929505352613;325265.2420427391;332420;332792;333303;334701;49677.50322496801;49859.50140498549;49868.750656246484;49930.75034624804;49964.500354996715;1340.9463621455227;1345.0382976172084;1358.891288696902;1378.7862881253423;1475.1149310413666;26026.21921342363;26938.865305673346;27309.180724578295;27412.45175096527;28375.290617733895;44080.338794918745;44599.77700111479;44712.32931506095;44817.10365792731;44889.32666009947;218090;218743;218897;219644;220497;30961.380772384877;31531.21171970719;31644.841775790974;31724.365512689154;31922.042338729876;39180.41229381619;39244.80377598094;39727.404088938114;39847;40059.79970100248;33400.83299583486;34990.825045874604;35656.93029209128;36013;36051.459228111074;35711.46432803562;36047.639523604426;36538.8173059133;36736.26527469489;36738.448923265634;34739.478907816905;35120;35150.64849351474;35404.82297588599;35571.82214088913;31273.530897037017;31922.84038579792;32748.50877236891;32984.835075825424;33023.50464742983;24756.876215618806;25203.11789087373;25457.363565911;25706.742932571182;25812.741872581035 05:52:26 INFO - 2015-11-19 05:52:26,917 DEBUG : BROWSER_OUTPUT: |2;jquery.html;347.48572113272354;350.2172644140346;356.35677601928506;358.1404628890662;358.23874267182237;12306;12665.176763510366;14150.64623384416;15421.074735515864;16829.579260518494;11859.584914527994;11895.821562676567;11905;12101;12106.273623582569;4565.2467342888385;4602.470715867685;4634.490206077328;4647.488776234612;4648.628109751228;4526.388937493446;4651.720896746206;4671.532846715319;4681.648876334289;4701.553352431531;4628.1669299526075;4648.907021859561;4657.9068418631605;6470.061841033047;6727.562708423961;6668.799936001928;6672.199336079701;6676.132102826626;6697.765578204788;6737.528373013891;6321.367863213688;6516.511261655395;6554.180727409074;6569.343065693392;6574.046763219307;22223.555528889494;22885.656715149285;23147.189848355396;23695;23862.642060369108;2314.9652755208685;2363.787259146668;2391.7847393734473;2394.341556072077;2440.341107900857;14241.216733079676;14281.285935703174;14436.350364233635;14644.194569298685;14883.18142502162;1889.1121173048646;1929.7263805888047;1936.3029309448586;1942.2328180368816;1955.706644003411;69217.30782692108;69953;70405.64797176032;71475.64262178655;72315;14835.703285934222;15695.764563531557;15748.370065197492;15850.52448426549;15894.205289735466;23611.7638823613;23700.7629923702;23814;23839.165629202893;23999;8522.446041007315;8537.18896704812;8557.614907329185;8580.528070956032;8589.098144694843;6864.656767161722;6973.721051157999;6985.022096906438;7008.544444611084;7217.133943926762;7596.240375962359;7737.535747855147;7785.221477852283;7803.570803605856;7833.138354780969;2900.3474218301135;2947.277916910328;2978.6723460419335;3042.680518545565;3067.2791893904914;439.7163829330128;440.2361902099854;448.8272015274143;453.47170546313174;486.4697479747022;10395.84406233907;10777.946110269555;10879.619213327496;11081.72295692598;11170.218084733991;426.6735947000547;446.2079808340157;447;457.89239528710726;473.82705312560665;5721.663563004345;5832.475077243069;5858.648481091148;5893.734781934823;6012.518998480111;522254;523267;544482;553184;554680;76388.23611763923;76649.616751917;78502;79367.60316198382;79868.20131798724;481.40546425164894;481.7759741720117;487.60747598183093;488.0799692579584;489.3320617357282;10185.541650625888;10469.895301046892;11294;11900.821487677866;11915.82126268124;36025;36098;36752;37862.24275514529;38157.427638586;47132.29301560548;47229.2915606273;47820.521794781605;48650.5134948646;48750.26874596813;6821.113255276782;6926.411255043377;6949.861002780017;6964.825879353057;6975.930240697731;12165.939170304091;12256;12699.873001269869;12719.87280127187;12727.363631818187;10688.519016644113;10810.51352689115;10961.45192740344;11100.056495197998;11317.886821132013;10495.16038716916;10906.454677266263;10969;11016.339019658846;11037.448127593749;10753.085987691135;10938.890611093788;10957.452127393439;10992.780144397228;11001.944990274997;6370.649614271259;6878.58728476293;6915.585064896122;6922.584644921119;6932.23745388017;10934.6719598409;10943.069839063768;11232.213745037769;11261.493232804707;11353.091752659602 05:52:26 INFO - 2015-11-19 05:52:26,919 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1507.0580886945659;1646.37437773646;1722.698527757643;1727.2486468386248;1737.6785294720478;7411.518251313668;7622.161562228157;7625.4280928930275;7882.9211707882905;8072.475289106204;7917.247861453167;7943.602819858998;7987.880181797278;7991.320737737285;7999.4800337977995;2279.9658005129936;2299.7815207555254;2313.3175713164655;2320.6751054852334;2333.0784340185614;2289.3246492284734;2338.1115176233093;2344.4724936889234;2348.8942997565064;2350.8001819845335;2055.629986602411;2094.2984100326407;2305.688732021181;2308.53829234153;2356.6582845487396;2316.536692661466;2355.7526459721653;2360.24472168906;2360.929172124839;2362.870042147681;2260.4800895793896;2336.836421450499;2339.4619237575284;2341.8946147423408;2343.297010896741;7623.6569354378635;7636.083669959585;7664.923350766532;7668.194839541824;7718.112417071996;4729.62163026957;4817.951820481785;4819.036192761427;4821.975890120562;4838.564529192389;3006.4738670732536;3122.67211942745;3142.842857857121;3147.27612649092;3162.7469802415753;994.2940512236321;998.8451789972565;1021.5607288865784;1026.3892983674702;1059.6715018344262;10544.578216871316;10703.946480267627;10705.1435885129;10715.946420267848;10724.088452481548;4350.0647360817575;4394.890127746832;4447.066116115652;4447.399601053881;4474.776261186993;9134.725958221265;9240.768980775534;9251.95374023139;9284.25725941923;9284.907150928539;4795.112904112751;4803.327534145223;4805.807767689324;4834.516548345137;4834.830780922651;4301.849435269751;4348.391225228471;4369.978150109229;4391.604755572014;4393.802278897393;3540.2652700176727;3621.4748861414946;3625.021244264034;3630.5643322801434;3647.94528082079;1188.4354931407686;1208.6736581123048;1223.2843786385065;1223.3240460569077;1231.310466138965;976.1019861727228;992.265723364705;1006.2805094357569;1100.6918062942389;1319.4854006937303;7341.522801017917;7348.63256837156;7356.77929662111;7364.631768411665;7417.332440080419;1170.5809825155552;1195.9402029898645;1226.7055906582357;1228.2937311046185;1238.3560548514722;5799.768009279666;5807.88384232313;5812.38969908162;5831.562632802556;5869.060950248026;9051.530993278566;9285.535723213812;9343.159115679622;9533.094356036165;9582.089701478348;9291.48896810682;9314.627414903463;9341.112594303666;9341.925678546919;9445.0554944505;7122.430205583644;7175.210726819942;7230.45771567124;7235.204127545966;7305.525140865827;7303.963480182565;7331;7332.19345871943;7332.523385979895;7335.8532829344185;7756.922430775846;7915.287624113857;8023.358131349361;8042.477238979448;8138.226868447488;8372.246497815226;8417.537035463109;8425.62084706202;8443.535605541509;8553.31573474133;6903.5167538273845;7071.646417678992;7104.076470058859;7109.253528379446;7120.145582530129;7537.698492060256;7544.886826697494;7579.962100189464;7594.544327340377;7596.430267729832;6525.184351956005;6966.3730264276455;7133.82165445868;7147.928520714726;7244.311790379906;6435.388638079375;6483.837904052436;6770.255271919987;6802.523823332319;7191.892121618284;7073.115860517435;7118.8932166018585;7171.964140179266;7189.424846012411;7206.207317195209;6450.580712253689;6458.806235812933;6460.030995350735;6517.543771935919;6530.118434011348;6720.5631633945595;6725.630090344882;6758.8648227036165;6759.526833121634;6782.525223234326 05:52:26 INFO - 2015-11-19 05:52:26,919 DEBUG : BROWSER_OUTPUT: |4;prototype.html;328.7665757312311;329.3561088072817;337.2967363048045;337.49881426082266;339.8844392906411;6352.904706429407;6353.174087368641;6368.076628888814;6371.649559274238;6556.704948277327;6330.018847078699;6335.081413195084;6341;6346.365363463651;6388.041793730943;4825.541573550507;4835.879103022417;4841.49164337743;4842.927356089662;4854.247591623304;4830.782614782343;4835.178019736635;4842.806287748486;4848.418189817209;4850.9029819403595;4695.1079294934025;4711.90576188476;4759.928601070987;4793.496682848303;4797.136515427222;4749.073930583538;4758.881027974311;4773.307870358779;4778.37881075458;4793.952060479385;4125.97937010316;4128.112455822013;4132.276851550967;4132.421460995463;4145.751254924714;5979.910301345483;5991.940080599182;6008.75964961401;6010.939890601082;6033.215681961361;5429.131338985743;5518.117101263819;5574.526165275956;5596.580256480739;5602;6747.93252067478;6760.932390676079;6765.2219994700745;6770.5937643741045;6772.255051944282;4590.380298659672;4593.655475839326;4600.6549508786975;4606.9078618427775;4609.608183304423;6779.796606101825;6919.927411251264;6974.267701891279;7013.684384202723;7015.088038555006;4610.884727881829;4677.672562920631;4678.69588476748;4684.859454216379;4694.81220751173;4717.646176536773;4739.123262196505;4742.834000809955;4753.287006948916;4755.048990201904;5505.201745746845;5521.613487055948;5522.723863806794;5527.2538207342295;5529.502344788908;6596.274409814917;6730.663466826639;6747.257801641816;6751.729930802811;6757.054012438262;1625.7154997875325;1651.0506458786247;1652.289515508319;1656.9254383552768;1657.32049859557;1053.2258789789498;1065.7495488560182;1077.757504561483;1082.0694202985426;1100.5542755184163;6362.045693146065;6386.489080873519;6412.166418365676;6441.742330306829;6450.0647406125845;6831.760888368884;6862.176538815274;6877.3810357067105;6880.931190688129;6882.8623427531165;6332.746690132435;6382.648954307465;6390.968045159744;6425.036244563353;6436.935630643634;3776.735628506033;3787.5265591801026;3790.1093243087853;3814.5803961564206;3830.8467661293794;7309.817254568572;7315.341619254293;7318.963405183046;7325.157606875277;7354.338109570164;7049.859002820018;7166.856662866818;7181.8922716159295;7210.062691850026;7252.419806415474;7238.0952380952385;7249.963750181215;7297.416206703451;7306.6711997959155;7340.009098771704;6947.166340039228;7005.614691192033;7016.22821489646;7018.789436316919;7055.118110236221;6852.417544508573;6917.23910369869;6945.861082778417;6963.512554121163;6968.477364197604;7284.235155308828;7326.9267307326245;7335.266473352622;7339.339459448675;7352.632368381666;4789.61683065347;4801.279808028824;4802.903941921212;4809.9038019240115;4840.515948405131;6702.966485167543;6758;6874.2782007890455;6884.242733299433;6943.861122777617;6763.628000459825;6780.050792888901;6796.01457788608;6828.453723702191;6832.419244364086;6806.353396427331;6813.829654258485;6825.590464571943;6826.044353790374;6833.145856767904;6567.587968586777;6676.432503237279;6679;6686.866262674622;6695.263521012587;6872.656367181721;6880.174379074543;6898.1377327834025;6901.792946211622;6909.827254318682;6638.867222655616;6702.83242918931;6710.597364158166;6711.664416779044;6714.764983225564 05:52:26 INFO - 2015-11-19 05:52:26,920 DEBUG : BROWSER_OUTPUT: |5;yui.html;689.4105539763502;749.737591842855;832.3091833777964;859.6776208921655;874.1913729799934;13191.934040329785;13212.867871321283;13353.399097040632;13367.933160334196;13410.798838017437;11627.418629068534;12834.871651283484;13134.868651313483;13461.394237259323;13535.729285414285;799.9960000200006;812.9674813007503;820.167529957091;822.942394032418;823.6046697585163;815.3721634341554;822.3051521464353;830.1573902488979;830.9335253179761;834.4617721569584;850.1413572291967;858.7509622209583;861.7242482405633;863.0721973878107;869.1959937058227;843.797488602734;857.6055014693248;861.4443683823961;862.8058686795483;864.0581765875227;630.19020558582;630.7035693224177;631.3780925788095;631.826247781859;633.6894921488483;12506.562270320495;12644.68388290291;12651.93674031624;12697.55558555446;12716.809247861289;10952.780944381067;10966.616168434066;10971.780564388748;11267.098632109493;11302.378369189692;9825.85261221075;9967.501624918797;10072.647457338959;10088.89911100887;10093.798124037477;3243.4486137356707;3310.652381499932;3440.363532746425;3499.1602015516346;3602.657747513982;37595.43606845899;38146.61853381458;38178.61821381806;44658.106837863386;48442.515574844154;9172.8624070639;9562.66530671437;9681.903180968242;9715.951420242995;9767.218622510181;19046.90476547636;19534.70697939532;19542.925139117484;19571.315003975076;19645.214191432467;7368.631568421558;7777.844443111106;7786.1824508426935;7931.405144614177;8033.357331413473;4045.8583949561626;4090.9590904091174;4166.895827604334;4244;4291.678124140702;3223.3553289342226;3225.2420681139915;3230.951535726966;3252.463343548288;3253.837308134584;458.7339343181014;478.7343024621299;479.0849477498032;608.0817964872986;627.8336240896075;436.2962630926175;487.9338645060554;568.2527476368562;568.9971550142305;634.3878157577867;8167.79580510492;8380.748577542683;8395.370347223983;8615.267702245379;8717.65129394817;562.5471495446205;563.9041362968305;571.1033677126871;576.1818218130265;581.7440326256434;3903.394973779069;3982.303096958021;4026.375911733686;4106.691998100155;4222.239996800544;56881;58733;58913.41086589165;59147;59232;52295.47704522982;52976.47023529792;53062.46937530652;53115;53373.46626533763;43922.34116488255;44856.3271550927;45741.313880291826;45849.77075114603;46669.29996050062;8217.79455513617;8347.123552027193;8402.705905293285;8433.746987590383;8591.914080859362;33336;34671.479927800596;34829.955101347;36785.448218277284;37783;46365.304520432896;46790.532094678616;46884.53115468802;46895.76552117217;47780;8128;8237.299829514559;8273.33813294947;8319.708810191614;8384.790380240544;11584.131190160602;11835.467403967014;11879.703007424538;11917.16579839403;11947.581834635746;10176.389416635026;10317.071463568314;10402.531886064993;10596.417197053928;10644.574217031231;10789.40658263803;11197.664070077584;11610;11633.709157271136;12015.399230038638;11208.49561769706;11305.547778088785;11336.206465547333;11481.598144064918;11771.352575608424;9555.12379813658;9947.353422027834;10060.195184385379;10096.747581310527;10655.467226638792;8797;8851.734447966572;9034;9279.257659387098;9366.906330936603 05:52:26 INFO - 2015-11-19 05:52:26,920 DEBUG : BROWSER_OUTPUT: __end_tp_report 05:52:26 INFO - 2015-11-19 05:52:26,920 DEBUG : BROWSER_OUTPUT: __start_cc_report 05:52:26 INFO - 2015-11-19 05:52:26,920 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,293 05:52:26 INFO - 2015-11-19 05:52:26,920 DEBUG : BROWSER_OUTPUT: __end_cc_report 05:52:26 INFO - 2015-11-19 05:52:26,920 DEBUG : BROWSER_OUTPUT: __startTimestamp1447941146910__endTimestamp 05:52:26 INFO - 2015-11-19 05:52:26,921 DEBUG : BROWSER_OUTPUT: 05:52:26 INFO - 2015-11-19 05:52:26,921 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 05:52:26 INFO - 2015-11-19 05:52:26,921 DEBUG : BROWSER_OUTPUT: Number of tests: 6 05:52:26 INFO - 2015-11-19 05:52:26,921 DEBUG : BROWSER_OUTPUT: 05:52:26 INFO - 2015-11-19 05:52:26,921 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5505.22 Median:5729.60 stddev:616.69 (10.8%) stddev-sans-first:591.19 05:52:26 INFO - 2015-11-19 05:52:26,922 DEBUG : BROWSER_OUTPUT: Values: 3188.5 3262.3 3288.4 3305.5 3308.5 5308.5 5422.6 5437.5 5441.9 5486.4 5329.6 5396.2 5422.1 5423.1 5432.9 4944.0 5046.6 5095.3 5130.7 5146.4 5083.8 5088.4 5167.8 5171.8 5232.7 4900.5 4966.0 5017.8 5084.5 5102.4 5057.3 5060.1 5061.1 5068.0 5093.4 4998.4 5044.7 5058.1 5063.3 5104.0 5175.2 5205.9 5232.3 5290.7 5373.9 5937.4 5944.1 5959.2 5962.4 5968.4 5989.3 5996.1 6011.0 6016.0 6017.2 5370.5 5469.5 5500.0 5553.7 5623.0 5973.6 6020.1 6029.1 6042.8 6045.5 5824.6 5825.2 5830.8 5831.2 5835.0 5729.6 5802.7 5838.9 5909.7 5959.2 5850.9 5991.3 5991.4 5994.9 5995.1 5691.9 5989.3 5994.0 6013.6 6014.6 4453.6 4557.6 4559.9 4567.7 4570.7 4014.7 4039.7 4047.6 4052.0 4076.5 5595.5 5615.1 5641.7 5652.4 5684.8 5807.9 5863.4 5920.2 5935.1 5942.9 5546.0 5610.4 5653.7 5681.6 5689.2 5028.2 5211.8 5220.4 5226.7 5265.4 5937.3 6039.2 6049.8 6066.7 6107.7 5951.3 5971.3 5991.8 5992.5 6004.0 5944.6 6120.3 6138.7 6148.5 6161.2 5811.4 5815.2 5832.4 5874.4 5918.3 5955.2 5969.8 5973.2 5989.5 5992.9 5738.9 5846.2 5855.5 5883.9 5914.5 5723.0 5826.6 5862.9 5887.8 5895.8 5710.6 5720.9 5751.3 5760.5 5779.9 5716.5 5834.9 5868.3 5887.2 5889.0 5627.8 5677.6 5768.3 5782.4 5799.2 05:52:26 INFO - 2015-11-19 05:52:26,923 DEBUG : BROWSER_OUTPUT: 05:52:26 INFO - 2015-11-19 05:52:26,923 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:44298.43 Median:35614.38 stddev:72781.31 (204.4%) stddev-sans-first:72911.31 05:52:26 INFO - 2015-11-19 05:52:26,923 DEBUG : BROWSER_OUTPUT: Values: 537.9 601.3 602.5 608.0 612.9 39185.0 39906.2 40336.8 40515.8 40541.4 42141.0 42616.4 42693.6 42868.8 43114.4 8651.7 8827.9 8828.4 8836.2 8840.6 8561.2 8624.0 8639.4 8688.6 8690.7 8619.2 8662.3 8689.1 8700.0 8739.9 8574.2 8593.3 8641.0 8692.3 8695.7 8476.4 8520.3 8590.5 8601.6 8607.2 39494.6 39909.2 39959.8 39995.0 40085.8 3216.0 3320.5 3342.7 3456.9 3612.0 29282.2 29391.3 29561.3 29922.4 29956.1 3529.2 3568.5 3585.5 3607.4 3671.7 272299.0 272767.0 273013.0 273743.0 274752.0 49736.3 51027.2 55721.4 57637.7 58938.5 55882.4 55911.7 55948.4 55991.0 56178.7 14867.9 15131.0 15141.8 15233.2 15261.9 11074.3 11250.8 11255.1 11322.7 11410.9 19285.6 19364.3 19381.3 19414.1 19440.1 7542.2 7721.7 7736.4 7796.5 7802.0 950.7 1020.3 1043.4 1049.8 1058.8 27974.6 28065.9 28693.0 28699.0 28893.1 1004.2 1009.2 1059.2 1117.6 1134.5 13816.9 13858.9 13876.4 14050.8 14098.9 325265.2 332420.0 332792.0 333303.0 334701.0 49677.5 49859.5 49868.8 49930.8 49964.5 1340.9 1345.0 1358.9 1378.8 1475.1 26026.2 26938.9 27309.2 27412.5 28375.3 44080.3 44599.8 44712.3 44817.1 44889.3 218090.0 218743.0 218897.0 219644.0 220497.0 30961.4 31531.2 31644.8 31724.4 31922.0 39180.4 39244.8 39727.4 39847.0 40059.8 33400.8 34990.8 35656.9 36013.0 36051.5 35711.5 36047.6 36538.8 36736.3 36738.4 34739.5 35120.0 35150.6 35404.8 35571.8 31273.5 31922.8 32748.5 32984.8 33023.5 24756.9 25203.1 25457.4 25706.7 25812.7 05:52:26 INFO - 2015-11-19 05:52:26,923 DEBUG : BROWSER_OUTPUT: 05:52:26 INFO - 2015-11-19 05:52:26,924 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:28747.57 Median:433.19 stddev:88380.79 (20402.1%) stddev-sans-first:88602.99 05:52:26 INFO - 2015-11-19 05:52:26,924 DEBUG : BROWSER_OUTPUT: Values: 347.5 350.2 356.4 358.1 358.2 12306.0 12665.2 14150.6 15421.1 16829.6 11859.6 11895.8 11905.0 12101.0 12106.3 4565.2 4602.5 4634.5 4647.5 4648.6 4526.4 4651.7 4671.5 4681.6 4701.6 4628.2 4648.9 4657.9 6470.1 6727.6 6668.8 6672.2 6676.1 6697.8 6737.5 6321.4 6516.5 6554.2 6569.3 6574.0 22223.6 22885.7 23147.2 23695.0 23862.6 2315.0 2363.8 2391.8 2394.3 2440.3 14241.2 14281.3 14436.4 14644.2 14883.2 1889.1 1929.7 1936.3 1942.2 1955.7 69217.3 69953.0 70405.6 71475.6 72315.0 14835.7 15695.8 15748.4 15850.5 15894.2 23611.8 23700.8 23814.0 23839.2 23999.0 8522.4 8537.2 8557.6 8580.5 8589.1 6864.7 6973.7 6985.0 7008.5 7217.1 7596.2 7737.5 7785.2 7803.6 7833.1 2900.3 2947.3 2978.7 3042.7 3067.3 439.7 440.2 448.8 453.5 486.5 10395.8 10777.9 10879.6 11081.7 11170.2 426.7 446.2 447.0 457.9 473.8 5721.7 5832.5 5858.6 5893.7 6012.5 522254.0 523267.0 544482.0 553184.0 554680.0 76388.2 76649.6 78502.0 79367.6 79868.2 481.4 481.8 487.6 488.1 489.3 10185.5 10469.9 11294.0 11900.8 11915.8 36025.0 36098.0 36752.0 37862.2 38157.4 47132.3 47229.3 47820.5 48650.5 48750.3 6821.1 6926.4 6949.9 6964.8 6975.9 12165.9 12256.0 12699.9 12719.9 12727.4 10688.5 10810.5 10961.5 11100.1 11317.9 10495.2 10906.5 10969.0 11016.3 11037.4 10753.1 10938.9 10957.5 10992.8 11001.9 6370.6 6878.6 6915.6 6922.6 6932.2 10934.7 10943.1 11232.2 11261.5 11353.1 05:52:26 INFO - 2015-11-19 05:52:26,924 DEBUG : BROWSER_OUTPUT: 05:52:26 INFO - 2015-11-19 05:52:26,924 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5493.18 Median:6500.69 stddev:2804.51 (43.1%) stddev-sans-first:2796.37 05:52:26 INFO - 2015-11-19 05:52:26,926 DEBUG : BROWSER_OUTPUT: Values: 1507.1 1646.4 1722.7 1727.2 1737.7 7411.5 7622.2 7625.4 7882.9 8072.5 7917.2 7943.6 7987.9 7991.3 7999.5 2280.0 2299.8 2313.3 2320.7 2333.1 2289.3 2338.1 2344.5 2348.9 2350.8 2055.6 2094.3 2305.7 2308.5 2356.7 2316.5 2355.8 2360.2 2360.9 2362.9 2260.5 2336.8 2339.5 2341.9 2343.3 7623.7 7636.1 7664.9 7668.2 7718.1 4729.6 4818.0 4819.0 4822.0 4838.6 3006.5 3122.7 3142.8 3147.3 3162.7 994.3 998.8 1021.6 1026.4 1059.7 10544.6 10703.9 10705.1 10715.9 10724.1 4350.1 4394.9 4447.1 4447.4 4474.8 9134.7 9240.8 9252.0 9284.3 9284.9 4795.1 4803.3 4805.8 4834.5 4834.8 4301.8 4348.4 4370.0 4391.6 4393.8 3540.3 3621.5 3625.0 3630.6 3647.9 1188.4 1208.7 1223.3 1223.3 1231.3 976.1 992.3 1006.3 1100.7 1319.5 7341.5 7348.6 7356.8 7364.6 7417.3 1170.6 1195.9 1226.7 1228.3 1238.4 5799.8 5807.9 5812.4 5831.6 5869.1 9051.5 9285.5 9343.2 9533.1 9582.1 9291.5 9314.6 9341.1 9341.9 9445.1 7122.4 7175.2 7230.5 7235.2 7305.5 7304.0 7331.0 7332.2 7332.5 7335.9 7756.9 7915.3 8023.4 8042.5 8138.2 8372.2 8417.5 8425.6 8443.5 8553.3 6903.5 7071.6 7104.1 7109.3 7120.1 7537.7 7544.9 7580.0 7594.5 7596.4 6525.2 6966.4 7133.8 7147.9 7244.3 6435.4 6483.8 6770.3 6802.5 7191.9 7073.1 7118.9 7172.0 7189.4 7206.2 6450.6 6458.8 6460.0 6517.5 6530.1 6720.6 6725.6 6758.9 6759.5 6782.5 05:52:26 INFO - 2015-11-19 05:52:26,926 DEBUG : BROWSER_OUTPUT: 05:52:26 INFO - 2015-11-19 05:52:26,926 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5629.96 Median:6384.57 stddev:1729.99 (27.1%) stddev-sans-first:1688.46 05:52:26 INFO - 2015-11-19 05:52:26,928 DEBUG : BROWSER_OUTPUT: Values: 328.8 329.4 337.3 337.5 339.9 6352.9 6353.2 6368.1 6371.6 6556.7 6330.0 6335.1 6341.0 6346.4 6388.0 4825.5 4835.9 4841.5 4842.9 4854.2 4830.8 4835.2 4842.8 4848.4 4850.9 4695.1 4711.9 4759.9 4793.5 4797.1 4749.1 4758.9 4773.3 4778.4 4794.0 4126.0 4128.1 4132.3 4132.4 4145.8 5979.9 5991.9 6008.8 6010.9 6033.2 5429.1 5518.1 5574.5 5596.6 5602.0 6747.9 6760.9 6765.2 6770.6 6772.3 4590.4 4593.7 4600.7 4606.9 4609.6 6779.8 6919.9 6974.3 7013.7 7015.1 4610.9 4677.7 4678.7 4684.9 4694.8 4717.6 4739.1 4742.8 4753.3 4755.0 5505.2 5521.6 5522.7 5527.3 5529.5 6596.3 6730.7 6747.3 6751.7 6757.1 1625.7 1651.1 1652.3 1656.9 1657.3 1053.2 1065.7 1077.8 1082.1 1100.6 6362.0 6386.5 6412.2 6441.7 6450.1 6831.8 6862.2 6877.4 6880.9 6882.9 6332.7 6382.6 6391.0 6425.0 6436.9 3776.7 3787.5 3790.1 3814.6 3830.8 7309.8 7315.3 7319.0 7325.2 7354.3 7049.9 7166.9 7181.9 7210.1 7252.4 7238.1 7250.0 7297.4 7306.7 7340.0 6947.2 7005.6 7016.2 7018.8 7055.1 6852.4 6917.2 6945.9 6963.5 6968.5 7284.2 7326.9 7335.3 7339.3 7352.6 4789.6 4801.3 4802.9 4809.9 4840.5 6703.0 6758.0 6874.3 6884.2 6943.9 6763.6 6780.1 6796.0 6828.5 6832.4 6806.4 6813.8 6825.6 6826.0 6833.1 6567.6 6676.4 6679.0 6686.9 6695.3 6872.7 6880.2 6898.1 6901.8 6909.8 6638.9 6702.8 6710.6 6711.7 6714.8 05:52:26 INFO - 2015-11-19 05:52:26,928 DEBUG : BROWSER_OUTPUT: 05:52:26 INFO - 2015-11-19 05:52:26,928 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13563.06 Median:47337.88 stddev:15944.55 (33.7%) stddev-sans-first:15959.97 05:52:26 INFO - 2015-11-19 05:52:26,930 DEBUG : BROWSER_OUTPUT: Values: 689.4 749.7 832.3 859.7 874.2 13191.9 13212.9 13353.4 13367.9 13410.8 11627.4 12834.9 13134.9 13461.4 13535.7 800.0 813.0 820.2 822.9 823.6 815.4 822.3 830.2 830.9 834.5 850.1 858.8 861.7 863.1 869.2 843.8 857.6 861.4 862.8 864.1 630.2 630.7 631.4 631.8 633.7 12506.6 12644.7 12651.9 12697.6 12716.8 10952.8 10966.6 10971.8 11267.1 11302.4 9825.9 9967.5 10072.6 10088.9 10093.8 3243.4 3310.7 3440.4 3499.2 3602.7 37595.4 38146.6 38178.6 44658.1 48442.5 9172.9 9562.7 9681.9 9716.0 9767.2 19046.9 19534.7 19542.9 19571.3 19645.2 7368.6 7777.8 7786.2 7931.4 8033.4 4045.9 4091.0 4166.9 4244.0 4291.7 3223.4 3225.2 3231.0 3252.5 3253.8 458.7 478.7 479.1 608.1 627.8 436.3 487.9 568.3 569.0 634.4 8167.8 8380.7 8395.4 8615.3 8717.7 562.5 563.9 571.1 576.2 581.7 3903.4 3982.3 4026.4 4106.7 4222.2 56881.0 58733.0 58913.4 59147.0 59232.0 52295.5 52976.5 53062.5 53115.0 53373.5 43922.3 44856.3 45741.3 45849.8 46669.3 8217.8 8347.1 8402.7 8433.7 8591.9 33336.0 34671.5 34830.0 36785.4 37783.0 46365.3 46790.5 46884.5 46895.8 47780.0 8128.0 8237.3 8273.3 8319.7 8384.8 11584.1 11835.5 11879.7 11917.2 11947.6 10176.4 10317.1 10402.5 10596.4 10644.6 10789.4 11197.7 11610.0 11633.7 12015.4 11208.5 11305.5 11336.2 11481.6 11771.4 9555.1 9947.4 10060.2 10096.7 10655.5 8797.0 8851.7 9034.0 9279.3 9366.9 05:52:26 INFO - 2015-11-19 05:52:26,930 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 05:52:26 INFO - 2015-11-19 05:52:26,930 DEBUG : BROWSER_OUTPUT: 05:52:27 INFO - 2015-11-19 05:52:27,602 INFO : Browser exited with error code: 0 05:52:27 INFO - 2015-11-19 05:52:27,620 INFO : Completed test dromaeo_css (00:18:30) 05:52:27 INFO - 2015-11-19 05:52:27,620 INFO : Starting test kraken 05:52:27 INFO - 2015-11-19 05:52:27,620 DEBUG : operating with platform_type : linux_ 05:52:27 INFO - 2015-11-19 05:52:27,621 INFO : Initialising browser for kraken test... 05:52:27 INFO - 2015-11-19 05:52:27,627 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp1lZFB3/profile http://localhost:35227/getInfo.html 05:52:31 INFO - 2015-11-19 05:52:31,520 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:52:31 INFO - 2015-11-19 05:52:31,520 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:52:31 INFO - 2015-11-19 05:52:31,520 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/668 05:52:31 INFO - 2015-11-19 05:52:31,521 DEBUG : BROWSER_OUTPUT: __metrics 05:52:32 INFO - 2015-11-19 05:52:32,497 INFO : Browser initialized. 05:52:32 INFO - 2015-11-19 05:52:32,498 INFO : Running cycle 1/1 for kraken test... 05:52:32 INFO - 2015-11-19 05:52:32,498 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp1lZFB3/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 05:52:44 INFO - 2015-11-19 05:52:44,255 DEBUG : BROWSER_OUTPUT: RSS: Main: 142090240 05:52:44 INFO - 2015-11-19 05:52:44,255 DEBUG : BROWSER_OUTPUT: 05:52:46 INFO - 2015-11-19 05:52:46,750 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35227/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35227/tests/kraken/driver.html?testName=audio-beat-detection) 05:52:47 INFO - 2015-11-19 05:52:47,352 DEBUG : BROWSER_OUTPUT: RSS: Main: 143355904 05:52:47 INFO - 2015-11-19 05:52:47,352 DEBUG : BROWSER_OUTPUT: 05:52:49 INFO - 2015-11-19 05:52:49,997 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35227/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35227/tests/kraken/driver.html?testName=audio-dft) 05:52:50 INFO - 2015-11-19 05:52:50,592 DEBUG : BROWSER_OUTPUT: RSS: Main: 142585856 05:52:50 INFO - 2015-11-19 05:52:50,592 DEBUG : BROWSER_OUTPUT: 05:52:53 INFO - 2015-11-19 05:52:53,436 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35227/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35227/tests/kraken/driver.html?testName=audio-fft) 05:52:54 INFO - 2015-11-19 05:52:54,009 DEBUG : BROWSER_OUTPUT: RSS: Main: 138141696 05:52:54 INFO - 2015-11-19 05:52:54,009 DEBUG : BROWSER_OUTPUT: 05:52:55 INFO - 2015-11-19 05:52:55,983 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35227/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35227/tests/kraken/driver.html?testName=audio-oscillator) 05:52:56 INFO - 2015-11-19 05:52:56,625 DEBUG : BROWSER_OUTPUT: RSS: Main: 138469376 05:52:56 INFO - 2015-11-19 05:52:56,626 DEBUG : BROWSER_OUTPUT: 05:52:58 INFO - 2015-11-19 05:52:58,828 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35227/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35227/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:52:59 INFO - 2015-11-19 05:52:59,410 DEBUG : BROWSER_OUTPUT: RSS: Main: 138764288 05:52:59 INFO - 2015-11-19 05:52:59,410 DEBUG : BROWSER_OUTPUT: 05:53:02 INFO - 2015-11-19 05:53:02,435 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35227/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35227/tests/kraken/driver.html?testName=imaging-darkroom) 05:53:03 INFO - 2015-11-19 05:53:03,021 DEBUG : BROWSER_OUTPUT: RSS: Main: 137588736 05:53:03 INFO - 2015-11-19 05:53:03,021 DEBUG : BROWSER_OUTPUT: 05:53:06 INFO - 2015-11-19 05:53:06,447 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35227/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35227/tests/kraken/driver.html?testName=imaging-desaturate) 05:53:07 INFO - 2015-11-19 05:53:07,036 DEBUG : BROWSER_OUTPUT: RSS: Main: 139055104 05:53:07 INFO - 2015-11-19 05:53:07,037 DEBUG : BROWSER_OUTPUT: 05:53:09 INFO - 2015-11-19 05:53:09,903 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35227/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35227/tests/kraken/driver.html?testName=json-parse-financial) 05:53:10 INFO - 2015-11-19 05:53:10,496 DEBUG : BROWSER_OUTPUT: RSS: Main: 138969088 05:53:10 INFO - 2015-11-19 05:53:10,497 DEBUG : BROWSER_OUTPUT: 05:53:12 INFO - 2015-11-19 05:53:12,093 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35227/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35227/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:53:12 INFO - 2015-11-19 05:53:12,674 DEBUG : BROWSER_OUTPUT: RSS: Main: 139603968 05:53:12 INFO - 2015-11-19 05:53:12,675 DEBUG : BROWSER_OUTPUT: 05:53:14 INFO - 2015-11-19 05:53:14,516 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35227/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35227/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:53:15 INFO - 2015-11-19 05:53:15,122 DEBUG : BROWSER_OUTPUT: RSS: Main: 139137024 05:53:15 INFO - 2015-11-19 05:53:15,122 DEBUG : BROWSER_OUTPUT: 05:53:17 INFO - 2015-11-19 05:53:17,226 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35227/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35227/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:53:17 INFO - 2015-11-19 05:53:17,812 DEBUG : BROWSER_OUTPUT: RSS: Main: 140869632 05:53:17 INFO - 2015-11-19 05:53:17,812 DEBUG : BROWSER_OUTPUT: 05:53:20 INFO - 2015-11-19 05:53:20,319 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35227/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35227/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:53:20 INFO - 2015-11-19 05:53:20,912 DEBUG : BROWSER_OUTPUT: RSS: Main: 139571200 05:53:20 INFO - 2015-11-19 05:53:20,912 DEBUG : BROWSER_OUTPUT: 05:53:23 INFO - 2015-11-19 05:53:23,508 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35227/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35227/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:53:24 INFO - 2015-11-19 05:53:24,094 DEBUG : BROWSER_OUTPUT: RSS: Main: 140267520 05:53:24 INFO - 2015-11-19 05:53:24,094 DEBUG : BROWSER_OUTPUT: 05:53:25 INFO - 2015-11-19 05:53:25,804 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35227/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35227/tests/kraken/driver.html?testName=ai-astar) 05:53:26 INFO - 2015-11-19 05:53:26,051 DEBUG : BROWSER_OUTPUT: RSS: Main: 140066816 05:53:26 INFO - 2015-11-19 05:53:26,051 DEBUG : BROWSER_OUTPUT: 05:53:26 INFO - 2015-11-19 05:53:26,052 DEBUG : BROWSER_OUTPUT: __start_tp_report 05:53:26 INFO - 2015-11-19 05:53:26,053 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 05:53:26 INFO - 2015-11-19 05:53:26,053 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 05:53:26 INFO - 2015-11-19 05:53:26,053 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 05:53:26 INFO - 2015-11-19 05:53:26,053 DEBUG : BROWSER_OUTPUT: |0;ai-astar;112;108;111;113;113;124;111;112;110;112 05:53:26 INFO - 2015-11-19 05:53:26,053 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;158;126;151;157;132;150;161;127;166;127 05:53:26 INFO - 2015-11-19 05:53:26,053 DEBUG : BROWSER_OUTPUT: |2;audio-dft;163;164;163;162;163;160;162;162;176;167 05:53:26 INFO - 2015-11-19 05:53:26,053 DEBUG : BROWSER_OUTPUT: |3;audio-fft;72;72;72;73;75;149;73;74;74;75 05:53:26 INFO - 2015-11-19 05:53:26,053 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;91;92;140;90;90;91;144;91;92;141 05:53:26 INFO - 2015-11-19 05:53:26,053 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;110;110;110;110;110;109;110;110;110 05:53:26 INFO - 2015-11-19 05:53:26,054 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;146;148;146;146;146;146;147;147;148;147 05:53:26 INFO - 2015-11-19 05:53:26,054 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;96;98;96;96;96;96;96;95;96 05:53:26 INFO - 2015-11-19 05:53:26,054 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;61;60;63;61;61;60;61;67;66;61 05:53:26 INFO - 2015-11-19 05:53:26,054 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;68;68;69;69;69;69;69;69;69;70 05:53:26 INFO - 2015-11-19 05:53:26,054 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;85;84;84;92;85;84;87;84;94;84 05:53:26 INFO - 2015-11-19 05:53:26,054 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;131;120;130;132;120;134;121;126;135;125 05:53:26 INFO - 2015-11-19 05:53:26,054 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;145;157;157;152;151;145;151;157;148;140 05:53:26 INFO - 2015-11-19 05:53:26,054 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;69;80;69;68;68;70;69;67;72;68 05:53:26 INFO - 2015-11-19 05:53:26,054 DEBUG : BROWSER_OUTPUT: __end_tp_report 05:53:26 INFO - 2015-11-19 05:53:26,054 DEBUG : BROWSER_OUTPUT: __start_cc_report 05:53:26 INFO - 2015-11-19 05:53:26,054 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,696 05:53:26 INFO - 2015-11-19 05:53:26,054 DEBUG : BROWSER_OUTPUT: __end_cc_report 05:53:26 INFO - 2015-11-19 05:53:26,054 DEBUG : BROWSER_OUTPUT: __startTimestamp1447941206052__endTimestamp 05:53:26 INFO - 2015-11-19 05:53:26,054 DEBUG : BROWSER_OUTPUT: 05:53:26 INFO - 2015-11-19 05:53:26,058 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 05:53:26 INFO - 2015-11-19 05:53:26,058 DEBUG : BROWSER_OUTPUT: Number of tests: 14 05:53:26 INFO - 2015-11-19 05:53:26,058 DEBUG : BROWSER_OUTPUT: 05:53:26 INFO - 2015-11-19 05:53:26,058 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:112.60 Median:112.00 stddev:4.27 (3.8%) stddev-sans-first:4.53 05:53:26 INFO - 2015-11-19 05:53:26,058 DEBUG : BROWSER_OUTPUT: Values: 112.0 108.0 111.0 113.0 113.0 124.0 111.0 112.0 110.0 112.0 05:53:26 INFO - 2015-11-19 05:53:26,058 DEBUG : BROWSER_OUTPUT: 05:53:26 INFO - 2015-11-19 05:53:26,058 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:145.50 Median:154.00 stddev:15.80 (10.3%) stddev-sans-first:16.10 05:53:26 INFO - 2015-11-19 05:53:26,058 DEBUG : BROWSER_OUTPUT: Values: 158.0 126.0 151.0 157.0 132.0 150.0 161.0 127.0 166.0 127.0 05:53:26 INFO - 2015-11-19 05:53:26,058 DEBUG : BROWSER_OUTPUT: 05:53:26 INFO - 2015-11-19 05:53:26,059 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:164.20 Median:163.00 stddev:4.52 (2.8%) stddev-sans-first:4.77 05:53:26 INFO - 2015-11-19 05:53:26,059 DEBUG : BROWSER_OUTPUT: Values: 163.0 164.0 163.0 162.0 163.0 160.0 162.0 162.0 176.0 167.0 05:53:26 INFO - 2015-11-19 05:53:26,059 DEBUG : BROWSER_OUTPUT: 05:53:26 INFO - 2015-11-19 05:53:26,059 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:80.90 Median:73.50 stddev:23.96 (32.6%) stddev-sans-first:25.19 05:53:26 INFO - 2015-11-19 05:53:26,059 DEBUG : BROWSER_OUTPUT: Values: 72.0 72.0 72.0 73.0 75.0 149.0 73.0 74.0 74.0 75.0 05:53:26 INFO - 2015-11-19 05:53:26,059 DEBUG : BROWSER_OUTPUT: 05:53:26 INFO - 2015-11-19 05:53:26,059 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.20 Median:91.00 stddev:24.50 (26.9%) stddev-sans-first:25.36 05:53:26 INFO - 2015-11-19 05:53:26,060 DEBUG : BROWSER_OUTPUT: Values: 91.0 92.0 140.0 90.0 90.0 91.0 144.0 91.0 92.0 141.0 05:53:26 INFO - 2015-11-19 05:53:26,060 DEBUG : BROWSER_OUTPUT: 05:53:26 INFO - 2015-11-19 05:53:26,060 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.90 Median:110.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 05:53:26 INFO - 2015-11-19 05:53:26,060 DEBUG : BROWSER_OUTPUT: Values: 110.0 110.0 110.0 110.0 110.0 110.0 109.0 110.0 110.0 110.0 05:53:26 INFO - 2015-11-19 05:53:26,060 DEBUG : BROWSER_OUTPUT: 05:53:26 INFO - 2015-11-19 05:53:26,060 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:146.70 Median:147.00 stddev:0.82 (0.6%) stddev-sans-first:0.83 05:53:26 INFO - 2015-11-19 05:53:26,060 DEBUG : BROWSER_OUTPUT: Values: 146.0 148.0 146.0 146.0 146.0 146.0 147.0 147.0 148.0 147.0 05:53:26 INFO - 2015-11-19 05:53:26,061 DEBUG : BROWSER_OUTPUT: 05:53:26 INFO - 2015-11-19 05:53:26,061 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:96.10 Median:96.00 stddev:0.74 (0.8%) stddev-sans-first:0.78 05:53:26 INFO - 2015-11-19 05:53:26,061 DEBUG : BROWSER_OUTPUT: Values: 96.0 96.0 98.0 96.0 96.0 96.0 96.0 96.0 95.0 96.0 05:53:26 INFO - 2015-11-19 05:53:26,061 DEBUG : BROWSER_OUTPUT: 05:53:26 INFO - 2015-11-19 05:53:26,061 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:62.10 Median:61.00 stddev:2.47 (4.0%) stddev-sans-first:2.59 05:53:26 INFO - 2015-11-19 05:53:26,061 DEBUG : BROWSER_OUTPUT: Values: 61.0 60.0 63.0 61.0 61.0 60.0 61.0 67.0 66.0 61.0 05:53:26 INFO - 2015-11-19 05:53:26,061 DEBUG : BROWSER_OUTPUT: 05:53:26 INFO - 2015-11-19 05:53:26,061 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:68.90 Median:69.00 stddev:0.57 (0.8%) stddev-sans-first:0.50 05:53:26 INFO - 2015-11-19 05:53:26,061 DEBUG : BROWSER_OUTPUT: Values: 68.0 68.0 69.0 69.0 69.0 69.0 69.0 69.0 69.0 70.0 05:53:26 INFO - 2015-11-19 05:53:26,061 DEBUG : BROWSER_OUTPUT: 05:53:26 INFO - 2015-11-19 05:53:26,062 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:86.30 Median:85.00 stddev:3.68 (4.3%) stddev-sans-first:3.88 05:53:26 INFO - 2015-11-19 05:53:26,062 DEBUG : BROWSER_OUTPUT: Values: 85.0 84.0 84.0 92.0 85.0 84.0 87.0 84.0 94.0 84.0 05:53:26 INFO - 2015-11-19 05:53:26,062 DEBUG : BROWSER_OUTPUT: 05:53:26 INFO - 2015-11-19 05:53:26,062 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:127.40 Median:130.50 stddev:5.78 (4.4%) stddev-sans-first:5.98 05:53:26 INFO - 2015-11-19 05:53:26,062 DEBUG : BROWSER_OUTPUT: Values: 131.0 120.0 130.0 132.0 120.0 134.0 121.0 126.0 135.0 125.0 05:53:26 INFO - 2015-11-19 05:53:26,062 DEBUG : BROWSER_OUTPUT: 05:53:26 INFO - 2015-11-19 05:53:26,062 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:150.30 Median:151.50 stddev:5.83 (3.8%) stddev-sans-first:5.86 05:53:26 INFO - 2015-11-19 05:53:26,062 DEBUG : BROWSER_OUTPUT: Values: 145.0 157.0 157.0 152.0 151.0 145.0 151.0 157.0 148.0 140.0 05:53:26 INFO - 2015-11-19 05:53:26,062 DEBUG : BROWSER_OUTPUT: 05:53:26 INFO - 2015-11-19 05:53:26,063 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.00 Median:69.00 stddev:3.77 (5.5%) stddev-sans-first:3.98 05:53:26 INFO - 2015-11-19 05:53:26,063 DEBUG : BROWSER_OUTPUT: Values: 69.0 80.0 69.0 68.0 68.0 70.0 69.0 67.0 72.0 68.0 05:53:26 INFO - 2015-11-19 05:53:26,063 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 05:53:26 INFO - 2015-11-19 05:53:26,063 DEBUG : BROWSER_OUTPUT: 05:53:26 INFO - 2015-11-19 05:53:26,929 INFO : Browser exited with error code: 0 05:53:26 INFO - 2015-11-19 05:53:26,980 INFO : Completed test kraken (00:00:59) 05:53:26 INFO - 2015-11-19 05:53:26,980 INFO : Starting test v8_7 05:53:26 INFO - 2015-11-19 05:53:26,980 DEBUG : operating with platform_type : linux_ 05:53:26 INFO - 2015-11-19 05:53:26,980 INFO : Initialising browser for v8_7 test... 05:53:26 INFO - 2015-11-19 05:53:26,986 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpoPtI5A/profile http://localhost:35227/getInfo.html 05:53:30 INFO - 2015-11-19 05:53:30,906 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:53:30 INFO - 2015-11-19 05:53:30,907 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:53:30 INFO - 2015-11-19 05:53:30,907 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/668 05:53:30 INFO - 2015-11-19 05:53:30,907 DEBUG : BROWSER_OUTPUT: __metrics 05:53:31 INFO - 2015-11-19 05:53:31,901 INFO : Browser initialized. 05:53:31 INFO - 2015-11-19 05:53:31,902 INFO : Running cycle 1/1 for v8_7 test... 05:53:31 INFO - 2015-11-19 05:53:31,902 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpoPtI5A/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 05:53:43 INFO - 2015-11-19 05:53:43,757 DEBUG : BROWSER_OUTPUT: RSS: Main: 142389248 05:53:43 INFO - 2015-11-19 05:53:43,757 DEBUG : BROWSER_OUTPUT: 05:54:05 INFO - 2015-11-19 05:54:05,158 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35227/tests/v8_7/run.html (next: http://localhost:35227/tests/v8_7/run.html) 05:54:05 INFO - 2015-11-19 05:54:05,408 DEBUG : BROWSER_OUTPUT: RSS: Main: 138682368 05:54:05 INFO - 2015-11-19 05:54:05,408 DEBUG : BROWSER_OUTPUT: 05:54:05 INFO - 2015-11-19 05:54:05,408 DEBUG : BROWSER_OUTPUT: __start_tp_report 05:54:05 INFO - 2015-11-19 05:54:05,408 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 05:54:05 INFO - 2015-11-19 05:54:05,408 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 05:54:05 INFO - 2015-11-19 05:54:05,408 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 05:54:05 INFO - 2015-11-19 05:54:05,408 DEBUG : BROWSER_OUTPUT: |0;Richards;164.88046166529267 05:54:05 INFO - 2015-11-19 05:54:05,409 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;186.81113394358303 05:54:05 INFO - 2015-11-19 05:54:05,409 DEBUG : BROWSER_OUTPUT: |2;Encrypt;280.74115665356544 05:54:05 INFO - 2015-11-19 05:54:05,409 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5040.201005025126 05:54:05 INFO - 2015-11-19 05:54:05,409 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1054.8523206751054 05:54:05 INFO - 2015-11-19 05:54:05,409 DEBUG : BROWSER_OUTPUT: |5;Earley;703.2348804500704 05:54:05 INFO - 2015-11-19 05:54:05,410 DEBUG : BROWSER_OUTPUT: |6;Boyer;12256.09756097561 05:54:05 INFO - 2015-11-19 05:54:05,410 DEBUG : BROWSER_OUTPUT: |7;RegExp;25375 05:54:05 INFO - 2015-11-19 05:54:05,410 DEBUG : BROWSER_OUTPUT: |8;Splay;697.9958534899793 05:54:05 INFO - 2015-11-19 05:54:05,410 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4840.579710144928 05:54:05 INFO - 2015-11-19 05:54:05,410 DEBUG : BROWSER_OUTPUT: __end_tp_report 05:54:05 INFO - 2015-11-19 05:54:05,411 DEBUG : BROWSER_OUTPUT: __startTimestamp1447941245406__endTimestamp 05:54:05 INFO - 2015-11-19 05:54:05,411 DEBUG : BROWSER_OUTPUT: 05:54:05 INFO - 2015-11-19 05:54:05,411 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 05:54:05 INFO - 2015-11-19 05:54:05,411 DEBUG : BROWSER_OUTPUT: Number of tests: 10 05:54:05 INFO - 2015-11-19 05:54:05,411 DEBUG : BROWSER_OUTPUT: 05:54:05 INFO - 2015-11-19 05:54:05,412 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:164.88 Median:164.88 stddev:0.00 (0.0%) 05:54:05 INFO - 2015-11-19 05:54:05,412 DEBUG : BROWSER_OUTPUT: Values: 164.9 05:54:05 INFO - 2015-11-19 05:54:05,412 DEBUG : BROWSER_OUTPUT: 05:54:05 INFO - 2015-11-19 05:54:05,412 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:186.81 Median:186.81 stddev:0.00 (0.0%) 05:54:05 INFO - 2015-11-19 05:54:05,412 DEBUG : BROWSER_OUTPUT: Values: 186.8 05:54:05 INFO - 2015-11-19 05:54:05,412 DEBUG : BROWSER_OUTPUT: 05:54:05 INFO - 2015-11-19 05:54:05,413 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:280.74 Median:280.74 stddev:0.00 (0.0%) 05:54:05 INFO - 2015-11-19 05:54:05,413 DEBUG : BROWSER_OUTPUT: Values: 280.7 05:54:05 INFO - 2015-11-19 05:54:05,413 DEBUG : BROWSER_OUTPUT: 05:54:05 INFO - 2015-11-19 05:54:05,413 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5040.20 Median:5040.20 stddev:0.00 (0.0%) 05:54:05 INFO - 2015-11-19 05:54:05,413 DEBUG : BROWSER_OUTPUT: Values: 5040.2 05:54:05 INFO - 2015-11-19 05:54:05,413 DEBUG : BROWSER_OUTPUT: 05:54:05 INFO - 2015-11-19 05:54:05,413 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1054.85 Median:1054.85 stddev:0.00 (0.0%) 05:54:05 INFO - 2015-11-19 05:54:05,414 DEBUG : BROWSER_OUTPUT: Values: 1054.9 05:54:05 INFO - 2015-11-19 05:54:05,414 DEBUG : BROWSER_OUTPUT: 05:54:05 INFO - 2015-11-19 05:54:05,414 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:703.23 Median:703.23 stddev:0.00 (0.0%) 05:54:05 INFO - 2015-11-19 05:54:05,414 DEBUG : BROWSER_OUTPUT: Values: 703.2 05:54:05 INFO - 2015-11-19 05:54:05,414 DEBUG : BROWSER_OUTPUT: 05:54:05 INFO - 2015-11-19 05:54:05,414 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12256.10 Median:12256.10 stddev:0.00 (0.0%) 05:54:05 INFO - 2015-11-19 05:54:05,414 DEBUG : BROWSER_OUTPUT: Values: 12256.1 05:54:05 INFO - 2015-11-19 05:54:05,414 DEBUG : BROWSER_OUTPUT: 05:54:05 INFO - 2015-11-19 05:54:05,414 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:25375.00 Median:25375.00 stddev:0.00 (0.0%) 05:54:05 INFO - 2015-11-19 05:54:05,414 DEBUG : BROWSER_OUTPUT: Values: 25375.0 05:54:05 INFO - 2015-11-19 05:54:05,415 DEBUG : BROWSER_OUTPUT: 05:54:05 INFO - 2015-11-19 05:54:05,415 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:698.00 Median:698.00 stddev:0.00 (0.0%) 05:54:05 INFO - 2015-11-19 05:54:05,415 DEBUG : BROWSER_OUTPUT: Values: 698.0 05:54:05 INFO - 2015-11-19 05:54:05,415 DEBUG : BROWSER_OUTPUT: 05:54:05 INFO - 2015-11-19 05:54:05,415 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4840.58 Median:4840.58 stddev:0.00 (0.0%) 05:54:05 INFO - 2015-11-19 05:54:05,415 DEBUG : BROWSER_OUTPUT: Values: 4840.6 05:54:05 INFO - 2015-11-19 05:54:05,415 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 05:54:05 INFO - 2015-11-19 05:54:05,415 DEBUG : BROWSER_OUTPUT: 05:54:06 INFO - 2015-11-19 05:54:06,269 INFO : Browser exited with error code: 0 05:54:06 INFO - 2015-11-19 05:54:06,281 INFO : Completed test v8_7 (00:00:39) 05:54:06 INFO - 2015-11-19 05:54:06,397 INFO : Completed test suite (00:20:09) 05:54:06 INFO - 2015-11-19 05:54:06,397 DEBUG : Working with test: dromaeo_css 05:54:06 INFO - 2015-11-19 05:54:06,397 DEBUG : Generating results file: dromaeo_css 05:54:06 INFO - 2015-11-19 05:54:06,399 DEBUG : Working with test: kraken 05:54:06 INFO - 2015-11-19 05:54:06,399 DEBUG : Generating results file: kraken 05:54:06 INFO - 2015-11-19 05:54:06,399 INFO : javascript benchmark 05:54:06 INFO - 2015-11-19 05:54:06,399 DEBUG : Working with test: v8_7 05:54:06 INFO - 2015-11-19 05:54:06,399 DEBUG : Generating results file: v8_7 05:54:06 INFO - 2015-11-19 05:54:06,400 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:54:06 INFO - 2015-11-19 05:54:06,456 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,1,43]] 05:54:06 INFO - 2015-11-19 05:54:06,456 DEBUG : process_Request line: dromaeo_css 6963.30 graph.html#tests=[[72,1,43]] 05:54:06 INFO - 2015-11-19 05:54:06,456 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:54:06 INFO - 2015-11-19 05:54:06,487 DEBUG : process_Request line: kraken 1527.10 graph.html#tests=[[232,1,43]] 05:54:06 INFO - 2015-11-19 05:54:06,487 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:54:06 INFO - 2015-11-19 05:54:06,516 DEBUG : process_Request line: v8_7 21201.36 graph.html#tests=[[230,1,43]] 05:54:06 INFO - 2015-11-19 05:54:06,518 INFO : javascript benchmark 05:54:06 INFO - 2015-11-19 05:54:06,522 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3188.5057816038516, 3262.314913868089, 3288.358770039843, 3305.5372247885302, 3308.5037244413334, 5308.5487733542695, 5422.566194704419, 5437.483439073281, 5441.891162176754, 5486.3690675572325, 5329.626926115174, 5396.217548455472, 5422.132458806592, 5423.078076726956, 5432.9185062224, 4943.975280123604, 5046.596272298208, 5095.312132862054, 5130.666506677054, 5146.382434107913, 5083.847484575469, 5088.4148322942965, 5167.7932882684645, 5171.818986335479, 5232.686038837663, 4900.509949005107, 4965.950340496621, 5017.824376146854, 5084.51697088776, 5102.413222479407, 5057.317262169634, 5060.139776238048, 5061.063703214917, 5068.011737711147, 5093.388793344822, 4998.3502144721315, 5044.747762611892, 5058.0895438821, 5063.316452278932, 5103.974480127613, 5175.223716442564, 5205.895882082337, 5232.2674825524455, 5290.656107353048, 5373.892522149574, 5937.376575459558, 5944.10838374243, 5959.195508606369, 5962.403759624046, 5968.373320801296, 5989.341172471069, 5996.100584912255, 6010.978133717234, 6016.037434010581, 6017.217761690996, 5370.5435038021815, 5469.453054694499, 5499.972500137514, 5553.666779993173, 5623.0, 5973.551983601247, 6020.096985452217, 6029.065494848305, 6042.848928776816, 6045.486133678686, 5824.592278540546, 5825.155352473867, 5830.76676932918, 5831.183634291203, 5835.0, 5729.598928075078, 5802.738876750556, 5838.883222335615, 5909.674967876808, 5959.165716799652, 5850.94149058504, 5991.281046274475, 5991.430814072656, 5994.940050599525, 5995.130706047598, 5691.886162276731, 5989.2812862456785, 5994.0, 6013.579049466583, 6014.578979471483, 4453.599176074169, 4557.635389168859, 4559.886002849956, 4567.725936443824, 4570.7257564546235, 4014.698897582693, 4039.6970227233073, 4047.615476529725, 4052.0, 4076.5108187017154, 5595.468430499096, 5615.129654903415, 5641.746121424546, 5652.378238393773, 5684.77260909559, 5807.912881306784, 5863.355030946593, 5920.171176035358, 5935.139404786281, 5942.910856337158, 5545.972270138624, 5610.410906854721, 5653.745581448845, 5681.630694004876, 5689.231953686282, 5028.19548872186, 5211.791528338824, 5220.425753167148, 5226.738663066832, 5265.447128051499, 5937.287525496968, 6039.184710064085, 6049.8487537811025, 6066.666333351708, 6107.704691583745, 5951.315598706031, 5971.343152253162, 5991.820245392645, 5992.520598352208, 6003.969980150246, 5944.613600115978, 6120.326764056039, 6138.693065346804, 6148.538859585459, 6161.199044124235, 5811.4479124483105, 5815.185873977731, 5832.387599302182, 5874.412558744262, 5918.348981611933, 5955.225820643289, 5969.791057312973, 5973.163757073926, 5989.520838332835, 5992.880142397041, 5738.9139162913425, 5846.152307915413, 5855.502282306052, 5883.941160588511, 5914.467697907209, 5722.971385143048, 5826.621269617462, 5862.912056319244, 5887.8233652990475, 5895.793647222326, 5710.600257982068, 5720.942790572041, 5751.338596061507, 5760.481556659753, 5779.942200577941, 5716.485516303553, 5834.854128646648, 5868.2664666916635, 5887.20522729426, 5889.0, 5627.774889004394, 5677.630953987978, 5768.307803063659, 5782.392848750821, 5799.188113664175], "unit": "ms", "name": "dojo.html", "value": 5464.569053164828}, {"lowerIsBetter": false, "replicates": [537.9004884096443, 601.314501468326, 602.4638072115815, 608.0271565495208, 612.9111278864565, 39184.99999999996, 39906.20187596246, 40336.798316008455, 40515.797421012925, 40541.39187912184, 42141.0, 42616.360754588706, 42693.573064269345, 42868.785656071675, 43114.35328470068, 8651.74044778656, 8827.867581986275, 8828.426152300111, 8836.248918841906, 8840.646374145059, 8561.1866872647, 8624.0, 8639.39524233304, 8688.56557172142, 8690.652373905068, 8619.1811777881, 8662.30701543878, 8689.087645797195, 8699.95650021749, 8739.912600874006, 8574.228319451247, 8593.312534997185, 8641.0, 8692.304615630734, 8695.695650652196, 8476.449030813039, 8520.275776559, 8590.527520986343, 8601.5699215039, 8607.225349718556, 39494.605053949665, 39909.20181596352, 39959.8002009991, 39995.0, 40085.79957100225, 3215.9678403215903, 3320.5019247112887, 3342.74929380295, 3456.9308613827834, 3612.0066981580276, 29282.24306541593, 29391.2652183695, 29561.26096847596, 29922.40155196884, 29956.101316960525, 3529.2235708144167, 3568.518250036238, 3585.46218067292, 3607.4408466687705, 3671.724620653435, 272299.0, 272767.0, 273013.0, 273743.0, 274752.0, 49736.25395619069, 51027.23459148116, 55721.442785572435, 57637.71181144068, 58938.51676315602, 55882.44117558854, 55911.720441397534, 55948.440515594324, 55991.0, 56178.71910640502, 14867.851321486647, 15131.0, 15141.848581514043, 15233.23833808305, 15261.9236903817, 11074.280171788809, 11250.831237531444, 11255.099592032782, 11322.716932076764, 11410.885891141148, 19285.61428771445, 19364.322248721226, 19381.321653742052, 19414.12636431364, 19440.125194366006, 7542.208068152875, 7721.729739459092, 7736.381089512825, 7796.4932279401655, 7801.9609901950125, 950.6815216902415, 1020.3316827478013, 1043.4000449741277, 1049.7795462952865, 1058.8200005999, 27974.580381294298, 28065.859670701517, 28693.0, 28699.0, 28893.133206003855, 1004.1866088468364, 1009.2380252909154, 1059.210887888528, 1117.6479408986143, 1134.5178299222962, 13816.861831381759, 13858.861411385757, 13876.375563099482, 14050.789238161437, 14098.929505352613, 325265.2420427391, 332420.0, 332792.0, 333303.0, 334701.0, 49677.50322496801, 49859.50140498549, 49868.750656246484, 49930.75034624804, 49964.500354996715, 1340.9463621455227, 1345.0382976172084, 1358.891288696902, 1378.7862881253423, 1475.1149310413666, 26026.21921342363, 26938.865305673346, 27309.180724578295, 27412.45175096527, 28375.290617733895, 44080.338794918745, 44599.77700111479, 44712.32931506095, 44817.10365792731, 44889.32666009947, 218090.0, 218743.0, 218897.0, 219644.0, 220497.0, 30961.380772384877, 31531.21171970719, 31644.841775790974, 31724.365512689154, 31922.042338729876, 39180.41229381619, 39244.80377598094, 39727.404088938114, 39847.0, 40059.79970100248, 33400.83299583486, 34990.825045874604, 35656.93029209128, 36013.0, 36051.459228111074, 35711.46432803562, 36047.639523604426, 36538.8173059133, 36736.26527469489, 36738.448923265634, 34739.478907816905, 35120.0, 35150.64849351474, 35404.82297588599, 35571.82214088913, 31273.530897037017, 31922.84038579792, 32748.50877236891, 32984.835075825424, 33023.50464742983, 24756.876215618806, 25203.11789087373, 25457.363565911, 25706.742932571182, 25812.741872581035], "unit": "ms", "name": "ext.html", "value": 18090.773931886313}, {"lowerIsBetter": false, "replicates": [347.48572113272354, 350.2172644140346, 356.35677601928506, 358.1404628890662, 358.23874267182237, 12306.0, 12665.176763510366, 14150.64623384416, 15421.074735515864, 16829.579260518494, 11859.584914527994, 11895.821562676567, 11905.0, 12101.0, 12106.273623582569, 4565.2467342888385, 4602.470715867685, 4634.490206077328, 4647.488776234612, 4648.628109751228, 4526.388937493446, 4651.720896746206, 4671.532846715319, 4681.648876334289, 4701.553352431531, 4628.1669299526075, 4648.907021859561, 4657.9068418631605, 6470.061841033047, 6727.562708423961, 6668.799936001928, 6672.199336079701, 6676.132102826626, 6697.765578204788, 6737.528373013891, 6321.367863213688, 6516.511261655395, 6554.180727409074, 6569.343065693392, 6574.046763219307, 22223.555528889494, 22885.656715149285, 23147.189848355396, 23695.0, 23862.642060369108, 2314.9652755208685, 2363.787259146668, 2391.7847393734473, 2394.341556072077, 2440.341107900857, 14241.216733079676, 14281.285935703174, 14436.350364233635, 14644.194569298685, 14883.18142502162, 1889.1121173048646, 1929.7263805888047, 1936.3029309448586, 1942.2328180368816, 1955.706644003411, 69217.30782692108, 69953.0, 70405.64797176032, 71475.64262178655, 72315.0, 14835.703285934222, 15695.764563531557, 15748.370065197492, 15850.52448426549, 15894.205289735466, 23611.7638823613, 23700.7629923702, 23814.0, 23839.165629202893, 23999.0, 8522.446041007315, 8537.18896704812, 8557.614907329185, 8580.528070956032, 8589.098144694843, 6864.656767161722, 6973.721051157999, 6985.022096906438, 7008.544444611084, 7217.133943926762, 7596.240375962359, 7737.535747855147, 7785.221477852283, 7803.570803605856, 7833.138354780969, 2900.3474218301135, 2947.277916910328, 2978.6723460419335, 3042.680518545565, 3067.2791893904914, 439.7163829330128, 440.2361902099854, 448.8272015274143, 453.47170546313174, 486.4697479747022, 10395.84406233907, 10777.946110269555, 10879.619213327496, 11081.72295692598, 11170.218084733991, 426.6735947000547, 446.2079808340157, 447.0, 457.89239528710726, 473.82705312560665, 5721.663563004345, 5832.475077243069, 5858.648481091148, 5893.734781934823, 6012.518998480111, 522254.0, 523267.0, 544482.0, 553184.0, 554680.0, 76388.23611763923, 76649.616751917, 78502.0, 79367.60316198382, 79868.20131798724, 481.40546425164894, 481.7759741720117, 487.60747598183093, 488.0799692579584, 489.3320617357282, 10185.541650625888, 10469.895301046892, 11294.0, 11900.821487677866, 11915.82126268124, 36025.0, 36098.0, 36752.0, 37862.24275514529, 38157.427638586, 47132.29301560548, 47229.2915606273, 47820.521794781605, 48650.5134948646, 48750.26874596813, 6821.113255276782, 6926.411255043377, 6949.861002780017, 6964.825879353057, 6975.930240697731, 12165.939170304091, 12256.0, 12699.873001269869, 12719.87280127187, 12727.363631818187, 10688.519016644113, 10810.51352689115, 10961.45192740344, 11100.056495197998, 11317.886821132013, 10495.16038716916, 10906.454677266263, 10969.0, 11016.339019658846, 11037.448127593749, 10753.085987691135, 10938.890611093788, 10957.452127393439, 10992.780144397228, 11001.944990274997, 6370.649614271259, 6878.58728476293, 6915.585064896122, 6922.584644921119, 6932.23745388017, 10934.6719598409, 10943.069839063768, 11232.213745037769, 11261.493232804707, 11353.091752659602], "unit": "ms", "name": "jquery.html", "value": 8199.475362116571}, {"lowerIsBetter": false, "replicates": [1507.0580886945659, 1646.37437773646, 1722.698527757643, 1727.2486468386248, 1737.6785294720478, 7411.518251313668, 7622.161562228157, 7625.4280928930275, 7882.9211707882905, 8072.475289106204, 7917.247861453167, 7943.602819858998, 7987.880181797278, 7991.320737737285, 7999.4800337977995, 2279.9658005129936, 2299.7815207555254, 2313.3175713164655, 2320.6751054852334, 2333.0784340185614, 2289.3246492284734, 2338.1115176233093, 2344.4724936889234, 2348.8942997565064, 2350.8001819845335, 2055.629986602411, 2094.2984100326407, 2305.688732021181, 2308.53829234153, 2356.6582845487396, 2316.536692661466, 2355.7526459721653, 2360.24472168906, 2360.929172124839, 2362.870042147681, 2260.4800895793896, 2336.836421450499, 2339.4619237575284, 2341.8946147423408, 2343.297010896741, 7623.6569354378635, 7636.083669959585, 7664.923350766532, 7668.194839541824, 7718.112417071996, 4729.62163026957, 4817.951820481785, 4819.036192761427, 4821.975890120562, 4838.564529192389, 3006.4738670732536, 3122.67211942745, 3142.842857857121, 3147.27612649092, 3162.7469802415753, 994.2940512236321, 998.8451789972565, 1021.5607288865784, 1026.3892983674702, 1059.6715018344262, 10544.578216871316, 10703.946480267627, 10705.1435885129, 10715.946420267848, 10724.088452481548, 4350.0647360817575, 4394.890127746832, 4447.066116115652, 4447.399601053881, 4474.776261186993, 9134.725958221265, 9240.768980775534, 9251.95374023139, 9284.25725941923, 9284.907150928539, 4795.112904112751, 4803.327534145223, 4805.807767689324, 4834.516548345137, 4834.830780922651, 4301.849435269751, 4348.391225228471, 4369.978150109229, 4391.604755572014, 4393.802278897393, 3540.2652700176727, 3621.4748861414946, 3625.021244264034, 3630.5643322801434, 3647.94528082079, 1188.4354931407686, 1208.6736581123048, 1223.2843786385065, 1223.3240460569077, 1231.310466138965, 976.1019861727228, 992.265723364705, 1006.2805094357569, 1100.6918062942389, 1319.4854006937303, 7341.522801017917, 7348.63256837156, 7356.77929662111, 7364.631768411665, 7417.332440080419, 1170.5809825155552, 1195.9402029898645, 1226.7055906582357, 1228.2937311046185, 1238.3560548514722, 5799.768009279666, 5807.88384232313, 5812.38969908162, 5831.562632802556, 5869.060950248026, 9051.530993278566, 9285.535723213812, 9343.159115679622, 9533.094356036165, 9582.089701478348, 9291.48896810682, 9314.627414903463, 9341.112594303666, 9341.925678546919, 9445.0554944505, 7122.430205583644, 7175.210726819942, 7230.45771567124, 7235.204127545966, 7305.525140865827, 7303.963480182565, 7331.0, 7332.19345871943, 7332.523385979895, 7335.8532829344185, 7756.922430775846, 7915.287624113857, 8023.358131349361, 8042.477238979448, 8138.226868447488, 8372.246497815226, 8417.537035463109, 8425.62084706202, 8443.535605541509, 8553.31573474133, 6903.5167538273845, 7071.646417678992, 7104.076470058859, 7109.253528379446, 7120.145582530129, 7537.698492060256, 7544.886826697494, 7579.962100189464, 7594.544327340377, 7596.430267729832, 6525.184351956005, 6966.3730264276455, 7133.82165445868, 7147.928520714726, 7244.311790379906, 6435.388638079375, 6483.837904052436, 6770.255271919987, 6802.523823332319, 7191.892121618284, 7073.115860517435, 7118.8932166018585, 7171.964140179266, 7189.424846012411, 7206.207317195209, 6450.580712253689, 6458.806235812933, 6460.030995350735, 6517.543771935919, 6530.118434011348, 6720.5631633945595, 6725.630090344882, 6758.8648227036165, 6759.526833121634, 6782.525223234326], "unit": "ms", "name": "mootools.html", "value": 4536.293386507648}, {"lowerIsBetter": false, "replicates": [328.7665757312311, 329.3561088072817, 337.2967363048045, 337.49881426082266, 339.8844392906411, 6352.904706429407, 6353.174087368641, 6368.076628888814, 6371.649559274238, 6556.704948277327, 6330.018847078699, 6335.081413195084, 6341.0, 6346.365363463651, 6388.041793730943, 4825.541573550507, 4835.879103022417, 4841.49164337743, 4842.927356089662, 4854.247591623304, 4830.782614782343, 4835.178019736635, 4842.806287748486, 4848.418189817209, 4850.9029819403595, 4695.1079294934025, 4711.90576188476, 4759.928601070987, 4793.496682848303, 4797.136515427222, 4749.073930583538, 4758.881027974311, 4773.307870358779, 4778.37881075458, 4793.952060479385, 4125.97937010316, 4128.112455822013, 4132.276851550967, 4132.421460995463, 4145.751254924714, 5979.910301345483, 5991.940080599182, 6008.75964961401, 6010.939890601082, 6033.215681961361, 5429.131338985743, 5518.117101263819, 5574.526165275956, 5596.580256480739, 5602.0, 6747.93252067478, 6760.932390676079, 6765.2219994700745, 6770.5937643741045, 6772.255051944282, 4590.380298659672, 4593.655475839326, 4600.6549508786975, 4606.9078618427775, 4609.608183304423, 6779.796606101825, 6919.927411251264, 6974.267701891279, 7013.684384202723, 7015.088038555006, 4610.884727881829, 4677.672562920631, 4678.69588476748, 4684.859454216379, 4694.81220751173, 4717.646176536773, 4739.123262196505, 4742.834000809955, 4753.287006948916, 4755.048990201904, 5505.201745746845, 5521.613487055948, 5522.723863806794, 5527.2538207342295, 5529.502344788908, 6596.274409814917, 6730.663466826639, 6747.257801641816, 6751.729930802811, 6757.054012438262, 1625.7154997875325, 1651.0506458786247, 1652.289515508319, 1656.9254383552768, 1657.32049859557, 1053.2258789789498, 1065.7495488560182, 1077.757504561483, 1082.0694202985426, 1100.5542755184163, 6362.045693146065, 6386.489080873519, 6412.166418365676, 6441.742330306829, 6450.0647406125845, 6831.760888368884, 6862.176538815274, 6877.3810357067105, 6880.931190688129, 6882.8623427531165, 6332.746690132435, 6382.648954307465, 6390.968045159744, 6425.036244563353, 6436.935630643634, 3776.735628506033, 3787.5265591801026, 3790.1093243087853, 3814.5803961564206, 3830.8467661293794, 7309.817254568572, 7315.341619254293, 7318.963405183046, 7325.157606875277, 7354.338109570164, 7049.859002820018, 7166.856662866818, 7181.8922716159295, 7210.062691850026, 7252.419806415474, 7238.0952380952385, 7249.963750181215, 7297.416206703451, 7306.6711997959155, 7340.009098771704, 6947.166340039228, 7005.614691192033, 7016.22821489646, 7018.789436316919, 7055.118110236221, 6852.417544508573, 6917.23910369869, 6945.861082778417, 6963.512554121163, 6968.477364197604, 7284.235155308828, 7326.9267307326245, 7335.266473352622, 7339.339459448675, 7352.632368381666, 4789.61683065347, 4801.279808028824, 4802.903941921212, 4809.9038019240115, 4840.515948405131, 6702.966485167543, 6758.0, 6874.2782007890455, 6884.242733299433, 6943.861122777617, 6763.628000459825, 6780.050792888901, 6796.01457788608, 6828.453723702191, 6832.419244364086, 6806.353396427331, 6813.829654258485, 6825.590464571943, 6826.044353790374, 6833.145856767904, 6567.587968586777, 6676.432503237279, 6679.0, 6686.866262674622, 6695.263521012587, 6872.656367181721, 6880.174379074543, 6898.1377327834025, 6901.792946211622, 6909.827254318682, 6638.867222655616, 6702.83242918931, 6710.597364158166, 6711.664416779044, 6714.764983225564], "unit": "ms", "name": "prototype.html", "value": 5053.805145870434}, {"lowerIsBetter": false, "replicates": [689.4105539763502, 749.737591842855, 832.3091833777964, 859.6776208921655, 874.1913729799934, 13191.934040329785, 13212.867871321283, 13353.399097040632, 13367.933160334196, 13410.798838017437, 11627.418629068534, 12834.871651283484, 13134.868651313483, 13461.394237259323, 13535.729285414285, 799.9960000200006, 812.9674813007503, 820.167529957091, 822.942394032418, 823.6046697585163, 815.3721634341554, 822.3051521464353, 830.1573902488979, 830.9335253179761, 834.4617721569584, 850.1413572291967, 858.7509622209583, 861.7242482405633, 863.0721973878107, 869.1959937058227, 843.797488602734, 857.6055014693248, 861.4443683823961, 862.8058686795483, 864.0581765875227, 630.19020558582, 630.7035693224177, 631.3780925788095, 631.826247781859, 633.6894921488483, 12506.562270320495, 12644.68388290291, 12651.93674031624, 12697.55558555446, 12716.809247861289, 10952.780944381067, 10966.616168434066, 10971.780564388748, 11267.098632109493, 11302.378369189692, 9825.85261221075, 9967.501624918797, 10072.647457338959, 10088.89911100887, 10093.798124037477, 3243.4486137356707, 3310.652381499932, 3440.363532746425, 3499.1602015516346, 3602.657747513982, 37595.43606845899, 38146.61853381458, 38178.61821381806, 44658.106837863386, 48442.515574844154, 9172.8624070639, 9562.66530671437, 9681.903180968242, 9715.951420242995, 9767.218622510181, 19046.90476547636, 19534.70697939532, 19542.925139117484, 19571.315003975076, 19645.214191432467, 7368.631568421558, 7777.844443111106, 7786.1824508426935, 7931.405144614177, 8033.357331413473, 4045.8583949561626, 4090.9590904091174, 4166.895827604334, 4244.0, 4291.678124140702, 3223.3553289342226, 3225.2420681139915, 3230.951535726966, 3252.463343548288, 3253.837308134584, 458.7339343181014, 478.7343024621299, 479.0849477498032, 608.0817964872986, 627.8336240896075, 436.2962630926175, 487.9338645060554, 568.2527476368562, 568.9971550142305, 634.3878157577867, 8167.79580510492, 8380.748577542683, 8395.370347223983, 8615.267702245379, 8717.65129394817, 562.5471495446205, 563.9041362968305, 571.1033677126871, 576.1818218130265, 581.7440326256434, 3903.394973779069, 3982.303096958021, 4026.375911733686, 4106.691998100155, 4222.239996800544, 56881.0, 58733.0, 58913.41086589165, 59147.0, 59232.0, 52295.47704522982, 52976.47023529792, 53062.46937530652, 53115.0, 53373.46626533763, 43922.34116488255, 44856.3271550927, 45741.313880291826, 45849.77075114603, 46669.29996050062, 8217.79455513617, 8347.123552027193, 8402.705905293285, 8433.746987590383, 8591.914080859362, 33336.0, 34671.479927800596, 34829.955101347, 36785.448218277284, 37783.0, 46365.304520432896, 46790.532094678616, 46884.53115468802, 46895.76552117217, 47780.0, 8128.0, 8237.299829514559, 8273.33813294947, 8319.708810191614, 8384.790380240544, 11584.131190160602, 11835.467403967014, 11879.703007424538, 11917.16579839403, 11947.581834635746, 10176.389416635026, 10317.071463568314, 10402.531886064993, 10596.417197053928, 10644.574217031231, 10789.40658263803, 11197.664070077584, 11610.0, 11633.709157271136, 12015.399230038638, 11208.49561769706, 11305.547778088785, 11336.206465547333, 11481.598144064918, 11771.352575608424, 9555.12379813658, 9947.353422027834, 10060.195184385379, 10096.747581310527, 10655.467226638792, 8797.0, 8851.734447966572, 9034.0, 9279.257659387098, 9366.906330936603], "unit": "ms", "name": "yui.html", "value": 6133.877181378228}], "name": "dromaeo_css", "value": 6963.2943729909475}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [112.0, 108.0, 111.0, 113.0, 113.0, 124.0, 111.0, 112.0, 110.0, 112.0], "unit": "ms", "name": "ai-astar", "value": 112.6}, {"lowerIsBetter": true, "replicates": [158.0, 126.0, 151.0, 157.0, 132.0, 150.0, 161.0, 127.0, 166.0, 127.0], "unit": "ms", "name": "audio-beat-detection", "value": 145.5}, {"lowerIsBetter": true, "replicates": [163.0, 164.0, 163.0, 162.0, 163.0, 160.0, 162.0, 162.0, 176.0, 167.0], "unit": "ms", "name": "audio-dft", "value": 164.2}, {"lowerIsBetter": true, "replicates": [72.0, 72.0, 72.0, 73.0, 75.0, 149.0, 73.0, 74.0, 74.0, 75.0], "unit": "ms", "name": "audio-fft", "value": 80.9}, {"lowerIsBetter": true, "replicates": [91.0, 92.0, 140.0, 90.0, 90.0, 91.0, 144.0, 91.0, 92.0, 141.0], "unit": "ms", "name": "audio-oscillator", "value": 106.2}, {"lowerIsBetter": true, "replicates": [110.0, 110.0, 110.0, 110.0, 110.0, 110.0, 109.0, 110.0, 110.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.9}, {"lowerIsBetter": true, "replicates": [146.0, 148.0, 146.0, 146.0, 146.0, 146.0, 147.0, 147.0, 148.0, 147.0], "unit": "ms", "name": "imaging-darkroom", "value": 146.7}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 98.0, 96.0, 96.0, 96.0, 96.0, 96.0, 95.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 96.1}, {"lowerIsBetter": true, "replicates": [61.0, 60.0, 63.0, 61.0, 61.0, 60.0, 61.0, 67.0, 66.0, 61.0], "unit": "ms", "name": "json-parse-financial", "value": 62.1}, {"lowerIsBetter": true, "replicates": [68.0, 68.0, 69.0, 69.0, 69.0, 69.0, 69.0, 69.0, 69.0, 70.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 68.9}, {"lowerIsBetter": true, "replicates": [85.0, 84.0, 84.0, 92.0, 85.0, 84.0, 87.0, 84.0, 94.0, 84.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 86.3}, {"lowerIsBetter": true, "replicates": [131.0, 120.0, 130.0, 132.0, 120.0, 134.0, 121.0, 126.0, 135.0, 125.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 127.4}, {"lowerIsBetter": true, "replicates": [145.0, 157.0, 157.0, 152.0, 151.0, 145.0, 151.0, 157.0, 148.0, 140.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 150.3}, {"lowerIsBetter": true, "replicates": [69.0, 80.0, 69.0, 68.0, 68.0, 70.0, 69.0, 67.0, 72.0, 68.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 70.0}], "name": "kraken", "value": 1527.1000000000001}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [164.88046166529267], "unit": "score", "name": "Richards", "value": 214.10663000000005}, {"lowerIsBetter": false, "replicates": [186.81113394358303], "unit": "score", "name": "DeltaBlue", "value": 353.92965399999986}, {"lowerIsBetter": false, "replicates": [280.74115665356544], "unit": "score", "name": "Encrypt", "value": 948.136722}, {"lowerIsBetter": false, "replicates": [5040.201005025126], "unit": "score", "name": "Decrypt", "value": 52.81158424725827}, {"lowerIsBetter": false, "replicates": [1054.8523206751054], "unit": "score", "name": "RayTrace", "value": 701.5095719999998}, {"lowerIsBetter": false, "replicates": [703.2348804500704], "unit": "score", "name": "Earley", "value": 947.7103860000001}, {"lowerIsBetter": false, "replicates": [12256.09756097561], "unit": "score", "name": "Boyer", "value": 54.378075621890574}, {"lowerIsBetter": false, "replicates": [25375.0], "unit": "score", "name": "RegExp", "value": 35.90088669950742}, {"lowerIsBetter": false, "replicates": [697.9958534899793], "unit": "score", "name": "Splay", "value": 116.74997722772275}, {"lowerIsBetter": false, "replicates": [4840.579710144928], "unit": "score", "name": "NavierStokes", "value": 306.5748502994012}], "name": "v8_7", "value": 21201.357702132886}]} 05:54:06 INFO - RETURN: dromaeo_css: 6963.30 05:54:06 INFO - RETURN: kraken: 1527.10 05:54:06 INFO - RETURN: v8_7: 21201.36 05:54:06 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,1,43]]", "result": "21201.36"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,1,43]]", "result": "1527.10"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,1,43]]", "result": "6963.30"}}} 05:54:06 INFO - Return code: 0 05:54:06 INFO - # TBPL SUCCESS # 05:54:06 INFO - Running post-action listener: _resource_record_post_action 05:54:06 INFO - Running post-run listener: _resource_record_post_run 05:54:07 INFO - Total resource usage - Wall time: 1224s; CPU: 13.0%; Read bytes: 24576; Write bytes: 514535424; Read time: 40; Write time: 362508 05:54:07 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 195067904; Read time: 0; Write time: 142536 05:54:07 INFO - run-tests - Wall time: 1210s; CPU: 13.0%; Read bytes: 24576; Write bytes: 274026496; Read time: 40; Write time: 171008 05:54:07 INFO - Running post-run listener: _upload_blobber_files 05:54:07 INFO - Blob upload gear active. 05:54:07 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 05:54:07 INFO - Copying logs to upload dir... 05:54:07 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1290.622578 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 30 secs) (at 2015-11-19 05:54:07.697745) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-19 05:54:07.701867) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test-pgo/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=6587686cb2da90a5f91b3170000001be-1447939594.172589-1818896900 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/t4BCKR2TQICXWQQunOtFDQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009783 build_url: 'https://queue.taskcluster.net/v1/task/t4BCKR2TQICXWQQunOtFDQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.05 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-19 05:54:07.758603) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 05:54:07.759112) ========= rm -f oauth.txt in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=6587686cb2da90a5f91b3170000001be-1447939594.172589-1818896900 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004558 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 05:54:07.811341) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-19 05:54:07.811804) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-19 05:54:07.812244) ========= ========= Total master_lag: 0.27 =========