builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-049 starttime: 1472774304.55 results: success (0) buildid: 20160901141621 builduid: 1b527e6a4c3c4fa09cdbd09d35cfc602 revision: 0a787a252077640330496045bdde5ca018ccc727 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 16:58:24.553020) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 16:58:24.553497) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 16:58:24.553777) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 16:58:24.601274) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 16:58:24.601544) ========= 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=db7e7c15f3ca076d53731445000001ba-1472772891.821423-592927209 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-01 16:58:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 45.3M=0s 2016-09-01 16:58:25 (45.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.165096 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 16:58:24.780464) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 16:58:24.780761) ========= 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=db7e7c15f3ca076d53731445000001ba-1472772891.821423-592927209 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019024 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 16:58:24.829505) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-01 16:58:24.829832) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 0a787a252077640330496045bdde5ca018ccc727 --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 0a787a252077640330496045bdde5ca018ccc727 --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=db7e7c15f3ca076d53731445000001ba-1472772891.821423-592927209 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-01 16:58:25,345 truncating revision to first 12 chars 2016-09-01 16:58:25,345 Setting DEBUG logging. 2016-09-01 16:58:25,345 attempt 1/10 2016-09-01 16:58:25,345 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/0a787a252077?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-01 16:58:26,344 unpacking tar archive at: mozilla-aurora-0a787a252077/testing/mozharness/ program finished with exit code 0 elapsedTime=1.448594 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-01 16:58:26.292241) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 16:58:26.292549) ========= script_repo_revision: 0a787a252077640330496045bdde5ca018ccc727 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 16:58:26.292968) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 16:58:26.293386) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 16:58:26.302683) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 57 secs) (at 2016-09-01 16:58:26.302967) ========= /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=db7e7c15f3ca076d53731445000001ba-1472772891.821423-592927209 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:58:26 INFO - MultiFileLogger online at 20160901 16:58:26 in /builds/slave/test 16:58:26 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 16:58:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:58:26 INFO - {'append_to_log': False, 16:58:26 INFO - 'base_work_dir': '/builds/slave/test', 16:58:26 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 16:58:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:58:26 INFO - 'branch': 'Mozilla-Aurora', 16:58:26 INFO - 'buildbot_json_path': 'buildprops.json', 16:58:26 INFO - 'config_files': ('talos/linux_config.py',), 16:58:26 INFO - 'default_actions': ('clobber', 16:58:26 INFO - 'read-buildbot-config', 16:58:26 INFO - 'download-and-extract', 16:58:26 INFO - 'populate-webroot', 16:58:26 INFO - 'create-virtualenv', 16:58:26 INFO - 'install', 16:58:26 INFO - 'run-tests'), 16:58:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:58:26 INFO - 'download_minidump_stackwalk': True, 16:58:26 INFO - 'download_symbols': 'ondemand', 16:58:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:58:26 INFO - 'tooltool.py': '/tools/tooltool.py', 16:58:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:58:26 INFO - '/tools/misc-python/virtualenv.py')}, 16:58:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:58:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:58:26 INFO - 'installer_path': 'installer.exe', 16:58:26 INFO - 'log_level': 'info', 16:58:26 INFO - 'log_name': 'talos', 16:58:26 INFO - 'log_to_console': True, 16:58:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:58:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:58:26 INFO - 'opt_config_files': (), 16:58:26 INFO - 'pip_index': False, 16:58:26 INFO - 'sps_profile': False, 16:58:26 INFO - 'sps_profile_interval': 0, 16:58:26 INFO - 'suite': 'dromaeojs', 16:58:26 INFO - 'system_bits': '32', 16:58:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:58:26 INFO - 'title': 'talos-linux64-ix-049', 16:58:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:58:26 INFO - 'use_talos_json': True, 16:58:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:58:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:58:26 INFO - 'work_dir': 'build'} 16:58:26 INFO - [mozharness: 2016-09-01 23:58:26.910908Z] Running clobber step. 16:58:26 INFO - Running pre-action listener: _resource_record_pre_action 16:58:26 INFO - Running main action method: clobber 16:58:26 INFO - rmtree: /builds/slave/test/build 16:58:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:58:27 INFO - Running post-action listener: _resource_record_post_action 16:58:27 INFO - [mozharness: 2016-09-01 23:58:27.382255Z] Finished clobber step (success) 16:58:27 INFO - [mozharness: 2016-09-01 23:58:27.382358Z] Running read-buildbot-config step. 16:58:27 INFO - Running pre-action listener: _resource_record_pre_action 16:58:27 INFO - Running main action method: read_buildbot_config 16:58:27 INFO - Using buildbot properties: 16:58:27 INFO - { 16:58:27 INFO - "project": "", 16:58:27 INFO - "product": "firefox", 16:58:27 INFO - "script_repo_revision": "production", 16:58:27 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 16:58:27 INFO - "repository": "", 16:58:27 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 16:58:27 INFO - "buildid": "20160901141621", 16:58:27 INFO - "pgo_build": "True", 16:58:27 INFO - "basedir": "/builds/slave/test", 16:58:27 INFO - "buildnumber": 440, 16:58:27 INFO - "slavename": "talos-linux64-ix-049", 16:58:27 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 16:58:27 INFO - "platform": "ubuntu64_hw", 16:58:27 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 16:58:27 INFO - "branch": "mozilla-aurora", 16:58:27 INFO - "repo_path": "releases/mozilla-aurora", 16:58:27 INFO - "revision": "0a787a252077640330496045bdde5ca018ccc727", 16:58:27 INFO - "stage_platform": "linux64-pgo", 16:58:27 INFO - "builduid": "1b527e6a4c3c4fa09cdbd09d35cfc602", 16:58:27 INFO - "slavebuilddir": "test" 16:58:27 INFO - } 16:58:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/a9CGJg-nRTCLA3AiqIN4YA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 16:58:27 INFO - Running post-action listener: _resource_record_post_action 16:58:27 INFO - [mozharness: 2016-09-01 23:58:27.384831Z] Finished read-buildbot-config step (success) 16:58:27 INFO - [mozharness: 2016-09-01 23:58:27.384921Z] Running download-and-extract step. 16:58:27 INFO - Running pre-action listener: _resource_record_pre_action 16:58:27 INFO - Running main action method: download_and_extract 16:58:27 INFO - mkdir: /builds/slave/test/build/tests 16:58:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:58:27 INFO - https://queue.taskcluster.net/v1/task/a9CGJg-nRTCLA3AiqIN4YA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 16:58:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a9CGJg-nRTCLA3AiqIN4YA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 16:58:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a9CGJg-nRTCLA3AiqIN4YA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 16:58:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a9CGJg-nRTCLA3AiqIN4YA/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 16:58:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a9CGJg-nRTCLA3AiqIN4YA/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 16:58:27 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a9CGJg-nRTCLA3AiqIN4YA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 16:58:27 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 16:58:27 INFO - retry: Failed, sleeping 30 seconds before retrying 16:58:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a9CGJg-nRTCLA3AiqIN4YA/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 16:58:57 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a9CGJg-nRTCLA3AiqIN4YA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 16:58:57 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 16:58:57 INFO - retry: Failed, sleeping 60 seconds before retrying 16:59:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a9CGJg-nRTCLA3AiqIN4YA/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 16:59:57 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a9CGJg-nRTCLA3AiqIN4YA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 16:59:57 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 16:59:57 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a9CGJg-nRTCLA3AiqIN4YA/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! 16:59:57 INFO - Caught exception: HTTP Error 404: Not Found 16:59:57 INFO - Caught exception: HTTP Error 404: Not Found 16:59:57 INFO - Caught exception: HTTP Error 404: Not Found 16:59:57 INFO - trying https://queue.taskcluster.net/v1/task/a9CGJg-nRTCLA3AiqIN4YA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 16:59:57 INFO - Downloading https://queue.taskcluster.net/v1/task/a9CGJg-nRTCLA3AiqIN4YA/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 16:59:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/a9CGJg-nRTCLA3AiqIN4YA/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 16:59:58 INFO - Downloaded 1361 bytes. 16:59:58 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 16:59:58 INFO - Using the following test package requirements: 16:59:58 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 16:59:58 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 16:59:58 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 16:59:58 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 16:59:58 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 16:59:58 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 16:59:58 INFO - u'jsshell-linux-x86_64.zip'], 16:59:58 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 16:59:58 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 16:59:58 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 16:59:58 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 16:59:58 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 16:59:58 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 16:59:58 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 16:59:58 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 16:59:58 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 16:59:58 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 16:59:58 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 16:59:58 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:59:58 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')]} 16:59:58 INFO - https://queue.taskcluster.net/v1/task/a9CGJg-nRTCLA3AiqIN4YA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:59:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a9CGJg-nRTCLA3AiqIN4YA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 16:59:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a9CGJg-nRTCLA3AiqIN4YA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 16:59:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a9CGJg-nRTCLA3AiqIN4YA/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 16:59:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a9CGJg-nRTCLA3AiqIN4YA/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 16:59:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a9CGJg-nRTCLA3AiqIN4YA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 16:59:58 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 16:59:58 INFO - retry: Failed, sleeping 30 seconds before retrying 17:00:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a9CGJg-nRTCLA3AiqIN4YA/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 17:00:28 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a9CGJg-nRTCLA3AiqIN4YA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 17:00:28 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 17:00:28 INFO - retry: Failed, sleeping 60 seconds before retrying 17:01:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a9CGJg-nRTCLA3AiqIN4YA/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 17:01:28 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a9CGJg-nRTCLA3AiqIN4YA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 17:01:28 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 17:01:28 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a9CGJg-nRTCLA3AiqIN4YA/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! 17:01:28 INFO - Caught exception: HTTP Error 404: Not Found 17:01:28 INFO - Caught exception: HTTP Error 404: Not Found 17:01:28 INFO - Caught exception: HTTP Error 404: Not Found 17:01:28 INFO - trying https://queue.taskcluster.net/v1/task/a9CGJg-nRTCLA3AiqIN4YA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 17:01:28 INFO - Downloading https://queue.taskcluster.net/v1/task/a9CGJg-nRTCLA3AiqIN4YA/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 17:01:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/a9CGJg-nRTCLA3AiqIN4YA/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 17:01:29 INFO - Downloaded 21771208 bytes. 17:01:29 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'] 17:01:29 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 17:01:30 INFO - Return code: 0 17:01:30 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 17:01:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:01:30 INFO - https://queue.taskcluster.net/v1/task/a9CGJg-nRTCLA3AiqIN4YA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 17:01:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a9CGJg-nRTCLA3AiqIN4YA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 17:01:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a9CGJg-nRTCLA3AiqIN4YA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 17:01:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a9CGJg-nRTCLA3AiqIN4YA/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 17:01:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a9CGJg-nRTCLA3AiqIN4YA/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 17:01:30 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a9CGJg-nRTCLA3AiqIN4YA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 17:01:30 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 17:01:30 INFO - retry: Failed, sleeping 30 seconds before retrying 17:02:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a9CGJg-nRTCLA3AiqIN4YA/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 17:02:00 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a9CGJg-nRTCLA3AiqIN4YA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 17:02:00 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 17:02:00 INFO - retry: Failed, sleeping 60 seconds before retrying 17:03:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a9CGJg-nRTCLA3AiqIN4YA/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 17:03:00 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a9CGJg-nRTCLA3AiqIN4YA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 17:03:00 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 17:03:00 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a9CGJg-nRTCLA3AiqIN4YA/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! 17:03:00 INFO - Caught exception: HTTP Error 404: Not Found 17:03:00 INFO - Caught exception: HTTP Error 404: Not Found 17:03:00 INFO - Caught exception: HTTP Error 404: Not Found 17:03:00 INFO - trying https://queue.taskcluster.net/v1/task/a9CGJg-nRTCLA3AiqIN4YA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 17:03:00 INFO - Downloading https://queue.taskcluster.net/v1/task/a9CGJg-nRTCLA3AiqIN4YA/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 17:03:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/a9CGJg-nRTCLA3AiqIN4YA/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 17:03:01 INFO - Downloaded 21771208 bytes. 17:03:01 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'] 17:03:01 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 17:03:02 INFO - Return code: 0 17:03:02 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')]} 17:03:02 INFO - https://queue.taskcluster.net/v1/task/a9CGJg-nRTCLA3AiqIN4YA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 17:03:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a9CGJg-nRTCLA3AiqIN4YA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 17:03:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a9CGJg-nRTCLA3AiqIN4YA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 17:03:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a9CGJg-nRTCLA3AiqIN4YA/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 17:03:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a9CGJg-nRTCLA3AiqIN4YA/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 17:03:02 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a9CGJg-nRTCLA3AiqIN4YA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 17:03:02 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 17:03:02 INFO - retry: Failed, sleeping 30 seconds before retrying 17:03:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a9CGJg-nRTCLA3AiqIN4YA/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 17:03:32 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a9CGJg-nRTCLA3AiqIN4YA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 17:03:32 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 17:03:32 INFO - retry: Failed, sleeping 60 seconds before retrying 17:04:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a9CGJg-nRTCLA3AiqIN4YA/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 17:04:32 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a9CGJg-nRTCLA3AiqIN4YA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 17:04:32 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 17:04:32 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a9CGJg-nRTCLA3AiqIN4YA/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! 17:04:32 INFO - Caught exception: HTTP Error 404: Not Found 17:04:32 INFO - Caught exception: HTTP Error 404: Not Found 17:04:32 INFO - Caught exception: HTTP Error 404: Not Found 17:04:32 INFO - trying https://queue.taskcluster.net/v1/task/a9CGJg-nRTCLA3AiqIN4YA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 17:04:32 INFO - Downloading https://queue.taskcluster.net/v1/task/a9CGJg-nRTCLA3AiqIN4YA/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 17:04:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/a9CGJg-nRTCLA3AiqIN4YA/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 17:04:33 INFO - Downloaded 14413740 bytes. 17:04:33 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'] 17:04:33 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 17:04:33 INFO - Return code: 0 17:04:33 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')]} 17:04:33 INFO - https://queue.taskcluster.net/v1/task/a9CGJg-nRTCLA3AiqIN4YA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 17:04:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a9CGJg-nRTCLA3AiqIN4YA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 17:04:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a9CGJg-nRTCLA3AiqIN4YA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 17:04:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a9CGJg-nRTCLA3AiqIN4YA/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 17:04:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a9CGJg-nRTCLA3AiqIN4YA/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 17:04:33 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a9CGJg-nRTCLA3AiqIN4YA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 17:04:33 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 17:04:33 INFO - retry: Failed, sleeping 30 seconds before retrying 17:05:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a9CGJg-nRTCLA3AiqIN4YA/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 17:05:03 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a9CGJg-nRTCLA3AiqIN4YA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 17:05:03 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 17:05:03 INFO - retry: Failed, sleeping 60 seconds before retrying 17:06:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a9CGJg-nRTCLA3AiqIN4YA/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 17:06:03 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a9CGJg-nRTCLA3AiqIN4YA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 17:06:03 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 17:06:03 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a9CGJg-nRTCLA3AiqIN4YA/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! 17:06:03 INFO - Caught exception: HTTP Error 404: Not Found 17:06:03 INFO - Caught exception: HTTP Error 404: Not Found 17:06:03 INFO - Caught exception: HTTP Error 404: Not Found 17:06:03 INFO - trying https://queue.taskcluster.net/v1/task/a9CGJg-nRTCLA3AiqIN4YA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 17:06:03 INFO - Downloading https://queue.taskcluster.net/v1/task/a9CGJg-nRTCLA3AiqIN4YA/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 17:06:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/a9CGJg-nRTCLA3AiqIN4YA/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 17:06:04 INFO - Downloaded 63882767 bytes. 17:06:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/a9CGJg-nRTCLA3AiqIN4YA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 17:06:04 INFO - mkdir: /builds/slave/test/properties 17:06:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:06:04 INFO - Writing to file /builds/slave/test/properties/build_url 17:06:04 INFO - Contents: 17:06:04 INFO - build_url:https://queue.taskcluster.net/v1/task/a9CGJg-nRTCLA3AiqIN4YA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 17:06:05 INFO - Running post-action listener: _resource_record_post_action 17:06:05 INFO - Running post-action listener: set_extra_try_arguments 17:06:05 INFO - [mozharness: 2016-09-02 00:06:05.715676Z] Finished download-and-extract step (success) 17:06:05 INFO - [mozharness: 2016-09-02 00:06:05.715913Z] Running populate-webroot step. 17:06:05 INFO - Running pre-action listener: _resource_record_pre_action 17:06:05 INFO - Running main action method: populate_webroot 17:06:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:06:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:06:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:06:05 INFO - '--tppagecycles', 17:06:05 INFO - '7'], 17:06:05 INFO - 'tests': ['tsvgm']}}, 17:06:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:06:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:06:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:06:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:06:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:06:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:06:05 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:06:05 INFO - 'talos_options': ['--disable-e10s'], 17:06:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:06:05 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:06:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:06:05 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:06:05 INFO - 'talos_options': ['--disable-e10s'], 17:06:05 INFO - 'tests': ['damp', 'tps']}, 17:06:05 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:06:05 INFO - 'tests': ['damp', 'tps']}, 17:06:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:06:05 INFO - 'tests': ['dromaeo_dom']}, 17:06:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:06:05 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:06:05 INFO - 'tests': ['basic_compositor_video']}, 17:06:05 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:06:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:06:05 INFO - 'tests': ['a11yr', 17:06:05 INFO - 'ts_paint', 17:06:05 INFO - 'tpaint', 17:06:05 INFO - 'sessionrestore', 17:06:05 INFO - 'sessionrestore_no_auto_restore', 17:06:05 INFO - 'tabpaint']}, 17:06:05 INFO - 'other-e10s': {'tests': ['a11yr', 17:06:05 INFO - 'ts_paint', 17:06:05 INFO - 'tpaint', 17:06:05 INFO - 'sessionrestore', 17:06:05 INFO - 'sessionrestore_no_auto_restore', 17:06:05 INFO - 'tabpaint']}, 17:06:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:06:05 INFO - 'ts_paint', 17:06:05 INFO - 'tpaint', 17:06:05 INFO - 'sessionrestore', 17:06:05 INFO - 'sessionrestore_no_auto_restore', 17:06:05 INFO - 'tabpaint']}, 17:06:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:06:05 INFO - 'ts_paint', 17:06:05 INFO - 'tpaint', 17:06:05 INFO - 'sessionrestore', 17:06:05 INFO - 'sessionrestore_no_auto_restore', 17:06:05 INFO - 'tabpaint']}, 17:06:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:06:05 INFO - 'tests': ['a11yr', 17:06:05 INFO - 'ts_paint', 17:06:05 INFO - 'tpaint', 17:06:05 INFO - 'sessionrestore', 17:06:05 INFO - 'sessionrestore_no_auto_restore', 17:06:05 INFO - 'tabpaint']}, 17:06:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:06:05 INFO - 'tests': ['a11yr', 17:06:05 INFO - 'ts_paint', 17:06:05 INFO - 'tpaint', 17:06:05 INFO - 'sessionrestore', 17:06:05 INFO - 'sessionrestore_no_auto_restore', 17:06:05 INFO - 'tabpaint']}, 17:06:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:06:05 INFO - 'tests': ['tsvgx', 17:06:05 INFO - 'tsvgr_opacity', 17:06:05 INFO - 'tart', 17:06:05 INFO - 'tscrollx', 17:06:05 INFO - 'cart']}, 17:06:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:06:05 INFO - 'tsvgr_opacity', 17:06:05 INFO - 'tart', 17:06:05 INFO - 'tscrollx', 17:06:05 INFO - 'cart']}, 17:06:05 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:06:05 INFO - 'talos_options': ['--disable-e10s'], 17:06:05 INFO - 'tests': ['tp5o']}, 17:06:05 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:06:05 INFO - 'tests': ['tp5o']}, 17:06:05 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:06:05 INFO - 'talos_options': ['--disable-e10s', 17:06:05 INFO - '--xperf_path', 17:06:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:06:05 INFO - 'tests': ['tp5n']}, 17:06:05 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:06:05 INFO - 'talos_options': ['--xperf_path', 17:06:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:06:05 INFO - 'tests': ['tp5n']}}, 17:06:05 INFO - 'talos.zip': {'path': '', 17:06:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:06:05 INFO - Running post-action listener: _resource_record_post_action 17:06:05 INFO - [mozharness: 2016-09-02 00:06:05.738650Z] Finished populate-webroot step (success) 17:06:05 INFO - [mozharness: 2016-09-02 00:06:05.738741Z] Running create-virtualenv step. 17:06:05 INFO - Running pre-action listener: _resource_record_pre_action 17:06:05 INFO - Running main action method: create_virtualenv 17:06:05 INFO - Creating virtualenv /builds/slave/test/build/venv 17:06:05 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 17:06:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:06:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:06:05 INFO - Using real prefix '/usr' 17:06:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:06:06 INFO - Installing distribute.............................................................................................................................................................................................done. 17:06:08 INFO - Installing pip.................done. 17:06:08 INFO - Return code: 0 17:06:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:06:08 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')]} 17:06:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:06:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:08 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')]} 17:06:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:06:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:08 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 0x291d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bde180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bdc3b0>, '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 0x2bdd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bdd740>, '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': 'db7e7c15f3ca076d53731445000001ba-1472772891.821423-592927209', '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 17:06:08 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 17:06:08 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 17:06:08 INFO - Using env: {'DISPLAY': ':0', 17:06:08 INFO - 'HOME': '/home/cltbld', 17:06:08 INFO - 'LANG': 'en_US.UTF-8', 17:06:08 INFO - 'LANGUAGE': 'en_US:en', 17:06:08 INFO - 'LOGNAME': 'cltbld', 17:06:08 INFO - 'MAIL': '/var/mail/cltbld', 17:06:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:06:08 INFO - 'MOZ_NO_REMOTE': '1', 17:06:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:06:08 INFO - 'NO_EM_RESTART': '1', 17:06:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:06:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:06:08 INFO - 'PWD': '/builds/slave/test', 17:06:08 INFO - 'SHELL': '/bin/bash', 17:06:08 INFO - 'SHLVL': '1', 17:06:08 INFO - 'TERM': 'linux', 17:06:08 INFO - 'TMOUT': '86400', 17:06:08 INFO - 'USER': 'cltbld', 17:06:08 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1472772891.821423-592927209', 17:06:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:06:08 INFO - '_': '/tools/buildbot/bin/python'} 17:06:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:06:08 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 17:06:08 INFO - Cleaning up... 17:06:08 INFO - Return code: 0 17:06:08 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:06:08 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')]} 17:06:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:06:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:08 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')]} 17:06:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:06:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:08 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 0x291d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bde180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bdc3b0>, '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 0x2bdd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bdd740>, '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': 'db7e7c15f3ca076d53731445000001ba-1472772891.821423-592927209', '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 17:06:08 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 17:06:08 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 17:06:08 INFO - Using env: {'DISPLAY': ':0', 17:06:08 INFO - 'HOME': '/home/cltbld', 17:06:08 INFO - 'LANG': 'en_US.UTF-8', 17:06:08 INFO - 'LANGUAGE': 'en_US:en', 17:06:08 INFO - 'LOGNAME': 'cltbld', 17:06:08 INFO - 'MAIL': '/var/mail/cltbld', 17:06:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:06:08 INFO - 'MOZ_NO_REMOTE': '1', 17:06:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:06:08 INFO - 'NO_EM_RESTART': '1', 17:06:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:06:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:06:08 INFO - 'PWD': '/builds/slave/test', 17:06:08 INFO - 'SHELL': '/bin/bash', 17:06:08 INFO - 'SHLVL': '1', 17:06:08 INFO - 'TERM': 'linux', 17:06:08 INFO - 'TMOUT': '86400', 17:06:08 INFO - 'USER': 'cltbld', 17:06:08 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1472772891.821423-592927209', 17:06:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:06:08 INFO - '_': '/tools/buildbot/bin/python'} 17:06:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:06:08 INFO - Downloading/unpacking psutil>=3.1.1 17:06:08 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 17:06:08 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 17:06:08 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 17:06:08 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 17:06:08 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 17:06:08 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 17:06:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:06:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:06:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:06:10 INFO - Installing collected packages: psutil 17:06:10 INFO - Running setup.py install for psutil 17:06:10 INFO - building 'psutil._psutil_linux' extension 17:06:10 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 17:06:10 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 17:06:10 INFO - building 'psutil._psutil_posix' extension 17:06:10 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 17:06:11 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 17:06:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:06:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:06:11 INFO - Successfully installed psutil 17:06:11 INFO - Cleaning up... 17:06:11 INFO - Return code: 0 17:06:11 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:06:11 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')]} 17:06:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:06:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:11 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')]} 17:06:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:06:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:11 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 0x291d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bde180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bdc3b0>, '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 0x2bdd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bdd740>, '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': 'db7e7c15f3ca076d53731445000001ba-1472772891.821423-592927209', '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 17:06:11 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 17:06:11 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 17:06:11 INFO - Using env: {'DISPLAY': ':0', 17:06:11 INFO - 'HOME': '/home/cltbld', 17:06:11 INFO - 'LANG': 'en_US.UTF-8', 17:06:11 INFO - 'LANGUAGE': 'en_US:en', 17:06:11 INFO - 'LOGNAME': 'cltbld', 17:06:11 INFO - 'MAIL': '/var/mail/cltbld', 17:06:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:06:11 INFO - 'MOZ_NO_REMOTE': '1', 17:06:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:06:11 INFO - 'NO_EM_RESTART': '1', 17:06:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:06:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:06:11 INFO - 'PWD': '/builds/slave/test', 17:06:11 INFO - 'SHELL': '/bin/bash', 17:06:11 INFO - 'SHLVL': '1', 17:06:11 INFO - 'TERM': 'linux', 17:06:11 INFO - 'TMOUT': '86400', 17:06:11 INFO - 'USER': 'cltbld', 17:06:11 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1472772891.821423-592927209', 17:06:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:06:11 INFO - '_': '/tools/buildbot/bin/python'} 17:06:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:06:11 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:06:11 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 17:06:11 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 17:06:11 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 17:06:11 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 17:06:11 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 17:06:11 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 17:06:13 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:06:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:06:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:06:13 INFO - Installing collected packages: mozsystemmonitor 17:06:13 INFO - Running setup.py install for mozsystemmonitor 17:06:13 INFO - Successfully installed mozsystemmonitor 17:06:13 INFO - Cleaning up... 17:06:13 INFO - Return code: 0 17:06:13 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:06:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:06:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:06:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:06:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:06:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:13 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 0x291d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bde180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bdc3b0>, '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 0x2bdd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bdd740>, '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': 'db7e7c15f3ca076d53731445000001ba-1472772891.821423-592927209', '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 17:06:13 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 17:06:13 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 17:06:13 INFO - Using env: {'DISPLAY': ':0', 17:06:13 INFO - 'HOME': '/home/cltbld', 17:06:13 INFO - 'LANG': 'en_US.UTF-8', 17:06:13 INFO - 'LANGUAGE': 'en_US:en', 17:06:13 INFO - 'LOGNAME': 'cltbld', 17:06:13 INFO - 'MAIL': '/var/mail/cltbld', 17:06:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:06:13 INFO - 'MOZ_NO_REMOTE': '1', 17:06:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:06:13 INFO - 'NO_EM_RESTART': '1', 17:06:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:06:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:06:13 INFO - 'PWD': '/builds/slave/test', 17:06:13 INFO - 'SHELL': '/bin/bash', 17:06:13 INFO - 'SHLVL': '1', 17:06:13 INFO - 'TERM': 'linux', 17:06:13 INFO - 'TMOUT': '86400', 17:06:13 INFO - 'USER': 'cltbld', 17:06:13 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1472772891.821423-592927209', 17:06:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:06:13 INFO - '_': '/tools/buildbot/bin/python'} 17:06:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:06:13 INFO - Downloading/unpacking jsonschema==2.5.1 17:06:13 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 17:06:13 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 17:06:13 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 17:06:13 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 17:06:13 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 17:06:13 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 17:06:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:06:15 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:06:15 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 17:06:15 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 17:06:15 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 17:06:15 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 17:06:15 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 17:06:15 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 17:06:15 INFO - Downloading functools32-3.2.3-2.tar.gz 17:06:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:06:15 INFO - warning: no files found matching '*.txt' 17:06:15 INFO - no previously-included directories found matching 'build' 17:06:15 INFO - no previously-included directories found matching 'dist' 17:06:15 INFO - no previously-included directories found matching '.git*' 17:06:15 INFO - Installing collected packages: jsonschema, functools32 17:06:15 INFO - Running setup.py install for functools32 17:06:16 INFO - warning: no files found matching '*.txt' 17:06:16 INFO - no previously-included directories found matching 'build' 17:06:16 INFO - no previously-included directories found matching 'dist' 17:06:16 INFO - no previously-included directories found matching '.git*' 17:06:16 INFO - Successfully installed jsonschema functools32 17:06:16 INFO - Cleaning up... 17:06:16 INFO - Return code: 0 17:06:16 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:06:16 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')]} 17:06:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:06:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:16 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')]} 17:06:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:06:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:16 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 0x291d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bde180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bdc3b0>, '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 0x2bdd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bdd740>, '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': 'db7e7c15f3ca076d53731445000001ba-1472772891.821423-592927209', '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 17:06:16 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 17:06:16 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 17:06:16 INFO - Using env: {'DISPLAY': ':0', 17:06:16 INFO - 'HOME': '/home/cltbld', 17:06:16 INFO - 'LANG': 'en_US.UTF-8', 17:06:16 INFO - 'LANGUAGE': 'en_US:en', 17:06:16 INFO - 'LOGNAME': 'cltbld', 17:06:16 INFO - 'MAIL': '/var/mail/cltbld', 17:06:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:06:16 INFO - 'MOZ_NO_REMOTE': '1', 17:06:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:06:16 INFO - 'NO_EM_RESTART': '1', 17:06:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:06:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:06:16 INFO - 'PWD': '/builds/slave/test', 17:06:16 INFO - 'SHELL': '/bin/bash', 17:06:16 INFO - 'SHLVL': '1', 17:06:16 INFO - 'TERM': 'linux', 17:06:16 INFO - 'TMOUT': '86400', 17:06:16 INFO - 'USER': 'cltbld', 17:06:16 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1472772891.821423-592927209', 17:06:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:06:16 INFO - '_': '/tools/buildbot/bin/python'} 17:06:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:06:16 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:06:16 INFO - Cleaning up... 17:06:16 INFO - Return code: 0 17:06:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:06:16 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')]} 17:06:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:06:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:16 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')]} 17:06:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:06:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:16 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 0x291d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bde180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bdc3b0>, '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 0x2bdd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bdd740>, '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': 'db7e7c15f3ca076d53731445000001ba-1472772891.821423-592927209', '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 17:06:16 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 17:06:16 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 17:06:16 INFO - Using env: {'DISPLAY': ':0', 17:06:16 INFO - 'HOME': '/home/cltbld', 17:06:16 INFO - 'LANG': 'en_US.UTF-8', 17:06:16 INFO - 'LANGUAGE': 'en_US:en', 17:06:16 INFO - 'LOGNAME': 'cltbld', 17:06:16 INFO - 'MAIL': '/var/mail/cltbld', 17:06:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:06:16 INFO - 'MOZ_NO_REMOTE': '1', 17:06:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:06:16 INFO - 'NO_EM_RESTART': '1', 17:06:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:06:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:06:16 INFO - 'PWD': '/builds/slave/test', 17:06:16 INFO - 'SHELL': '/bin/bash', 17:06:16 INFO - 'SHLVL': '1', 17:06:16 INFO - 'TERM': 'linux', 17:06:16 INFO - 'TMOUT': '86400', 17:06:16 INFO - 'USER': 'cltbld', 17:06:16 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1472772891.821423-592927209', 17:06:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:06:16 INFO - '_': '/tools/buildbot/bin/python'} 17:06:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:06:16 INFO - Downloading/unpacking blobuploader==1.2.4 17:06:16 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 17:06:16 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 17:06:16 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 17:06:16 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 17:06:16 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 17:06:16 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 17:06:18 INFO - Downloading blobuploader-1.2.4.tar.gz 17:06:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:06:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:06:18 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 17:06:18 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 17:06:18 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 17:06:18 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 17:06:18 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 17:06:18 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 17:06:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:06:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:06:18 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 17:06:18 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 17:06:18 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 17:06:18 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 17:06:18 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 17:06:18 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 17:06:19 INFO - Downloading docopt-0.6.1.tar.gz 17:06:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:06:19 INFO - Installing collected packages: blobuploader, requests, docopt 17:06:19 INFO - Running setup.py install for blobuploader 17:06:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:06:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:06:19 INFO - Running setup.py install for requests 17:06:19 INFO - Running setup.py install for docopt 17:06:19 INFO - Successfully installed blobuploader requests docopt 17:06:19 INFO - Cleaning up... 17:06:19 INFO - Return code: 0 17:06:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:06:19 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')]} 17:06:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:06:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:19 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')]} 17:06:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:06:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:19 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 0x291d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bde180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bdc3b0>, '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 0x2bdd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bdd740>, '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': 'db7e7c15f3ca076d53731445000001ba-1472772891.821423-592927209', '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 17:06:19 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 17:06:19 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 17:06:19 INFO - Using env: {'DISPLAY': ':0', 17:06:19 INFO - 'HOME': '/home/cltbld', 17:06:19 INFO - 'LANG': 'en_US.UTF-8', 17:06:19 INFO - 'LANGUAGE': 'en_US:en', 17:06:19 INFO - 'LOGNAME': 'cltbld', 17:06:19 INFO - 'MAIL': '/var/mail/cltbld', 17:06:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:06:19 INFO - 'MOZ_NO_REMOTE': '1', 17:06:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:06:19 INFO - 'NO_EM_RESTART': '1', 17:06:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:06:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:06:19 INFO - 'PWD': '/builds/slave/test', 17:06:19 INFO - 'SHELL': '/bin/bash', 17:06:19 INFO - 'SHLVL': '1', 17:06:19 INFO - 'TERM': 'linux', 17:06:19 INFO - 'TMOUT': '86400', 17:06:19 INFO - 'USER': 'cltbld', 17:06:19 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1472772891.821423-592927209', 17:06:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:06:19 INFO - '_': '/tools/buildbot/bin/python'} 17:06:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:06:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:06:20 INFO - Running setup.py (path:/tmp/pip-xag2ap-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:06:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:06:20 INFO - Running setup.py (path:/tmp/pip-Kxt_vr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:06:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:06:20 INFO - Running setup.py (path:/tmp/pip-1im1zi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:06:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:06:20 INFO - Running setup.py (path:/tmp/pip-2eyXtm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:06:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:06:20 INFO - Running setup.py (path:/tmp/pip-_9eNxj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:06:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:06:20 INFO - Running setup.py (path:/tmp/pip-9Rfz_O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:06:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:06:20 INFO - Running setup.py (path:/tmp/pip-oVj24a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:06:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:06:20 INFO - Running setup.py (path:/tmp/pip-fXN9A0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:06:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:06:20 INFO - Running setup.py (path:/tmp/pip-VlCES9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:06:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:06:20 INFO - Running setup.py (path:/tmp/pip-HbQTaA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:06:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:06:20 INFO - Running setup.py (path:/tmp/pip-dMqImZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:06:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:06:20 INFO - Running setup.py (path:/tmp/pip-zmLpqw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:06:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:06:20 INFO - Running setup.py (path:/tmp/pip-DTyl30-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:06:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:06:20 INFO - Running setup.py (path:/tmp/pip-n1H5xM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:06:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:06:21 INFO - Running setup.py (path:/tmp/pip-40zDH_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:06:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:06:21 INFO - Running setup.py (path:/tmp/pip-QNDZUa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:06:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:06:21 INFO - Running setup.py (path:/tmp/pip-y5fq8c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:06:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:06:21 INFO - Running setup.py install for manifestparser 17:06:21 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:06:21 INFO - Running setup.py install for mozcrash 17:06:21 INFO - Running setup.py install for mozdebug 17:06:21 INFO - Running setup.py install for mozdevice 17:06:21 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:06:21 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:06:21 INFO - Running setup.py install for mozfile 17:06:21 INFO - Running setup.py install for mozhttpd 17:06:21 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:06:21 INFO - Running setup.py install for mozinfo 17:06:21 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:06:21 INFO - Running setup.py install for mozInstall 17:06:22 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:06:22 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:06:22 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:06:22 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:06:22 INFO - Running setup.py install for mozleak 17:06:22 INFO - Running setup.py install for mozlog 17:06:22 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:06:22 INFO - Running setup.py install for moznetwork 17:06:22 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:06:22 INFO - Running setup.py install for mozprocess 17:06:22 INFO - Running setup.py install for mozprofile 17:06:22 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:06:22 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:06:22 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:06:22 INFO - Running setup.py install for mozrunner 17:06:22 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:06:22 INFO - Running setup.py install for mozscreenshot 17:06:22 INFO - Running setup.py install for moztest 17:06:22 INFO - Running setup.py install for mozversion 17:06:23 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:06:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:06:23 INFO - Cleaning up... 17:06:23 INFO - Return code: 0 17:06:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:06:23 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')]} 17:06:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:06:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:23 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')]} 17:06:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:06:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:23 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 0x291d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bde180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bdc3b0>, '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 0x2bdd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bdd740>, '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': 'db7e7c15f3ca076d53731445000001ba-1472772891.821423-592927209', '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 17:06:23 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 17:06:23 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 17:06:23 INFO - Using env: {'DISPLAY': ':0', 17:06:23 INFO - 'HOME': '/home/cltbld', 17:06:23 INFO - 'LANG': 'en_US.UTF-8', 17:06:23 INFO - 'LANGUAGE': 'en_US:en', 17:06:23 INFO - 'LOGNAME': 'cltbld', 17:06:23 INFO - 'MAIL': '/var/mail/cltbld', 17:06:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:06:23 INFO - 'MOZ_NO_REMOTE': '1', 17:06:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:06:23 INFO - 'NO_EM_RESTART': '1', 17:06:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:06:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:06:23 INFO - 'PWD': '/builds/slave/test', 17:06:23 INFO - 'SHELL': '/bin/bash', 17:06:23 INFO - 'SHLVL': '1', 17:06:23 INFO - 'TERM': 'linux', 17:06:23 INFO - 'TMOUT': '86400', 17:06:23 INFO - 'USER': 'cltbld', 17:06:23 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1472772891.821423-592927209', 17:06:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:06:23 INFO - '_': '/tools/buildbot/bin/python'} 17:06:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:06:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:06:23 INFO - Running setup.py (path:/tmp/pip-3Hq504-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:06:23 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)) 17:06:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:06:23 INFO - Running setup.py (path:/tmp/pip-jrnMCp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:06:23 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)) 17:06:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:06:23 INFO - Running setup.py (path:/tmp/pip-LlZSOa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:06:23 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)) 17:06:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:06:23 INFO - Running setup.py (path:/tmp/pip-IHy7VQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:06:23 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)) 17:06:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:06:23 INFO - Running setup.py (path:/tmp/pip-3Wa3Qf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:06:23 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)) 17:06:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:06:23 INFO - Running setup.py (path:/tmp/pip-869UOi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:06:23 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)) 17:06:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:06:23 INFO - Running setup.py (path:/tmp/pip-zdAaTu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:06:23 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)) 17:06:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:06:23 INFO - Running setup.py (path:/tmp/pip-oP8Vk9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:06:23 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)) 17:06:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:06:23 INFO - Running setup.py (path:/tmp/pip-7ddcq1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:06:23 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)) 17:06:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:06:23 INFO - Running setup.py (path:/tmp/pip-qM2YCD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:06:24 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)) 17:06:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:06:24 INFO - Running setup.py (path:/tmp/pip-Jf8yjT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:06:24 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)) 17:06:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:06:24 INFO - Running setup.py (path:/tmp/pip-vyGgEX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:06:24 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)) 17:06:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:06:24 INFO - Running setup.py (path:/tmp/pip-p1ivgu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:06:24 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)) 17:06:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:06:24 INFO - Running setup.py (path:/tmp/pip-Xc_L84-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:06:24 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)) 17:06:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:06:24 INFO - Running setup.py (path:/tmp/pip-phDk4y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:06:24 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)) 17:06:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:06:24 INFO - Running setup.py (path:/tmp/pip-oHFEJq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:06:24 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)) 17:06:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:06:24 INFO - Running setup.py (path:/tmp/pip-VgQ1df-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:06:24 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)) 17:06:24 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)) 17:06:24 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)) 17:06:24 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)) 17:06:24 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)) 17:06:24 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)) 17:06:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:06:24 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 17:06:24 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 17:06:24 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 17:06:24 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 17:06:24 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 17:06:24 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 17:06:26 INFO - Downloading blessings-1.6.tar.gz 17:06:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:06:26 INFO - Installing collected packages: blessings 17:06:26 INFO - Running setup.py install for blessings 17:06:26 INFO - Successfully installed blessings 17:06:26 INFO - Cleaning up... 17:06:26 INFO - Return code: 0 17:06:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:06:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:06:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:06:26 INFO - Reading from file tmpfile_stdout 17:06:26 INFO - Current package versions: 17:06:26 INFO - argparse == 1.2.1 17:06:26 INFO - blessings == 1.6 17:06:26 INFO - blobuploader == 1.2.4 17:06:26 INFO - docopt == 0.6.1 17:06:26 INFO - functools32 == 3.2.3-2 17:06:26 INFO - jsonschema == 2.5.1 17:06:26 INFO - manifestparser == 1.1 17:06:26 INFO - mozInstall == 1.12 17:06:26 INFO - mozcrash == 0.17 17:06:26 INFO - mozdebug == 0.1 17:06:26 INFO - mozdevice == 0.48 17:06:26 INFO - mozfile == 1.2 17:06:26 INFO - mozhttpd == 0.7 17:06:26 INFO - mozinfo == 0.9 17:06:26 INFO - mozleak == 0.1 17:06:26 INFO - mozlog == 3.3 17:06:26 INFO - moznetwork == 0.27 17:06:26 INFO - mozprocess == 0.23 17:06:26 INFO - mozprofile == 0.28 17:06:26 INFO - mozrunner == 6.12 17:06:26 INFO - mozscreenshot == 0.1 17:06:26 INFO - mozsystemmonitor == 0.3 17:06:26 INFO - moztest == 0.7 17:06:26 INFO - mozversion == 1.4 17:06:26 INFO - psutil == 3.1.1 17:06:26 INFO - requests == 1.2.3 17:06:26 INFO - wsgiref == 0.1.2 17:06:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:06:26 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')]} 17:06:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:06:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:26 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')]} 17:06:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:06:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:26 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 0x291d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bde180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bdc3b0>, '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 0x2bdd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bdd740>, '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': 'db7e7c15f3ca076d53731445000001ba-1472772891.821423-592927209', '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 17:06:26 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 17:06:26 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 17:06:26 INFO - Using env: {'DISPLAY': ':0', 17:06:26 INFO - 'HOME': '/home/cltbld', 17:06:26 INFO - 'LANG': 'en_US.UTF-8', 17:06:26 INFO - 'LANGUAGE': 'en_US:en', 17:06:26 INFO - 'LOGNAME': 'cltbld', 17:06:26 INFO - 'MAIL': '/var/mail/cltbld', 17:06:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:06:26 INFO - 'MOZ_NO_REMOTE': '1', 17:06:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:06:26 INFO - 'NO_EM_RESTART': '1', 17:06:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:06:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:06:26 INFO - 'PWD': '/builds/slave/test', 17:06:26 INFO - 'SHELL': '/bin/bash', 17:06:26 INFO - 'SHLVL': '1', 17:06:26 INFO - 'TERM': 'linux', 17:06:26 INFO - 'TMOUT': '86400', 17:06:26 INFO - 'USER': 'cltbld', 17:06:26 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1472772891.821423-592927209', 17:06:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:06:26 INFO - '_': '/tools/buildbot/bin/python'} 17:06:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:06:27 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)) 17:06:27 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)) 17:06:27 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)) 17:06:27 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)) 17:06:27 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)) 17:06:27 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)) 17:06:27 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)) 17:06:27 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)) 17:06:27 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)) 17:06:27 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)) 17:06:27 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)) 17:06:27 INFO - Cleaning up... 17:06:27 INFO - Return code: 0 17:06:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:06:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:06:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:06:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:06:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:06:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:27 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 0x291d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bde180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bdc3b0>, '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 0x2bdd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bdd740>, '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': 'db7e7c15f3ca076d53731445000001ba-1472772891.821423-592927209', '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 17:06:27 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 17:06:27 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 17:06:27 INFO - Using env: {'DISPLAY': ':0', 17:06:27 INFO - 'HOME': '/home/cltbld', 17:06:27 INFO - 'LANG': 'en_US.UTF-8', 17:06:27 INFO - 'LANGUAGE': 'en_US:en', 17:06:27 INFO - 'LOGNAME': 'cltbld', 17:06:27 INFO - 'MAIL': '/var/mail/cltbld', 17:06:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:06:27 INFO - 'MOZ_NO_REMOTE': '1', 17:06:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:06:27 INFO - 'NO_EM_RESTART': '1', 17:06:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:06:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:06:27 INFO - 'PWD': '/builds/slave/test', 17:06:27 INFO - 'SHELL': '/bin/bash', 17:06:27 INFO - 'SHLVL': '1', 17:06:27 INFO - 'TERM': 'linux', 17:06:27 INFO - 'TMOUT': '86400', 17:06:27 INFO - 'USER': 'cltbld', 17:06:27 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1472772891.821423-592927209', 17:06:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:06:27 INFO - '_': '/tools/buildbot/bin/python'} 17:06:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:06:27 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:06:27 INFO - Cleaning up... 17:06:27 INFO - Return code: 0 17:06:27 INFO - Running post-action listener: _resource_record_post_action 17:06:27 INFO - Running post-action listener: _start_resource_monitoring 17:06:27 INFO - Starting resource monitoring. 17:06:27 INFO - [mozharness: 2016-09-02 00:06:27.359123Z] Finished create-virtualenv step (success) 17:06:27 INFO - [mozharness: 2016-09-02 00:06:27.359556Z] Running install step. 17:06:27 INFO - Running pre-action listener: _resource_record_pre_action 17:06:27 INFO - Running main action method: install 17:06:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:06:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:06:27 INFO - Reading from file tmpfile_stdout 17:06:27 INFO - Detecting whether we're running mozinstall >=1.0... 17:06:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:06:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:06:27 INFO - Reading from file tmpfile_stdout 17:06:27 INFO - Output received: 17:06:27 INFO - Usage: mozinstall [options] installer 17:06:27 INFO - Options: 17:06:27 INFO - -h, --help show this help message and exit 17:06:27 INFO - -d DEST, --destination=DEST 17:06:27 INFO - Directory to install application into. [default: 17:06:27 INFO - "/builds/slave/test"] 17:06:27 INFO - --app=APP Application being installed. [default: firefox] 17:06:27 INFO - mkdir: /builds/slave/test/build/application 17:06:27 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'] 17:06:27 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 17:06:44 INFO - Reading from file tmpfile_stdout 17:06:44 INFO - Output received: 17:06:44 INFO - /builds/slave/test/build/application/firefox/firefox 17:06:44 INFO - Running post-action listener: _resource_record_post_action 17:06:44 INFO - [mozharness: 2016-09-02 00:06:44.412385Z] Finished install step (success) 17:06:44 INFO - [mozharness: 2016-09-02 00:06:44.412660Z] Running run-tests step. 17:06:44 INFO - Running pre-action listener: _resource_record_pre_action 17:06:44 INFO - Running main action method: run_tests 17:06:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:06:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:06:44 INFO - Python 2.7.3 17:06:44 INFO - Return code: 0 17:06:44 INFO - grabbing minidump binary from tooltool 17:06:44 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')]} 17:06:44 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 0x2bdc3b0>, '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 0x2bdd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bdd740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:06:44 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 17:06:44 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 17:06:44 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 17:06:44 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:06:44 INFO - Return code: 0 17:06:44 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:06:44 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:06:44 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:06:44 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:06:44 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:06:44 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/a9CGJg-nRTCLA3AiqIN4YA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-049', '--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 17:06:44 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/a9CGJg-nRTCLA3AiqIN4YA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-049 --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 17:06:44 INFO - Using env: {'DISPLAY': ':0', 17:06:44 INFO - 'HOME': '/home/cltbld', 17:06:44 INFO - 'LANG': 'en_US.UTF-8', 17:06:44 INFO - 'LANGUAGE': 'en_US:en', 17:06:44 INFO - 'LOGNAME': 'cltbld', 17:06:44 INFO - 'MAIL': '/var/mail/cltbld', 17:06:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:06:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:06:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:06:44 INFO - 'MOZ_NO_REMOTE': '1', 17:06:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:06:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:06:44 INFO - 'NO_EM_RESTART': '1', 17:06:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:06:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:06:44 INFO - 'PWD': '/builds/slave/test', 17:06:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:06:44 INFO - 'SHELL': '/bin/bash', 17:06:44 INFO - 'SHLVL': '1', 17:06:44 INFO - 'TERM': 'linux', 17:06:44 INFO - 'TMOUT': '86400', 17:06:44 INFO - 'USER': 'cltbld', 17:06:44 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1472772891.821423-592927209', 17:06:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:06:44 INFO - '_': '/tools/buildbot/bin/python'} 17:06:44 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/a9CGJg-nRTCLA3AiqIN4YA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-049', '--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 17:06:44 INFO - mozversion application_buildid: 20160901141621 17:06:44 INFO - mozversion application_changeset: 0a787a252077640330496045bdde5ca018ccc727 17:06:44 INFO - mozversion application_display_name: FirefoxDeveloperEdition 17:06:44 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:06:44 INFO - mozversion application_name: Firefox 17:06:44 INFO - mozversion application_remotingname: firefox-dev 17:06:44 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 17:06:44 INFO - mozversion application_vendor: Mozilla 17:06:44 INFO - mozversion application_version: 50.0a2 17:06:44 INFO - mozversion platform_buildid: 20160901141621 17:06:44 INFO - mozversion platform_changeset: 0a787a252077640330496045bdde5ca018ccc727 17:06:44 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 17:06:44 INFO - mozversion platform_version: 50.0a2 17:06:44 INFO - using testdate: 1472774804 17:06:44 INFO - actual date: 1472774804 17:06:44 INFO - starting webserver on 'localhost:57813' 17:06:44 INFO - SUITE-START | Running 2 tests 17:06:44 INFO - TEST-START | dromaeo_css 17:06:44 INFO - operating with platform_type : linux_ 17:06:44 INFO - Initialising browser for dromaeo_css test... 17:06:44 INFO - TEST-INFO | started process 26904 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfcLNKA/profile http://localhost:57813/getInfo.html) 17:06:48 INFO - PROCESS | 26904 | __metrics Screen width/height:1600/1200 17:06:48 INFO - PROCESS | 26904 | colorDepth:24 17:06:48 INFO - PROCESS | 26904 | Browser inner width/height: 1024/697 17:06:48 INFO - PROCESS | 26904 | __metrics 17:06:49 INFO - PROCESS | 26904 | 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" 17:06:49 INFO - PROCESS | 26904 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:06:50 INFO - TEST-INFO | 26904: exit 0 17:06:50 INFO - Browser initialized. 17:06:50 INFO - Running cycle 1/1 for dromaeo_css test... 17:06:50 INFO - TEST-INFO | started process 26994 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfcLNKA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:07:02 INFO - PROCESS | 26994 | RSS: Main: 145334272 17:07:02 INFO - PROCESS | 26994 | 17:07:53 INFO - PROCESS | 26994 | 1472774873687 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] 17:10:02 INFO - PROCESS | 26994 | Cycle 1(1): loaded http://localhost:57813/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57813/tests/dromaeo/cssquery-ext.html) 17:10:03 INFO - PROCESS | 26994 | RSS: Main: 271982592 17:10:03 INFO - PROCESS | 26994 | 17:13:05 INFO - PROCESS | 26994 | Cycle 1(1): loaded http://localhost:57813/tests/dromaeo/cssquery-ext.html (next: http://localhost:57813/tests/dromaeo/cssquery-jquery.html) 17:13:06 INFO - PROCESS | 26994 | RSS: Main: 230600704 17:13:06 INFO - PROCESS | 26994 | 17:16:08 INFO - PROCESS | 26994 | Cycle 1(1): loaded http://localhost:57813/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57813/tests/dromaeo/cssquery-mootools.html) 17:16:09 INFO - PROCESS | 26994 | RSS: Main: 173387776 17:16:09 INFO - PROCESS | 26994 | 17:19:10 INFO - PROCESS | 26994 | Cycle 1(1): loaded http://localhost:57813/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57813/tests/dromaeo/cssquery-prototype.html) 17:19:11 INFO - PROCESS | 26994 | RSS: Main: 173826048 17:19:11 INFO - PROCESS | 26994 | 17:22:14 INFO - PROCESS | 26994 | Cycle 1(1): loaded http://localhost:57813/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57813/tests/dromaeo/cssquery-yui.html) 17:22:14 INFO - PROCESS | 26994 | RSS: Main: 175136768 17:22:14 INFO - PROCESS | 26994 | 17:25:17 INFO - PROCESS | 26994 | Cycle 1(1): loaded http://localhost:57813/tests/dromaeo/cssquery-yui.html (next: http://localhost:57813/tests/dromaeo/cssquery-dojo.html) 17:25:17 INFO - PROCESS | 26994 | RSS: Main: 257982464 17:25:17 INFO - PROCESS | 26994 | 17:25:17 INFO - PROCESS | 26994 | __start_tp_report 17:25:17 INFO - PROCESS | 26994 | _x_x_mozilla_page_load 17:25:17 INFO - PROCESS | 26994 | _x_x_mozilla_page_load_details 17:25:17 INFO - PROCESS | 26994 | |i|pagename|runs| 17:25:17 INFO - PROCESS | 26994 | |0;dojo.html;3232.983835080824;3266.0365192268277;3266.2161081340478;3293.045016945086;3313.7349012079035;5621.297337832771;5742.3109226892775;5746.7413966371505;5749.453801888824;5785.3346865110525;5592.189132575774;5717.256756621644;5718.113692377678;5724.570657200717;5728.828135155952;4884.6824956377895;4987.650864439491;5142.922856157152;5168.948310516903;5233.633645644806;5035.723035233061;5041;5073.695578265316;5091.46539613339;5144.4598317176715;5211.4267430582795;5244.816431424901;5251.711155886424;5254.290670759437;5279.86800329991;5233.685978841263;5238.345206849144;5245.396779370383;5246.186841039645;5274.498922602346;4922.80308787648;4998.3502144721315;5047.924281135786;5144.356955380577;5158.3552055993005;5561.304836895388;5587.357453892813;5609.551235901118;5625.6905870177125;5709.314882214119;6104.847378815521;6114.694265286718;6145.477634401082;6155.784547540859;6163.2604087509335;5748.511376533;5957.344692083867;5978.790742323998;6195.380461953813;6217.191765070557;5624.3813180550105;5636.0982242841355;5664.773409063674;5664.830055098354;5670.914936275959;6208.255009398811;6216.191895053614;6217.316095229521;6220.191375121205;6223.159873417121;5644.153376993402;5864.70676466175;5903.616264942851;5909.409059094056;6047.425494578008;6065.835411471322;6083.7566497340495;6099.690984817992;6116.021436570217;6123;6036.879262414727;6049.51603871698;6050.092486127116;6051.576389652682;6053.152558641794;5728.112142617901;5971.910421343683;6050.031994880886;6054.939450605526;6143.96928015366;4656.301554766812;4665.113628410591;4665.2302370108555;4689.249720044844;4696.201645720235;4147.294959856831;4165.708400411942;4172.144710334374;4174.83300667976;4194.643455306333;5523.6685798852195;5529;5769.451902069297;5818.970905145447;5844.649321040752;6150.323464418911;6183.783567575203;6198.163247961467;6210.875782484415;6211.844703882439;5885.823425297162;5901.822945311647;5902.852428689317;5912.054071348563;5922.378150294243;5013.022460620217;5169.870753231199;5219.217117432416;5329.067413202674;5343.412224655285;6254.280757712831;6257.061440783918;6260.123582698426;6272.184615999982;6279.0895320178315;6078.574499784972;6086.847828804138;6089.482393996383;6095.085737139465;6104.69476526163;5928.644281343133;6122.97760420812;6164.7842325518395;6214.751409943732;6316.305206427382;5912.5861189718025;5916.73374698131;5927.288725352985;5937.435943585352;5952.910706339495;6059.363766804422;6070.0591408331775;6075.27096748393;6075.27096748393;6077.6353418795015;5676.035074037341;5882.382349853204;5930.911036334544;5948.434898684618;5956.672383018976;5990.820275391745;5991.820245392645;5999.790007349722;6011.458968692664;6031.276246850406;5926.051831706906;5936.317323507679;5936.851578710567;5937.7921772737745;5939.703014849154;5656.943430565641;5874.383189765013;5945.227120474484;5952.73212705438;6064.3935606439;5772.365039845529;5828.242328497268;5832.708364581838;5869.706514674334;5884.705764711662 17:25:17 INFO - PROCESS | 26994 | |1;ext.html;609.4240942309516;621.4313902778956;649.8830210562098;663.3466035954586;673.0039541478615;45901.31148032778;47839.521604783986;47953.52046479534;47968.999999999956;51203.23195152066;49777;49912.50087499124;50121.749391253084;50355.49644503554;50395;8711.651533938635;9425.76435589109;9589.089036541553;9590.856137157949;11210.66368008957;11121.666350009513;11618;11621.244619099774;11622;11640.767184656303;11673.824892626575;11724.355160466213;11726.530938762438;11727;11732;11549.422528873607;11607;11645.53417863293;11650.184487085908;11663.533458661686;11345.205835591512;11402.087832973342;11411.60059397917;11412.885871141265;11423.885761142448;46281;46692;46796.76601617004;46889.5311046892;47023.29465058027;3534.5228394166716;3671.963280367189;3789.3368660484307;3911.374180131194;4136.152088821785;35589;35621.10947226314;35666;35948.46077308842;35992.280154396765;4250.447441832542;4375.7155784873885;4407.735535867826;4411.382406463098;4456.041950980555;385957;389836;393110;556800;557818;67328.66335668389;67913;68023.31976680268;68143.65928170328;68221.65889170523;37742.62257377446;39821;39892.60107398947;41957.370639440436;72507.27492725111;19512.512187195432;19666.115024823917;19668.606627867648;19673.508162296057;19697.21211151538;13613.251271179983;14643.56069317922;15028.474003409829;15037.548873533593;15048.548543543711;21231.893840530698;21285.78714212838;21326.14695412197;21504.13983440676;21511.56976860454;7436.430513353513;7452.143003554552;7503.737369192052;7542.208068152765;7613.124490683532;1034.1416624202045;1071.994640026795;1098.6484325016079;1142.8914253146104;1247.650657815813;34837.303253935286;35042.824785876415;35183;35335.64664353323;35362.29275414477;1182.9822552661717;1209.0448545649015;1233.8704436034086;1235.4934476864441;1266.4047897488165;18172.091395430176;18216.08919554017;18314.725279120823;18351.90824045898;18596.81403185978;613215;615250;617492;618222;618301;64697.67651161808;65008.34991650023;65062.34937650657;65095.67452162709;65128;1372.9176249424868;1428.4143501164472;1483.5252719129776;1508.3589474473306;1535.3167840310955;34248.82875585606;36007.63992360148;36022.639773602976;36072;36076.27847443089;53471;54284;54358;54358.72820635871;54448.72775636097;431097;434884;435080;435867;436357;29168.124956251348;30212.09363719092;30288.394232114795;30305.24236894095;30355.02692324992;50432.74783626058;50487.49512504828;51378.74310628423;51436.74281628717;52227;43081.784591078096;43162.1367572653;43491.56508434875;43937.34093988529;44990.325145123505;43243.35134972915;43514.34728479139;43903.560964391225;44473.332900007175;45914;43350.34974475322;45143;45398;45652.086958259984;46520.302195466415;37737.245255095295;40090.599094008685;40148;40431.39352909767;40588.391174133;29757.5536366959;29770.249706887866;29858.073417885804;30208.395832083675;30460.84769576138 17:25:17 INFO - PROCESS | 26994 | |2;jquery.html;400.6374231320656;402.73822015689797;415.46820070309997;419.57413225576045;426.93169092945135;15687.921560392197;19868.999999999964;20060.799392006076;20263.39209823706;22172.334829955085;18684.62630747384;19032.429027129172;19167.80832191678;20609.587808243825;20852.37442876712;5275.314209152804;5591.245181900432;5977.282726072855;6008.699565021753;6015.278166620012;3996.3206254936586;4243.554426785189;5971;6051.304100028509;6060.878782424349;5895.616784908989;6113.32753397126;6145.2625684917875;6237.438630523252;6247.156633854417;6120.8469788255425;6157.3534778848025;6161.599496032743;6200.751969921198;6231.127642130083;6020.518358531307;6029.155918171459;6060.545459090542;6063.120847477136;6064.36324185963;14814.777778333333;14926.029808062549;15062.096274223582;15143.545693629208;15293.61765955849;2298.2645553422913;2439.146298795425;2466.9506609867703;2478.281298423455;2571.2800415883585;10286.639967601099;10724.195685323632;10992.17558683094;12666.683332916658;14532.91003174766;2075.9065842037144;2094.130935661714;2141.4646338415396;2154.493693981913;2164.1235299703644;85054;85647.57176214141;86120;86624.13375866224;87973.99999999936;11701.590444334408;11742.471588778526;11896.21556766492;11973.281603103842;11985.22096063753;16613;16664.16679166037;16690;16691.666166676598;16694.4156954506;8976.37165398429;9220.53897305132;9257.768555786159;9300.348975571645;9334.113259240361;9059.728672032617;9078.773030674285;9121.908780912107;9295.209907157832;9350.719478415658;5037.823676171316;5097.592192624656;5110.795568177231;5158.92261616076;5198.2202669599865;2054.938351849447;2093.194120263679;2100.4328831215485;2140.7324084489437;2170.1319472211244;980.3725615606018;1027.2295778166376;1059.0204061243319;1103;1119.6137332620233;19971.001449927444;20114.39656810298;20667.79332206689;20754.273600423912;21034.78965210328;1018.5874720738113;1070.29895418501;1092.4027900423716;1096.8683757948938;1126.8478755368085;11351.772964540663;11844.644660660022;11903.761924761457;11960.521579136737;11993.820092698617;517635;522445;524452;531966;533145;93164;99386.5030674842;99982.50008750055;100553.49723251483;102092;1033.5865653738415;1048.2766890845292;1066.2855886556026;1068.3376306689747;1074.1836204484491;17901.641967160846;18191.45425637233;18648.533786655444;18672.346467873558;18830.24679012824;43274.78362608167;45073.32390014218;45496.77251613721;45735;46389.76805115953;60543.39456605377;61892.38107619046;67208;68619.65690171518;70193;8011.43919925623;8573.785655358666;9007.639694412412;9174.311926605398;9192.54037298119;10674.62638807638;13914.443422262995;14315.642108946942;14404.351804169048;14781.408743650563;12360.25838449696;12558.309292988974;12582.685432864251;12612.243265404106;12650.30423326689;12243.938780306042;12340.382980851102;12512.812307815571;12775;12852.807207892076;12091.214071085351;12301.692457688629;12306.942607381196;12624.558140465408;12875;8098.19018098209;8287.709930152416;8319.417640765088;8677.132286771272;8878.600462979311;9481.146696797321;12280;12394.132410731163;12560.874391255971;12943.741125177632 17:25:17 INFO - PROCESS | 26994 | |3;mootools.html;1362.489066600025;1502.0612117426613;1547.7136729705003;1717.7938647362319;1831.679456095183;8597.097304783001;8919.554022298884;8925.50909699967;9209.309301802376;9217.262618990482;9038;9153;9156.771080722985;9159.267258619311;9163.908360916388;2502.5370306493355;2512.52262070207;2527.0902475108946;2535.353484861367;2538.5684433646234;2421.9757802421927;2485.5028994201143;2486.428121532049;2486.7015958085053;2494.825362224645;2418.141559746291;2445.926622201328;2456.4227406559444;2458.791002764767;2459.225344016629;2357.116081469449;2361.681173041643;2381.535600557892;2444.9755502444928;2447.4248551590363;2355.1874603262017;2362.810975122003;2365.775251351119;2365.8343915925893;2402.3513651314047;9047.050059743764;9117.179453849185;9170.128837760401;9170.541472926327;9268.21220196284;5467.17992301154;5679.119736440858;5690.032694441955;5690.260266165372;5690.9146362804595;3482.9651703482896;3534.9469757953652;3560.1989552350774;3569.6430356964356;3588.4796704477703;1115.3865374044244;1155.96532104037;1156.583629893246;1157.8842115788439;1180.4274926660562;13287.468501259938;13360.532381366605;13415.46338146473;13418.261995590336;13432.664183395396;4769.379980602499;4789.856304310876;4852.684575502581;4906.779194936236;4936.037472692862;10582.20633452494;10593.258471906893;10599.523021464052;10605.522751476048;10615.893841061645;5595.972020139955;5603.187537807076;5636.718164091779;5638;5638.6052976292085;4501.954980450219;4677.087967846237;5106.617003724736;5184.662996905265;5278.736063196825;4057.208844275338;4084.2035803018125;4185.832566697298;4188.392683060939;4194.538600753915;1216.4951545108859;1230.698478872682;1232.7657745028416;1237.4369661803858;1246.052999720219;1032.8037672842136;1069.5026812532055;1073.3828048871774;1078.5038882114159;1083.2633809009792;6509.544331896816;6535.973848107102;6642.269350371455;6735.393814556615;6846.178458585002;480.5866954419197;481.066730542746;486.2948724349707;489.08785115759565;504.71483611759237;4508.842190523316;4624.445066591963;4646.395968524071;4684.508126646721;4734.502877197845;11558.075353971662;11889.524419023142;11957.103217258566;11957.760844783055;12005.519779208735;11574.710632234093;11594.536218551164;11784.941075294568;11807.586734464252;11867.109966752529;8881.822363552692;8911;8959.686410975584;8960.1935825776;8961;6500.317466666121;6502.934970650233;6604.735810567524;6622.602643841385;6705.966470167618;10841.07850832688;10926.453677315943;10946.452677366258;11024.779504410028;11155.051820595016;10719.035286824223;10855.67432977012;10998.450077495932;11037.83443248368;11130.721731956442;8052.87920681202;8143.877841832259;8165.959170204349;8285.668573257002;8348.415610907177;9447.527623618655;9501.144896959308;9568.186704130227;9578.425294482353;9579.473128977974;8292.792680183044;8369.455985360932;8443.704470343508;8444.408891377545;8467.83064338722;8189.918100818915;8230.259276665129;8231.34149268045;8296.502209867427;8311.542865142475;7676.193999629959;7766.766996990099;7870.606469676608;7875;7881.881771773543;5472.589555783412;5702.857428564319;5749.597528172988;5893.116032595145;5961.910571341346;5850.327212370632;5869.325027621878;5940.495057919952;5986.9401305986385;6008.759649613967 17:25:17 INFO - PROCESS | 26994 | |4;prototype.html;351.2833819009221;352.8782570013346;358.8349359294724;360.12848905648326;361.39466393790394;5483.1501117326825;5521.585881058922;5523.585731070166;5533.6956467394275;5550.58370622204;5439.428859969706;5456.236126942231;5464.316960379952;5478.808241711541;5483.479069488402;3831.712621553381;4070.694697897655;4262;4297.312430011199;4305.547917468668;4283.121959998193;4301.139772045603;4313.352997050436;4335.869923902288;4372.5627437256335;4190.161967606476;4318.805653745589;4344;4345.760983145941;4346.065595896882;4166.500019997605;4246.853349595623;4248.830046798124;4260.25445547028;4262.0836520148005;3766.8304926278383;3770.132869440044;3787.886363409102;3807.096451774113;3808.9809550952345;5162.638615296931;5305.4614161893005;5307.495787900143;5345.705986170759;5431.34823821144;4945.629077819142;4983.825566105205;4988.5260900214425;4995.825146119904;5009.824656137054;5656.802011929604;5708.942910570882;5709.400512946122;5724.141378793172;5724.942750572524;4086.6730661547003;4277.786110694453;4279.957200427987;4280.101178752466;4288.44250247469;5652.886942261173;5720.914186287209;5913.142594323842;5918.171455996164;5931.199288096138;4334.566543345641;4344;4349.499807522112;4402.647788176938;4407.140607581491;4422.545487254604;4425.22558552252;4438.911221775546;4452.621527170162;4455.376247325377;4879.243717223836;4941.604671626261;4953.405591329064;4994.751312171957;5014.893744627192;5720.94046143388;5726.363759013057;5747;5753.194552762617;5814.563907706939;1510.886683498742;1526.366557878468;1527.9847201527843;1537.3312609015113;1539.5920081178497;960.4237457525429;981.3964411886639;986.7039888033561;1000.2698030437821;1021.7547788530699;4904.190808516626;4913.87715307113;4914.926276105862;4990.6506544541535;4998.525140111684;5183.393148124093;5201.271821944931;5223.373195216523;5225.216217567396;5235.240890070919;4994.500549944976;5006.299118123466;5048.485454363677;5208.255234496486;5678.176664383641;3118.6881311869083;3132.185631735765;3154.6529881712995;3156.1320636825058;3164.351307981858;6099.756009759649;6108.4807791338235;6130.203073600493;6135.754569817246;6144.586745048575;5791.507721843691;6018.789342372996;6048.4274088736365;6071;6076.817695469143;6178.7219575119225;6183.598066125687;6187.535934804908;6198.783042593487;6200.255969283715;5640.294963129609;5926.881462370815;5997.220361352996;6005.489533389711;6007.969960150172;5877.265341832271;5879.911801322898;5885.764569417175;5895.852603684943;5917.201177840936;5830.82507524775;5953.226080609493;6135.877282454237;6148.354422785543;6174.394657389022;4285.785710714389;4289.163613095417;4313.892152696208;4329.567043295645;4330.52364239927;5830.154627578892;6022.7289771959495;6031.276246850406;6047.123167140828;6064.818055458343;5791.536677065909;5798.855028624318;5810.4189581041555;5810.796622118206;5811.302643682785;5753.137029445449;5784.8430313938225;5790.494471437372;5791.362950075404;5803.7388317524965;5454.963556924172;5681.147827825859;5703.258576385043;5717.485426311486;5757.424257574209;5822.883542329045;5840.269966254218;5865.296164460292;5869.239228231428;5889.146073819358;5531.640443371168;5707.771689132388;5732.254806875079;5743.770249189985;5746.620811005245 17:25:17 INFO - PROCESS | 26994 | |5;yui.html;828.2752591482453;864.3560547392194;890.6214858685059;893.7140115163143;926.0271250434696;16616.750748738777;16778.83221167788;16882.324707011703;16922.576935576617;16933.745993810102;16237.350505979775;16396.426125085625;16447.917760411212;16450.506484805475;16475.83524164761;725.611797688237;737.9188289288174;851.5188918261193;857.8713193021034;872.2019352292644;873.2882700599023;888.4757992784256;888.8533391990314;888.9022207557196;889.1819526036062;934.9205317548017;936.8547875079373;937.4281688170245;937.9015203403646;938.3619138985487;768.8731359325704;900.1763386501343;915.4461550761827;931.6692574136151;932.822763674903;691.550492180087;697.3340459860821;697.6023666510091;697.6302559643347;698.1377998172253;15422.537323880302;15507.92246038774;15576.065436073872;15596.922015389964;15622.687546249012;14969.251537423193;15438.614034649112;15571.065736055873;15713;15954.680906381924;14234.277245645873;14262.57212283633;14335.139891606537;14397.928010359881;14550.19973901435;4625.930611040837;4744;4744.5967092797155;4750.904981900378;4782.904341913177;90456.99999999935;92130;92398.5380073102;92574.5371273146;93005.06994930032;12344.876551234373;12415.93792031034;12440.440180191914;12477;12595.685107872376;23609.29172124839;23643.88178059133;23765;23783.52432951366;24588.877055614965;8958.731238062868;8980.506072165963;8998.370114092082;9035.864462033074;9317.72046838596;4577.8397756078375;4745.715257084586;4946.936408672081;4989.501049894981;5202.115640341151;3754.718396120302;3910.6284902934176;3911.0026943129888;3913.3151698453225;3914.0410599402758;641.3298103481873;641.6054126712132;645.3610925184034;652.5660435810228;659.9210291173965;581.2966310764023;586.3962063755625;662.8011596521025;719.7157122936411;740.714824792448;9967.044529590456;10306.690799276033;10758.46207689628;10942.890571094347;10958.835617465744;707.3245050976305;711.4237467651219;715.849671568966;723.5260904107818;740.5852722475431;4770.332153498513;4902;4925.975370123127;4940.2342636891335;4956.083124621957;71596.28403716;128896;131082.68917310896;139058;143302;93483;95585.5220723892;112923;116189.4190529042;116200.41899790616;63616.36383636197;80549;104870.4756476228;105786;112382.43808781067;10812.945935270272;11347.602833900773;11410.714732131431;11577.189596728485;11714.648560543197;57993.420065798804;59586;60203.698981504815;60714.39285607264;63370.68314658397;84272;89805.55097224472;90084;91910.16179676235;94043.52978235066;15586.298616562508;15700.764488532453;15969.281382337591;15998.12010339396;16030;16757.32970681159;17065.744013839554;17350.91324543369;17418.65066509865;17501.649967000332;15924.363025478851;15985;16101.194940252706;16829.24268407949;17006.48980530586;15180.392784288504;15395.538133856002;15713.607159821097;16191;16365.34538618442;14478.229963563186;14484.275786210857;15127.773083403537;15804.60488487797;16177.110258935401;13071.803922940973;13075.41160647754;13179.67050823737;13404.501836979265;14331.570052898429;11765.294082355085;12881.14745967804;13180.406881690154;13452.39464224132;13694.52069177574 17:25:17 INFO - PROCESS | 26994 | __end_tp_report 17:25:17 INFO - PROCESS | 26994 | __start_cc_report 17:25:17 INFO - PROCESS | 26994 | _x_x_mozilla_cycle_collect,1145 17:25:17 INFO - PROCESS | 26994 | __end_cc_report 17:25:17 INFO - PROCESS | 26994 | __startTimestamp1472775917652__endTimestamp 17:25:17 INFO - PROCESS | 26994 | 17:25:17 INFO - PROCESS | 26994 | ------- Summary: start ------- 17:25:17 INFO - PROCESS | 26994 | Number of tests: 6 17:25:17 INFO - PROCESS | 26994 | 17:25:17 INFO - PROCESS | 26994 | [#0] dojo.html Cycles:165 Average:5635.74 Median:5884.71 stddev:641.62 (10.9%) stddev-sans-first:615.27 17:25:17 INFO - PROCESS | 26994 | Values: 3233.0 3266.0 3266.2 3293.0 3313.7 5621.3 5742.3 5746.7 5749.5 5785.3 5592.2 5717.3 5718.1 5724.6 5728.8 4884.7 4987.7 5142.9 5168.9 5233.6 5035.7 5041.0 5073.7 5091.5 5144.5 5211.4 5244.8 5251.7 5254.3 5279.9 5233.7 5238.3 5245.4 5246.2 5274.5 4922.8 4998.4 5047.9 5144.4 5158.4 5561.3 5587.4 5609.6 5625.7 5709.3 6104.8 6114.7 6145.5 6155.8 6163.3 5748.5 5957.3 5978.8 6195.4 6217.2 5624.4 5636.1 5664.8 5664.8 5670.9 6208.3 6216.2 6217.3 6220.2 6223.2 5644.2 5864.7 5903.6 5909.4 6047.4 6065.8 6083.8 6099.7 6116.0 6123.0 6036.9 6049.5 6050.1 6051.6 6053.2 5728.1 5971.9 6050.0 6054.9 6144.0 4656.3 4665.1 4665.2 4689.2 4696.2 4147.3 4165.7 4172.1 4174.8 4194.6 5523.7 5529.0 5769.5 5819.0 5844.6 6150.3 6183.8 6198.2 6210.9 6211.8 5885.8 5901.8 5902.9 5912.1 5922.4 5013.0 5169.9 5219.2 5329.1 5343.4 6254.3 6257.1 6260.1 6272.2 6279.1 6078.6 6086.8 6089.5 6095.1 6104.7 5928.6 6123.0 6164.8 6214.8 6316.3 5912.6 5916.7 5927.3 5937.4 5952.9 6059.4 6070.1 6075.3 6075.3 6077.6 5676.0 5882.4 5930.9 5948.4 5956.7 5990.8 5991.8 5999.8 6011.5 6031.3 5926.1 5936.3 5936.9 5937.8 5939.7 5656.9 5874.4 5945.2 5952.7 6064.4 5772.4 5828.2 5832.7 5869.7 5884.7 17:25:17 INFO - PROCESS | 26994 | 17:25:17 INFO - PROCESS | 26994 | [#1] ext.html Cycles:180 Average:67426.07 Median:39856.80 stddev:135739.07 (340.6%) stddev-sans-first:136027.15 17:25:17 INFO - PROCESS | 26994 | Values: 609.4 621.4 649.9 663.3 673.0 45901.3 47839.5 47953.5 47969.0 51203.2 49777.0 49912.5 50121.7 50355.5 50395.0 8711.7 9425.8 9589.1 9590.9 11210.7 11121.7 11618.0 11621.2 11622.0 11640.8 11673.8 11724.4 11726.5 11727.0 11732.0 11549.4 11607.0 11645.5 11650.2 11663.5 11345.2 11402.1 11411.6 11412.9 11423.9 46281.0 46692.0 46796.8 46889.5 47023.3 3534.5 3672.0 3789.3 3911.4 4136.2 35589.0 35621.1 35666.0 35948.5 35992.3 4250.4 4375.7 4407.7 4411.4 4456.0 385957.0 389836.0 393110.0 556800.0 557818.0 67328.7 67913.0 68023.3 68143.7 68221.7 37742.6 39821.0 39892.6 41957.4 72507.3 19512.5 19666.1 19668.6 19673.5 19697.2 13613.3 14643.6 15028.5 15037.5 15048.5 21231.9 21285.8 21326.1 21504.1 21511.6 7436.4 7452.1 7503.7 7542.2 7613.1 1034.1 1072.0 1098.6 1142.9 1247.7 34837.3 35042.8 35183.0 35335.6 35362.3 1183.0 1209.0 1233.9 1235.5 1266.4 18172.1 18216.1 18314.7 18351.9 18596.8 613215.0 615250.0 617492.0 618222.0 618301.0 64697.7 65008.3 65062.3 65095.7 65128.0 1372.9 1428.4 1483.5 1508.4 1535.3 34248.8 36007.6 36022.6 36072.0 36076.3 53471.0 54284.0 54358.0 54358.7 54448.7 431097.0 434884.0 435080.0 435867.0 436357.0 29168.1 30212.1 30288.4 30305.2 30355.0 50432.7 50487.5 51378.7 51436.7 52227.0 43081.8 43162.1 43491.6 43937.3 44990.3 43243.4 43514.3 43903.6 44473.3 45914.0 43350.3 45143.0 45398.0 45652.1 46520.3 37737.2 40090.6 40148.0 40431.4 40588.4 29757.6 29770.2 29858.1 30208.4 30460.8 17:25:17 INFO - PROCESS | 26994 | 17:25:17 INFO - PROCESS | 26994 | [#2] jquery.html Cycles:180 Average:30864.97 Median:2147.98 stddev:86808.86 (4041.4%) stddev-sans-first:87022.24 17:25:17 INFO - PROCESS | 26994 | Values: 400.6 402.7 415.5 419.6 426.9 15687.9 19869.0 20060.8 20263.4 22172.3 18684.6 19032.4 19167.8 20609.6 20852.4 5275.3 5591.2 5977.3 6008.7 6015.3 3996.3 4243.6 5971.0 6051.3 6060.9 5895.6 6113.3 6145.3 6237.4 6247.2 6120.8 6157.4 6161.6 6200.8 6231.1 6020.5 6029.2 6060.5 6063.1 6064.4 14814.8 14926.0 15062.1 15143.5 15293.6 2298.3 2439.1 2467.0 2478.3 2571.3 10286.6 10724.2 10992.2 12666.7 14532.9 2075.9 2094.1 2141.5 2154.5 2164.1 85054.0 85647.6 86120.0 86624.1 87974.0 11701.6 11742.5 11896.2 11973.3 11985.2 16613.0 16664.2 16690.0 16691.7 16694.4 8976.4 9220.5 9257.8 9300.3 9334.1 9059.7 9078.8 9121.9 9295.2 9350.7 5037.8 5097.6 5110.8 5158.9 5198.2 2054.9 2093.2 2100.4 2140.7 2170.1 980.4 1027.2 1059.0 1103.0 1119.6 19971.0 20114.4 20667.8 20754.3 21034.8 1018.6 1070.3 1092.4 1096.9 1126.8 11351.8 11844.6 11903.8 11960.5 11993.8 517635.0 522445.0 524452.0 531966.0 533145.0 93164.0 99386.5 99982.5 100553.5 102092.0 1033.6 1048.3 1066.3 1068.3 1074.2 17901.6 18191.5 18648.5 18672.3 18830.2 43274.8 45073.3 45496.8 45735.0 46389.8 60543.4 61892.4 67208.0 68619.7 70193.0 8011.4 8573.8 9007.6 9174.3 9192.5 10674.6 13914.4 14315.6 14404.4 14781.4 12360.3 12558.3 12582.7 12612.2 12650.3 12243.9 12340.4 12512.8 12775.0 12852.8 12091.2 12301.7 12306.9 12624.6 12875.0 8098.2 8287.7 8319.4 8677.1 8878.6 9481.1 12280.0 12394.1 12560.9 12943.7 17:25:17 INFO - PROCESS | 26994 | 17:25:17 INFO - PROCESS | 26994 | [#3] mootools.html Cycles:180 Average:6221.75 Median:4709.51 stddev:3576.74 (75.9%) stddev-sans-first:3568.13 17:25:17 INFO - PROCESS | 26994 | Values: 1362.5 1502.1 1547.7 1717.8 1831.7 8597.1 8919.6 8925.5 9209.3 9217.3 9038.0 9153.0 9156.8 9159.3 9163.9 2502.5 2512.5 2527.1 2535.4 2538.6 2422.0 2485.5 2486.4 2486.7 2494.8 2418.1 2445.9 2456.4 2458.8 2459.2 2357.1 2361.7 2381.5 2445.0 2447.4 2355.2 2362.8 2365.8 2365.8 2402.4 9047.1 9117.2 9170.1 9170.5 9268.2 5467.2 5679.1 5690.0 5690.3 5690.9 3483.0 3534.9 3560.2 3569.6 3588.5 1115.4 1156.0 1156.6 1157.9 1180.4 13287.5 13360.5 13415.5 13418.3 13432.7 4769.4 4789.9 4852.7 4906.8 4936.0 10582.2 10593.3 10599.5 10605.5 10615.9 5596.0 5603.2 5636.7 5638.0 5638.6 4502.0 4677.1 5106.6 5184.7 5278.7 4057.2 4084.2 4185.8 4188.4 4194.5 1216.5 1230.7 1232.8 1237.4 1246.1 1032.8 1069.5 1073.4 1078.5 1083.3 6509.5 6536.0 6642.3 6735.4 6846.2 480.6 481.1 486.3 489.1 504.7 4508.8 4624.4 4646.4 4684.5 4734.5 11558.1 11889.5 11957.1 11957.8 12005.5 11574.7 11594.5 11784.9 11807.6 11867.1 8881.8 8911.0 8959.7 8960.2 8961.0 6500.3 6502.9 6604.7 6622.6 6706.0 10841.1 10926.5 10946.5 11024.8 11155.1 10719.0 10855.7 10998.5 11037.8 11130.7 8052.9 8143.9 8166.0 8285.7 8348.4 9447.5 9501.1 9568.2 9578.4 9579.5 8292.8 8369.5 8443.7 8444.4 8467.8 8189.9 8230.3 8231.3 8296.5 8311.5 7676.2 7766.8 7870.6 7875.0 7881.9 5472.6 5702.9 5749.6 5893.1 5961.9 5850.3 5869.3 5940.5 5986.9 6008.8 17:25:17 INFO - PROCESS | 26994 | 17:25:17 INFO - PROCESS | 26994 | [#4] prototype.html Cycles:180 Average:4834.12 Median:5435.39 stddev:1404.05 (25.8%) stddev-sans-first:1367.08 17:25:17 INFO - PROCESS | 26994 | Values: 351.3 352.9 358.8 360.1 361.4 5483.2 5521.6 5523.6 5533.7 5550.6 5439.4 5456.2 5464.3 5478.8 5483.5 3831.7 4070.7 4262.0 4297.3 4305.5 4283.1 4301.1 4313.4 4335.9 4372.6 4190.2 4318.8 4344.0 4345.8 4346.1 4166.5 4246.9 4248.8 4260.3 4262.1 3766.8 3770.1 3787.9 3807.1 3809.0 5162.6 5305.5 5307.5 5345.7 5431.3 4945.6 4983.8 4988.5 4995.8 5009.8 5656.8 5708.9 5709.4 5724.1 5724.9 4086.7 4277.8 4280.0 4280.1 4288.4 5652.9 5720.9 5913.1 5918.2 5931.2 4334.6 4344.0 4349.5 4402.6 4407.1 4422.5 4425.2 4438.9 4452.6 4455.4 4879.2 4941.6 4953.4 4994.8 5014.9 5720.9 5726.4 5747.0 5753.2 5814.6 1510.9 1526.4 1528.0 1537.3 1539.6 960.4 981.4 986.7 1000.3 1021.8 4904.2 4913.9 4914.9 4990.7 4998.5 5183.4 5201.3 5223.4 5225.2 5235.2 4994.5 5006.3 5048.5 5208.3 5678.2 3118.7 3132.2 3154.7 3156.1 3164.4 6099.8 6108.5 6130.2 6135.8 6144.6 5791.5 6018.8 6048.4 6071.0 6076.8 6178.7 6183.6 6187.5 6198.8 6200.3 5640.3 5926.9 5997.2 6005.5 6008.0 5877.3 5879.9 5885.8 5895.9 5917.2 5830.8 5953.2 6135.9 6148.4 6174.4 4285.8 4289.2 4313.9 4329.6 4330.5 5830.2 6022.7 6031.3 6047.1 6064.8 5791.5 5798.9 5810.4 5810.8 5811.3 5753.1 5784.8 5790.5 5791.4 5803.7 5455.0 5681.1 5703.3 5717.5 5757.4 5822.9 5840.3 5865.3 5869.2 5889.1 5531.6 5707.8 5732.3 5743.8 5746.6 17:25:17 INFO - PROCESS | 26994 | 17:25:17 INFO - PROCESS | 26994 | [#5] yui.html Cycles:180 Average:23444.05 Median:3910.82 stddev:33777.80 (863.7%) stddev-sans-first:33829.87 17:25:17 INFO - PROCESS | 26994 | Values: 828.3 864.4 890.6 893.7 926.0 16616.8 16778.8 16882.3 16922.6 16933.7 16237.4 16396.4 16447.9 16450.5 16475.8 725.6 737.9 851.5 857.9 872.2 873.3 888.5 888.9 888.9 889.2 934.9 936.9 937.4 937.9 938.4 768.9 900.2 915.4 931.7 932.8 691.6 697.3 697.6 697.6 698.1 15422.5 15507.9 15576.1 15596.9 15622.7 14969.3 15438.6 15571.1 15713.0 15954.7 14234.3 14262.6 14335.1 14397.9 14550.2 4625.9 4744.0 4744.6 4750.9 4782.9 90457.0 92130.0 92398.5 92574.5 93005.1 12344.9 12415.9 12440.4 12477.0 12595.7 23609.3 23643.9 23765.0 23783.5 24588.9 8958.7 8980.5 8998.4 9035.9 9317.7 4577.8 4745.7 4946.9 4989.5 5202.1 3754.7 3910.6 3911.0 3913.3 3914.0 641.3 641.6 645.4 652.6 659.9 581.3 586.4 662.8 719.7 740.7 9967.0 10306.7 10758.5 10942.9 10958.8 707.3 711.4 715.8 723.5 740.6 4770.3 4902.0 4926.0 4940.2 4956.1 71596.3 128896.0 131082.7 139058.0 143302.0 93483.0 95585.5 112923.0 116189.4 116200.4 63616.4 80549.0 104870.5 105786.0 112382.4 10812.9 11347.6 11410.7 11577.2 11714.6 57993.4 59586.0 60203.7 60714.4 63370.7 84272.0 89805.6 90084.0 91910.2 94043.5 15586.3 15700.8 15969.3 15998.1 16030.0 16757.3 17065.7 17350.9 17418.7 17501.6 15924.4 15985.0 16101.2 16829.2 17006.5 15180.4 15395.5 15713.6 16191.0 16365.3 14478.2 14484.3 15127.8 15804.6 16177.1 13071.8 13075.4 13179.7 13404.5 14331.6 11765.3 12881.1 13180.4 13452.4 13694.5 17:25:17 INFO - PROCESS | 26994 | -------- Summary: end -------- 17:25:17 INFO - PROCESS | 26994 | 17:25:18 INFO - TEST-INFO | 26994: exit 0 17:25:18 INFO - TEST-OK | dromaeo_css | took 1113669ms 17:25:18 INFO - TEST-START | kraken 17:25:18 INFO - operating with platform_type : linux_ 17:25:18 INFO - Initialising browser for kraken test... 17:25:18 INFO - TEST-INFO | started process 27199 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLNBnq7/profile http://localhost:57813/getInfo.html) 17:25:22 INFO - PROCESS | 27199 | __metrics Screen width/height:1600/1200 17:25:22 INFO - PROCESS | 27199 | colorDepth:24 17:25:22 INFO - PROCESS | 27199 | Browser inner width/height: 1024/697 17:25:22 INFO - PROCESS | 27199 | __metrics 17:25:23 INFO - PROCESS | 27199 | 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" 17:25:23 INFO - PROCESS | 27199 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:25:24 INFO - TEST-INFO | 27199: exit 0 17:25:24 INFO - Browser initialized. 17:25:24 INFO - Running cycle 1/1 for kraken test... 17:25:24 INFO - TEST-INFO | started process 27285 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLNBnq7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:25:35 INFO - PROCESS | 27285 | RSS: Main: 145199104 17:25:35 INFO - PROCESS | 27285 | 17:25:38 INFO - PROCESS | 27285 | Cycle 1(1): loaded http://localhost:57813/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57813/tests/kraken/driver.html?testName=audio-beat-detection) 17:25:39 INFO - PROCESS | 27285 | RSS: Main: 190812160 17:25:39 INFO - PROCESS | 27285 | 17:25:41 INFO - PROCESS | 27285 | Cycle 1(1): loaded http://localhost:57813/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57813/tests/kraken/driver.html?testName=audio-dft) 17:25:42 INFO - PROCESS | 27285 | RSS: Main: 181542912 17:25:42 INFO - PROCESS | 27285 | 17:25:45 INFO - PROCESS | 27285 | Cycle 1(1): loaded http://localhost:57813/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57813/tests/kraken/driver.html?testName=audio-fft) 17:25:45 INFO - PROCESS | 27285 | RSS: Main: 269647872 17:25:45 INFO - PROCESS | 27285 | 17:25:47 INFO - PROCESS | 27285 | Cycle 1(1): loaded http://localhost:57813/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57813/tests/kraken/driver.html?testName=audio-oscillator) 17:25:48 INFO - PROCESS | 27285 | RSS: Main: 175050752 17:25:48 INFO - PROCESS | 27285 | 17:25:50 INFO - PROCESS | 27285 | Cycle 1(1): loaded http://localhost:57813/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57813/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:25:51 INFO - PROCESS | 27285 | RSS: Main: 176689152 17:25:51 INFO - PROCESS | 27285 | 17:25:54 INFO - PROCESS | 27285 | Cycle 1(1): loaded http://localhost:57813/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57813/tests/kraken/driver.html?testName=imaging-darkroom) 17:25:54 INFO - PROCESS | 27285 | RSS: Main: 212570112 17:25:54 INFO - PROCESS | 27285 | 17:25:58 INFO - PROCESS | 27285 | Cycle 1(1): loaded http://localhost:57813/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57813/tests/kraken/driver.html?testName=imaging-desaturate) 17:25:58 INFO - PROCESS | 27285 | RSS: Main: 211206144 17:25:58 INFO - PROCESS | 27285 | 17:26:01 INFO - PROCESS | 27285 | Cycle 1(1): loaded http://localhost:57813/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57813/tests/kraken/driver.html?testName=json-parse-financial) 17:26:02 INFO - PROCESS | 27285 | RSS: Main: 211206144 17:26:02 INFO - PROCESS | 27285 | 17:26:03 INFO - PROCESS | 27285 | Cycle 1(1): loaded http://localhost:57813/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57813/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:26:04 INFO - PROCESS | 27285 | RSS: Main: 175513600 17:26:04 INFO - PROCESS | 27285 | 17:26:05 INFO - PROCESS | 27285 | Cycle 1(1): loaded http://localhost:57813/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57813/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:26:06 INFO - PROCESS | 27285 | RSS: Main: 181444608 17:26:06 INFO - PROCESS | 27285 | 17:26:08 INFO - PROCESS | 27285 | Cycle 1(1): loaded http://localhost:57813/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57813/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:26:09 INFO - PROCESS | 27285 | RSS: Main: 185782272 17:26:09 INFO - PROCESS | 27285 | 17:26:11 INFO - PROCESS | 27285 | Cycle 1(1): loaded http://localhost:57813/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57813/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:26:12 INFO - PROCESS | 27285 | RSS: Main: 180600832 17:26:12 INFO - PROCESS | 27285 | 17:26:14 INFO - PROCESS | 27285 | Cycle 1(1): loaded http://localhost:57813/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57813/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:26:15 INFO - PROCESS | 27285 | RSS: Main: 184573952 17:26:15 INFO - PROCESS | 27285 | 17:26:17 INFO - PROCESS | 27285 | Cycle 1(1): loaded http://localhost:57813/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57813/tests/kraken/driver.html?testName=ai-astar) 17:26:17 INFO - PROCESS | 27285 | RSS: Main: 235352064 17:26:17 INFO - PROCESS | 27285 | 17:26:17 INFO - PROCESS | 27285 | __start_tp_report 17:26:17 INFO - PROCESS | 27285 | _x_x_mozilla_page_load 17:26:17 INFO - PROCESS | 27285 | _x_x_mozilla_page_load_details 17:26:17 INFO - PROCESS | 27285 | |i|pagename|runs| 17:26:17 INFO - PROCESS | 27285 | |0;ai-astar;103;112;110;111;111;111;110;111;112;110 17:26:17 INFO - PROCESS | 27285 | |1;audio-beat-detection;150;115;123;184;114;123;146;119;165;120 17:26:17 INFO - PROCESS | 27285 | |2;audio-dft;160;161;160;162;161;161;160;160;159;159 17:26:17 INFO - PROCESS | 27285 | |3;audio-fft;75;76;74;109;76;75;76;76;106;75 17:26:17 INFO - PROCESS | 27285 | |4;audio-oscillator;84;84;131;84;83;84;114;83;84;125 17:26:17 INFO - PROCESS | 27285 | |5;imaging-gaussian-blur;117;117;116;116;117;117;117;117;118;116 17:26:17 INFO - PROCESS | 27285 | |6;imaging-darkroom;146;145;146;146;147;146;146;145;145;145 17:26:17 INFO - PROCESS | 27285 | |7;imaging-desaturate;95;95;95;95;95;95;95;95;95;94 17:26:17 INFO - PROCESS | 27285 | |8;json-parse-financial;52;50;52;50;52;52;53;51;49;50 17:26:17 INFO - PROCESS | 27285 | |9;json-stringify-tinderbox;61;61;60;60;60;60;60;60;60;61 17:26:17 INFO - PROCESS | 27285 | |10;stanford-crypto-aes;73;72;72;73;73;72;71;81;77;81 17:26:17 INFO - PROCESS | 27285 | |11;stanford-crypto-ccm;118;124;119;118;128;118;124;123;157;119 17:26:17 INFO - PROCESS | 27285 | |12;stanford-crypto-pbkdf2;151;150;150;152;153;153;154;153;156;150 17:26:17 INFO - PROCESS | 27285 | |13;stanford-crypto-sha256-iterative;62;62;61;61;62;61;61;61;61;60 17:26:17 INFO - PROCESS | 27285 | __end_tp_report 17:26:17 INFO - PROCESS | 27285 | __start_cc_report 17:26:17 INFO - PROCESS | 27285 | _x_x_mozilla_cycle_collect,1622 17:26:17 INFO - PROCESS | 27285 | __end_cc_report 17:26:17 INFO - PROCESS | 27285 | __startTimestamp1472775977397__endTimestamp 17:26:17 INFO - PROCESS | 27285 | 17:26:17 INFO - PROCESS | 27285 | ------- Summary: start ------- 17:26:17 INFO - PROCESS | 27285 | Number of tests: 14 17:26:17 INFO - PROCESS | 27285 | 17:26:17 INFO - PROCESS | 27285 | [#0] ai-astar Cycles:10 Average:110.10 Median:111.00 stddev:2.60 (2.3%) stddev-sans-first:0.78 17:26:17 INFO - PROCESS | 27285 | Values: 103.0 112.0 110.0 111.0 111.0 111.0 110.0 111.0 112.0 110.0 17:26:17 INFO - PROCESS | 27285 | 17:26:17 INFO - PROCESS | 27285 | [#1] audio-beat-detection Cycles:10 Average:135.90 Median:134.50 stddev:24.15 (18.0%) stddev-sans-first:25.07 17:26:17 INFO - PROCESS | 27285 | Values: 150.0 115.0 123.0 184.0 114.0 123.0 146.0 119.0 165.0 120.0 17:26:17 INFO - PROCESS | 27285 | 17:26:17 INFO - PROCESS | 27285 | [#2] audio-dft Cycles:10 Average:160.30 Median:160.50 stddev:0.95 (0.6%) stddev-sans-first:1.00 17:26:17 INFO - PROCESS | 27285 | Values: 160.0 161.0 160.0 162.0 161.0 161.0 160.0 160.0 159.0 159.0 17:26:17 INFO - PROCESS | 27285 | 17:26:17 INFO - PROCESS | 27285 | [#3] audio-fft Cycles:10 Average:81.80 Median:75.50 stddev:13.58 (18.0%) stddev-sans-first:14.18 17:26:17 INFO - PROCESS | 27285 | Values: 75.0 76.0 74.0 109.0 76.0 75.0 76.0 76.0 106.0 75.0 17:26:17 INFO - PROCESS | 27285 | 17:26:17 INFO - PROCESS | 27285 | [#4] audio-oscillator Cycles:10 Average:95.60 Median:84.00 stddev:19.57 (23.3%) stddev-sans-first:20.30 17:26:17 INFO - PROCESS | 27285 | Values: 84.0 84.0 131.0 84.0 83.0 84.0 114.0 83.0 84.0 125.0 17:26:17 INFO - PROCESS | 27285 | 17:26:17 INFO - PROCESS | 27285 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.63 (0.5%) stddev-sans-first:0.67 17:26:17 INFO - PROCESS | 27285 | Values: 117.0 117.0 116.0 116.0 117.0 117.0 117.0 117.0 118.0 116.0 17:26:17 INFO - PROCESS | 27285 | 17:26:17 INFO - PROCESS | 27285 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 17:26:17 INFO - PROCESS | 27285 | Values: 146.0 145.0 146.0 146.0 147.0 146.0 146.0 145.0 145.0 145.0 17:26:17 INFO - PROCESS | 27285 | 17:26:17 INFO - PROCESS | 27285 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 17:26:17 INFO - PROCESS | 27285 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 94.0 17:26:17 INFO - PROCESS | 27285 | 17:26:17 INFO - PROCESS | 27285 | [#8] json-parse-financial Cycles:10 Average:51.10 Median:52.00 stddev:1.29 (2.5%) stddev-sans-first:1.32 17:26:17 INFO - PROCESS | 27285 | Values: 52.0 50.0 52.0 50.0 52.0 52.0 53.0 51.0 49.0 50.0 17:26:17 INFO - PROCESS | 27285 | 17:26:17 INFO - PROCESS | 27285 | [#9] json-stringify-tinderbox Cycles:10 Average:60.30 Median:60.00 stddev:0.48 (0.8%) stddev-sans-first:0.44 17:26:17 INFO - PROCESS | 27285 | Values: 61.0 61.0 60.0 60.0 60.0 60.0 60.0 60.0 60.0 61.0 17:26:17 INFO - PROCESS | 27285 | 17:26:17 INFO - PROCESS | 27285 | [#10] stanford-crypto-aes Cycles:10 Average:74.50 Median:73.00 stddev:3.78 (5.2%) stddev-sans-first:3.97 17:26:17 INFO - PROCESS | 27285 | Values: 73.0 72.0 72.0 73.0 73.0 72.0 71.0 81.0 77.0 81.0 17:26:17 INFO - PROCESS | 27285 | 17:26:17 INFO - PROCESS | 27285 | [#11] stanford-crypto-ccm Cycles:10 Average:124.80 Median:123.50 stddev:11.82 (9.6%) stddev-sans-first:12.28 17:26:17 INFO - PROCESS | 27285 | Values: 118.0 124.0 119.0 118.0 128.0 118.0 124.0 123.0 157.0 119.0 17:26:17 INFO - PROCESS | 27285 | 17:26:17 INFO - PROCESS | 27285 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.20 Median:153.00 stddev:1.99 (1.3%) stddev-sans-first:2.06 17:26:17 INFO - PROCESS | 27285 | Values: 151.0 150.0 150.0 152.0 153.0 153.0 154.0 153.0 156.0 150.0 17:26:17 INFO - PROCESS | 27285 | 17:26:17 INFO - PROCESS | 27285 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.20 Median:61.00 stddev:0.63 (1.0%) stddev-sans-first:0.60 17:26:17 INFO - PROCESS | 27285 | Values: 62.0 62.0 61.0 61.0 62.0 61.0 61.0 61.0 61.0 60.0 17:26:17 INFO - PROCESS | 27285 | -------- Summary: end -------- 17:26:17 INFO - PROCESS | 27285 | 17:26:18 INFO - TEST-INFO | 27285: exit 0 17:26:18 INFO - TEST-OK | kraken | took 60020ms 17:26:18 INFO - SUITE-END | took 1173s 17:26:18 INFO - Completed test suite (00:19:33) 17:26:18 INFO - javascript benchmark 17:26:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3232.983835080824, 3266.0365192268277, 3266.2161081340478, 3293.045016945086, 3313.7349012079035, 5621.297337832771, 5742.3109226892775, 5746.7413966371505, 5749.453801888824, 5785.3346865110525, 5592.189132575774, 5717.256756621644, 5718.113692377678, 5724.570657200717, 5728.828135155952, 4884.6824956377895, 4987.650864439491, 5142.922856157152, 5168.948310516903, 5233.633645644806, 5035.723035233061, 5041.0, 5073.695578265316, 5091.46539613339, 5144.4598317176715, 5211.4267430582795, 5244.816431424901, 5251.711155886424, 5254.290670759437, 5279.86800329991, 5233.685978841263, 5238.345206849144, 5245.396779370383, 5246.186841039645, 5274.498922602346, 4922.80308787648, 4998.3502144721315, 5047.924281135786, 5144.356955380577, 5158.3552055993005, 5561.304836895388, 5587.357453892813, 5609.551235901118, 5625.6905870177125, 5709.314882214119, 6104.847378815521, 6114.694265286718, 6145.477634401082, 6155.784547540859, 6163.2604087509335, 5748.511376533, 5957.344692083867, 5978.790742323998, 6195.380461953813, 6217.191765070557, 5624.3813180550105, 5636.0982242841355, 5664.773409063674, 5664.830055098354, 5670.914936275959, 6208.255009398811, 6216.191895053614, 6217.316095229521, 6220.191375121205, 6223.159873417121, 5644.153376993402, 5864.70676466175, 5903.616264942851, 5909.409059094056, 6047.425494578008, 6065.835411471322, 6083.7566497340495, 6099.690984817992, 6116.021436570217, 6123.0, 6036.879262414727, 6049.51603871698, 6050.092486127116, 6051.576389652682, 6053.152558641794, 5728.112142617901, 5971.910421343683, 6050.031994880886, 6054.939450605526, 6143.96928015366, 4656.301554766812, 4665.113628410591, 4665.2302370108555, 4689.249720044844, 4696.201645720235, 4147.294959856831, 4165.708400411942, 4172.144710334374, 4174.83300667976, 4194.643455306333, 5523.6685798852195, 5529.0, 5769.451902069297, 5818.970905145447, 5844.649321040752, 6150.323464418911, 6183.783567575203, 6198.163247961467, 6210.875782484415, 6211.844703882439, 5885.823425297162, 5901.822945311647, 5902.852428689317, 5912.054071348563, 5922.378150294243, 5013.022460620217, 5169.870753231199, 5219.217117432416, 5329.067413202674, 5343.412224655285, 6254.280757712831, 6257.061440783918, 6260.123582698426, 6272.184615999982, 6279.0895320178315, 6078.574499784972, 6086.847828804138, 6089.482393996383, 6095.085737139465, 6104.69476526163, 5928.644281343133, 6122.97760420812, 6164.7842325518395, 6214.751409943732, 6316.305206427382, 5912.5861189718025, 5916.73374698131, 5927.288725352985, 5937.435943585352, 5952.910706339495, 6059.363766804422, 6070.0591408331775, 6075.27096748393, 6075.27096748393, 6077.6353418795015, 5676.035074037341, 5882.382349853204, 5930.911036334544, 5948.434898684618, 5956.672383018976, 5990.820275391745, 5991.820245392645, 5999.790007349722, 6011.458968692664, 6031.276246850406, 5926.051831706906, 5936.317323507679, 5936.851578710567, 5937.7921772737745, 5939.703014849154, 5656.943430565641, 5874.383189765013, 5945.227120474484, 5952.73212705438, 6064.3935606439, 5772.365039845529, 5828.242328497268, 5832.708364581838, 5869.706514674334, 5884.705764711662], "value": 5592.55859506124, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [609.4240942309516, 621.4313902778956, 649.8830210562098, 663.3466035954586, 673.0039541478615, 45901.31148032778, 47839.521604783986, 47953.52046479534, 47968.999999999956, 51203.23195152066, 49777.0, 49912.50087499124, 50121.749391253084, 50355.49644503554, 50395.0, 8711.651533938635, 9425.76435589109, 9589.089036541553, 9590.856137157949, 11210.66368008957, 11121.666350009513, 11618.0, 11621.244619099774, 11622.0, 11640.767184656303, 11673.824892626575, 11724.355160466213, 11726.530938762438, 11727.0, 11732.0, 11549.422528873607, 11607.0, 11645.53417863293, 11650.184487085908, 11663.533458661686, 11345.205835591512, 11402.087832973342, 11411.60059397917, 11412.885871141265, 11423.885761142448, 46281.0, 46692.0, 46796.76601617004, 46889.5311046892, 47023.29465058027, 3534.5228394166716, 3671.963280367189, 3789.3368660484307, 3911.374180131194, 4136.152088821785, 35589.0, 35621.10947226314, 35666.0, 35948.46077308842, 35992.280154396765, 4250.447441832542, 4375.7155784873885, 4407.735535867826, 4411.382406463098, 4456.041950980555, 385957.0, 389836.0, 393110.0, 556800.0, 557818.0, 67328.66335668389, 67913.0, 68023.31976680268, 68143.65928170328, 68221.65889170523, 37742.62257377446, 39821.0, 39892.60107398947, 41957.370639440436, 72507.27492725111, 19512.512187195432, 19666.115024823917, 19668.606627867648, 19673.508162296057, 19697.21211151538, 13613.251271179983, 14643.56069317922, 15028.474003409829, 15037.548873533593, 15048.548543543711, 21231.893840530698, 21285.78714212838, 21326.14695412197, 21504.13983440676, 21511.56976860454, 7436.430513353513, 7452.143003554552, 7503.737369192052, 7542.208068152765, 7613.124490683532, 1034.1416624202045, 1071.994640026795, 1098.6484325016079, 1142.8914253146104, 1247.650657815813, 34837.303253935286, 35042.824785876415, 35183.0, 35335.64664353323, 35362.29275414477, 1182.9822552661717, 1209.0448545649015, 1233.8704436034086, 1235.4934476864441, 1266.4047897488165, 18172.091395430176, 18216.08919554017, 18314.725279120823, 18351.90824045898, 18596.81403185978, 613215.0, 615250.0, 617492.0, 618222.0, 618301.0, 64697.67651161808, 65008.34991650023, 65062.34937650657, 65095.67452162709, 65128.0, 1372.9176249424868, 1428.4143501164472, 1483.5252719129776, 1508.3589474473306, 1535.3167840310955, 34248.82875585606, 36007.63992360148, 36022.639773602976, 36072.0, 36076.27847443089, 53471.0, 54284.0, 54358.0, 54358.72820635871, 54448.72775636097, 431097.0, 434884.0, 435080.0, 435867.0, 436357.0, 29168.124956251348, 30212.09363719092, 30288.394232114795, 30305.24236894095, 30355.02692324992, 50432.74783626058, 50487.49512504828, 51378.74310628423, 51436.74281628717, 52227.0, 43081.784591078096, 43162.1367572653, 43491.56508434875, 43937.34093988529, 44990.325145123505, 43243.35134972915, 43514.34728479139, 43903.560964391225, 44473.332900007175, 45914.0, 43350.34974475322, 45143.0, 45398.0, 45652.086958259984, 46520.302195466415, 37737.245255095295, 40090.599094008685, 40148.0, 40431.39352909767, 40588.391174133, 29757.5536366959, 29770.249706887866, 29858.073417885804, 30208.395832083675, 30460.84769576138], "value": 22311.465815447216, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [400.6374231320656, 402.73822015689797, 415.46820070309997, 419.57413225576045, 426.93169092945135, 15687.921560392197, 19868.999999999964, 20060.799392006076, 20263.39209823706, 22172.334829955085, 18684.62630747384, 19032.429027129172, 19167.80832191678, 20609.587808243825, 20852.37442876712, 5275.314209152804, 5591.245181900432, 5977.282726072855, 6008.699565021753, 6015.278166620012, 3996.3206254936586, 4243.554426785189, 5971.0, 6051.304100028509, 6060.878782424349, 5895.616784908989, 6113.32753397126, 6145.2625684917875, 6237.438630523252, 6247.156633854417, 6120.8469788255425, 6157.3534778848025, 6161.599496032743, 6200.751969921198, 6231.127642130083, 6020.518358531307, 6029.155918171459, 6060.545459090542, 6063.120847477136, 6064.36324185963, 14814.777778333333, 14926.029808062549, 15062.096274223582, 15143.545693629208, 15293.61765955849, 2298.2645553422913, 2439.146298795425, 2466.9506609867703, 2478.281298423455, 2571.2800415883585, 10286.639967601099, 10724.195685323632, 10992.17558683094, 12666.683332916658, 14532.91003174766, 2075.9065842037144, 2094.130935661714, 2141.4646338415396, 2154.493693981913, 2164.1235299703644, 85054.0, 85647.57176214141, 86120.0, 86624.13375866224, 87973.99999999936, 11701.590444334408, 11742.471588778526, 11896.21556766492, 11973.281603103842, 11985.22096063753, 16613.0, 16664.16679166037, 16690.0, 16691.666166676598, 16694.4156954506, 8976.37165398429, 9220.53897305132, 9257.768555786159, 9300.348975571645, 9334.113259240361, 9059.728672032617, 9078.773030674285, 9121.908780912107, 9295.209907157832, 9350.719478415658, 5037.823676171316, 5097.592192624656, 5110.795568177231, 5158.92261616076, 5198.2202669599865, 2054.938351849447, 2093.194120263679, 2100.4328831215485, 2140.7324084489437, 2170.1319472211244, 980.3725615606018, 1027.2295778166376, 1059.0204061243319, 1103.0, 1119.6137332620233, 19971.001449927444, 20114.39656810298, 20667.79332206689, 20754.273600423912, 21034.78965210328, 1018.5874720738113, 1070.29895418501, 1092.4027900423716, 1096.8683757948938, 1126.8478755368085, 11351.772964540663, 11844.644660660022, 11903.761924761457, 11960.521579136737, 11993.820092698617, 517635.0, 522445.0, 524452.0, 531966.0, 533145.0, 93164.0, 99386.5030674842, 99982.50008750055, 100553.49723251483, 102092.0, 1033.5865653738415, 1048.2766890845292, 1066.2855886556026, 1068.3376306689747, 1074.1836204484491, 17901.641967160846, 18191.45425637233, 18648.533786655444, 18672.346467873558, 18830.24679012824, 43274.78362608167, 45073.32390014218, 45496.77251613721, 45735.0, 46389.76805115953, 60543.39456605377, 61892.38107619046, 67208.0, 68619.65690171518, 70193.0, 8011.43919925623, 8573.785655358666, 9007.639694412412, 9174.311926605398, 9192.54037298119, 10674.62638807638, 13914.443422262995, 14315.642108946942, 14404.351804169048, 14781.408743650563, 12360.25838449696, 12558.309292988974, 12582.685432864251, 12612.243265404106, 12650.30423326689, 12243.938780306042, 12340.382980851102, 12512.812307815571, 12775.0, 12852.807207892076, 12091.214071085351, 12301.692457688629, 12306.942607381196, 12624.558140465408, 12875.0, 8098.19018098209, 8287.709930152416, 8319.417640765088, 8677.132286771272, 8878.600462979311, 9481.146696797321, 12280.0, 12394.132410731163, 12560.874391255971, 12943.741125177632], "value": 9687.94391507948, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1362.489066600025, 1502.0612117426613, 1547.7136729705003, 1717.7938647362319, 1831.679456095183, 8597.097304783001, 8919.554022298884, 8925.50909699967, 9209.309301802376, 9217.262618990482, 9038.0, 9153.0, 9156.771080722985, 9159.267258619311, 9163.908360916388, 2502.5370306493355, 2512.52262070207, 2527.0902475108946, 2535.353484861367, 2538.5684433646234, 2421.9757802421927, 2485.5028994201143, 2486.428121532049, 2486.7015958085053, 2494.825362224645, 2418.141559746291, 2445.926622201328, 2456.4227406559444, 2458.791002764767, 2459.225344016629, 2357.116081469449, 2361.681173041643, 2381.535600557892, 2444.9755502444928, 2447.4248551590363, 2355.1874603262017, 2362.810975122003, 2365.775251351119, 2365.8343915925893, 2402.3513651314047, 9047.050059743764, 9117.179453849185, 9170.128837760401, 9170.541472926327, 9268.21220196284, 5467.17992301154, 5679.119736440858, 5690.032694441955, 5690.260266165372, 5690.9146362804595, 3482.9651703482896, 3534.9469757953652, 3560.1989552350774, 3569.6430356964356, 3588.4796704477703, 1115.3865374044244, 1155.96532104037, 1156.583629893246, 1157.8842115788439, 1180.4274926660562, 13287.468501259938, 13360.532381366605, 13415.46338146473, 13418.261995590336, 13432.664183395396, 4769.379980602499, 4789.856304310876, 4852.684575502581, 4906.779194936236, 4936.037472692862, 10582.20633452494, 10593.258471906893, 10599.523021464052, 10605.522751476048, 10615.893841061645, 5595.972020139955, 5603.187537807076, 5636.718164091779, 5638.0, 5638.6052976292085, 4501.954980450219, 4677.087967846237, 5106.617003724736, 5184.662996905265, 5278.736063196825, 4057.208844275338, 4084.2035803018125, 4185.832566697298, 4188.392683060939, 4194.538600753915, 1216.4951545108859, 1230.698478872682, 1232.7657745028416, 1237.4369661803858, 1246.052999720219, 1032.8037672842136, 1069.5026812532055, 1073.3828048871774, 1078.5038882114159, 1083.2633809009792, 6509.544331896816, 6535.973848107102, 6642.269350371455, 6735.393814556615, 6846.178458585002, 480.5866954419197, 481.066730542746, 486.2948724349707, 489.08785115759565, 504.71483611759237, 4508.842190523316, 4624.445066591963, 4646.395968524071, 4684.508126646721, 4734.502877197845, 11558.075353971662, 11889.524419023142, 11957.103217258566, 11957.760844783055, 12005.519779208735, 11574.710632234093, 11594.536218551164, 11784.941075294568, 11807.586734464252, 11867.109966752529, 8881.822363552692, 8911.0, 8959.686410975584, 8960.1935825776, 8961.0, 6500.317466666121, 6502.934970650233, 6604.735810567524, 6622.602643841385, 6705.966470167618, 10841.07850832688, 10926.453677315943, 10946.452677366258, 11024.779504410028, 11155.051820595016, 10719.035286824223, 10855.67432977012, 10998.450077495932, 11037.83443248368, 11130.721731956442, 8052.87920681202, 8143.877841832259, 8165.959170204349, 8285.668573257002, 8348.415610907177, 9447.527623618655, 9501.144896959308, 9568.186704130227, 9578.425294482353, 9579.473128977974, 8292.792680183044, 8369.455985360932, 8443.704470343508, 8444.408891377545, 8467.83064338722, 8189.918100818915, 8230.259276665129, 8231.34149268045, 8296.502209867427, 8311.542865142475, 7676.193999629959, 7766.766996990099, 7870.606469676608, 7875.0, 7881.881771773543, 5472.589555783412, 5702.857428564319, 5749.597528172988, 5893.116032595145, 5961.910571341346, 5850.327212370632, 5869.325027621878, 5940.495057919952, 5986.9401305986385, 6008.759649613967], "value": 4864.045764661317, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.2833819009221, 352.8782570013346, 358.8349359294724, 360.12848905648326, 361.39466393790394, 5483.1501117326825, 5521.585881058922, 5523.585731070166, 5533.6956467394275, 5550.58370622204, 5439.428859969706, 5456.236126942231, 5464.316960379952, 5478.808241711541, 5483.479069488402, 3831.712621553381, 4070.694697897655, 4262.0, 4297.312430011199, 4305.547917468668, 4283.121959998193, 4301.139772045603, 4313.352997050436, 4335.869923902288, 4372.5627437256335, 4190.161967606476, 4318.805653745589, 4344.0, 4345.760983145941, 4346.065595896882, 4166.500019997605, 4246.853349595623, 4248.830046798124, 4260.25445547028, 4262.0836520148005, 3766.8304926278383, 3770.132869440044, 3787.886363409102, 3807.096451774113, 3808.9809550952345, 5162.638615296931, 5305.4614161893005, 5307.495787900143, 5345.705986170759, 5431.34823821144, 4945.629077819142, 4983.825566105205, 4988.5260900214425, 4995.825146119904, 5009.824656137054, 5656.802011929604, 5708.942910570882, 5709.400512946122, 5724.141378793172, 5724.942750572524, 4086.6730661547003, 4277.786110694453, 4279.957200427987, 4280.101178752466, 4288.44250247469, 5652.886942261173, 5720.914186287209, 5913.142594323842, 5918.171455996164, 5931.199288096138, 4334.566543345641, 4344.0, 4349.499807522112, 4402.647788176938, 4407.140607581491, 4422.545487254604, 4425.22558552252, 4438.911221775546, 4452.621527170162, 4455.376247325377, 4879.243717223836, 4941.604671626261, 4953.405591329064, 4994.751312171957, 5014.893744627192, 5720.94046143388, 5726.363759013057, 5747.0, 5753.194552762617, 5814.563907706939, 1510.886683498742, 1526.366557878468, 1527.9847201527843, 1537.3312609015113, 1539.5920081178497, 960.4237457525429, 981.3964411886639, 986.7039888033561, 1000.2698030437821, 1021.7547788530699, 4904.190808516626, 4913.87715307113, 4914.926276105862, 4990.6506544541535, 4998.525140111684, 5183.393148124093, 5201.271821944931, 5223.373195216523, 5225.216217567396, 5235.240890070919, 4994.500549944976, 5006.299118123466, 5048.485454363677, 5208.255234496486, 5678.176664383641, 3118.6881311869083, 3132.185631735765, 3154.6529881712995, 3156.1320636825058, 3164.351307981858, 6099.756009759649, 6108.4807791338235, 6130.203073600493, 6135.754569817246, 6144.586745048575, 5791.507721843691, 6018.789342372996, 6048.4274088736365, 6071.0, 6076.817695469143, 6178.7219575119225, 6183.598066125687, 6187.535934804908, 6198.783042593487, 6200.255969283715, 5640.294963129609, 5926.881462370815, 5997.220361352996, 6005.489533389711, 6007.969960150172, 5877.265341832271, 5879.911801322898, 5885.764569417175, 5895.852603684943, 5917.201177840936, 5830.82507524775, 5953.226080609493, 6135.877282454237, 6148.354422785543, 6174.394657389022, 4285.785710714389, 4289.163613095417, 4313.892152696208, 4329.567043295645, 4330.52364239927, 5830.154627578892, 6022.7289771959495, 6031.276246850406, 6047.123167140828, 6064.818055458343, 5791.536677065909, 5798.855028624318, 5810.4189581041555, 5810.796622118206, 5811.302643682785, 5753.137029445449, 5784.8430313938225, 5790.494471437372, 5791.362950075404, 5803.7388317524965, 5454.963556924172, 5681.147827825859, 5703.258576385043, 5717.485426311486, 5757.424257574209, 5822.883542329045, 5840.269966254218, 5865.296164460292, 5869.239228231428, 5889.146073819358, 5531.640443371168, 5707.771689132388, 5732.254806875079, 5743.770249189985, 5746.620811005245], "value": 4393.506014687687, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [828.2752591482453, 864.3560547392194, 890.6214858685059, 893.7140115163143, 926.0271250434696, 16616.750748738777, 16778.83221167788, 16882.324707011703, 16922.576935576617, 16933.745993810102, 16237.350505979775, 16396.426125085625, 16447.917760411212, 16450.506484805475, 16475.83524164761, 725.611797688237, 737.9188289288174, 851.5188918261193, 857.8713193021034, 872.2019352292644, 873.2882700599023, 888.4757992784256, 888.8533391990314, 888.9022207557196, 889.1819526036062, 934.9205317548017, 936.8547875079373, 937.4281688170245, 937.9015203403646, 938.3619138985487, 768.8731359325704, 900.1763386501343, 915.4461550761827, 931.6692574136151, 932.822763674903, 691.550492180087, 697.3340459860821, 697.6023666510091, 697.6302559643347, 698.1377998172253, 15422.537323880302, 15507.92246038774, 15576.065436073872, 15596.922015389964, 15622.687546249012, 14969.251537423193, 15438.614034649112, 15571.065736055873, 15713.0, 15954.680906381924, 14234.277245645873, 14262.57212283633, 14335.139891606537, 14397.928010359881, 14550.19973901435, 4625.930611040837, 4744.0, 4744.5967092797155, 4750.904981900378, 4782.904341913177, 90456.99999999935, 92130.0, 92398.5380073102, 92574.5371273146, 93005.06994930032, 12344.876551234373, 12415.93792031034, 12440.440180191914, 12477.0, 12595.685107872376, 23609.29172124839, 23643.88178059133, 23765.0, 23783.52432951366, 24588.877055614965, 8958.731238062868, 8980.506072165963, 8998.370114092082, 9035.864462033074, 9317.72046838596, 4577.8397756078375, 4745.715257084586, 4946.936408672081, 4989.501049894981, 5202.115640341151, 3754.718396120302, 3910.6284902934176, 3911.0026943129888, 3913.3151698453225, 3914.0410599402758, 641.3298103481873, 641.6054126712132, 645.3610925184034, 652.5660435810228, 659.9210291173965, 581.2966310764023, 586.3962063755625, 662.8011596521025, 719.7157122936411, 740.714824792448, 9967.044529590456, 10306.690799276033, 10758.46207689628, 10942.890571094347, 10958.835617465744, 707.3245050976305, 711.4237467651219, 715.849671568966, 723.5260904107818, 740.5852722475431, 4770.332153498513, 4902.0, 4925.975370123127, 4940.2342636891335, 4956.083124621957, 71596.28403716, 128896.0, 131082.68917310896, 139058.0, 143302.0, 93483.0, 95585.5220723892, 112923.0, 116189.4190529042, 116200.41899790616, 63616.36383636197, 80549.0, 104870.4756476228, 105786.0, 112382.43808781067, 10812.945935270272, 11347.602833900773, 11410.714732131431, 11577.189596728485, 11714.648560543197, 57993.420065798804, 59586.0, 60203.698981504815, 60714.39285607264, 63370.68314658397, 84272.0, 89805.55097224472, 90084.0, 91910.16179676235, 94043.52978235066, 15586.298616562508, 15700.764488532453, 15969.281382337591, 15998.12010339396, 16030.0, 16757.32970681159, 17065.744013839554, 17350.91324543369, 17418.65066509865, 17501.649967000332, 15924.363025478851, 15985.0, 16101.194940252706, 16829.24268407949, 17006.48980530586, 15180.392784288504, 15395.538133856002, 15713.607159821097, 16191.0, 16365.34538618442, 14478.229963563186, 14484.275786210857, 15127.773083403537, 15804.60488487797, 16177.110258935401, 13071.803922940973, 13075.41160647754, 13179.67050823737, 13404.501836979265, 14331.570052898429, 11765.294082355085, 12881.14745967804, 13180.406881690154, 13452.39464224132, 13694.52069177574], "value": 8387.109238304818, "unit": "score"}], "name": "dromaeo_css", "value": 7750.079871475993, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 112.0, 110.0, 111.0, 111.0, 111.0, 110.0, 111.0, 112.0, 110.0], "value": 110.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 115.0, 123.0, 184.0, 114.0, 123.0, 146.0, 119.0, 165.0, 120.0], "value": 135.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 160.0, 162.0, 161.0, 161.0, 160.0, 160.0, 159.0, 159.0], "value": 160.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 74.0, 109.0, 76.0, 75.0, 76.0, 76.0, 106.0, 75.0], "value": 81.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 131.0, 84.0, 83.0, 84.0, 114.0, 83.0, 84.0, 125.0], "value": 95.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 116.0, 117.0, 117.0, 117.0, 117.0, 118.0, 116.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 146.0, 146.0, 147.0, 146.0, 146.0, 145.0, 145.0, 145.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 50.0, 52.0, 50.0, 52.0, 52.0, 53.0, 51.0, 49.0, 50.0], "value": 51.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 60.0, 60.0, 60.0, 60.0, 60.0, 60.0, 60.0, 61.0], "value": 60.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 72.0, 72.0, 73.0, 73.0, 72.0, 71.0, 81.0, 77.0, 81.0], "value": 74.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 124.0, 119.0, 118.0, 128.0, 118.0, 124.0, 123.0, 157.0, 119.0], "value": 124.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 150.0, 150.0, 152.0, 153.0, 153.0, 154.0, 153.0, 156.0, 150.0], "value": 152.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 61.0, 61.0, 62.0, 61.0, 61.0, 61.0, 61.0, 60.0], "value": 61.2, "unit": "score"}], "name": "kraken", "value": 1465.2, "alertThreshold": 2.0}]} 17:26:18 INFO - Return code: 0 17:26:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:26:18 INFO - # TBPL SUCCESS # 17:26:18 INFO - Running post-action listener: _resource_record_post_action 17:26:18 INFO - [mozharness: 2016-09-02 00:26:18.704614Z] Finished run-tests step (success) 17:26:18 INFO - Running post-run listener: _resource_record_post_run 17:26:18 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 4096; Write bytes: 328437760; Read time: 8; Write time: 309284 17:26:18 INFO - TinderboxPrint: CPU usage
12.9% 17:26:18 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 17:26:18 INFO - TinderboxPrint: I/O write bytes / time
328,437,760 / 309,284 17:26:18 INFO - TinderboxPrint: CPU idle
8,276.1 (87.1%) 17:26:18 INFO - TinderboxPrint: CPU user
1,182.8 (12.4%) 17:26:18 INFO - TinderboxPrint: Swap in / out
0 / 0 17:26:18 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 78684160; Read time: 0; Write time: 117248 17:26:18 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 248627200; Read time: 0; Write time: 191848 17:26:19 INFO - Running post-run listener: _upload_blobber_files 17:26:19 INFO - Blob upload gear active. 17:26:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:26:19 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 17:26:19 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'] 17:26:19 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 17:26:19 INFO - (blobuploader) - INFO - Open directory for files ... 17:26:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:26:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:26:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:26:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:26:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:26:20 INFO - (blobuploader) - INFO - Done attempting. 17:26:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:26:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:26:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:26:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:26:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:26:21 INFO - (blobuploader) - INFO - Done attempting. 17:26:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:26:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:26:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:26:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:26:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:26:24 INFO - (blobuploader) - INFO - Done attempting. 17:26:24 INFO - (blobuploader) - INFO - Iteration through files over. 17:26:24 INFO - Return code: 0 17:26:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:26:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:26:24 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c682d7f9dd66ca98fe6de5ede8ba1cb3054c4acda3189b922cb1f5371571032563393cb81bf844a80af40b1713c54b45af82893bbce883fff835bdc2ee424588", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7399c8670ef8306a5cfec72d59b4cc0b4bf6649c61a0ebd1f68fd38f487c9eed801376e822f01197a168019d881a5c02908e23f166fb474092e3367a8a312053", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0608ab5d9a0662d90087e14feba5464965b8a440f3e5b6c8b2d42cf10a2f6fbdeaa183f6356fb3fd67b78ec15eae4b49901ea0b3c2ac0cd3931bec697599f5c6"} 17:26:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:26:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:26:24 INFO - Contents: 17:26:24 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c682d7f9dd66ca98fe6de5ede8ba1cb3054c4acda3189b922cb1f5371571032563393cb81bf844a80af40b1713c54b45af82893bbce883fff835bdc2ee424588", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7399c8670ef8306a5cfec72d59b4cc0b4bf6649c61a0ebd1f68fd38f487c9eed801376e822f01197a168019d881a5c02908e23f166fb474092e3367a8a312053", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0608ab5d9a0662d90087e14feba5464965b8a440f3e5b6c8b2d42cf10a2f6fbdeaa183f6356fb3fd67b78ec15eae4b49901ea0b3c2ac0cd3931bec697599f5c6"} 17:26:24 INFO - Running post-run listener: copy_logs_to_upload_dir 17:26:24 INFO - Copying logs to upload dir... 17:26:24 INFO - mkdir: /builds/slave/test/build/upload/logs 17:26:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1677.678874 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 57 secs) (at 2016-09-01 17:26:23.988108) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 17:26:23.993707) ========= 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=db7e7c15f3ca076d53731445000001ba-1472772891.821423-592927209 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/c682d7f9dd66ca98fe6de5ede8ba1cb3054c4acda3189b922cb1f5371571032563393cb81bf844a80af40b1713c54b45af82893bbce883fff835bdc2ee424588", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7399c8670ef8306a5cfec72d59b4cc0b4bf6649c61a0ebd1f68fd38f487c9eed801376e822f01197a168019d881a5c02908e23f166fb474092e3367a8a312053", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0608ab5d9a0662d90087e14feba5464965b8a440f3e5b6c8b2d42cf10a2f6fbdeaa183f6356fb3fd67b78ec15eae4b49901ea0b3c2ac0cd3931bec697599f5c6"} build_url:https://queue.taskcluster.net/v1/task/a9CGJg-nRTCLA3AiqIN4YA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011392 build_url: 'https://queue.taskcluster.net/v1/task/a9CGJg-nRTCLA3AiqIN4YA/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/c682d7f9dd66ca98fe6de5ede8ba1cb3054c4acda3189b922cb1f5371571032563393cb81bf844a80af40b1713c54b45af82893bbce883fff835bdc2ee424588", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7399c8670ef8306a5cfec72d59b4cc0b4bf6649c61a0ebd1f68fd38f487c9eed801376e822f01197a168019d881a5c02908e23f166fb474092e3367a8a312053", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0608ab5d9a0662d90087e14feba5464965b8a440f3e5b6c8b2d42cf10a2f6fbdeaa183f6356fb3fd67b78ec15eae4b49901ea0b3c2ac0cd3931bec697599f5c6"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 17:26:24.019282) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 17:26:24.019588) ========= 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=db7e7c15f3ca076d53731445000001ba-1472772891.821423-592927209 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004397 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 17:26:24.103870) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 17:26:24.104216) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 17:26:24.104541) ========= ========= Total master_lag: 0.16 =========