builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-016 starttime: 1474121990.94 results: success (0) buildid: 20160917004009 builduid: 39c0864104644d618a468a713b19f1a0 revision: b9499912b32324acd84792bf02b533b40e98e496 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-17 07:19:50.942512) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-17 07:19:50.943067) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-17 07:19:50.943432) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-17 07:19:50.958367) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:19:50.958767) ========= 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/. (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 SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=00204705a9daa57ed339a427000001b9-1474121990.323651-1902662426 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-17 07:19:51-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 70.3M=0s 2016-09-17 07:19:51 (70.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.114744 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:19:51.087794) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:19:51.088141) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', '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 SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=00204705a9daa57ed339a427000001b9-1474121990.323651-1902662426 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016334 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:19:51.137598) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:19:51.137961) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev b9499912b32324acd84792bf02b533b40e98e496 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev b9499912b32324acd84792bf02b533b40e98e496 --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 SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=00204705a9daa57ed339a427000001b9-1474121990.323651-1902662426 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-17 07:19:51,242 truncating revision to first 12 chars 2016-09-17 07:19:51,242 Setting DEBUG logging. 2016-09-17 07:19:51,242 attempt 1/10 2016-09-17 07:19:51,242 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/b9499912b323?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-17 07:19:51,547 unpacking tar archive at: mozilla-aurora-b9499912b323/testing/mozharness/ program finished with exit code 0 elapsedTime=0.654901 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:19:51.806968) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-17 07:19:51.807295) ========= script_repo_revision: b9499912b32324acd84792bf02b533b40e98e496 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-17 07:19:51.807689) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-17 07:19:51.807955) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-17 07:19:51.818554) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 59 secs) (at 2016-09-17 07:19:51.819156) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=00204705a9daa57ed339a427000001b9-1474121990.323651-1902662426 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:19:52 INFO - MultiFileLogger online at 20160917 07:19:52 in /builds/slave/test 07:19:52 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 07:19:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:19:52 INFO - {'append_to_log': False, 07:19:52 INFO - 'base_work_dir': '/builds/slave/test', 07:19:52 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 07:19:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:19:52 INFO - 'branch': 'Mozilla-Aurora', 07:19:52 INFO - 'buildbot_json_path': 'buildprops.json', 07:19:52 INFO - 'config_files': ('talos/linux_config.py',), 07:19:52 INFO - 'default_actions': ('clobber', 07:19:52 INFO - 'read-buildbot-config', 07:19:52 INFO - 'download-and-extract', 07:19:52 INFO - 'populate-webroot', 07:19:52 INFO - 'create-virtualenv', 07:19:52 INFO - 'install', 07:19:52 INFO - 'run-tests'), 07:19:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:19:52 INFO - 'download_minidump_stackwalk': True, 07:19:52 INFO - 'download_symbols': 'ondemand', 07:19:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:19:52 INFO - 'tooltool.py': '/tools/tooltool.py', 07:19:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:19:52 INFO - '/tools/misc-python/virtualenv.py')}, 07:19:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:19:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:19:52 INFO - 'installer_path': 'installer.exe', 07:19:52 INFO - 'log_level': 'info', 07:19:52 INFO - 'log_name': 'talos', 07:19:52 INFO - 'log_to_console': True, 07:19:52 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:19:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:19:52 INFO - 'opt_config_files': (), 07:19:52 INFO - 'pip_index': False, 07:19:52 INFO - 'sps_profile': False, 07:19:52 INFO - 'sps_profile_interval': 0, 07:19:52 INFO - 'suite': 'dromaeojs', 07:19:52 INFO - 'system_bits': '32', 07:19:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:19:52 INFO - 'title': 'talos-linux64-ix-016', 07:19:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:19:52 INFO - 'use_talos_json': True, 07:19:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:19:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:19:52 INFO - 'work_dir': 'build'} 07:19:52 INFO - [mozharness: 2016-09-17 14:19:52.014506Z] Running clobber step. 07:19:52 INFO - Running pre-action listener: _resource_record_pre_action 07:19:52 INFO - Running main action method: clobber 07:19:52 INFO - rmtree: /builds/slave/test/build 07:19:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:19:52 INFO - Running post-action listener: _resource_record_post_action 07:19:52 INFO - [mozharness: 2016-09-17 14:19:52.498931Z] Finished clobber step (success) 07:19:52 INFO - [mozharness: 2016-09-17 14:19:52.499031Z] Running read-buildbot-config step. 07:19:52 INFO - Running pre-action listener: _resource_record_pre_action 07:19:52 INFO - Running main action method: read_buildbot_config 07:19:52 INFO - Using buildbot properties: 07:19:52 INFO - { 07:19:52 INFO - "project": "", 07:19:52 INFO - "product": "firefox", 07:19:52 INFO - "script_repo_revision": "production", 07:19:52 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 07:19:52 INFO - "repository": "", 07:19:52 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 07:19:52 INFO - "buildid": "20160917004009", 07:19:52 INFO - "stage_platform": "linux64-pgo", 07:19:52 INFO - "basedir": "/builds/slave/test", 07:19:52 INFO - "nightly_build": "True", 07:19:52 INFO - "buildnumber": 755, 07:19:52 INFO - "slavename": "talos-linux64-ix-016", 07:19:52 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 07:19:52 INFO - "platform": "ubuntu64_hw", 07:19:52 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 07:19:52 INFO - "branch": "mozilla-aurora", 07:19:52 INFO - "repo_path": "releases/mozilla-aurora", 07:19:52 INFO - "revision": "b9499912b32324acd84792bf02b533b40e98e496", 07:19:52 INFO - "pgo_build": "True", 07:19:52 INFO - "builduid": "39c0864104644d618a468a713b19f1a0", 07:19:52 INFO - "slavebuilddir": "test" 07:19:52 INFO - } 07:19:52 INFO - Found installer url https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 07:19:52 INFO - Running post-action listener: _resource_record_post_action 07:19:52 INFO - [mozharness: 2016-09-17 14:19:52.501837Z] Finished read-buildbot-config step (success) 07:19:52 INFO - [mozharness: 2016-09-17 14:19:52.501927Z] Running download-and-extract step. 07:19:52 INFO - Running pre-action listener: _resource_record_pre_action 07:19:52 INFO - Running main action method: download_and_extract 07:19:52 INFO - mkdir: /builds/slave/test/build/tests 07:19: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')]} 07:19:52 INFO - https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 07:19:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 07:19:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 07:19:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 07:19:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 07:19:52 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 07:19:52 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 07:19:52 INFO - retry: Failed, sleeping 30 seconds before retrying 07:20:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #2 07:20:22 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 07:20:22 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 07:20:22 INFO - retry: Failed, sleeping 60 seconds before retrying 07:21:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #3 07:21:22 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 07:21:22 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 07:21:22 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json! 07:21:22 INFO - Caught exception: HTTP Error 404: Not Found 07:21:22 INFO - Caught exception: HTTP Error 404: Not Found 07:21:22 INFO - Caught exception: HTTP Error 404: Not Found 07:21:22 INFO - trying https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 07:21:22 INFO - Downloading https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 07:21:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 07:21:24 INFO - Downloaded 1361 bytes. 07:21:24 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 07:21:24 INFO - Using the following test package requirements: 07:21:24 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 07:21:24 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 07:21:24 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 07:21:24 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 07:21:24 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 07:21:24 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 07:21:24 INFO - u'jsshell-linux-x86_64.zip'], 07:21:24 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 07:21:24 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 07:21:24 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 07:21:24 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 07:21:24 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 07:21:24 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 07:21:24 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 07:21:24 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 07:21:24 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 07:21:24 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 07:21:24 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 07:21:24 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 07:21:24 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')]} 07:21:24 INFO - https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 07:21:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:21:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:21:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:21:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:21:24 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:21:24 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 07:21:24 INFO - retry: Failed, sleeping 30 seconds before retrying 07:21:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 07:21:54 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:21:54 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 07:21:54 INFO - retry: Failed, sleeping 60 seconds before retrying 07:22:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 07:22:54 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:22:54 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 07:22:54 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 07:22:54 INFO - Caught exception: HTTP Error 404: Not Found 07:22:54 INFO - Caught exception: HTTP Error 404: Not Found 07:22:54 INFO - Caught exception: HTTP Error 404: Not Found 07:22:54 INFO - trying https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:22:54 INFO - Downloading https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:22:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:22:55 INFO - Downloaded 21771059 bytes. 07:22:55 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:22:55 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 07:22:56 INFO - Return code: 0 07:22:56 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 07:22:56 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')]} 07:22:56 INFO - https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 07:22:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:22:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:22:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:22:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:22:56 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:22:56 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 07:22:56 INFO - retry: Failed, sleeping 30 seconds before retrying 07:23:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 07:23:26 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:23:26 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 07:23:26 INFO - retry: Failed, sleeping 60 seconds before retrying 07:24:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 07:24:26 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:24:26 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 07:24:26 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 07:24:26 INFO - Caught exception: HTTP Error 404: Not Found 07:24:26 INFO - Caught exception: HTTP Error 404: Not Found 07:24:26 INFO - Caught exception: HTTP Error 404: Not Found 07:24:26 INFO - trying https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:24:26 INFO - Downloading https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:24:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:24:27 INFO - Downloaded 21771059 bytes. 07:24:27 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:24:27 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 07:24:28 INFO - Return code: 0 07:24:28 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')]} 07:24:28 INFO - https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 07:24:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 07:24:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 07:24:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 07:24:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 07:24:28 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 07:24:28 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 07:24:28 INFO - retry: Failed, sleeping 30 seconds before retrying 07:24:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 07:24:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 07:24:58 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 07:24:58 INFO - retry: Failed, sleeping 60 seconds before retrying 07:25:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 07:25:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 07:25:58 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 07:25:58 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip! 07:25:58 INFO - Caught exception: HTTP Error 404: Not Found 07:25:58 INFO - Caught exception: HTTP Error 404: Not Found 07:25:58 INFO - Caught exception: HTTP Error 404: Not Found 07:25:58 INFO - trying https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 07:25:58 INFO - Downloading https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 07:25:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 07:25:59 INFO - Downloaded 14413740 bytes. 07:25:59 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:25:59 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 07:25:59 INFO - Return code: 0 07:25:59 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')]} 07:25:59 INFO - https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 07:25:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:25:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:25:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:25:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 07:25:59 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:25:59 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 07:25:59 INFO - retry: Failed, sleeping 30 seconds before retrying 07:26:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #2 07:26:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:26:29 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 07:26:29 INFO - retry: Failed, sleeping 60 seconds before retrying 07:27:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #3 07:27:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:27:29 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 07:27:29 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2! 07:27:29 INFO - Caught exception: HTTP Error 404: Not Found 07:27:29 INFO - Caught exception: HTTP Error 404: Not Found 07:27:29 INFO - Caught exception: HTTP Error 404: Not Found 07:27:29 INFO - trying https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:27:29 INFO - Downloading https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:27:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 07:27:31 INFO - Downloaded 63891050 bytes. 07:27:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:27:31 INFO - mkdir: /builds/slave/test/properties 07:27:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:27:31 INFO - Writing to file /builds/slave/test/properties/build_url 07:27:31 INFO - Contents: 07:27:31 INFO - build_url:https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:27:31 INFO - Running post-action listener: _resource_record_post_action 07:27:31 INFO - Running post-action listener: set_extra_try_arguments 07:27:31 INFO - [mozharness: 2016-09-17 14:27:31.701973Z] Finished download-and-extract step (success) 07:27:31 INFO - [mozharness: 2016-09-17 14:27:31.702208Z] Running populate-webroot step. 07:27:31 INFO - Running pre-action listener: _resource_record_pre_action 07:27:31 INFO - Running main action method: populate_webroot 07:27:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:27:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:27:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:27:31 INFO - '--tppagecycles', 07:27:31 INFO - '7'], 07:27:31 INFO - 'tests': ['tsvgm']}}, 07:27:31 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:27:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:27:31 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:27:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:27:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:27:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:27:31 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:27:31 INFO - 'talos_options': ['--disable-e10s'], 07:27:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:27:31 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:27:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:27:31 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:27:31 INFO - 'talos_options': ['--disable-e10s'], 07:27:31 INFO - 'tests': ['damp', 'tps']}, 07:27:31 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:27:31 INFO - 'tests': ['damp', 'tps']}, 07:27:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:27:31 INFO - 'tests': ['dromaeo_dom']}, 07:27:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:27:31 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:27:31 INFO - 'tests': ['basic_compositor_video']}, 07:27:31 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:27:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:27:31 INFO - 'tests': ['a11yr', 07:27:31 INFO - 'ts_paint', 07:27:31 INFO - 'tpaint', 07:27:31 INFO - 'sessionrestore', 07:27:31 INFO - 'sessionrestore_no_auto_restore', 07:27:31 INFO - 'tabpaint']}, 07:27:31 INFO - 'other-e10s': {'tests': ['a11yr', 07:27:31 INFO - 'ts_paint', 07:27:31 INFO - 'tpaint', 07:27:31 INFO - 'sessionrestore', 07:27:31 INFO - 'sessionrestore_no_auto_restore', 07:27:31 INFO - 'tabpaint']}, 07:27:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:27:31 INFO - 'ts_paint', 07:27:31 INFO - 'tpaint', 07:27:31 INFO - 'sessionrestore', 07:27:31 INFO - 'sessionrestore_no_auto_restore', 07:27:31 INFO - 'tabpaint']}, 07:27:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:27:31 INFO - 'ts_paint', 07:27:31 INFO - 'tpaint', 07:27:31 INFO - 'sessionrestore', 07:27:31 INFO - 'sessionrestore_no_auto_restore', 07:27:31 INFO - 'tabpaint']}, 07:27:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:27:31 INFO - 'tests': ['a11yr', 07:27:31 INFO - 'ts_paint', 07:27:31 INFO - 'tpaint', 07:27:31 INFO - 'sessionrestore', 07:27:31 INFO - 'sessionrestore_no_auto_restore', 07:27:31 INFO - 'tabpaint']}, 07:27:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:27:31 INFO - 'tests': ['a11yr', 07:27:31 INFO - 'ts_paint', 07:27:31 INFO - 'tpaint', 07:27:31 INFO - 'sessionrestore', 07:27:31 INFO - 'sessionrestore_no_auto_restore', 07:27:31 INFO - 'tabpaint']}, 07:27:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:27:31 INFO - 'tests': ['tsvgx', 07:27:31 INFO - 'tsvgr_opacity', 07:27:31 INFO - 'tart', 07:27:31 INFO - 'tscrollx', 07:27:31 INFO - 'cart']}, 07:27:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:27:31 INFO - 'tsvgr_opacity', 07:27:31 INFO - 'tart', 07:27:31 INFO - 'tscrollx', 07:27:31 INFO - 'cart']}, 07:27:31 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:27:31 INFO - 'talos_options': ['--disable-e10s'], 07:27:31 INFO - 'tests': ['tp5o']}, 07:27:31 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:27:31 INFO - 'tests': ['tp5o']}, 07:27:31 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:27:31 INFO - 'talos_options': ['--disable-e10s', 07:27:31 INFO - '--xperf_path', 07:27:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:27:31 INFO - 'tests': ['tp5n']}, 07:27:31 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:27:31 INFO - 'talos_options': ['--xperf_path', 07:27:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:27:31 INFO - 'tests': ['tp5n']}}, 07:27:31 INFO - 'talos.zip': {'path': '', 07:27:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:27:31 INFO - Running post-action listener: _resource_record_post_action 07:27:31 INFO - [mozharness: 2016-09-17 14:27:31.720053Z] Finished populate-webroot step (success) 07:27:31 INFO - [mozharness: 2016-09-17 14:27:31.720142Z] Running create-virtualenv step. 07:27:31 INFO - Running pre-action listener: _resource_record_pre_action 07:27:31 INFO - Running main action method: create_virtualenv 07:27:31 INFO - Creating virtualenv /builds/slave/test/build/venv 07:27:31 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 07:27:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:27:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:27:31 INFO - Using real prefix '/usr' 07:27:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:27:32 INFO - Installing distribute.............................................................................................................................................................................................done. 07:27:34 INFO - Installing pip.................done. 07:27:34 INFO - Return code: 0 07:27:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:27:34 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')]} 07:27:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:27:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:34 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')]} 07:27:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:27:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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 0x1f2c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x208f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21ed180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21eb3b0>, '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 0x21ec2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21ec740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1474121990.323651-1902662426', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 07:27:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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/build 07:27:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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 07:27:34 INFO - Using env: {'DISPLAY': ':0', 07:27:34 INFO - 'HOME': '/home/cltbld', 07:27:34 INFO - 'LANG': 'en_US.UTF-8', 07:27:34 INFO - 'LANGUAGE': 'en_US:en', 07:27:34 INFO - 'LOGNAME': 'cltbld', 07:27:34 INFO - 'MAIL': '/var/mail/cltbld', 07:27:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:27:34 INFO - 'MOZ_NO_REMOTE': '1', 07:27:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:27:34 INFO - 'NO_EM_RESTART': '1', 07:27:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:27:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:27:34 INFO - 'PWD': '/builds/slave/test', 07:27:34 INFO - 'SHELL': '/bin/bash', 07:27:34 INFO - 'SHLVL': '1', 07:27:34 INFO - 'TERM': 'linux', 07:27:34 INFO - 'TMOUT': '86400', 07:27:34 INFO - 'USER': 'cltbld', 07:27:34 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1474121990.323651-1902662426', 07:27:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:27:34 INFO - '_': '/tools/buildbot/bin/python'} 07:27:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:27:34 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 07:27:34 INFO - Cleaning up... 07:27:34 INFO - Return code: 0 07:27:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:27:34 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')]} 07:27:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:27:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:34 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')]} 07:27:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:27:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f2c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x208f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21ed180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21eb3b0>, '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 0x21ec2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21ec740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1474121990.323651-1902662426', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 07:27:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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>=3.1.1'] in /builds/slave/test/build 07:27:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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>=3.1.1 07:27:34 INFO - Using env: {'DISPLAY': ':0', 07:27:34 INFO - 'HOME': '/home/cltbld', 07:27:34 INFO - 'LANG': 'en_US.UTF-8', 07:27:34 INFO - 'LANGUAGE': 'en_US:en', 07:27:34 INFO - 'LOGNAME': 'cltbld', 07:27:34 INFO - 'MAIL': '/var/mail/cltbld', 07:27:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:27:34 INFO - 'MOZ_NO_REMOTE': '1', 07:27:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:27:34 INFO - 'NO_EM_RESTART': '1', 07:27:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:27:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:27:34 INFO - 'PWD': '/builds/slave/test', 07:27:34 INFO - 'SHELL': '/bin/bash', 07:27:34 INFO - 'SHLVL': '1', 07:27:34 INFO - 'TERM': 'linux', 07:27:34 INFO - 'TMOUT': '86400', 07:27:34 INFO - 'USER': 'cltbld', 07:27:34 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1474121990.323651-1902662426', 07:27:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:27:34 INFO - '_': '/tools/buildbot/bin/python'} 07:27:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:27:34 INFO - Downloading/unpacking psutil>=3.1.1 07:27: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 07:27: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 07:27: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 07:27: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 07:27: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 07:27: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 07:27:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:27:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:27:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:27:36 INFO - Installing collected packages: psutil 07:27:36 INFO - Running setup.py install for psutil 07:27:36 INFO - building 'psutil._psutil_linux' extension 07:27:36 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 07:27:36 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 07:27:36 INFO - building 'psutil._psutil_posix' extension 07:27:36 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 07:27:37 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 07:27:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:27:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:27:37 INFO - Successfully installed psutil 07:27:37 INFO - Cleaning up... 07:27:37 INFO - Return code: 0 07:27:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:27: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')]} 07:27:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:27:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27: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')]} 07:27:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:27:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f2c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x208f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21ed180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21eb3b0>, '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 0x21ec2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21ec740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1474121990.323651-1902662426', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 07:27:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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.3'] in /builds/slave/test/build 07:27:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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.3 07:27:37 INFO - Using env: {'DISPLAY': ':0', 07:27:37 INFO - 'HOME': '/home/cltbld', 07:27:37 INFO - 'LANG': 'en_US.UTF-8', 07:27:37 INFO - 'LANGUAGE': 'en_US:en', 07:27:37 INFO - 'LOGNAME': 'cltbld', 07:27:37 INFO - 'MAIL': '/var/mail/cltbld', 07:27:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:27:37 INFO - 'MOZ_NO_REMOTE': '1', 07:27:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:27:37 INFO - 'NO_EM_RESTART': '1', 07:27:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:27:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:27:37 INFO - 'PWD': '/builds/slave/test', 07:27:37 INFO - 'SHELL': '/bin/bash', 07:27:37 INFO - 'SHLVL': '1', 07:27:37 INFO - 'TERM': 'linux', 07:27:37 INFO - 'TMOUT': '86400', 07:27:37 INFO - 'USER': 'cltbld', 07:27:37 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1474121990.323651-1902662426', 07:27:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:27:37 INFO - '_': '/tools/buildbot/bin/python'} 07:27:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:27:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:27:37 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 07:27:37 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 07:27:37 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 07:27:37 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 07:27:37 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 07:27:37 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 07:27:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:27:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:27:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:27:39 INFO - Installing collected packages: mozsystemmonitor 07:27:39 INFO - Running setup.py install for mozsystemmonitor 07:27:39 INFO - Successfully installed mozsystemmonitor 07:27:39 INFO - Cleaning up... 07:27:39 INFO - Return code: 0 07:27:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:27:39 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')]} 07:27:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:27:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:39 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')]} 07:27:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:27:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f2c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x208f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21ed180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21eb3b0>, '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 0x21ec2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21ec740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1474121990.323651-1902662426', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 07:27:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:27:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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 jsonschema==2.5.1 07:27:39 INFO - Using env: {'DISPLAY': ':0', 07:27:39 INFO - 'HOME': '/home/cltbld', 07:27:39 INFO - 'LANG': 'en_US.UTF-8', 07:27:39 INFO - 'LANGUAGE': 'en_US:en', 07:27:39 INFO - 'LOGNAME': 'cltbld', 07:27:39 INFO - 'MAIL': '/var/mail/cltbld', 07:27:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:27:39 INFO - 'MOZ_NO_REMOTE': '1', 07:27:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:27:39 INFO - 'NO_EM_RESTART': '1', 07:27:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:27:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:27:39 INFO - 'PWD': '/builds/slave/test', 07:27:39 INFO - 'SHELL': '/bin/bash', 07:27:39 INFO - 'SHLVL': '1', 07:27:39 INFO - 'TERM': 'linux', 07:27:39 INFO - 'TMOUT': '86400', 07:27:39 INFO - 'USER': 'cltbld', 07:27:39 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1474121990.323651-1902662426', 07:27:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:27:39 INFO - '_': '/tools/buildbot/bin/python'} 07:27:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:27:39 INFO - Downloading/unpacking jsonschema==2.5.1 07:27:39 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 07:27:39 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 07:27:39 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 07:27:39 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 07:27:39 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 07:27:39 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 07:27:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:27:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:27:41 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 07:27:41 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 07:27:41 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 07:27:41 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 07:27:41 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 07:27:41 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 07:27:41 INFO - Downloading functools32-3.2.3-2.tar.gz 07:27:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:27:41 INFO - warning: no files found matching '*.txt' 07:27:41 INFO - no previously-included directories found matching 'build' 07:27:41 INFO - no previously-included directories found matching 'dist' 07:27:41 INFO - no previously-included directories found matching '.git*' 07:27:41 INFO - Installing collected packages: jsonschema, functools32 07:27:41 INFO - Running setup.py install for functools32 07:27:42 INFO - warning: no files found matching '*.txt' 07:27:42 INFO - no previously-included directories found matching 'build' 07:27:42 INFO - no previously-included directories found matching 'dist' 07:27:42 INFO - no previously-included directories found matching '.git*' 07:27:42 INFO - Successfully installed jsonschema functools32 07:27:42 INFO - Cleaning up... 07:27:42 INFO - Return code: 0 07:27:42 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:27: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')]} 07:27:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:27:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27: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')]} 07:27:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:27:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f2c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x208f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21ed180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21eb3b0>, '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 0x21ec2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21ec740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1474121990.323651-1902662426', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 07:27:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:27:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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 functools32==3.2.3-2 07:27:42 INFO - Using env: {'DISPLAY': ':0', 07:27:42 INFO - 'HOME': '/home/cltbld', 07:27:42 INFO - 'LANG': 'en_US.UTF-8', 07:27:42 INFO - 'LANGUAGE': 'en_US:en', 07:27:42 INFO - 'LOGNAME': 'cltbld', 07:27:42 INFO - 'MAIL': '/var/mail/cltbld', 07:27:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:27:42 INFO - 'MOZ_NO_REMOTE': '1', 07:27:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:27:42 INFO - 'NO_EM_RESTART': '1', 07:27:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:27:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:27:42 INFO - 'PWD': '/builds/slave/test', 07:27:42 INFO - 'SHELL': '/bin/bash', 07:27:42 INFO - 'SHLVL': '1', 07:27:42 INFO - 'TERM': 'linux', 07:27:42 INFO - 'TMOUT': '86400', 07:27:42 INFO - 'USER': 'cltbld', 07:27:42 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1474121990.323651-1902662426', 07:27:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:27:42 INFO - '_': '/tools/buildbot/bin/python'} 07:27:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:27:42 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:27:42 INFO - Cleaning up... 07:27:42 INFO - Return code: 0 07:27:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:27: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')]} 07:27:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:27:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27: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')]} 07:27:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:27:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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 0x1f2c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x208f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21ed180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21eb3b0>, '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 0x21ec2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21ec740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1474121990.323651-1902662426', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 07:27:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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/build 07:27:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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 07:27:42 INFO - Using env: {'DISPLAY': ':0', 07:27:42 INFO - 'HOME': '/home/cltbld', 07:27:42 INFO - 'LANG': 'en_US.UTF-8', 07:27:42 INFO - 'LANGUAGE': 'en_US:en', 07:27:42 INFO - 'LOGNAME': 'cltbld', 07:27:42 INFO - 'MAIL': '/var/mail/cltbld', 07:27:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:27:42 INFO - 'MOZ_NO_REMOTE': '1', 07:27:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:27:42 INFO - 'NO_EM_RESTART': '1', 07:27:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:27:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:27:42 INFO - 'PWD': '/builds/slave/test', 07:27:42 INFO - 'SHELL': '/bin/bash', 07:27:42 INFO - 'SHLVL': '1', 07:27:42 INFO - 'TERM': 'linux', 07:27:42 INFO - 'TMOUT': '86400', 07:27:42 INFO - 'USER': 'cltbld', 07:27:42 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1474121990.323651-1902662426', 07:27:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:27:42 INFO - '_': '/tools/buildbot/bin/python'} 07:27:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:27:42 INFO - Downloading/unpacking blobuploader==1.2.4 07:27:42 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 07:27:42 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 07:27:42 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 07:27:42 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 07:27:42 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 07:27:42 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 07:27:44 INFO - Downloading blobuploader-1.2.4.tar.gz 07:27:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:27:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:27:44 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 07:27:44 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 07:27:44 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 07:27:44 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 07:27:44 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 07:27:44 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 07:27:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:27:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:27:44 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 07:27:44 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 07:27:44 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 07:27:44 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 07:27:44 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 07:27:44 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 07:27:45 INFO - Downloading docopt-0.6.1.tar.gz 07:27:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:27:45 INFO - Installing collected packages: blobuploader, requests, docopt 07:27:45 INFO - Running setup.py install for blobuploader 07:27:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:27:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:27:45 INFO - Running setup.py install for requests 07:27:45 INFO - Running setup.py install for docopt 07:27:45 INFO - Successfully installed blobuploader requests docopt 07:27:45 INFO - Cleaning up... 07:27:45 INFO - Return code: 0 07:27:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:27:45 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')]} 07:27:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:27:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:45 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')]} 07:27:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:27:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/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 0x1f2c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x208f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21ed180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21eb3b0>, '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 0x21ec2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21ec740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1474121990.323651-1902662426', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 07:27:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/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/build/tests/config 07:27:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/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 07:27:45 INFO - Using env: {'DISPLAY': ':0', 07:27:45 INFO - 'HOME': '/home/cltbld', 07:27:45 INFO - 'LANG': 'en_US.UTF-8', 07:27:45 INFO - 'LANGUAGE': 'en_US:en', 07:27:45 INFO - 'LOGNAME': 'cltbld', 07:27:45 INFO - 'MAIL': '/var/mail/cltbld', 07:27:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:27:45 INFO - 'MOZ_NO_REMOTE': '1', 07:27:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:27:45 INFO - 'NO_EM_RESTART': '1', 07:27:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:27:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:27:45 INFO - 'PWD': '/builds/slave/test', 07:27:45 INFO - 'SHELL': '/bin/bash', 07:27:45 INFO - 'SHLVL': '1', 07:27:45 INFO - 'TERM': 'linux', 07:27:45 INFO - 'TMOUT': '86400', 07:27:45 INFO - 'USER': 'cltbld', 07:27:45 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1474121990.323651-1902662426', 07:27:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:27:45 INFO - '_': '/tools/buildbot/bin/python'} 07:27:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:27:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:27:46 INFO - Running setup.py (path:/tmp/pip-ksrcMh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:27:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:27:46 INFO - Running setup.py (path:/tmp/pip-lpMpyx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:27:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:27:46 INFO - Running setup.py (path:/tmp/pip-Ac2Jjc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:27:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:27:46 INFO - Running setup.py (path:/tmp/pip-rWwNsP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:27:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:27:46 INFO - Running setup.py (path:/tmp/pip-E5NxOn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:27:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:27:46 INFO - Running setup.py (path:/tmp/pip-hqxby5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:27:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:27:46 INFO - Running setup.py (path:/tmp/pip-h6Y_3g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:27:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:27:46 INFO - Running setup.py (path:/tmp/pip-p7xMhI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:27:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:27:46 INFO - Running setup.py (path:/tmp/pip-Y9h4Gw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:27:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:27:46 INFO - Running setup.py (path:/tmp/pip-YEwtGO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:27:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:27:46 INFO - Running setup.py (path:/tmp/pip-ZBljio-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:27:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:27:46 INFO - Running setup.py (path:/tmp/pip-yFDj56-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:27:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:27:46 INFO - Running setup.py (path:/tmp/pip-U1o1Oo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:27:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:27:47 INFO - Running setup.py (path:/tmp/pip-SDCvhk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:27:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:27:47 INFO - Running setup.py (path:/tmp/pip-_YnGRp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:27:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:27:47 INFO - Running setup.py (path:/tmp/pip-JSR2Xe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:27:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:27:47 INFO - Running setup.py (path:/tmp/pip-h0zvR7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:27:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:27:47 INFO - Running setup.py install for manifestparser 07:27:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:27:47 INFO - Running setup.py install for mozcrash 07:27:47 INFO - Running setup.py install for mozdebug 07:27:47 INFO - Running setup.py install for mozdevice 07:27:47 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:27:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:27:47 INFO - Running setup.py install for mozfile 07:27:47 INFO - Running setup.py install for mozhttpd 07:27:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:27:47 INFO - Running setup.py install for mozinfo 07:27:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:27:48 INFO - Running setup.py install for mozInstall 07:27:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:27:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:27:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:27:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:27:48 INFO - Running setup.py install for mozleak 07:27:48 INFO - Running setup.py install for mozlog 07:27:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:27:48 INFO - Running setup.py install for moznetwork 07:27:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:27:48 INFO - Running setup.py install for mozprocess 07:27:48 INFO - Running setup.py install for mozprofile 07:27:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:27:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:27:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:27:48 INFO - Running setup.py install for mozrunner 07:27:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:27:48 INFO - Running setup.py install for mozscreenshot 07:27:48 INFO - Running setup.py install for moztest 07:27:48 INFO - Running setup.py install for mozversion 07:27:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:27:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:27:49 INFO - Cleaning up... 07:27:49 INFO - Return code: 0 07:27:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:27:49 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')]} 07:27:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:27:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:49 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')]} 07:27:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:27:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/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 0x1f2c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x208f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21ed180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21eb3b0>, '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 0x21ec2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21ec740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1474121990.323651-1902662426', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 07:27:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/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/build/tests/config 07:27:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/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 07:27:49 INFO - Using env: {'DISPLAY': ':0', 07:27:49 INFO - 'HOME': '/home/cltbld', 07:27:49 INFO - 'LANG': 'en_US.UTF-8', 07:27:49 INFO - 'LANGUAGE': 'en_US:en', 07:27:49 INFO - 'LOGNAME': 'cltbld', 07:27:49 INFO - 'MAIL': '/var/mail/cltbld', 07:27:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:27:49 INFO - 'MOZ_NO_REMOTE': '1', 07:27:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:27:49 INFO - 'NO_EM_RESTART': '1', 07:27:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:27:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:27:49 INFO - 'PWD': '/builds/slave/test', 07:27:49 INFO - 'SHELL': '/bin/bash', 07:27:49 INFO - 'SHLVL': '1', 07:27:49 INFO - 'TERM': 'linux', 07:27:49 INFO - 'TMOUT': '86400', 07:27:49 INFO - 'USER': 'cltbld', 07:27:49 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1474121990.323651-1902662426', 07:27:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:27:49 INFO - '_': '/tools/buildbot/bin/python'} 07:27:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:27:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:27:49 INFO - Running setup.py (path:/tmp/pip-PUgvlD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:27:49 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 07:27:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:27:49 INFO - Running setup.py (path:/tmp/pip-yrbkBN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:27:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:27:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:27:49 INFO - Running setup.py (path:/tmp/pip-H2yswx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:27:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:27:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:27:49 INFO - Running setup.py (path:/tmp/pip-_Fgmno-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:27:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:27:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:27:49 INFO - Running setup.py (path:/tmp/pip-VFeLnX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:27:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 07:27:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:27:49 INFO - Running setup.py (path:/tmp/pip-Hb38Hf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:27:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 07:27:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:27:49 INFO - Running setup.py (path:/tmp/pip-7Nb53g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:27:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 07:27:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:27:49 INFO - Running setup.py (path:/tmp/pip-2dnAwu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:27:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 07:27:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:27:49 INFO - Running setup.py (path:/tmp/pip-aLu_N8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:27:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 07:27:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:27:49 INFO - Running setup.py (path:/tmp/pip-_iZEOh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:27:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:27:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:27:50 INFO - Running setup.py (path:/tmp/pip-fL6AZK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:27:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 07:27:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:27:50 INFO - Running setup.py (path:/tmp/pip-OxdA4i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:27:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 07:27:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:27:50 INFO - Running setup.py (path:/tmp/pip-AtginD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:27:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 07:27:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:27:50 INFO - Running setup.py (path:/tmp/pip-ftHFkn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:27:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 07:27:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:27:50 INFO - Running setup.py (path:/tmp/pip-qbnSa_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:27:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 07:27:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:27:50 INFO - Running setup.py (path:/tmp/pip-cbwePi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:27:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 07:27:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:27:50 INFO - Running setup.py (path:/tmp/pip-Fno3DL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:27:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 07:27:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:27:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:27:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:27:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:27:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:27:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:27:50 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 07:27:50 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 07:27:50 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 07:27:50 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 07:27:50 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 07:27:50 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 07:27:52 INFO - Downloading blessings-1.6.tar.gz 07:27:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:27:52 INFO - Installing collected packages: blessings 07:27:52 INFO - Running setup.py install for blessings 07:27:52 INFO - Successfully installed blessings 07:27:52 INFO - Cleaning up... 07:27:52 INFO - Return code: 0 07:27:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:27:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:27:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:27:52 INFO - Reading from file tmpfile_stdout 07:27:52 INFO - Current package versions: 07:27:52 INFO - argparse == 1.2.1 07:27:52 INFO - blessings == 1.6 07:27:52 INFO - blobuploader == 1.2.4 07:27:52 INFO - docopt == 0.6.1 07:27:52 INFO - functools32 == 3.2.3-2 07:27:52 INFO - jsonschema == 2.5.1 07:27:52 INFO - manifestparser == 1.1 07:27:52 INFO - mozInstall == 1.12 07:27:52 INFO - mozcrash == 0.17 07:27:52 INFO - mozdebug == 0.1 07:27:52 INFO - mozdevice == 0.48 07:27:52 INFO - mozfile == 1.2 07:27:52 INFO - mozhttpd == 0.7 07:27:52 INFO - mozinfo == 0.9 07:27:52 INFO - mozleak == 0.1 07:27:52 INFO - mozlog == 3.3 07:27:52 INFO - moznetwork == 0.27 07:27:52 INFO - mozprocess == 0.23 07:27:52 INFO - mozprofile == 0.28 07:27:52 INFO - mozrunner == 6.12 07:27:52 INFO - mozscreenshot == 0.1 07:27:52 INFO - mozsystemmonitor == 0.3 07:27:52 INFO - moztest == 0.7 07:27:52 INFO - mozversion == 1.4 07:27:52 INFO - psutil == 3.1.1 07:27:52 INFO - requests == 1.2.3 07:27:52 INFO - wsgiref == 0.1.2 07:27:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:27: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')]} 07:27:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:27:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27: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')]} 07:27:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:27:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/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 0x1f2c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x208f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21ed180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21eb3b0>, '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 0x21ec2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21ec740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1474121990.323651-1902662426', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 07:27:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/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/build/tests/talos 07:27:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/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 07:27:52 INFO - Using env: {'DISPLAY': ':0', 07:27:52 INFO - 'HOME': '/home/cltbld', 07:27:52 INFO - 'LANG': 'en_US.UTF-8', 07:27:52 INFO - 'LANGUAGE': 'en_US:en', 07:27:52 INFO - 'LOGNAME': 'cltbld', 07:27:52 INFO - 'MAIL': '/var/mail/cltbld', 07:27:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:27:52 INFO - 'MOZ_NO_REMOTE': '1', 07:27:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:27:52 INFO - 'NO_EM_RESTART': '1', 07:27:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:27:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:27:52 INFO - 'PWD': '/builds/slave/test', 07:27:52 INFO - 'SHELL': '/bin/bash', 07:27:52 INFO - 'SHLVL': '1', 07:27:52 INFO - 'TERM': 'linux', 07:27:52 INFO - 'TMOUT': '86400', 07:27:52 INFO - 'USER': 'cltbld', 07:27:52 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1474121990.323651-1902662426', 07:27:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:27:52 INFO - '_': '/tools/buildbot/bin/python'} 07:27:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:27:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:27:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 07:27:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 07:27:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:27:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 07:27:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 07:27:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 07:27:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 07:27:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 07:27:53 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:27:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:27:53 INFO - Cleaning up... 07:27:53 INFO - Return code: 0 07:27:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:27:53 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')]} 07:27:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:27:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:53 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')]} 07:27:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:27:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f2c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x208f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21ed180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21eb3b0>, '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 0x21ec2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21ec740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1474121990.323651-1902662426', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 07:27:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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', 'jsonschema'] in /builds/slave/test/build 07:27:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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 jsonschema 07:27:53 INFO - Using env: {'DISPLAY': ':0', 07:27:53 INFO - 'HOME': '/home/cltbld', 07:27:53 INFO - 'LANG': 'en_US.UTF-8', 07:27:53 INFO - 'LANGUAGE': 'en_US:en', 07:27:53 INFO - 'LOGNAME': 'cltbld', 07:27:53 INFO - 'MAIL': '/var/mail/cltbld', 07:27:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:27:53 INFO - 'MOZ_NO_REMOTE': '1', 07:27:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:27:53 INFO - 'NO_EM_RESTART': '1', 07:27:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:27:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:27:53 INFO - 'PWD': '/builds/slave/test', 07:27:53 INFO - 'SHELL': '/bin/bash', 07:27:53 INFO - 'SHLVL': '1', 07:27:53 INFO - 'TERM': 'linux', 07:27:53 INFO - 'TMOUT': '86400', 07:27:53 INFO - 'USER': 'cltbld', 07:27:53 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1474121990.323651-1902662426', 07:27:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:27:53 INFO - '_': '/tools/buildbot/bin/python'} 07:27:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:27:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:27:53 INFO - Cleaning up... 07:27:53 INFO - Return code: 0 07:27:53 INFO - Running post-action listener: _resource_record_post_action 07:27:53 INFO - Running post-action listener: _start_resource_monitoring 07:27:53 INFO - Starting resource monitoring. 07:27:53 INFO - [mozharness: 2016-09-17 14:27:53.349693Z] Finished create-virtualenv step (success) 07:27:53 INFO - [mozharness: 2016-09-17 14:27:53.350127Z] Running install step. 07:27:53 INFO - Running pre-action listener: _resource_record_pre_action 07:27:53 INFO - Running main action method: install 07:27:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:27:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:27:53 INFO - Reading from file tmpfile_stdout 07:27:53 INFO - Detecting whether we're running mozinstall >=1.0... 07:27:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:27:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:27:53 INFO - Reading from file tmpfile_stdout 07:27:53 INFO - Output received: 07:27:53 INFO - Usage: mozinstall [options] installer 07:27:53 INFO - Options: 07:27:53 INFO - -h, --help show this help message and exit 07:27:53 INFO - -d DEST, --destination=DEST 07:27:53 INFO - Directory to install application into. [default: 07:27:53 INFO - "/builds/slave/test"] 07:27:53 INFO - --app=APP Application being installed. [default: firefox] 07:27:53 INFO - mkdir: /builds/slave/test/build/application 07:27:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 07:27:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 07:28:10 INFO - Reading from file tmpfile_stdout 07:28:10 INFO - Output received: 07:28:10 INFO - /builds/slave/test/build/application/firefox/firefox 07:28:10 INFO - Running post-action listener: _resource_record_post_action 07:28:10 INFO - [mozharness: 2016-09-17 14:28:10.178991Z] Finished install step (success) 07:28:10 INFO - [mozharness: 2016-09-17 14:28:10.179270Z] Running run-tests step. 07:28:10 INFO - Running pre-action listener: _resource_record_pre_action 07:28:10 INFO - Running main action method: run_tests 07:28:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:28:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:28:10 INFO - Python 2.7.3 07:28:10 INFO - Return code: 0 07:28:10 INFO - grabbing minidump binary from tooltool 07:28:10 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')]} 07:28:10 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/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21eb3b0>, '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 0x21ec2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21ec740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:28:10 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 07:28:10 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 07:28:10 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 07:28:10 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:28:10 INFO - Return code: 0 07:28:10 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:28:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:28:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:28:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:28:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:28:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-016', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 07:28:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-016 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 07:28:10 INFO - Using env: {'DISPLAY': ':0', 07:28:10 INFO - 'HOME': '/home/cltbld', 07:28:10 INFO - 'LANG': 'en_US.UTF-8', 07:28:10 INFO - 'LANGUAGE': 'en_US:en', 07:28:10 INFO - 'LOGNAME': 'cltbld', 07:28:10 INFO - 'MAIL': '/var/mail/cltbld', 07:28:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:28:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:28:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:28:10 INFO - 'MOZ_NO_REMOTE': '1', 07:28:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:28:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:28:10 INFO - 'NO_EM_RESTART': '1', 07:28:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:28:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:28:10 INFO - 'PWD': '/builds/slave/test', 07:28:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:28:10 INFO - 'SHELL': '/bin/bash', 07:28:10 INFO - 'SHLVL': '1', 07:28:10 INFO - 'TERM': 'linux', 07:28:10 INFO - 'TMOUT': '86400', 07:28:10 INFO - 'USER': 'cltbld', 07:28:10 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1474121990.323651-1902662426', 07:28:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:28:10 INFO - '_': '/tools/buildbot/bin/python'} 07:28:10 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-016', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 07:28:10 INFO - mozversion application_buildid: 20160917004009 07:28:10 INFO - mozversion application_changeset: b9499912b32324acd84792bf02b533b40e98e496 07:28:10 INFO - mozversion application_display_name: FirefoxDeveloperEdition 07:28:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:28:10 INFO - mozversion application_name: Firefox 07:28:10 INFO - mozversion application_remotingname: firefox-dev 07:28:10 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:28:10 INFO - mozversion application_vendor: Mozilla 07:28:10 INFO - mozversion application_version: 50.0a2 07:28:10 INFO - mozversion platform_buildid: 20160917004009 07:28:10 INFO - mozversion platform_changeset: b9499912b32324acd84792bf02b533b40e98e496 07:28:10 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:28:10 INFO - mozversion platform_version: 50.0a2 07:28:10 INFO - using testdate: 1474122490 07:28:10 INFO - actual date: 1474122490 07:28:10 INFO - starting webserver on 'localhost:43400' 07:28:10 INFO - SUITE-START | Running 2 tests 07:28:10 INFO - TEST-START | dromaeo_css 07:28:10 INFO - operating with platform_type : linux_ 07:28:10 INFO - Initialising browser for dromaeo_css test... 07:28:10 INFO - TEST-INFO | started process 25657 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp06Jmu2/profile http://localhost:43400/getInfo.html) 07:28:14 INFO - PROCESS | 25657 | __metrics Screen width/height:1600/1200 07:28:14 INFO - PROCESS | 25657 | colorDepth:24 07:28:14 INFO - PROCESS | 25657 | Browser inner width/height: 1024/697 07:28:14 INFO - PROCESS | 25657 | __metrics 07:28:15 INFO - PROCESS | 25657 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:28:15 INFO - PROCESS | 25657 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:28:16 INFO - TEST-INFO | 25657: exit 0 07:28:16 INFO - Browser initialized. 07:28:16 INFO - Running cycle 1/1 for dromaeo_css test... 07:28:16 INFO - TEST-INFO | started process 25745 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp06Jmu2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:28:27 INFO - PROCESS | 25745 | RSS: Main: 146337792 07:28:27 INFO - PROCESS | 25745 | 07:29:18 INFO - PROCESS | 25745 | 1474122558832 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] 07:31:27 INFO - PROCESS | 25745 | Cycle 1(1): loaded http://localhost:43400/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43400/tests/dromaeo/cssquery-ext.html) 07:31:29 INFO - PROCESS | 25745 | RSS: Main: 283250688 07:31:29 INFO - PROCESS | 25745 | 07:34:31 INFO - PROCESS | 25745 | Cycle 1(1): loaded http://localhost:43400/tests/dromaeo/cssquery-ext.html (next: http://localhost:43400/tests/dromaeo/cssquery-jquery.html) 07:34:32 INFO - PROCESS | 25745 | RSS: Main: 239239168 07:34:32 INFO - PROCESS | 25745 | 07:37:34 INFO - PROCESS | 25745 | Cycle 1(1): loaded http://localhost:43400/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43400/tests/dromaeo/cssquery-mootools.html) 07:37:34 INFO - PROCESS | 25745 | RSS: Main: 176443392 07:37:34 INFO - PROCESS | 25745 | 07:40:36 INFO - PROCESS | 25745 | Cycle 1(1): loaded http://localhost:43400/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43400/tests/dromaeo/cssquery-prototype.html) 07:40:37 INFO - PROCESS | 25745 | RSS: Main: 174772224 07:40:37 INFO - PROCESS | 25745 | 07:43:39 INFO - PROCESS | 25745 | Cycle 1(1): loaded http://localhost:43400/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43400/tests/dromaeo/cssquery-yui.html) 07:43:40 INFO - PROCESS | 25745 | RSS: Main: 177356800 07:43:40 INFO - PROCESS | 25745 | 07:46:43 INFO - PROCESS | 25745 | Cycle 1(1): loaded http://localhost:43400/tests/dromaeo/cssquery-yui.html (next: http://localhost:43400/tests/dromaeo/cssquery-dojo.html) 07:46:43 INFO - PROCESS | 25745 | RSS: Main: 255373312 07:46:43 INFO - PROCESS | 25745 | 07:46:43 INFO - PROCESS | 25745 | __start_tp_report 07:46:43 INFO - PROCESS | 25745 | _x_x_mozilla_page_load 07:46:43 INFO - PROCESS | 25745 | _x_x_mozilla_page_load_details 07:46:43 INFO - PROCESS | 25745 | |i|pagename|runs| 07:46:43 INFO - PROCESS | 25745 | |0;dojo.html;3213.116392991927;3234.4824828027513;3283.5567198428207;3303.8678452861886;3323.3685599736064;5639.66162030278;5738.110592858108;5753.626014309078;5773.105168698848;5793.623414478061;5700.885982280362;5774.480296773289;5777.306723193212;5787.074068149086;5798.449147331007;5155.407128180251;5204.063268611639;5228.241904923784;5257.263983042358;5298.36419629645;5284.867878303054;5339.679619222859;5343.385510666284;5349.037173308803;5350.35795704516;5145.922811157817;5178.637495375326;5188.740562971855;5200.817971371002;5338.733063346817;5277.86805329866;5304.575633949314;5307.389650190239;5310.6813591184655;5320.281761962125;5054.570361519275;5097.7196254206;5105.387353517602;5206.401263854629;5223.033738758342;5631.521320687746;5658.575606829504;5669.121286200645;5676.659400435987;5683.488486036276;6168;6186.56694031418;6198.101275315099;6203.038529027963;6204.9689751551405;5999.400059993965;6088.238970128734;6113.689152827286;6137.6010559313945;6174.783882564134;5769.451902069297;5770.9422905770825;5779.104238842986;5783.624064435799;5801.7389217485315;6336.809895703136;6342;6343.080253363237;6348.301686814447;6350.714217860207;5875.089361149029;5937.52499800015;5968.1644569760265;5980.13288073227;6059.272887253558;6112.877742445126;6116.3883611638485;6119.816405507842;6137.447629713258;6143.232095988002;6018.247719035121;6081.79899933651;6100;6105.908411373833;6124.112003759519;5928.318243401977;6015.037593984942;6016.548758843104;6020.638761674313;6118.80192544137;4540.614047805908;4565.406497155349;4576.931346029745;4578.313253012008;4594.058218065288;4074.9185016299507;4086.3053280942313;4092.6725861930972;4105.91788164235;4127.422160897477;5714.971425142848;5755.625884317506;5798.623089499169;5803.709814509258;5824.155497452845;6164.851665427854;6178.752849886044;6204.565680402418;6206.596571222856;6209.9379006210265;5867.765289388462;5874.089516125007;5875.6474611523445;5882.264716910387;5920.141579471038;5050.49495050492;5072.644914855999;5117.8464646060675;5121.206213036985;5159.2003239497935;6302;6325.146105275913;6349.492040636738;6354.173957385603;6370;6081.604695694766;6154.1999540059505;6159.4456498915315;6162.106494558354;6162.506999440124;6174.44430001302;6213.844653883689;6277.215348081307;6301.4328710416285;6343;6075.104756101768;6078.90881636767;6080.939190608037;6086.239220097488;6126;6153.569789026232;6175.197224360804;6184.5980011299125;6197.752089916353;6201.007838745779;5860.824175274749;5962.612430192024;5985.042393217064;6031.125486804301;6116.3883611638485;6029.457348838626;6044.818655440168;6046.2139921809885;6074.817755467343;6100.450959413674;5993.70031498432;6006.309274433322;6034.487068599218;6055.515558755377;6057.878842423039;5973.313068997121;5980.461758441761;6003.519718422603;6036.486898613667;6083.543734219863;5923.229980102559;5930.733117009831;5937.851553711192;5945.970270148795;5954.523638109027 07:46:43 INFO - PROCESS | 25745 | |1;ext.html;572.6335145506877;606.657238660157;636.2778246690006;662.6620423583977;663.0849427789647;34517.65482345176;47205.76397118018;48268;48296.27555586669;48362.27456588154;48543;49000.50999490004;49001.26498102531;49065.754671226685;49079;8553.786155346104;9202.401843880143;9407.2474202064;9408.623655053789;11095.611653592126;10897.782044359148;11309.604163854267;11318.547258109666;11321.773564528705;11325.660230193107;11407.600733974312;11469;11476.713082172972;11479.713007174803;11481.196316257865;11378.2035257532;11418.942905285421;11445.999999999958;11453.484593193327;11462.54149834014;11170.329780213133;11236.21346505745;11241.887581124247;11246.49390777417;11247.212695111346;44934;45326.093478130584;45331.320030199575;45332.320015199795;45346;3920.3923391874305;4015.5984401559904;4066.5730098339545;4125.154343359604;4199.68502362321;35278.647213527795;35359.82320088383;35372.877627342445;35520.28959420823;35629.64370356289;4625.884352891171;4726.4328280606205;4796.208625576775;4807.759612019385;4876.414830220361;391107;391539;392021.99999999715;394016;395215;62157.37842621606;62409;62450;62481.062784057365;62553;39474.802625987264;40362;40380.394294085614;41732.79133604313;65920.6703966477;19564.90217548903;19849.60300793976;19856.90071549633;19870.403887883385;19874.801251987585;12804.42380092898;12953.093283470254;14242.928785356007;14572.417103315749;14844;20696.58606827885;20702.792972070085;20827.791722082584;20880.477988050116;20917.895410523153;7430.331270185709;7430.331270185709;7443.516171448839;7598.506097103671;7640.617969101634;1024.3546565663585;1094.0809719835377;1116.1238427834112;1123.5898896902572;1203.881169142316;33353.83323083369;35167.824160879034;35203.82398088044;35500.64499355025;35667.82166089205;1164.7612239490884;1165.6594915846674;1174.3834486894448;1253.3169422664669;1258.5217617305366;18411.079446027914;18483.210941756908;19096.61806763857;20120.49698757543;20145.395638130878;597068;602340;603837;604015;604432;64067.679661601396;64473;64573.67713161498;64664.353356465836;64673.353266467675;1341.9060665753304;1391.4156054457153;1460.5545308680742;1505.9247037648074;1505.954821355361;33691.49462758011;35997.280054398245;36250.4562431569;36322.455163172046;36565.45151822671;52418.475815241356;53186;53281;53380;53410;458490;463029;464088;464923;466659;30914.690853091182;30980.22549436282;32228.677713223504;32265;33112;52174.12987427542;52315.73842130765;52654.73672631612;52815.735921321684;53487.73256133695;43282;43506.129877402906;43674.781626091666;44595.77702111599;45310;43820.78089609532;43968.581728005265;44263.336049958634;45095.77452112718;45210.77394613006;44151;44954.3256851154;45166.32250516311;45215.547844521134;45381;37059.8147009274;37842.62157378391;38108.42837357497;38599.228015438974;39368.212635747695;29740.08017427381;29980.95066672656;30178.24554386158;30281.545776812924;30321.696783031886 07:46:43 INFO - PROCESS | 25745 | |2;jquery.html;396.59547261792954;411.8414410451975;430.56943056943055;436.09510266197645;437.549324196078;15302.923485382571;20214.09036593353;20790.584188316225;20836.583268334623;21267.680984785242;20817.27139550116;20824;20842.583148337024;20854;21114.89442552785;5681;5761.395053519362;6043.486303664172;6090.66501342426;6092.756289748405;5992.34084250732;6015.9607120933115;6063.787767428141;6076.2708474983065;6082.635041897478;5865.208196893429;5949.226600541923;5950.9107363389585;5968.940310596882;5981.760729570812;5768.913466298009;5867.061270196748;5962.07587823888;6006.159137720722;6006.7897623583185;5386.380566234894;5389.514943655051;5391.433899440542;5391.460853914617;5396;24134;24201.515969680684;24386;24460.755392446204;24771.380715482257;2629.842209467438;2649.1787545860843;2660.0955675070377;2755.3111722069484;3007.2181232879607;14385.496507622183;16573;17100.144992750313;17667.293308267654;17735.822641773546;2042.3260324093162;2108.95782084359;2152.5264441822774;2201.889905504718;2271.193726227182;100596.49701751518;101358;103871.48064259706;107205.46397268042;110063.44968275187;18893.52766180829;18998;19388.515287117934;19627.901860490892;20039.799602004085;35023.29953400918;35148.472772908426;35588.644113558534;35712.10719732028;35953;10936.070434013198;11083.72290692739;11175.497102630401;11221.438928053565;11311.490982905789;9550.092741189575;9559.952200238955;9592.808143837085;9629.181519570775;9851.359661621971;9153;9271.165595096474;9451.621935122517;9505.5722492488;9697.369670971364;3284.655111213336;3423.6576342365565;3594.281143771256;3623.4021386470936;3698.4267438547067;973.5132433783108;990.4898977026693;1007.5314978534869;1012.4785735346303;1033.2767063055892;21062.57874842525;21085.367438976857;21513.21148877257;21565.78434215669;21900.671489927663;1065.8507808906759;1086.9184811139196;1142.440204299887;1155.5204590095016;1161.227783523964;10961.561537538568;11253.156013299036;11431.256968297033;11496.712582185512;11569.363684997237;542220;543993;554901;557974;559542;92896;98511.50744246376;98719.50640246752;100531;101453.49273253734;1057.1225882517492;1075.655790147161;1106.1869525898458;1115.4144074360863;1121.6803211084964;18750.718739219195;19128.330508432136;19327.613447731248;19408.708869367252;19424;41878.37182442205;43016.569834301255;44010.55989440065;45215.32177017413;46374.53625463837;61543;61766.382336176066;61794;61903.6904815473;61980.38019619746;8690.695825646071;8697.95651021741;8767.430117042371;8875.73372798817;8981.14679105484;11121.666350009513;13240.271785051915;13244.933775331447;13466.79799802984;13627.38676759568;11759.76480470403;11905.226160299553;12006.579769708018;12113.33376664292;12413.813792792935;11321.264117832314;11462.254953428;11785.175037747618;11832.526698931946;12148.696282593006;11838.940805295919;12288.631341059783;12325.198862073936;12329.198602090835;12616.432260548114;8154.592270386575;8382.952130983627;8411.327093832357;8637.8704319434;9152.816943660957;9158.084191580789;12219;12599.93700031494;12615.747685046064;12616.55842045524 07:46:43 INFO - PROCESS | 25745 | |3;mootools.html;1443.9205843678594;1514.6440586462184;1548.3419546692655;1746.7205247160457;1880.3230836898722;8851.911480885197;9232.307576931731;9253.629854805808;9610.327277090606;9620.422774633527;9352.672656457025;9478.952605236964;9486.810263794738;9487.762805929839;9497.477638729868;2510.359858236152;2539.7079335876333;2543.326018605071;2543.504016716741;2544.3639090227443;2479.429731161834;2506.749325067488;2509.7992160627105;2515.937101572466;2516.4086439686753;2492.252324302711;2498.3379404457833;2501.124606387768;2506.385935445818;2529.140092368594;2394.8443351182204;2424.0425032112307;2424.151546958568;2425.4300239443714;2430.708315002202;2386.916457923982;2415.3720032791603;2415.625578035407;2416.1422694943394;2423.0671191591186;9127.817443651156;9209.401388909766;9211.493367864765;9492.145706886413;9603.4718090505;5657.943420565783;5886.882262354772;5888.852778680524;5895.764169433218;5896.587238893279;3745.400735882246;3834.2331533693095;3837.750546214515;3884.6309600587897;3898.1229223424784;1199.394305875536;1207.1791181996239;1209.1354681402752;1245.5702782540009;1262.09760021585;13433.932830335883;13519.459221631123;13548.390322435514;13565.796513052312;13571.592852214351;4855.271709243571;4873.585745211626;5041.470645582234;5066.670666406671;5170.97414512925;10815.080718138888;10816.621418250323;10822.134229261641;10822.945885270523;10835.295705779099;5910.674912879746;5923.911141332884;5927.703614819242;5929.347771745104;5930.377310382434;4689.976550117228;4822.252550854623;5353.839384818461;5358.035553600389;5503.367112782002;4163.306253319412;4166.562510936369;4231.576842315744;4257.808398622069;4265.808038638207;1205.825155352469;1224.1064023263068;1229.0352073622341;1245.414655112096;1248.15125714515;724.272106532931;810.1736229046339;814.5601375257413;817.6320655704837;825.1913125137279;5996.370381110008;6450.193725784277;6558.245801732766;6612.669366531654;6788.9660551696925;507.73343994402495;515.2400209690676;518.1657531374484;518.3753576939779;529.1190168369699;4516.390287311171;4598.448186217675;4598.931016034762;4695;4713.929291060636;11636.301821890713;11938.343391113573;12280.877191228152;12299.262044277371;12385.256884586954;12070.637880863587;12364.505419783109;12400.317982510867;12464.252144871336;12514.3742812859;8566.229039386486;8714.520701361485;8754.693585724597;8767.342449316327;8769.035406105324;6378.425941665272;6639.933600663931;6791.524593278423;6930.203026652;6972.7210911565;10817.40504272241;11156.386398748147;11189.384583847966;11189.776204476027;11190.496427660937;11291.548338066385;11345.149113816662;11397.487113079766;11400;11733.061355091402;8285.710000149966;8510.361722870686;8526.190011948855;8650.956745216485;8660.48037117775;9491.145796878314;9617.23062155012;9623.326367154512;9629.566669499749;9638.518074096408;8423.410361274651;8533.232009119209;8549;8691.043985161754;8759.956200218958;7965.163657816078;8012.038555373392;8012.759617211493;8065.072516660424;8265.710700125466;7677.692892284246;7743.341815945482;7761.61191940412;7820.608969551614;7902.407319450949;5599.272094627672;5698.686572238566;5797.826065218051;5832.591718579659;5960.314563825215;5808.535317174531;5852.268466441695;5934.6142500737315;5990.371011043778;5993.280806303272 07:46:43 INFO - PROCESS | 25745 | |4;prototype.html;343.4419069012854;346.5425638157631;354.26136505386273;362.35139101009196;364.72827743331237;5584.134459158827;5587.804426845061;5588.357338906027;5613.971930140354;5634.098544232918;5461.18082287657;5492.945070549304;5523.447655234475;5526.668399896009;5534.944650553493;3887.4751908492276;4067.389891516274;4299.527771531366;4324.805383757738;4332.718373305741;4317.913641727164;4357.803898824561;4382.51792302848;4386.9561304387025;4398.34024896265;4364.825406983717;4365.781710914457;4378.124375124972;4379.773663374261;4382.320740285261;4107.17856428714;4202.831886724527;4256.212600668886;4299.01122741768;4302.483701955754;3811.8653554009566;3812.3519001769764;3814.275287695329;3822.57951625321;3831.4252862070634;5426.240326354314;5430.83707488776;5444.549952521342;5449.754761035763;5486.753096110685;4769.356136921506;4919.2129259318335;4919.90160196794;4924.876878078041;4959.578435832958;5682.7442765075675;5700.914486282709;5721.141828725683;5723.685197314146;5731.828045158652;3981.5865367794595;4133.937990930139;4196.454460920061;4226.323788193905;4226.344916537942;5674.886502269973;5856.355800861902;5964.373740757244;5986.580939334249;5996.610220335664;4210.305299625527;4224.324108142682;4228.175505776406;4265.7440553566885;4280.72175308604;4308.655307575386;4322.265214913472;4333.458317710286;4333.5016473106;4345.130973805251;4720.693154944917;4897.334906131932;4929.556339929423;4930.506949305112;4952.678075925125;5763.827085187451;5800.53595712342;5803.47768700819;5863.3257175424515;5880.294364676266;1471.3158381352723;1487.9479218227527;1488.95533134004;1505.7741338799049;1506.6158129676862;925.0841666750003;951.8667386565747;958.9136977672043;961.1974001708512;976.3848775271675;4917.237828136645;4980.95399966004;5020.242914979728;5037.924431133536;5079.847604571869;5260.763265653055;5282.867928301746;5287.373548145373;5296.390915044819;5299.788008479618;4949.876253093629;4994.775235114428;5004.949950500449;5306.336707911511;5679.886402271932;3163.9525407118913;3189.3780712761254;3192.6328472225528;3206.198450387403;3213.4055199788113;6008.579399442085;6187.288461826857;6188.452886778306;6223.595466294676;6228.532860035515;5967.582269241111;6057.818265452043;6085.847853803691;6093.421124993119;6100.633961962208;6098.749756299948;6113.174721412642;6113.541484388688;6170.259568851677;6242.6266221431215;5794.246747922743;5943.64338139713;5953.404659534012;5959.940400595939;6013.548983826318;5910.822675319747;5916.7041647918295;5919.852003699942;5930.34766175729;5982.162497250475;5907.641242514332;6021.578489505692;6035.823014512128;6082.574219804571;6188.319284878749;4214.241436541452;4269;4286.592773686495;4297.785110744388;4298.634616057545;5765;5968.671723055274;5978;6049.546284028627;6058.697380063308;5788.768449262151;5792.82621521355;5834.679092649945;5836.474717275296;5853.970730146322;5811.27359080115;5818.359980402068;5826.475617194473;5828.242328497268;5828.795992140254;5555.50000499957;5754.395788442321;5825;5832;5843.1235314703135;5878.676672783038;5882.411758824083;5896.616719913192;5913.881722365442;5928.407159284209;5542.5565954722715;5777.884442311046;5791.391903850035;5817.243758311392;5823.097419899922 07:46:43 INFO - PROCESS | 25745 | |5;yui.html;830.9252167304945;857.5026484638909;864.5504337744372;907.9273658107352;921.1617428140394;15460;16484.505464836046;16489.587760305967;16542.586435339108;16583;16351.264193111308;16478.34086636533;16637.33450661972;16642.58393540162;16664.58338541537;731.0605871455168;868.3660927522913;897.7082448204327;903.8056817784175;908.0284096017264;903.2457897655432;903.9954800225989;904.900460949295;905.995470022649;906.365544119116;766.3294617209941;959.932804703671;961.7162936933587;961.8893827209855;962.0860182826308;912.3841407050247;919.6321471411422;926.2358554192817;926.7219834049826;932.7155217658645;689.4553302890711;692.6190595172635;693.3413257405434;693.4694958356861;693.8855088910324;15127.092374457568;15151.318190681446;15202.695946081016;15241.695166096726;15252.237388130548;14780.113193208334;15173.393064277416;15285.159316237603;15423.151726655029;15461.613459663486;12436.253824770543;12601.558945436956;12825.807612885814;13954.162750235018;14971;4726.83456079039;4735.928961065587;4765.690230135065;4791.257355109963;4905.484924082955;78364.57746934962;81189.59405202995;81341.59329203375;83039;83298.16701833025;11035.062019728253;11243.269187502787;11532.192746507832;11677.88322116785;11722.003629691571;22584.66123008156;22623.095076197096;23085;23142.53714925692;23160.42098947506;8577.82844343122;8669.696560620474;8690.435121717068;8750.47497150173;8830.205281524693;4844.709317440895;5073.213651883964;5174.7930082796265;5178.430372659004;5189.532942035235;3662.560492740835;3775.01849519127;3777.320082385197;3781.546214454283;3785.734998550075;503.8941822217302;638.1926862518917;647.6599785112853;721.7401735375261;722.259683824074;728.6429649471721;798.3453568074124;828.4656396624143;829.3655353654459;852.1350828908538;10559.36643801359;10689.946550267354;10800.729981750363;11327.32036077838;11490.310581365145;670.936261055199;706.8303607134353;713.258211460086;716.0440811516684;806.5483329335591;5061.497392909653;5068.949310506848;5105.591552675852;5155.9742201288755;5258;116146;127511;127892;128961.4499853418;129615;103373;103665.48167259115;112022.43988780004;116179.41910290564;120348;99352;100057;101360;101845.49077254419;103646.48176759068;10019.348742331726;10089.949550252202;10104.444255566015;10108.797824043624;10163.695089147337;56079.00760688262;57017.42982570121;58040.12939805822;58310;59733;60146.69926650339;71958.64020679863;78972.21027789879;80273;83898.58050709707;14332.49836255726;14656.193909334663;14870.330835112662;15061.246937652855;15332.080075195525;14539.92730036343;15378.615534611277;15815;15858.920705396398;16417.308123458923;15054.548363549111;15193.088414694712;15211.847881521044;15521.534353969399;16467.505974820295;14652.487162949672;14949.626259343604;15144.469943551923;16145.919270403572;16174.838251617333;13338;13500.324983750577;13588.86411135876;14321.64195895111;14443.133411995314;11694.941525292319;11917.284962902253;11928.4035798208;12668.303243321705;12700.87299126997;11396.259243149168;12101.939490302491;12709.80935285953;12933.676658083623;13067.52066197896 07:46:43 INFO - PROCESS | 25745 | __end_tp_report 07:46:43 INFO - PROCESS | 25745 | __start_cc_report 07:46:43 INFO - PROCESS | 25745 | _x_x_mozilla_cycle_collect,1226 07:46:43 INFO - PROCESS | 25745 | __end_cc_report 07:46:43 INFO - PROCESS | 25745 | __startTimestamp1474123603440__endTimestamp 07:46:43 INFO - PROCESS | 25745 | 07:46:43 INFO - PROCESS | 25745 | ------- Summary: start ------- 07:46:43 INFO - PROCESS | 25745 | Number of tests: 6 07:46:43 INFO - PROCESS | 25745 | 07:46:43 INFO - PROCESS | 25745 | [#0] dojo.html Cycles:165 Average:5695.58 Median:5945.97 stddev:667.60 (11.2%) stddev-sans-first:640.61 07:46:43 INFO - PROCESS | 25745 | Values: 3213.1 3234.5 3283.6 3303.9 3323.4 5639.7 5738.1 5753.6 5773.1 5793.6 5700.9 5774.5 5777.3 5787.1 5798.4 5155.4 5204.1 5228.2 5257.3 5298.4 5284.9 5339.7 5343.4 5349.0 5350.4 5145.9 5178.6 5188.7 5200.8 5338.7 5277.9 5304.6 5307.4 5310.7 5320.3 5054.6 5097.7 5105.4 5206.4 5223.0 5631.5 5658.6 5669.1 5676.7 5683.5 6168.0 6186.6 6198.1 6203.0 6205.0 5999.4 6088.2 6113.7 6137.6 6174.8 5769.5 5770.9 5779.1 5783.6 5801.7 6336.8 6342.0 6343.1 6348.3 6350.7 5875.1 5937.5 5968.2 5980.1 6059.3 6112.9 6116.4 6119.8 6137.4 6143.2 6018.2 6081.8 6100.0 6105.9 6124.1 5928.3 6015.0 6016.5 6020.6 6118.8 4540.6 4565.4 4576.9 4578.3 4594.1 4074.9 4086.3 4092.7 4105.9 4127.4 5715.0 5755.6 5798.6 5803.7 5824.2 6164.9 6178.8 6204.6 6206.6 6209.9 5867.8 5874.1 5875.6 5882.3 5920.1 5050.5 5072.6 5117.8 5121.2 5159.2 6302.0 6325.1 6349.5 6354.2 6370.0 6081.6 6154.2 6159.4 6162.1 6162.5 6174.4 6213.8 6277.2 6301.4 6343.0 6075.1 6078.9 6080.9 6086.2 6126.0 6153.6 6175.2 6184.6 6197.8 6201.0 5860.8 5962.6 5985.0 6031.1 6116.4 6029.5 6044.8 6046.2 6074.8 6100.5 5993.7 6006.3 6034.5 6055.5 6057.9 5973.3 5980.5 6003.5 6036.5 6083.5 5923.2 5930.7 5937.9 5946.0 5954.5 07:46:43 INFO - PROCESS | 25745 | 07:46:43 INFO - PROCESS | 25745 | [#1] ext.html Cycles:180 Average:65711.93 Median:216692.11 stddev:130958.64 (60.4%) stddev-sans-first:131234.69 07:46:43 INFO - PROCESS | 25745 | Values: 572.6 606.7 636.3 662.7 663.1 34517.7 47205.8 48268.0 48296.3 48362.3 48543.0 49000.5 49001.3 49065.8 49079.0 8553.8 9202.4 9407.2 9408.6 11095.6 10897.8 11309.6 11318.5 11321.8 11325.7 11407.6 11469.0 11476.7 11479.7 11481.2 11378.2 11418.9 11446.0 11453.5 11462.5 11170.3 11236.2 11241.9 11246.5 11247.2 44934.0 45326.1 45331.3 45332.3 45346.0 3920.4 4015.6 4066.6 4125.2 4199.7 35278.6 35359.8 35372.9 35520.3 35629.6 4625.9 4726.4 4796.2 4807.8 4876.4 391107.0 391539.0 392022.0 394016.0 395215.0 62157.4 62409.0 62450.0 62481.1 62553.0 39474.8 40362.0 40380.4 41732.8 65920.7 19564.9 19849.6 19856.9 19870.4 19874.8 12804.4 12953.1 14242.9 14572.4 14844.0 20696.6 20702.8 20827.8 20880.5 20917.9 7430.3 7430.3 7443.5 7598.5 7640.6 1024.4 1094.1 1116.1 1123.6 1203.9 33353.8 35167.8 35203.8 35500.6 35667.8 1164.8 1165.7 1174.4 1253.3 1258.5 18411.1 18483.2 19096.6 20120.5 20145.4 597068.0 602340.0 603837.0 604015.0 604432.0 64067.7 64473.0 64573.7 64664.4 64673.4 1341.9 1391.4 1460.6 1505.9 1506.0 33691.5 35997.3 36250.5 36322.5 36565.5 52418.5 53186.0 53281.0 53380.0 53410.0 458490.0 463029.0 464088.0 464923.0 466659.0 30914.7 30980.2 32228.7 32265.0 33112.0 52174.1 52315.7 52654.7 52815.7 53487.7 43282.0 43506.1 43674.8 44595.8 45310.0 43820.8 43968.6 44263.3 45095.8 45210.8 44151.0 44954.3 45166.3 45215.5 45381.0 37059.8 37842.6 38108.4 38599.2 39368.2 29740.1 29981.0 30178.2 30281.5 30321.7 07:46:43 INFO - PROCESS | 25745 | 07:46:43 INFO - PROCESS | 25745 | [#2] jquery.html Cycles:180 Average:33300.86 Median:24293.76 stddev:91028.73 (374.7%) stddev-sans-first:91250.56 07:46:43 INFO - PROCESS | 25745 | Values: 396.6 411.8 430.6 436.1 437.5 15302.9 20214.1 20790.6 20836.6 21267.7 20817.3 20824.0 20842.6 20854.0 21114.9 5681.0 5761.4 6043.5 6090.7 6092.8 5992.3 6016.0 6063.8 6076.3 6082.6 5865.2 5949.2 5950.9 5968.9 5981.8 5768.9 5867.1 5962.1 6006.2 6006.8 5386.4 5389.5 5391.4 5391.5 5396.0 24134.0 24201.5 24386.0 24460.8 24771.4 2629.8 2649.2 2660.1 2755.3 3007.2 14385.5 16573.0 17100.1 17667.3 17735.8 2042.3 2109.0 2152.5 2201.9 2271.2 100596.5 101358.0 103871.5 107205.5 110063.4 18893.5 18998.0 19388.5 19627.9 20039.8 35023.3 35148.5 35588.6 35712.1 35953.0 10936.1 11083.7 11175.5 11221.4 11311.5 9550.1 9560.0 9592.8 9629.2 9851.4 9153.0 9271.2 9451.6 9505.6 9697.4 3284.7 3423.7 3594.3 3623.4 3698.4 973.5 990.5 1007.5 1012.5 1033.3 21062.6 21085.4 21513.2 21565.8 21900.7 1065.9 1086.9 1142.4 1155.5 1161.2 10961.6 11253.2 11431.3 11496.7 11569.4 542220.0 543993.0 554901.0 557974.0 559542.0 92896.0 98511.5 98719.5 100531.0 101453.5 1057.1 1075.7 1106.2 1115.4 1121.7 18750.7 19128.3 19327.6 19408.7 19424.0 41878.4 43016.6 44010.6 45215.3 46374.5 61543.0 61766.4 61794.0 61903.7 61980.4 8690.7 8698.0 8767.4 8875.7 8981.1 11121.7 13240.3 13244.9 13466.8 13627.4 11759.8 11905.2 12006.6 12113.3 12413.8 11321.3 11462.3 11785.2 11832.5 12148.7 11838.9 12288.6 12325.2 12329.2 12616.4 8154.6 8383.0 8411.3 8637.9 9152.8 9158.1 12219.0 12599.9 12615.7 12616.6 07:46:43 INFO - PROCESS | 25745 | 07:46:43 INFO - PROCESS | 25745 | [#3] mootools.html Cycles:180 Average:6334.35 Median:2787.20 stddev:3665.80 (131.5%) stddev-sans-first:3657.66 07:46:43 INFO - PROCESS | 25745 | Values: 1443.9 1514.6 1548.3 1746.7 1880.3 8851.9 9232.3 9253.6 9610.3 9620.4 9352.7 9479.0 9486.8 9487.8 9497.5 2510.4 2539.7 2543.3 2543.5 2544.4 2479.4 2506.7 2509.8 2515.9 2516.4 2492.3 2498.3 2501.1 2506.4 2529.1 2394.8 2424.0 2424.2 2425.4 2430.7 2386.9 2415.4 2415.6 2416.1 2423.1 9127.8 9209.4 9211.5 9492.1 9603.5 5657.9 5886.9 5888.9 5895.8 5896.6 3745.4 3834.2 3837.8 3884.6 3898.1 1199.4 1207.2 1209.1 1245.6 1262.1 13433.9 13519.5 13548.4 13565.8 13571.6 4855.3 4873.6 5041.5 5066.7 5171.0 10815.1 10816.6 10822.1 10822.9 10835.3 5910.7 5923.9 5927.7 5929.3 5930.4 4690.0 4822.3 5353.8 5358.0 5503.4 4163.3 4166.6 4231.6 4257.8 4265.8 1205.8 1224.1 1229.0 1245.4 1248.2 724.3 810.2 814.6 817.6 825.2 5996.4 6450.2 6558.2 6612.7 6789.0 507.7 515.2 518.2 518.4 529.1 4516.4 4598.4 4598.9 4695.0 4713.9 11636.3 11938.3 12280.9 12299.3 12385.3 12070.6 12364.5 12400.3 12464.3 12514.4 8566.2 8714.5 8754.7 8767.3 8769.0 6378.4 6639.9 6791.5 6930.2 6972.7 10817.4 11156.4 11189.4 11189.8 11190.5 11291.5 11345.1 11397.5 11400.0 11733.1 8285.7 8510.4 8526.2 8651.0 8660.5 9491.1 9617.2 9623.3 9629.6 9638.5 8423.4 8533.2 8549.0 8691.0 8760.0 7965.2 8012.0 8012.8 8065.1 8265.7 7677.7 7743.3 7761.6 7820.6 7902.4 5599.3 5698.7 5797.8 5832.6 5960.3 5808.5 5852.3 5934.6 5990.4 5993.3 07:46:43 INFO - PROCESS | 25745 | 07:46:43 INFO - PROCESS | 25745 | [#4] prototype.html Cycles:180 Average:4848.01 Median:5508.20 stddev:1422.49 (25.8%) stddev-sans-first:1385.72 07:46:43 INFO - PROCESS | 25745 | Values: 343.4 346.5 354.3 362.4 364.7 5584.1 5587.8 5588.4 5614.0 5634.1 5461.2 5492.9 5523.4 5526.7 5534.9 3887.5 4067.4 4299.5 4324.8 4332.7 4317.9 4357.8 4382.5 4387.0 4398.3 4364.8 4365.8 4378.1 4379.8 4382.3 4107.2 4202.8 4256.2 4299.0 4302.5 3811.9 3812.4 3814.3 3822.6 3831.4 5426.2 5430.8 5444.5 5449.8 5486.8 4769.4 4919.2 4919.9 4924.9 4959.6 5682.7 5700.9 5721.1 5723.7 5731.8 3981.6 4133.9 4196.5 4226.3 4226.3 5674.9 5856.4 5964.4 5986.6 5996.6 4210.3 4224.3 4228.2 4265.7 4280.7 4308.7 4322.3 4333.5 4333.5 4345.1 4720.7 4897.3 4929.6 4930.5 4952.7 5763.8 5800.5 5803.5 5863.3 5880.3 1471.3 1487.9 1489.0 1505.8 1506.6 925.1 951.9 958.9 961.2 976.4 4917.2 4981.0 5020.2 5037.9 5079.8 5260.8 5282.9 5287.4 5296.4 5299.8 4949.9 4994.8 5004.9 5306.3 5679.9 3164.0 3189.4 3192.6 3206.2 3213.4 6008.6 6187.3 6188.5 6223.6 6228.5 5967.6 6057.8 6085.8 6093.4 6100.6 6098.7 6113.2 6113.5 6170.3 6242.6 5794.2 5943.6 5953.4 5959.9 6013.5 5910.8 5916.7 5919.9 5930.3 5982.2 5907.6 6021.6 6035.8 6082.6 6188.3 4214.2 4269.0 4286.6 4297.8 4298.6 5765.0 5968.7 5978.0 6049.5 6058.7 5788.8 5792.8 5834.7 5836.5 5854.0 5811.3 5818.4 5826.5 5828.2 5828.8 5555.5 5754.4 5825.0 5832.0 5843.1 5878.7 5882.4 5896.6 5913.9 5928.4 5542.6 5777.9 5791.4 5817.2 5823.1 07:46:43 INFO - PROCESS | 25745 | 07:46:43 INFO - PROCESS | 25745 | [#5] yui.html Cycles:180 Average:22762.42 Median:22854.05 stddev:33114.93 (144.9%) stddev-sans-first:33166.88 07:46:43 INFO - PROCESS | 25745 | Values: 830.9 857.5 864.6 907.9 921.2 15460.0 16484.5 16489.6 16542.6 16583.0 16351.3 16478.3 16637.3 16642.6 16664.6 731.1 868.4 897.7 903.8 908.0 903.2 904.0 904.9 906.0 906.4 766.3 959.9 961.7 961.9 962.1 912.4 919.6 926.2 926.7 932.7 689.5 692.6 693.3 693.5 693.9 15127.1 15151.3 15202.7 15241.7 15252.2 14780.1 15173.4 15285.2 15423.2 15461.6 12436.3 12601.6 12825.8 13954.2 14971.0 4726.8 4735.9 4765.7 4791.3 4905.5 78364.6 81189.6 81341.6 83039.0 83298.2 11035.1 11243.3 11532.2 11677.9 11722.0 22584.7 22623.1 23085.0 23142.5 23160.4 8577.8 8669.7 8690.4 8750.5 8830.2 4844.7 5073.2 5174.8 5178.4 5189.5 3662.6 3775.0 3777.3 3781.5 3785.7 503.9 638.2 647.7 721.7 722.3 728.6 798.3 828.5 829.4 852.1 10559.4 10689.9 10800.7 11327.3 11490.3 670.9 706.8 713.3 716.0 806.5 5061.5 5068.9 5105.6 5156.0 5258.0 116146.0 127511.0 127892.0 128961.4 129615.0 103373.0 103665.5 112022.4 116179.4 120348.0 99352.0 100057.0 101360.0 101845.5 103646.5 10019.3 10089.9 10104.4 10108.8 10163.7 56079.0 57017.4 58040.1 58310.0 59733.0 60146.7 71958.6 78972.2 80273.0 83898.6 14332.5 14656.2 14870.3 15061.2 15332.1 14539.9 15378.6 15815.0 15858.9 16417.3 15054.5 15193.1 15211.8 15521.5 16467.5 14652.5 14949.6 15144.5 16145.9 16174.8 13338.0 13500.3 13588.9 14321.6 14443.1 11694.9 11917.3 11928.4 12668.3 12700.9 11396.3 12101.9 12709.8 12933.7 13067.5 07:46:43 INFO - PROCESS | 25745 | -------- Summary: end -------- 07:46:43 INFO - PROCESS | 25745 | 07:46:44 INFO - TEST-INFO | 25745: exit 0 07:46:44 INFO - TEST-OK | dromaeo_css | took 1113748ms 07:46:44 INFO - TEST-START | kraken 07:46:44 INFO - operating with platform_type : linux_ 07:46:44 INFO - Initialising browser for kraken test... 07:46:44 INFO - TEST-INFO | started process 25949 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpp_753b/profile http://localhost:43400/getInfo.html) 07:46:48 INFO - PROCESS | 25949 | __metrics Screen width/height:1600/1200 07:46:48 INFO - PROCESS | 25949 | colorDepth:24 07:46:48 INFO - PROCESS | 25949 | Browser inner width/height: 1024/697 07:46:48 INFO - PROCESS | 25949 | __metrics 07:46:48 INFO - PROCESS | 25949 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:46:48 INFO - PROCESS | 25949 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:46:49 INFO - TEST-INFO | 25949: exit 0 07:46:49 INFO - Browser initialized. 07:46:49 INFO - Running cycle 1/1 for kraken test... 07:46:49 INFO - TEST-INFO | started process 26041 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpp_753b/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:47:01 INFO - PROCESS | 26041 | RSS: Main: 146251776 07:47:01 INFO - PROCESS | 26041 | 07:47:04 INFO - PROCESS | 26041 | Cycle 1(1): loaded http://localhost:43400/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43400/tests/kraken/driver.html?testName=audio-beat-detection) 07:47:04 INFO - PROCESS | 26041 | RSS: Main: 200949760 07:47:04 INFO - PROCESS | 26041 | 07:47:07 INFO - PROCESS | 26041 | Cycle 1(1): loaded http://localhost:43400/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43400/tests/kraken/driver.html?testName=audio-dft) 07:47:08 INFO - PROCESS | 26041 | RSS: Main: 185749504 07:47:08 INFO - PROCESS | 26041 | 07:47:10 INFO - PROCESS | 26041 | Cycle 1(1): loaded http://localhost:43400/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43400/tests/kraken/driver.html?testName=audio-fft) 07:47:11 INFO - PROCESS | 26041 | RSS: Main: 276385792 07:47:11 INFO - PROCESS | 26041 | 07:47:13 INFO - PROCESS | 26041 | Cycle 1(1): loaded http://localhost:43400/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43400/tests/kraken/driver.html?testName=audio-oscillator) 07:47:14 INFO - PROCESS | 26041 | RSS: Main: 182145024 07:47:14 INFO - PROCESS | 26041 | 07:47:16 INFO - PROCESS | 26041 | Cycle 1(1): loaded http://localhost:43400/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43400/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:47:16 INFO - PROCESS | 26041 | RSS: Main: 180568064 07:47:16 INFO - PROCESS | 26041 | 07:47:19 INFO - PROCESS | 26041 | Cycle 1(1): loaded http://localhost:43400/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43400/tests/kraken/driver.html?testName=imaging-darkroom) 07:47:20 INFO - PROCESS | 26041 | RSS: Main: 216772608 07:47:20 INFO - PROCESS | 26041 | 07:47:23 INFO - PROCESS | 26041 | Cycle 1(1): loaded http://localhost:43400/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43400/tests/kraken/driver.html?testName=imaging-desaturate) 07:47:24 INFO - PROCESS | 26041 | RSS: Main: 216182784 07:47:24 INFO - PROCESS | 26041 | 07:47:27 INFO - PROCESS | 26041 | Cycle 1(1): loaded http://localhost:43400/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43400/tests/kraken/driver.html?testName=json-parse-financial) 07:47:27 INFO - PROCESS | 26041 | RSS: Main: 215498752 07:47:27 INFO - PROCESS | 26041 | 07:47:29 INFO - PROCESS | 26041 | Cycle 1(1): loaded http://localhost:43400/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43400/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:47:29 INFO - PROCESS | 26041 | RSS: Main: 178098176 07:47:29 INFO - PROCESS | 26041 | 07:47:31 INFO - PROCESS | 26041 | Cycle 1(1): loaded http://localhost:43400/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43400/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:47:32 INFO - PROCESS | 26041 | RSS: Main: 185323520 07:47:32 INFO - PROCESS | 26041 | 07:47:34 INFO - PROCESS | 26041 | Cycle 1(1): loaded http://localhost:43400/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43400/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:47:34 INFO - PROCESS | 26041 | RSS: Main: 186331136 07:47:34 INFO - PROCESS | 26041 | 07:47:37 INFO - PROCESS | 26041 | Cycle 1(1): loaded http://localhost:43400/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43400/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:47:38 INFO - PROCESS | 26041 | RSS: Main: 181813248 07:47:38 INFO - PROCESS | 26041 | 07:47:40 INFO - PROCESS | 26041 | Cycle 1(1): loaded http://localhost:43400/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43400/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:47:41 INFO - PROCESS | 26041 | RSS: Main: 185131008 07:47:41 INFO - PROCESS | 26041 | 07:47:42 INFO - PROCESS | 26041 | Cycle 1(1): loaded http://localhost:43400/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43400/tests/kraken/driver.html?testName=ai-astar) 07:47:43 INFO - PROCESS | 26041 | RSS: Main: 236306432 07:47:43 INFO - PROCESS | 26041 | 07:47:43 INFO - PROCESS | 26041 | __start_tp_report 07:47:43 INFO - PROCESS | 26041 | _x_x_mozilla_page_load 07:47:43 INFO - PROCESS | 26041 | _x_x_mozilla_page_load_details 07:47:43 INFO - PROCESS | 26041 | |i|pagename|runs| 07:47:43 INFO - PROCESS | 26041 | |0;ai-astar;111;109;117;108;109;110;108;109;109;110 07:47:43 INFO - PROCESS | 26041 | |1;audio-beat-detection;143;115;139;165;115;151;147;117;169;116 07:47:43 INFO - PROCESS | 26041 | |2;audio-dft;162;163;160;161;160;160;161;160;161;161 07:47:43 INFO - PROCESS | 26041 | |3;audio-fft;75;74;75;175;75;75;75;76;183;76 07:47:43 INFO - PROCESS | 26041 | |4;audio-oscillator;83;84;146;82;82;84;155;82;83;146 07:47:43 INFO - PROCESS | 26041 | |5;imaging-gaussian-blur;117;117;117;117;118;117;118;117;116;117 07:47:43 INFO - PROCESS | 26041 | |6;imaging-darkroom;146;146;146;146;146;146;146;146;147;146 07:47:43 INFO - PROCESS | 26041 | |7;imaging-desaturate;95;95;94;96;95;95;96;95;95;95 07:47:43 INFO - PROCESS | 26041 | |8;json-parse-financial;49;50;51;51;54;51;52;52;49;51 07:47:43 INFO - PROCESS | 26041 | |9;json-stringify-tinderbox;60;62;61;61;61;62;61;61;65;61 07:47:43 INFO - PROCESS | 26041 | |10;stanford-crypto-aes;72;72;73;78;80;73;71;81;77;72 07:47:43 INFO - PROCESS | 26041 | |11;stanford-crypto-ccm;120;121;127;129;126;114;117;116;157;116 07:47:43 INFO - PROCESS | 26041 | |12;stanford-crypto-pbkdf2;156;153;157;152;151;156;153;151;151;153 07:47:43 INFO - PROCESS | 26041 | |13;stanford-crypto-sha256-iterative;64;64;65;61;61;61;70;71;61;61 07:47:43 INFO - PROCESS | 26041 | __end_tp_report 07:47:43 INFO - PROCESS | 26041 | __start_cc_report 07:47:43 INFO - PROCESS | 26041 | _x_x_mozilla_cycle_collect,1669 07:47:43 INFO - PROCESS | 26041 | __end_cc_report 07:47:43 INFO - PROCESS | 26041 | __startTimestamp1474123663176__endTimestamp 07:47:43 INFO - PROCESS | 26041 | 07:47:43 INFO - PROCESS | 26041 | ------- Summary: start ------- 07:47:43 INFO - PROCESS | 26041 | Number of tests: 14 07:47:43 INFO - PROCESS | 26041 | 07:47:43 INFO - PROCESS | 26041 | [#0] ai-astar Cycles:10 Average:110.00 Median:109.50 stddev:2.62 (2.4%) stddev-sans-first:2.76 07:47:43 INFO - PROCESS | 26041 | Values: 111.0 109.0 117.0 108.0 109.0 110.0 108.0 109.0 109.0 110.0 07:47:43 INFO - PROCESS | 26041 | 07:47:43 INFO - PROCESS | 26041 | [#1] audio-beat-detection Cycles:10 Average:137.70 Median:145.00 stddev:20.94 (14.4%) stddev-sans-first:22.13 07:47:43 INFO - PROCESS | 26041 | Values: 143.0 115.0 139.0 165.0 115.0 151.0 147.0 117.0 169.0 116.0 07:47:43 INFO - PROCESS | 26041 | 07:47:43 INFO - PROCESS | 26041 | [#2] audio-dft Cycles:10 Average:160.90 Median:161.00 stddev:0.99 (0.6%) stddev-sans-first:0.97 07:47:43 INFO - PROCESS | 26041 | Values: 162.0 163.0 160.0 161.0 160.0 160.0 161.0 160.0 161.0 161.0 07:47:43 INFO - PROCESS | 26041 | 07:47:43 INFO - PROCESS | 26041 | [#3] audio-fft Cycles:10 Average:95.90 Median:75.00 stddev:43.84 (58.5%) stddev-sans-first:45.84 07:47:43 INFO - PROCESS | 26041 | Values: 75.0 74.0 75.0 175.0 75.0 75.0 75.0 76.0 183.0 76.0 07:47:43 INFO - PROCESS | 26041 | 07:47:43 INFO - PROCESS | 26041 | [#4] audio-oscillator Cycles:10 Average:102.70 Median:82.50 stddev:32.05 (38.9%) stddev-sans-first:33.19 07:47:43 INFO - PROCESS | 26041 | Values: 83.0 84.0 146.0 82.0 82.0 84.0 155.0 82.0 83.0 146.0 07:47:43 INFO - PROCESS | 26041 | 07:47:43 INFO - PROCESS | 26041 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 07:47:43 INFO - PROCESS | 26041 | Values: 117.0 117.0 117.0 117.0 118.0 117.0 118.0 117.0 116.0 117.0 07:47:43 INFO - PROCESS | 26041 | 07:47:43 INFO - PROCESS | 26041 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.32 (0.2%) stddev-sans-first:0.33 07:47:43 INFO - PROCESS | 26041 | Values: 146.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 147.0 146.0 07:47:43 INFO - PROCESS | 26041 | 07:47:43 INFO - PROCESS | 26041 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 07:47:43 INFO - PROCESS | 26041 | Values: 95.0 95.0 94.0 96.0 95.0 95.0 96.0 95.0 95.0 95.0 07:47:43 INFO - PROCESS | 26041 | 07:47:43 INFO - PROCESS | 26041 | [#8] json-parse-financial Cycles:10 Average:51.00 Median:51.00 stddev:1.49 (2.9%) stddev-sans-first:1.39 07:47:43 INFO - PROCESS | 26041 | Values: 49.0 50.0 51.0 51.0 54.0 51.0 52.0 52.0 49.0 51.0 07:47:43 INFO - PROCESS | 26041 | 07:47:43 INFO - PROCESS | 26041 | [#9] json-stringify-tinderbox Cycles:10 Average:61.50 Median:61.00 stddev:1.35 (2.2%) stddev-sans-first:1.32 07:47:43 INFO - PROCESS | 26041 | Values: 60.0 62.0 61.0 61.0 61.0 62.0 61.0 61.0 65.0 61.0 07:47:43 INFO - PROCESS | 26041 | 07:47:43 INFO - PROCESS | 26041 | [#10] stanford-crypto-aes Cycles:10 Average:74.90 Median:75.00 stddev:3.73 (5.0%) stddev-sans-first:3.80 07:47:43 INFO - PROCESS | 26041 | Values: 72.0 72.0 73.0 78.0 80.0 73.0 71.0 81.0 77.0 72.0 07:47:43 INFO - PROCESS | 26041 | 07:47:43 INFO - PROCESS | 26041 | [#11] stanford-crypto-ccm Cycles:10 Average:124.30 Median:123.50 stddev:12.60 (10.2%) stddev-sans-first:13.26 07:47:43 INFO - PROCESS | 26041 | Values: 120.0 121.0 127.0 129.0 126.0 114.0 117.0 116.0 157.0 116.0 07:47:43 INFO - PROCESS | 26041 | 07:47:43 INFO - PROCESS | 26041 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.30 Median:153.00 stddev:2.26 (1.5%) stddev-sans-first:2.18 07:47:43 INFO - PROCESS | 26041 | Values: 156.0 153.0 157.0 152.0 151.0 156.0 153.0 151.0 151.0 153.0 07:47:43 INFO - PROCESS | 26041 | 07:47:43 INFO - PROCESS | 26041 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.90 Median:64.00 stddev:3.81 (6.0%) stddev-sans-first:4.04 07:47:43 INFO - PROCESS | 26041 | Values: 64.0 64.0 65.0 61.0 61.0 61.0 70.0 71.0 61.0 61.0 07:47:43 INFO - PROCESS | 26041 | -------- Summary: end -------- 07:47:43 INFO - PROCESS | 26041 | 07:47:44 INFO - TEST-INFO | 26041: exit 0 07:47:44 INFO - TEST-OK | kraken | took 59957ms 07:47:44 INFO - SUITE-END | took 1173s 07:47:44 INFO - Completed test suite (00:19:33) 07:47:44 INFO - javascript benchmark 07:47:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3213.116392991927, 3234.4824828027513, 3283.5567198428207, 3303.8678452861886, 3323.3685599736064, 5639.66162030278, 5738.110592858108, 5753.626014309078, 5773.105168698848, 5793.623414478061, 5700.885982280362, 5774.480296773289, 5777.306723193212, 5787.074068149086, 5798.449147331007, 5155.407128180251, 5204.063268611639, 5228.241904923784, 5257.263983042358, 5298.36419629645, 5284.867878303054, 5339.679619222859, 5343.385510666284, 5349.037173308803, 5350.35795704516, 5145.922811157817, 5178.637495375326, 5188.740562971855, 5200.817971371002, 5338.733063346817, 5277.86805329866, 5304.575633949314, 5307.389650190239, 5310.6813591184655, 5320.281761962125, 5054.570361519275, 5097.7196254206, 5105.387353517602, 5206.401263854629, 5223.033738758342, 5631.521320687746, 5658.575606829504, 5669.121286200645, 5676.659400435987, 5683.488486036276, 6168.0, 6186.56694031418, 6198.101275315099, 6203.038529027963, 6204.9689751551405, 5999.400059993965, 6088.238970128734, 6113.689152827286, 6137.6010559313945, 6174.783882564134, 5769.451902069297, 5770.9422905770825, 5779.104238842986, 5783.624064435799, 5801.7389217485315, 6336.809895703136, 6342.0, 6343.080253363237, 6348.301686814447, 6350.714217860207, 5875.089361149029, 5937.52499800015, 5968.1644569760265, 5980.13288073227, 6059.272887253558, 6112.877742445126, 6116.3883611638485, 6119.816405507842, 6137.447629713258, 6143.232095988002, 6018.247719035121, 6081.79899933651, 6100.0, 6105.908411373833, 6124.112003759519, 5928.318243401977, 6015.037593984942, 6016.548758843104, 6020.638761674313, 6118.80192544137, 4540.614047805908, 4565.406497155349, 4576.931346029745, 4578.313253012008, 4594.058218065288, 4074.9185016299507, 4086.3053280942313, 4092.6725861930972, 4105.91788164235, 4127.422160897477, 5714.971425142848, 5755.625884317506, 5798.623089499169, 5803.709814509258, 5824.155497452845, 6164.851665427854, 6178.752849886044, 6204.565680402418, 6206.596571222856, 6209.9379006210265, 5867.765289388462, 5874.089516125007, 5875.6474611523445, 5882.264716910387, 5920.141579471038, 5050.49495050492, 5072.644914855999, 5117.8464646060675, 5121.206213036985, 5159.2003239497935, 6302.0, 6325.146105275913, 6349.492040636738, 6354.173957385603, 6370.0, 6081.604695694766, 6154.1999540059505, 6159.4456498915315, 6162.106494558354, 6162.506999440124, 6174.44430001302, 6213.844653883689, 6277.215348081307, 6301.4328710416285, 6343.0, 6075.104756101768, 6078.90881636767, 6080.939190608037, 6086.239220097488, 6126.0, 6153.569789026232, 6175.197224360804, 6184.5980011299125, 6197.752089916353, 6201.007838745779, 5860.824175274749, 5962.612430192024, 5985.042393217064, 6031.125486804301, 6116.3883611638485, 6029.457348838626, 6044.818655440168, 6046.2139921809885, 6074.817755467343, 6100.450959413674, 5993.70031498432, 6006.309274433322, 6034.487068599218, 6055.515558755377, 6057.878842423039, 5973.313068997121, 5980.461758441761, 6003.519718422603, 6036.486898613667, 6083.543734219863, 5923.229980102559, 5930.733117009831, 5937.851553711192, 5945.970270148795, 5954.523638109027], "value": 5648.536720223186, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [572.6335145506877, 606.657238660157, 636.2778246690006, 662.6620423583977, 663.0849427789647, 34517.65482345176, 47205.76397118018, 48268.0, 48296.27555586669, 48362.27456588154, 48543.0, 49000.50999490004, 49001.26498102531, 49065.754671226685, 49079.0, 8553.786155346104, 9202.401843880143, 9407.2474202064, 9408.623655053789, 11095.611653592126, 10897.782044359148, 11309.604163854267, 11318.547258109666, 11321.773564528705, 11325.660230193107, 11407.600733974312, 11469.0, 11476.713082172972, 11479.713007174803, 11481.196316257865, 11378.2035257532, 11418.942905285421, 11445.999999999958, 11453.484593193327, 11462.54149834014, 11170.329780213133, 11236.21346505745, 11241.887581124247, 11246.49390777417, 11247.212695111346, 44934.0, 45326.093478130584, 45331.320030199575, 45332.320015199795, 45346.0, 3920.3923391874305, 4015.5984401559904, 4066.5730098339545, 4125.154343359604, 4199.68502362321, 35278.647213527795, 35359.82320088383, 35372.877627342445, 35520.28959420823, 35629.64370356289, 4625.884352891171, 4726.4328280606205, 4796.208625576775, 4807.759612019385, 4876.414830220361, 391107.0, 391539.0, 392021.99999999715, 394016.0, 395215.0, 62157.37842621606, 62409.0, 62450.0, 62481.062784057365, 62553.0, 39474.802625987264, 40362.0, 40380.394294085614, 41732.79133604313, 65920.6703966477, 19564.90217548903, 19849.60300793976, 19856.90071549633, 19870.403887883385, 19874.801251987585, 12804.42380092898, 12953.093283470254, 14242.928785356007, 14572.417103315749, 14844.0, 20696.58606827885, 20702.792972070085, 20827.791722082584, 20880.477988050116, 20917.895410523153, 7430.331270185709, 7430.331270185709, 7443.516171448839, 7598.506097103671, 7640.617969101634, 1024.3546565663585, 1094.0809719835377, 1116.1238427834112, 1123.5898896902572, 1203.881169142316, 33353.83323083369, 35167.824160879034, 35203.82398088044, 35500.64499355025, 35667.82166089205, 1164.7612239490884, 1165.6594915846674, 1174.3834486894448, 1253.3169422664669, 1258.5217617305366, 18411.079446027914, 18483.210941756908, 19096.61806763857, 20120.49698757543, 20145.395638130878, 597068.0, 602340.0, 603837.0, 604015.0, 604432.0, 64067.679661601396, 64473.0, 64573.67713161498, 64664.353356465836, 64673.353266467675, 1341.9060665753304, 1391.4156054457153, 1460.5545308680742, 1505.9247037648074, 1505.954821355361, 33691.49462758011, 35997.280054398245, 36250.4562431569, 36322.455163172046, 36565.45151822671, 52418.475815241356, 53186.0, 53281.0, 53380.0, 53410.0, 458490.0, 463029.0, 464088.0, 464923.0, 466659.0, 30914.690853091182, 30980.22549436282, 32228.677713223504, 32265.0, 33112.0, 52174.12987427542, 52315.73842130765, 52654.73672631612, 52815.735921321684, 53487.73256133695, 43282.0, 43506.129877402906, 43674.781626091666, 44595.77702111599, 45310.0, 43820.78089609532, 43968.581728005265, 44263.336049958634, 45095.77452112718, 45210.77394613006, 44151.0, 44954.3256851154, 45166.32250516311, 45215.547844521134, 45381.0, 37059.8147009274, 37842.62157378391, 38108.42837357497, 38599.228015438974, 39368.212635747695, 29740.08017427381, 29980.95066672656, 30178.24554386158, 30281.545776812924, 30321.696783031886], "value": 22097.083772542606, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [396.59547261792954, 411.8414410451975, 430.56943056943055, 436.09510266197645, 437.549324196078, 15302.923485382571, 20214.09036593353, 20790.584188316225, 20836.583268334623, 21267.680984785242, 20817.27139550116, 20824.0, 20842.583148337024, 20854.0, 21114.89442552785, 5681.0, 5761.395053519362, 6043.486303664172, 6090.66501342426, 6092.756289748405, 5992.34084250732, 6015.9607120933115, 6063.787767428141, 6076.2708474983065, 6082.635041897478, 5865.208196893429, 5949.226600541923, 5950.9107363389585, 5968.940310596882, 5981.760729570812, 5768.913466298009, 5867.061270196748, 5962.07587823888, 6006.159137720722, 6006.7897623583185, 5386.380566234894, 5389.514943655051, 5391.433899440542, 5391.460853914617, 5396.0, 24134.0, 24201.515969680684, 24386.0, 24460.755392446204, 24771.380715482257, 2629.842209467438, 2649.1787545860843, 2660.0955675070377, 2755.3111722069484, 3007.2181232879607, 14385.496507622183, 16573.0, 17100.144992750313, 17667.293308267654, 17735.822641773546, 2042.3260324093162, 2108.95782084359, 2152.5264441822774, 2201.889905504718, 2271.193726227182, 100596.49701751518, 101358.0, 103871.48064259706, 107205.46397268042, 110063.44968275187, 18893.52766180829, 18998.0, 19388.515287117934, 19627.901860490892, 20039.799602004085, 35023.29953400918, 35148.472772908426, 35588.644113558534, 35712.10719732028, 35953.0, 10936.070434013198, 11083.72290692739, 11175.497102630401, 11221.438928053565, 11311.490982905789, 9550.092741189575, 9559.952200238955, 9592.808143837085, 9629.181519570775, 9851.359661621971, 9153.0, 9271.165595096474, 9451.621935122517, 9505.5722492488, 9697.369670971364, 3284.655111213336, 3423.6576342365565, 3594.281143771256, 3623.4021386470936, 3698.4267438547067, 973.5132433783108, 990.4898977026693, 1007.5314978534869, 1012.4785735346303, 1033.2767063055892, 21062.57874842525, 21085.367438976857, 21513.21148877257, 21565.78434215669, 21900.671489927663, 1065.8507808906759, 1086.9184811139196, 1142.440204299887, 1155.5204590095016, 1161.227783523964, 10961.561537538568, 11253.156013299036, 11431.256968297033, 11496.712582185512, 11569.363684997237, 542220.0, 543993.0, 554901.0, 557974.0, 559542.0, 92896.0, 98511.50744246376, 98719.50640246752, 100531.0, 101453.49273253734, 1057.1225882517492, 1075.655790147161, 1106.1869525898458, 1115.4144074360863, 1121.6803211084964, 18750.718739219195, 19128.330508432136, 19327.613447731248, 19408.708869367252, 19424.0, 41878.37182442205, 43016.569834301255, 44010.55989440065, 45215.32177017413, 46374.53625463837, 61543.0, 61766.382336176066, 61794.0, 61903.6904815473, 61980.38019619746, 8690.695825646071, 8697.95651021741, 8767.430117042371, 8875.73372798817, 8981.14679105484, 11121.666350009513, 13240.271785051915, 13244.933775331447, 13466.79799802984, 13627.38676759568, 11759.76480470403, 11905.226160299553, 12006.579769708018, 12113.33376664292, 12413.813792792935, 11321.264117832314, 11462.254953428, 11785.175037747618, 11832.526698931946, 12148.696282593006, 11838.940805295919, 12288.631341059783, 12325.198862073936, 12329.198602090835, 12616.432260548114, 8154.592270386575, 8382.952130983627, 8411.327093832357, 8637.8704319434, 9152.816943660957, 9158.084191580789, 12219.0, 12599.93700031494, 12615.747685046064, 12616.55842045524], "value": 10726.264017702517, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1443.9205843678594, 1514.6440586462184, 1548.3419546692655, 1746.7205247160457, 1880.3230836898722, 8851.911480885197, 9232.307576931731, 9253.629854805808, 9610.327277090606, 9620.422774633527, 9352.672656457025, 9478.952605236964, 9486.810263794738, 9487.762805929839, 9497.477638729868, 2510.359858236152, 2539.7079335876333, 2543.326018605071, 2543.504016716741, 2544.3639090227443, 2479.429731161834, 2506.749325067488, 2509.7992160627105, 2515.937101572466, 2516.4086439686753, 2492.252324302711, 2498.3379404457833, 2501.124606387768, 2506.385935445818, 2529.140092368594, 2394.8443351182204, 2424.0425032112307, 2424.151546958568, 2425.4300239443714, 2430.708315002202, 2386.916457923982, 2415.3720032791603, 2415.625578035407, 2416.1422694943394, 2423.0671191591186, 9127.817443651156, 9209.401388909766, 9211.493367864765, 9492.145706886413, 9603.4718090505, 5657.943420565783, 5886.882262354772, 5888.852778680524, 5895.764169433218, 5896.587238893279, 3745.400735882246, 3834.2331533693095, 3837.750546214515, 3884.6309600587897, 3898.1229223424784, 1199.394305875536, 1207.1791181996239, 1209.1354681402752, 1245.5702782540009, 1262.09760021585, 13433.932830335883, 13519.459221631123, 13548.390322435514, 13565.796513052312, 13571.592852214351, 4855.271709243571, 4873.585745211626, 5041.470645582234, 5066.670666406671, 5170.97414512925, 10815.080718138888, 10816.621418250323, 10822.134229261641, 10822.945885270523, 10835.295705779099, 5910.674912879746, 5923.911141332884, 5927.703614819242, 5929.347771745104, 5930.377310382434, 4689.976550117228, 4822.252550854623, 5353.839384818461, 5358.035553600389, 5503.367112782002, 4163.306253319412, 4166.562510936369, 4231.576842315744, 4257.808398622069, 4265.808038638207, 1205.825155352469, 1224.1064023263068, 1229.0352073622341, 1245.414655112096, 1248.15125714515, 724.272106532931, 810.1736229046339, 814.5601375257413, 817.6320655704837, 825.1913125137279, 5996.370381110008, 6450.193725784277, 6558.245801732766, 6612.669366531654, 6788.9660551696925, 507.73343994402495, 515.2400209690676, 518.1657531374484, 518.3753576939779, 529.1190168369699, 4516.390287311171, 4598.448186217675, 4598.931016034762, 4695.0, 4713.929291060636, 11636.301821890713, 11938.343391113573, 12280.877191228152, 12299.262044277371, 12385.256884586954, 12070.637880863587, 12364.505419783109, 12400.317982510867, 12464.252144871336, 12514.3742812859, 8566.229039386486, 8714.520701361485, 8754.693585724597, 8767.342449316327, 8769.035406105324, 6378.425941665272, 6639.933600663931, 6791.524593278423, 6930.203026652, 6972.7210911565, 10817.40504272241, 11156.386398748147, 11189.384583847966, 11189.776204476027, 11190.496427660937, 11291.548338066385, 11345.149113816662, 11397.487113079766, 11400.0, 11733.061355091402, 8285.710000149966, 8510.361722870686, 8526.190011948855, 8650.956745216485, 8660.48037117775, 9491.145796878314, 9617.23062155012, 9623.326367154512, 9629.566669499749, 9638.518074096408, 8423.410361274651, 8533.232009119209, 8549.0, 8691.043985161754, 8759.956200218958, 7965.163657816078, 8012.038555373392, 8012.759617211493, 8065.072516660424, 8265.710700125466, 7677.692892284246, 7743.341815945482, 7761.61191940412, 7820.608969551614, 7902.407319450949, 5599.272094627672, 5698.686572238566, 5797.826065218051, 5832.591718579659, 5960.314563825215, 5808.535317174531, 5852.268466441695, 5934.6142500737315, 5990.371011043778, 5993.280806303272], "value": 4922.4372239970335, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.4419069012854, 346.5425638157631, 354.26136505386273, 362.35139101009196, 364.72827743331237, 5584.134459158827, 5587.804426845061, 5588.357338906027, 5613.971930140354, 5634.098544232918, 5461.18082287657, 5492.945070549304, 5523.447655234475, 5526.668399896009, 5534.944650553493, 3887.4751908492276, 4067.389891516274, 4299.527771531366, 4324.805383757738, 4332.718373305741, 4317.913641727164, 4357.803898824561, 4382.51792302848, 4386.9561304387025, 4398.34024896265, 4364.825406983717, 4365.781710914457, 4378.124375124972, 4379.773663374261, 4382.320740285261, 4107.17856428714, 4202.831886724527, 4256.212600668886, 4299.01122741768, 4302.483701955754, 3811.8653554009566, 3812.3519001769764, 3814.275287695329, 3822.57951625321, 3831.4252862070634, 5426.240326354314, 5430.83707488776, 5444.549952521342, 5449.754761035763, 5486.753096110685, 4769.356136921506, 4919.2129259318335, 4919.90160196794, 4924.876878078041, 4959.578435832958, 5682.7442765075675, 5700.914486282709, 5721.141828725683, 5723.685197314146, 5731.828045158652, 3981.5865367794595, 4133.937990930139, 4196.454460920061, 4226.323788193905, 4226.344916537942, 5674.886502269973, 5856.355800861902, 5964.373740757244, 5986.580939334249, 5996.610220335664, 4210.305299625527, 4224.324108142682, 4228.175505776406, 4265.7440553566885, 4280.72175308604, 4308.655307575386, 4322.265214913472, 4333.458317710286, 4333.5016473106, 4345.130973805251, 4720.693154944917, 4897.334906131932, 4929.556339929423, 4930.506949305112, 4952.678075925125, 5763.827085187451, 5800.53595712342, 5803.47768700819, 5863.3257175424515, 5880.294364676266, 1471.3158381352723, 1487.9479218227527, 1488.95533134004, 1505.7741338799049, 1506.6158129676862, 925.0841666750003, 951.8667386565747, 958.9136977672043, 961.1974001708512, 976.3848775271675, 4917.237828136645, 4980.95399966004, 5020.242914979728, 5037.924431133536, 5079.847604571869, 5260.763265653055, 5282.867928301746, 5287.373548145373, 5296.390915044819, 5299.788008479618, 4949.876253093629, 4994.775235114428, 5004.949950500449, 5306.336707911511, 5679.886402271932, 3163.9525407118913, 3189.3780712761254, 3192.6328472225528, 3206.198450387403, 3213.4055199788113, 6008.579399442085, 6187.288461826857, 6188.452886778306, 6223.595466294676, 6228.532860035515, 5967.582269241111, 6057.818265452043, 6085.847853803691, 6093.421124993119, 6100.633961962208, 6098.749756299948, 6113.174721412642, 6113.541484388688, 6170.259568851677, 6242.6266221431215, 5794.246747922743, 5943.64338139713, 5953.404659534012, 5959.940400595939, 6013.548983826318, 5910.822675319747, 5916.7041647918295, 5919.852003699942, 5930.34766175729, 5982.162497250475, 5907.641242514332, 6021.578489505692, 6035.823014512128, 6082.574219804571, 6188.319284878749, 4214.241436541452, 4269.0, 4286.592773686495, 4297.785110744388, 4298.634616057545, 5765.0, 5968.671723055274, 5978.0, 6049.546284028627, 6058.697380063308, 5788.768449262151, 5792.82621521355, 5834.679092649945, 5836.474717275296, 5853.970730146322, 5811.27359080115, 5818.359980402068, 5826.475617194473, 5828.242328497268, 5828.795992140254, 5555.50000499957, 5754.395788442321, 5825.0, 5832.0, 5843.1235314703135, 5878.676672783038, 5882.411758824083, 5896.616719913192, 5913.881722365442, 5928.407159284209, 5542.5565954722715, 5777.884442311046, 5791.391903850035, 5817.243758311392, 5823.097419899922], "value": 4396.963529102495, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [830.9252167304945, 857.5026484638909, 864.5504337744372, 907.9273658107352, 921.1617428140394, 15460.0, 16484.505464836046, 16489.587760305967, 16542.586435339108, 16583.0, 16351.264193111308, 16478.34086636533, 16637.33450661972, 16642.58393540162, 16664.58338541537, 731.0605871455168, 868.3660927522913, 897.7082448204327, 903.8056817784175, 908.0284096017264, 903.2457897655432, 903.9954800225989, 904.900460949295, 905.995470022649, 906.365544119116, 766.3294617209941, 959.932804703671, 961.7162936933587, 961.8893827209855, 962.0860182826308, 912.3841407050247, 919.6321471411422, 926.2358554192817, 926.7219834049826, 932.7155217658645, 689.4553302890711, 692.6190595172635, 693.3413257405434, 693.4694958356861, 693.8855088910324, 15127.092374457568, 15151.318190681446, 15202.695946081016, 15241.695166096726, 15252.237388130548, 14780.113193208334, 15173.393064277416, 15285.159316237603, 15423.151726655029, 15461.613459663486, 12436.253824770543, 12601.558945436956, 12825.807612885814, 13954.162750235018, 14971.0, 4726.83456079039, 4735.928961065587, 4765.690230135065, 4791.257355109963, 4905.484924082955, 78364.57746934962, 81189.59405202995, 81341.59329203375, 83039.0, 83298.16701833025, 11035.062019728253, 11243.269187502787, 11532.192746507832, 11677.88322116785, 11722.003629691571, 22584.66123008156, 22623.095076197096, 23085.0, 23142.53714925692, 23160.42098947506, 8577.82844343122, 8669.696560620474, 8690.435121717068, 8750.47497150173, 8830.205281524693, 4844.709317440895, 5073.213651883964, 5174.7930082796265, 5178.430372659004, 5189.532942035235, 3662.560492740835, 3775.01849519127, 3777.320082385197, 3781.546214454283, 3785.734998550075, 503.8941822217302, 638.1926862518917, 647.6599785112853, 721.7401735375261, 722.259683824074, 728.6429649471721, 798.3453568074124, 828.4656396624143, 829.3655353654459, 852.1350828908538, 10559.36643801359, 10689.946550267354, 10800.729981750363, 11327.32036077838, 11490.310581365145, 670.936261055199, 706.8303607134353, 713.258211460086, 716.0440811516684, 806.5483329335591, 5061.497392909653, 5068.949310506848, 5105.591552675852, 5155.9742201288755, 5258.0, 116146.0, 127511.0, 127892.0, 128961.4499853418, 129615.0, 103373.0, 103665.48167259115, 112022.43988780004, 116179.41910290564, 120348.0, 99352.0, 100057.0, 101360.0, 101845.49077254419, 103646.48176759068, 10019.348742331726, 10089.949550252202, 10104.444255566015, 10108.797824043624, 10163.695089147337, 56079.00760688262, 57017.42982570121, 58040.12939805822, 58310.0, 59733.0, 60146.69926650339, 71958.64020679863, 78972.21027789879, 80273.0, 83898.58050709707, 14332.49836255726, 14656.193909334663, 14870.330835112662, 15061.246937652855, 15332.080075195525, 14539.92730036343, 15378.615534611277, 15815.0, 15858.920705396398, 16417.308123458923, 15054.548363549111, 15193.088414694712, 15211.847881521044, 15521.534353969399, 16467.505974820295, 14652.487162949672, 14949.626259343604, 15144.469943551923, 16145.919270403572, 16174.838251617333, 13338.0, 13500.324983750577, 13588.86411135876, 14321.64195895111, 14443.133411995314, 11694.941525292319, 11917.284962902253, 11928.4035798208, 12668.303243321705, 12700.87299126997, 11396.259243149168, 12101.939490302491, 12709.80935285953, 12933.676658083623, 13067.52066197896], "value": 8226.057042305412, "unit": "score"}], "name": "dromaeo_css", "value": 7874.35536089268, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 109.0, 117.0, 108.0, 109.0, 110.0, 108.0, 109.0, 109.0, 110.0], "value": 110.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 115.0, 139.0, 165.0, 115.0, 151.0, 147.0, 117.0, 169.0, 116.0], "value": 137.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 163.0, 160.0, 161.0, 160.0, 160.0, 161.0, 160.0, 161.0, 161.0], "value": 160.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 75.0, 175.0, 75.0, 75.0, 75.0, 76.0, 183.0, 76.0], "value": 95.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 146.0, 82.0, 82.0, 84.0, 155.0, 82.0, 83.0, 146.0], "value": 102.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 118.0, 117.0, 118.0, 117.0, 116.0, 117.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 147.0, 146.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 96.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 50.0, 51.0, 51.0, 54.0, 51.0, 52.0, 52.0, 49.0, 51.0], "value": 51.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 62.0, 61.0, 61.0, 61.0, 62.0, 61.0, 61.0, 65.0, 61.0], "value": 61.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 72.0, 73.0, 78.0, 80.0, 73.0, 71.0, 81.0, 77.0, 72.0], "value": 74.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 121.0, 127.0, 129.0, 126.0, 114.0, 117.0, 116.0, 157.0, 116.0], "value": 124.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 153.0, 157.0, 152.0, 151.0, 156.0, 153.0, 151.0, 151.0, 153.0], "value": 153.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 65.0, 61.0, 61.0, 61.0, 70.0, 71.0, 61.0, 61.0], "value": 63.9, "unit": "score"}], "name": "kraken", "value": 1494.4, "alertThreshold": 2.0}]} 07:47:44 INFO - Return code: 0 07:47:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:47:44 INFO - # TBPL SUCCESS # 07:47:44 INFO - Running post-action listener: _resource_record_post_action 07:47:44 INFO - [mozharness: 2016-09-17 14:47:44.468479Z] Finished run-tests step (success) 07:47:44 INFO - Running post-run listener: _resource_record_post_run 07:47:44 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 274432; Write bytes: 329719808; Read time: 268; Write time: 235360 07:47:44 INFO - TinderboxPrint: CPU usage
12.9% 07:47:44 INFO - TinderboxPrint: I/O read bytes / time
274,432 / 268 07:47:44 INFO - TinderboxPrint: I/O write bytes / time
329,719,808 / 235,360 07:47:44 INFO - TinderboxPrint: CPU idle
8,274.8 (87.0%) 07:47:44 INFO - TinderboxPrint: CPU user
1,186.4 (12.5%) 07:47:44 INFO - TinderboxPrint: Swap in / out
0 / 0 07:47:44 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 78299136; Read time: 0; Write time: 67216 07:47:44 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 274432; Write bytes: 251154432; Read time: 268; Write time: 168116 07:47:45 INFO - Running post-run listener: _upload_blobber_files 07:47:45 INFO - Blob upload gear active. 07:47:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:47:45 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 07:47:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:47:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:47:45 INFO - (blobuploader) - INFO - Open directory for files ... 07:47:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:47:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:47:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:47:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:47:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:47:46 INFO - (blobuploader) - INFO - Done attempting. 07:47:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:47:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:47:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:47:50 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:47:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:47:50 INFO - (blobuploader) - INFO - Done attempting. 07:47:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:47:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:47:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:47:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:47:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:47:50 INFO - (blobuploader) - INFO - Done attempting. 07:47:50 INFO - (blobuploader) - INFO - Iteration through files over. 07:47:50 INFO - Return code: 0 07:47:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:47:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:47:50 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4686d08da6266eeffb4669f83d8cee4cdbeb4edd3170c45dd60902e18fa387296f6af11045de35b81f5eb8741c4b95edadd33ef674b6d3ede0b40cf647e612f6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/212a6da51586f75ef56a66bc51a20fc8babe43178d3201c8f8f799552db6491c81e79b0b9f05a27b06f94f9521ab18a11740a4133597cc431413ea3714fa2ea8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6e6100d9e9ed2ffa4e9853ba2d72875b8376755f830d7c454f9280d6c6fa05434fceeb8dd419924041508665b81c4f25fc356174970f3cb5e2f222fee37361f4"} 07:47:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:47:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:47:50 INFO - Contents: 07:47:50 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4686d08da6266eeffb4669f83d8cee4cdbeb4edd3170c45dd60902e18fa387296f6af11045de35b81f5eb8741c4b95edadd33ef674b6d3ede0b40cf647e612f6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/212a6da51586f75ef56a66bc51a20fc8babe43178d3201c8f8f799552db6491c81e79b0b9f05a27b06f94f9521ab18a11740a4133597cc431413ea3714fa2ea8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6e6100d9e9ed2ffa4e9853ba2d72875b8376755f830d7c454f9280d6c6fa05434fceeb8dd419924041508665b81c4f25fc356174970f3cb5e2f222fee37361f4"} 07:47:50 INFO - Running post-run listener: copy_logs_to_upload_dir 07:47:50 INFO - Copying logs to upload dir... 07:47:50 INFO - mkdir: /builds/slave/test/build/upload/logs 07:47:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1679.005546 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 59 secs) (at 2016-09-17 07:47:50.851005) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-17 07:47:50.854169) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/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/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=00204705a9daa57ed339a427000001b9-1474121990.323651-1902662426 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4686d08da6266eeffb4669f83d8cee4cdbeb4edd3170c45dd60902e18fa387296f6af11045de35b81f5eb8741c4b95edadd33ef674b6d3ede0b40cf647e612f6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/212a6da51586f75ef56a66bc51a20fc8babe43178d3201c8f8f799552db6491c81e79b0b9f05a27b06f94f9521ab18a11740a4133597cc431413ea3714fa2ea8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6e6100d9e9ed2ffa4e9853ba2d72875b8376755f830d7c454f9280d6c6fa05434fceeb8dd419924041508665b81c4f25fc356174970f3cb5e2f222fee37361f4"} build_url:https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011222 build_url: 'https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4686d08da6266eeffb4669f83d8cee4cdbeb4edd3170c45dd60902e18fa387296f6af11045de35b81f5eb8741c4b95edadd33ef674b6d3ede0b40cf647e612f6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/212a6da51586f75ef56a66bc51a20fc8babe43178d3201c8f8f799552db6491c81e79b0b9f05a27b06f94f9521ab18a11740a4133597cc431413ea3714fa2ea8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6e6100d9e9ed2ffa4e9853ba2d72875b8376755f830d7c454f9280d6c6fa05434fceeb8dd419924041508665b81c4f25fc356174970f3cb5e2f222fee37361f4"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-17 07:47:50.880405) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:47:50.880798) ========= rm -f oauth.txt in dir /builds/slave/test/. (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 SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=00204705a9daa57ed339a427000001b9-1474121990.323651-1902662426 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004534 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:47:50.963113) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-17 07:47:50.963468) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-17 07:47:50.963811) ========= ========= Total master_lag: 0.18 =========