builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-018 starttime: 1472104284.09 results: success (0) buildid: 20160824195426 builduid: 6962f3fbcc3c463f98dcc47eb9154d6c revision: d560a3e332dd38246c2b624a1c1d36c0a4a8d196 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-24 22:51:24.091038) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-24 22:51:24.091462) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-24 22:51:24.091747) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-24 22:51:24.106036) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-24 22:51:24.106358) ========= 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=62c568580acbfdaac3eec7be000001dc-1472104282.255875-1638833407 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-24 22:51:24-- 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% 68.7M=0s 2016-08-24 22:51:24 (68.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.108832 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-24 22:51:24.228960) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-24 22:51:24.229284) ========= 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=62c568580acbfdaac3eec7be000001dc-1472104282.255875-1638833407 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018743 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-24 22:51:24.278592) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-24 22:51:24.278956) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev d560a3e332dd38246c2b624a1c1d36c0a4a8d196 --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 d560a3e332dd38246c2b624a1c1d36c0a4a8d196 --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=62c568580acbfdaac3eec7be000001dc-1472104282.255875-1638833407 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-24 22:51:24,328 truncating revision to first 12 chars 2016-08-24 22:51:24,329 Setting DEBUG logging. 2016-08-24 22:51:24,329 attempt 1/10 2016-08-24 22:51:24,329 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/d560a3e332dd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-24 22:51:25,564 unpacking tar archive at: mozilla-aurora-d560a3e332dd/testing/mozharness/ program finished with exit code 0 elapsedTime=1.576314 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-24 22:51:25.868997) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-24 22:51:25.869304) ========= script_repo_revision: d560a3e332dd38246c2b624a1c1d36c0a4a8d196 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-24 22:51:25.869751) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-24 22:51:25.870017) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-24 22:51:25.878481) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 57 secs) (at 2016-08-24 22:51:25.878872) ========= /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=62c568580acbfdaac3eec7be000001dc-1472104282.255875-1638833407 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:51:26 INFO - MultiFileLogger online at 20160824 22:51:26 in /builds/slave/test 22:51: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 22:51:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:51:26 INFO - {'append_to_log': False, 22:51:26 INFO - 'base_work_dir': '/builds/slave/test', 22:51:26 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 22:51:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:51:26 INFO - 'branch': 'Mozilla-Aurora', 22:51:26 INFO - 'buildbot_json_path': 'buildprops.json', 22:51:26 INFO - 'config_files': ('talos/linux_config.py',), 22:51:26 INFO - 'default_actions': ('clobber', 22:51:26 INFO - 'read-buildbot-config', 22:51:26 INFO - 'download-and-extract', 22:51:26 INFO - 'populate-webroot', 22:51:26 INFO - 'create-virtualenv', 22:51:26 INFO - 'install', 22:51:26 INFO - 'run-tests'), 22:51:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:51:26 INFO - 'download_minidump_stackwalk': True, 22:51:26 INFO - 'download_symbols': 'ondemand', 22:51:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:51:26 INFO - 'tooltool.py': '/tools/tooltool.py', 22:51:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:51:26 INFO - '/tools/misc-python/virtualenv.py')}, 22:51:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:51:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:51:26 INFO - 'installer_path': 'installer.exe', 22:51:26 INFO - 'log_level': 'info', 22:51:26 INFO - 'log_name': 'talos', 22:51:26 INFO - 'log_to_console': True, 22:51:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:51:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:51:26 INFO - 'opt_config_files': (), 22:51:26 INFO - 'pip_index': False, 22:51:26 INFO - 'sps_profile': False, 22:51:26 INFO - 'sps_profile_interval': 0, 22:51:26 INFO - 'suite': 'dromaeojs', 22:51:26 INFO - 'system_bits': '32', 22:51:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:51:26 INFO - 'title': 'talos-linux64-ix-018', 22:51:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:51:26 INFO - 'use_talos_json': True, 22:51:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:51:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:51:26 INFO - 'work_dir': 'build'} 22:51:26 INFO - [mozharness: 2016-08-25 05:51:26.021776Z] Running clobber step. 22:51:26 INFO - Running pre-action listener: _resource_record_pre_action 22:51:26 INFO - Running main action method: clobber 22:51:26 INFO - rmtree: /builds/slave/test/build 22:51:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:51:26 INFO - Running post-action listener: _resource_record_post_action 22:51:26 INFO - [mozharness: 2016-08-25 05:51:26.475992Z] Finished clobber step (success) 22:51:26 INFO - [mozharness: 2016-08-25 05:51:26.476094Z] Running read-buildbot-config step. 22:51:26 INFO - Running pre-action listener: _resource_record_pre_action 22:51:26 INFO - Running main action method: read_buildbot_config 22:51:26 INFO - Using buildbot properties: 22:51:26 INFO - { 22:51:26 INFO - "project": "", 22:51:26 INFO - "product": "firefox", 22:51:26 INFO - "script_repo_revision": "production", 22:51:26 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 22:51:26 INFO - "repository": "", 22:51:26 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 22:51:26 INFO - "buildid": "20160824195426", 22:51:26 INFO - "pgo_build": "True", 22:51:26 INFO - "basedir": "/builds/slave/test", 22:51:26 INFO - "buildnumber": 698, 22:51:26 INFO - "slavename": "talos-linux64-ix-018", 22:51:26 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 22:51:26 INFO - "platform": "ubuntu64_hw", 22:51:26 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 22:51:26 INFO - "branch": "mozilla-aurora", 22:51:26 INFO - "repo_path": "releases/mozilla-aurora", 22:51:26 INFO - "revision": "d560a3e332dd38246c2b624a1c1d36c0a4a8d196", 22:51:26 INFO - "stage_platform": "linux64-pgo", 22:51:26 INFO - "builduid": "6962f3fbcc3c463f98dcc47eb9154d6c", 22:51:26 INFO - "slavebuilddir": "test" 22:51:26 INFO - } 22:51:26 INFO - Found installer url https://queue.taskcluster.net/v1/task/FMRHA52ETKKpHoodCIXFOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 22:51:26 INFO - Running post-action listener: _resource_record_post_action 22:51:26 INFO - [mozharness: 2016-08-25 05:51:26.478564Z] Finished read-buildbot-config step (success) 22:51:26 INFO - [mozharness: 2016-08-25 05:51:26.478654Z] Running download-and-extract step. 22:51:26 INFO - Running pre-action listener: _resource_record_pre_action 22:51:26 INFO - Running main action method: download_and_extract 22:51:26 INFO - mkdir: /builds/slave/test/build/tests 22:51: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')]} 22:51:26 INFO - https://queue.taskcluster.net/v1/task/FMRHA52ETKKpHoodCIXFOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 22:51:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FMRHA52ETKKpHoodCIXFOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 22:51:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FMRHA52ETKKpHoodCIXFOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 22:51:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FMRHA52ETKKpHoodCIXFOQ/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 22:51:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FMRHA52ETKKpHoodCIXFOQ/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 22:51:26 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FMRHA52ETKKpHoodCIXFOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 22:51:26 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 22:51:26 INFO - retry: Failed, sleeping 30 seconds before retrying 22:51:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FMRHA52ETKKpHoodCIXFOQ/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 22:51:56 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FMRHA52ETKKpHoodCIXFOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 22:51:56 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 22:51:56 INFO - retry: Failed, sleeping 60 seconds before retrying 22:52:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FMRHA52ETKKpHoodCIXFOQ/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 22:52:56 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FMRHA52ETKKpHoodCIXFOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 22:52:56 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 22:52:56 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FMRHA52ETKKpHoodCIXFOQ/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! 22:52:56 INFO - Caught exception: HTTP Error 404: Not Found 22:52:56 INFO - Caught exception: HTTP Error 404: Not Found 22:52:56 INFO - Caught exception: HTTP Error 404: Not Found 22:52:56 INFO - trying https://queue.taskcluster.net/v1/task/FMRHA52ETKKpHoodCIXFOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 22:52:56 INFO - Downloading https://queue.taskcluster.net/v1/task/FMRHA52ETKKpHoodCIXFOQ/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 22:52:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FMRHA52ETKKpHoodCIXFOQ/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 22:52:58 INFO - Downloaded 1361 bytes. 22:52:58 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 22:52:58 INFO - Using the following test package requirements: 22:52:58 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 22:52:58 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 22:52:58 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 22:52:58 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 22:52:58 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 22:52:58 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 22:52:58 INFO - u'jsshell-linux-x86_64.zip'], 22:52:58 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 22:52:58 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 22:52:58 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 22:52:58 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 22:52:58 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 22:52:58 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 22:52:58 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 22:52:58 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 22:52:58 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 22:52:58 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 22:52:58 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 22:52:58 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:52: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')]} 22:52:58 INFO - https://queue.taskcluster.net/v1/task/FMRHA52ETKKpHoodCIXFOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:52:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FMRHA52ETKKpHoodCIXFOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 22:52:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FMRHA52ETKKpHoodCIXFOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 22:52:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FMRHA52ETKKpHoodCIXFOQ/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 22:52:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FMRHA52ETKKpHoodCIXFOQ/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 22:52:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FMRHA52ETKKpHoodCIXFOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 22:52:58 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 22:52:58 INFO - retry: Failed, sleeping 30 seconds before retrying 22:53:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FMRHA52ETKKpHoodCIXFOQ/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 22:53:28 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FMRHA52ETKKpHoodCIXFOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 22:53:28 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 22:53:28 INFO - retry: Failed, sleeping 60 seconds before retrying 22:54:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FMRHA52ETKKpHoodCIXFOQ/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 22:54:28 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FMRHA52ETKKpHoodCIXFOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 22:54:28 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 22:54:28 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FMRHA52ETKKpHoodCIXFOQ/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! 22:54:28 INFO - Caught exception: HTTP Error 404: Not Found 22:54:28 INFO - Caught exception: HTTP Error 404: Not Found 22:54:28 INFO - Caught exception: HTTP Error 404: Not Found 22:54:28 INFO - trying https://queue.taskcluster.net/v1/task/FMRHA52ETKKpHoodCIXFOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 22:54:28 INFO - Downloading https://queue.taskcluster.net/v1/task/FMRHA52ETKKpHoodCIXFOQ/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 22:54:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FMRHA52ETKKpHoodCIXFOQ/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 22:54:29 INFO - Downloaded 21769376 bytes. 22:54: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'] 22:54: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 22:54:30 INFO - Return code: 0 22:54: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 22:54: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')]} 22:54:30 INFO - https://queue.taskcluster.net/v1/task/FMRHA52ETKKpHoodCIXFOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:54:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FMRHA52ETKKpHoodCIXFOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 22:54:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FMRHA52ETKKpHoodCIXFOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 22:54:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FMRHA52ETKKpHoodCIXFOQ/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 22:54:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FMRHA52ETKKpHoodCIXFOQ/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 22:54:30 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FMRHA52ETKKpHoodCIXFOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 22:54:30 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 22:54:30 INFO - retry: Failed, sleeping 30 seconds before retrying 22:55:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FMRHA52ETKKpHoodCIXFOQ/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 22:55:00 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FMRHA52ETKKpHoodCIXFOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 22:55:00 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 22:55:00 INFO - retry: Failed, sleeping 60 seconds before retrying 22:56:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FMRHA52ETKKpHoodCIXFOQ/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 22:56:00 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FMRHA52ETKKpHoodCIXFOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 22:56:00 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 22:56:00 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FMRHA52ETKKpHoodCIXFOQ/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! 22:56:00 INFO - Caught exception: HTTP Error 404: Not Found 22:56:00 INFO - Caught exception: HTTP Error 404: Not Found 22:56:00 INFO - Caught exception: HTTP Error 404: Not Found 22:56:00 INFO - trying https://queue.taskcluster.net/v1/task/FMRHA52ETKKpHoodCIXFOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 22:56:00 INFO - Downloading https://queue.taskcluster.net/v1/task/FMRHA52ETKKpHoodCIXFOQ/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 22:56:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FMRHA52ETKKpHoodCIXFOQ/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 22:56:01 INFO - Downloaded 21769376 bytes. 22:56: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'] 22:56: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 22:56:02 INFO - Return code: 0 22:56: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')]} 22:56:02 INFO - https://queue.taskcluster.net/v1/task/FMRHA52ETKKpHoodCIXFOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:56:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FMRHA52ETKKpHoodCIXFOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 22:56:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FMRHA52ETKKpHoodCIXFOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 22:56:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FMRHA52ETKKpHoodCIXFOQ/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 22:56:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FMRHA52ETKKpHoodCIXFOQ/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 22:56:02 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FMRHA52ETKKpHoodCIXFOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 22:56:02 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 22:56:02 INFO - retry: Failed, sleeping 30 seconds before retrying 22:56:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FMRHA52ETKKpHoodCIXFOQ/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 22:56:32 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FMRHA52ETKKpHoodCIXFOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 22:56:32 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 22:56:32 INFO - retry: Failed, sleeping 60 seconds before retrying 22:57:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FMRHA52ETKKpHoodCIXFOQ/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 22:57:32 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FMRHA52ETKKpHoodCIXFOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 22:57:32 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 22:57:32 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FMRHA52ETKKpHoodCIXFOQ/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! 22:57:32 INFO - Caught exception: HTTP Error 404: Not Found 22:57:32 INFO - Caught exception: HTTP Error 404: Not Found 22:57:32 INFO - Caught exception: HTTP Error 404: Not Found 22:57:32 INFO - trying https://queue.taskcluster.net/v1/task/FMRHA52ETKKpHoodCIXFOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 22:57:32 INFO - Downloading https://queue.taskcluster.net/v1/task/FMRHA52ETKKpHoodCIXFOQ/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 22:57:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FMRHA52ETKKpHoodCIXFOQ/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 22:57:33 INFO - Downloaded 14413723 bytes. 22:57: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'] 22:57: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 22:57:33 INFO - Return code: 0 22:57: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')]} 22:57:33 INFO - https://queue.taskcluster.net/v1/task/FMRHA52ETKKpHoodCIXFOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:57:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FMRHA52ETKKpHoodCIXFOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 22:57:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FMRHA52ETKKpHoodCIXFOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 22:57:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FMRHA52ETKKpHoodCIXFOQ/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 22:57:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FMRHA52ETKKpHoodCIXFOQ/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 22:57:33 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FMRHA52ETKKpHoodCIXFOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 22:57:33 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 22:57:33 INFO - retry: Failed, sleeping 30 seconds before retrying 22:58:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FMRHA52ETKKpHoodCIXFOQ/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 22:58:03 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FMRHA52ETKKpHoodCIXFOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 22:58:03 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 22:58:03 INFO - retry: Failed, sleeping 60 seconds before retrying 22:59:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FMRHA52ETKKpHoodCIXFOQ/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 22:59:03 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FMRHA52ETKKpHoodCIXFOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 22:59:03 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 22:59:03 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FMRHA52ETKKpHoodCIXFOQ/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! 22:59:03 INFO - Caught exception: HTTP Error 404: Not Found 22:59:03 INFO - Caught exception: HTTP Error 404: Not Found 22:59:03 INFO - Caught exception: HTTP Error 404: Not Found 22:59:03 INFO - trying https://queue.taskcluster.net/v1/task/FMRHA52ETKKpHoodCIXFOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 22:59:03 INFO - Downloading https://queue.taskcluster.net/v1/task/FMRHA52ETKKpHoodCIXFOQ/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 22:59:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FMRHA52ETKKpHoodCIXFOQ/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 22:59:04 INFO - Downloaded 63749829 bytes. 22:59:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FMRHA52ETKKpHoodCIXFOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 22:59:04 INFO - mkdir: /builds/slave/test/properties 22:59:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:59:04 INFO - Writing to file /builds/slave/test/properties/build_url 22:59:04 INFO - Contents: 22:59:04 INFO - build_url:https://queue.taskcluster.net/v1/task/FMRHA52ETKKpHoodCIXFOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 22:59:05 INFO - Running post-action listener: _resource_record_post_action 22:59:05 INFO - Running post-action listener: set_extra_try_arguments 22:59:05 INFO - [mozharness: 2016-08-25 05:59:05.591064Z] Finished download-and-extract step (success) 22:59:05 INFO - [mozharness: 2016-08-25 05:59:05.591297Z] Running populate-webroot step. 22:59:05 INFO - Running pre-action listener: _resource_record_pre_action 22:59:05 INFO - Running main action method: populate_webroot 22:59:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:59:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:59:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:59:05 INFO - '--tppagecycles', 22:59:05 INFO - '7'], 22:59:05 INFO - 'tests': ['tsvgm']}}, 22:59:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:59:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:59:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:59:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:59:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:59:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:59:05 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:59:05 INFO - 'talos_options': ['--disable-e10s'], 22:59:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:59:05 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:59:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:59:05 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:59:05 INFO - 'talos_options': ['--disable-e10s'], 22:59:05 INFO - 'tests': ['damp', 'tps']}, 22:59:05 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:59:05 INFO - 'tests': ['damp', 'tps']}, 22:59:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:59:05 INFO - 'tests': ['dromaeo_dom']}, 22:59:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:59:05 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:59:05 INFO - 'tests': ['basic_compositor_video']}, 22:59:05 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:59:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:59:05 INFO - 'tests': ['a11yr', 22:59:05 INFO - 'ts_paint', 22:59:05 INFO - 'tpaint', 22:59:05 INFO - 'sessionrestore', 22:59:05 INFO - 'sessionrestore_no_auto_restore', 22:59:05 INFO - 'tabpaint']}, 22:59:05 INFO - 'other-e10s': {'tests': ['a11yr', 22:59:05 INFO - 'ts_paint', 22:59:05 INFO - 'tpaint', 22:59:05 INFO - 'sessionrestore', 22:59:05 INFO - 'sessionrestore_no_auto_restore', 22:59:05 INFO - 'tabpaint']}, 22:59:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:59:05 INFO - 'ts_paint', 22:59:05 INFO - 'tpaint', 22:59:05 INFO - 'sessionrestore', 22:59:05 INFO - 'sessionrestore_no_auto_restore', 22:59:05 INFO - 'tabpaint']}, 22:59:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:59:05 INFO - 'ts_paint', 22:59:05 INFO - 'tpaint', 22:59:05 INFO - 'sessionrestore', 22:59:05 INFO - 'sessionrestore_no_auto_restore', 22:59:05 INFO - 'tabpaint']}, 22:59:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:59:05 INFO - 'tests': ['a11yr', 22:59:05 INFO - 'ts_paint', 22:59:05 INFO - 'tpaint', 22:59:05 INFO - 'sessionrestore', 22:59:05 INFO - 'sessionrestore_no_auto_restore', 22:59:05 INFO - 'tabpaint']}, 22:59:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:59:05 INFO - 'tests': ['a11yr', 22:59:05 INFO - 'ts_paint', 22:59:05 INFO - 'tpaint', 22:59:05 INFO - 'sessionrestore', 22:59:05 INFO - 'sessionrestore_no_auto_restore', 22:59:05 INFO - 'tabpaint']}, 22:59:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:59:05 INFO - 'tests': ['tsvgx', 22:59:05 INFO - 'tsvgr_opacity', 22:59:05 INFO - 'tart', 22:59:05 INFO - 'tscrollx', 22:59:05 INFO - 'cart']}, 22:59:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:59:05 INFO - 'tsvgr_opacity', 22:59:05 INFO - 'tart', 22:59:05 INFO - 'tscrollx', 22:59:05 INFO - 'cart']}, 22:59:05 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:59:05 INFO - 'talos_options': ['--disable-e10s'], 22:59:05 INFO - 'tests': ['tp5o']}, 22:59:05 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:59:05 INFO - 'tests': ['tp5o']}, 22:59:05 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:59:05 INFO - 'talos_options': ['--disable-e10s', 22:59:05 INFO - '--xperf_path', 22:59:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:59:05 INFO - 'tests': ['tp5n']}, 22:59:05 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:59:05 INFO - 'talos_options': ['--xperf_path', 22:59:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:59:05 INFO - 'tests': ['tp5n']}}, 22:59:05 INFO - 'talos.zip': {'path': '', 22:59:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:59:05 INFO - Running post-action listener: _resource_record_post_action 22:59:05 INFO - [mozharness: 2016-08-25 05:59:05.608263Z] Finished populate-webroot step (success) 22:59:05 INFO - [mozharness: 2016-08-25 05:59:05.608352Z] Running create-virtualenv step. 22:59:05 INFO - Running pre-action listener: _resource_record_pre_action 22:59:05 INFO - Running main action method: create_virtualenv 22:59:05 INFO - Creating virtualenv /builds/slave/test/build/venv 22:59: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 22:59:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:59:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:59:05 INFO - Using real prefix '/usr' 22:59:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:59:06 INFO - Installing distribute.............................................................................................................................................................................................done. 22:59:08 INFO - Installing pip.................done. 22:59:08 INFO - Return code: 0 22:59:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:59: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')]} 22:59:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:59:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59: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')]} 22:59:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:59:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59: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 0xf01030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10648a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11c2180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c03b0>, '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 0x11c12b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c1740>, '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': '62c568580acbfdaac3eec7be000001dc-1472104282.255875-1638833407', '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 22:59: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 22:59: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 22:59:08 INFO - Using env: {'DISPLAY': ':0', 22:59:08 INFO - 'HOME': '/home/cltbld', 22:59:08 INFO - 'LANG': 'en_US.UTF-8', 22:59:08 INFO - 'LANGUAGE': 'en_US:en', 22:59:08 INFO - 'LOGNAME': 'cltbld', 22:59:08 INFO - 'MAIL': '/var/mail/cltbld', 22:59:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:59:08 INFO - 'MOZ_NO_REMOTE': '1', 22:59:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:59:08 INFO - 'NO_EM_RESTART': '1', 22:59:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:59:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:59:08 INFO - 'PWD': '/builds/slave/test', 22:59:08 INFO - 'SHELL': '/bin/bash', 22:59:08 INFO - 'SHLVL': '1', 22:59:08 INFO - 'TERM': 'linux', 22:59:08 INFO - 'TMOUT': '86400', 22:59:08 INFO - 'USER': 'cltbld', 22:59:08 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1472104282.255875-1638833407', 22:59:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:59:08 INFO - '_': '/tools/buildbot/bin/python'} 22:59:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:59: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 22:59:08 INFO - Cleaning up... 22:59:08 INFO - Return code: 0 22:59:08 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:59: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')]} 22:59:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:59:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59: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')]} 22:59:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:59:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59: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 0xf01030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10648a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11c2180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c03b0>, '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 0x11c12b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c1740>, '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': '62c568580acbfdaac3eec7be000001dc-1472104282.255875-1638833407', '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 22:59: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 22:59: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 22:59:08 INFO - Using env: {'DISPLAY': ':0', 22:59:08 INFO - 'HOME': '/home/cltbld', 22:59:08 INFO - 'LANG': 'en_US.UTF-8', 22:59:08 INFO - 'LANGUAGE': 'en_US:en', 22:59:08 INFO - 'LOGNAME': 'cltbld', 22:59:08 INFO - 'MAIL': '/var/mail/cltbld', 22:59:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:59:08 INFO - 'MOZ_NO_REMOTE': '1', 22:59:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:59:08 INFO - 'NO_EM_RESTART': '1', 22:59:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:59:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:59:08 INFO - 'PWD': '/builds/slave/test', 22:59:08 INFO - 'SHELL': '/bin/bash', 22:59:08 INFO - 'SHLVL': '1', 22:59:08 INFO - 'TERM': 'linux', 22:59:08 INFO - 'TMOUT': '86400', 22:59:08 INFO - 'USER': 'cltbld', 22:59:08 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1472104282.255875-1638833407', 22:59:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:59:08 INFO - '_': '/tools/buildbot/bin/python'} 22:59:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:59:08 INFO - Downloading/unpacking psutil>=3.1.1 22:59: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 22:59: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 22:59: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 22:59: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 22:59: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 22:59: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 22:59:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:59:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:59:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:59:10 INFO - Installing collected packages: psutil 22:59:10 INFO - Running setup.py install for psutil 22:59:10 INFO - building 'psutil._psutil_linux' extension 22:59: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 22:59: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 22:59:10 INFO - building 'psutil._psutil_posix' extension 22:59: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 22:59: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_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 22:59:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:59:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:59:11 INFO - Successfully installed psutil 22:59:11 INFO - Cleaning up... 22:59:11 INFO - Return code: 0 22:59:11 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:59: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')]} 22:59:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:59:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59: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')]} 22:59:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:59:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59: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 0xf01030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10648a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11c2180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c03b0>, '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 0x11c12b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c1740>, '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': '62c568580acbfdaac3eec7be000001dc-1472104282.255875-1638833407', '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 22:59: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 22:59: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 22:59:11 INFO - Using env: {'DISPLAY': ':0', 22:59:11 INFO - 'HOME': '/home/cltbld', 22:59:11 INFO - 'LANG': 'en_US.UTF-8', 22:59:11 INFO - 'LANGUAGE': 'en_US:en', 22:59:11 INFO - 'LOGNAME': 'cltbld', 22:59:11 INFO - 'MAIL': '/var/mail/cltbld', 22:59:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:59:11 INFO - 'MOZ_NO_REMOTE': '1', 22:59:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:59:11 INFO - 'NO_EM_RESTART': '1', 22:59:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:59:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:59:11 INFO - 'PWD': '/builds/slave/test', 22:59:11 INFO - 'SHELL': '/bin/bash', 22:59:11 INFO - 'SHLVL': '1', 22:59:11 INFO - 'TERM': 'linux', 22:59:11 INFO - 'TMOUT': '86400', 22:59:11 INFO - 'USER': 'cltbld', 22:59:11 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1472104282.255875-1638833407', 22:59:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:59:11 INFO - '_': '/tools/buildbot/bin/python'} 22:59:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:59:11 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:59: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 22:59: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 22:59: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 22:59: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 22:59: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 22:59: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 22:59:13 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:59:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:59:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:59:13 INFO - Installing collected packages: mozsystemmonitor 22:59:13 INFO - Running setup.py install for mozsystemmonitor 22:59:13 INFO - Successfully installed mozsystemmonitor 22:59:13 INFO - Cleaning up... 22:59:13 INFO - Return code: 0 22:59:13 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:59: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')]} 22:59:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:59:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59: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')]} 22:59:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:59:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59: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 0xf01030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10648a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11c2180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c03b0>, '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 0x11c12b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c1740>, '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': '62c568580acbfdaac3eec7be000001dc-1472104282.255875-1638833407', '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 22:59: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 22:59: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 22:59:13 INFO - Using env: {'DISPLAY': ':0', 22:59:13 INFO - 'HOME': '/home/cltbld', 22:59:13 INFO - 'LANG': 'en_US.UTF-8', 22:59:13 INFO - 'LANGUAGE': 'en_US:en', 22:59:13 INFO - 'LOGNAME': 'cltbld', 22:59:13 INFO - 'MAIL': '/var/mail/cltbld', 22:59:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:59:13 INFO - 'MOZ_NO_REMOTE': '1', 22:59:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:59:13 INFO - 'NO_EM_RESTART': '1', 22:59:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:59:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:59:13 INFO - 'PWD': '/builds/slave/test', 22:59:13 INFO - 'SHELL': '/bin/bash', 22:59:13 INFO - 'SHLVL': '1', 22:59:13 INFO - 'TERM': 'linux', 22:59:13 INFO - 'TMOUT': '86400', 22:59:13 INFO - 'USER': 'cltbld', 22:59:13 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1472104282.255875-1638833407', 22:59:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:59:13 INFO - '_': '/tools/buildbot/bin/python'} 22:59:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:59:13 INFO - Downloading/unpacking jsonschema==2.5.1 22:59: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 22:59: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 22:59: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 22:59: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 22:59: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 22:59: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 22:59:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:59:15 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:59: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 22:59: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 22:59: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 22:59: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 22:59: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 22:59: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 22:59:15 INFO - Downloading functools32-3.2.3-2.tar.gz 22:59:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:59:15 INFO - warning: no files found matching '*.txt' 22:59:15 INFO - no previously-included directories found matching 'build' 22:59:15 INFO - no previously-included directories found matching 'dist' 22:59:15 INFO - no previously-included directories found matching '.git*' 22:59:15 INFO - Installing collected packages: jsonschema, functools32 22:59:15 INFO - Running setup.py install for functools32 22:59:15 INFO - warning: no files found matching '*.txt' 22:59:15 INFO - no previously-included directories found matching 'build' 22:59:15 INFO - no previously-included directories found matching 'dist' 22:59:15 INFO - no previously-included directories found matching '.git*' 22:59:15 INFO - Successfully installed jsonschema functools32 22:59:15 INFO - Cleaning up... 22:59:15 INFO - Return code: 0 22:59:15 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:59:15 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')]} 22:59:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:59:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59:15 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')]} 22:59:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:59:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59:15 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 0xf01030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10648a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11c2180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c03b0>, '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 0x11c12b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c1740>, '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': '62c568580acbfdaac3eec7be000001dc-1472104282.255875-1638833407', '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 22:59:15 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 22:59:15 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 22:59:15 INFO - Using env: {'DISPLAY': ':0', 22:59:15 INFO - 'HOME': '/home/cltbld', 22:59:15 INFO - 'LANG': 'en_US.UTF-8', 22:59:15 INFO - 'LANGUAGE': 'en_US:en', 22:59:15 INFO - 'LOGNAME': 'cltbld', 22:59:15 INFO - 'MAIL': '/var/mail/cltbld', 22:59:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:59:15 INFO - 'MOZ_NO_REMOTE': '1', 22:59:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:59:15 INFO - 'NO_EM_RESTART': '1', 22:59:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:59:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:59:15 INFO - 'PWD': '/builds/slave/test', 22:59:15 INFO - 'SHELL': '/bin/bash', 22:59:15 INFO - 'SHLVL': '1', 22:59:15 INFO - 'TERM': 'linux', 22:59:15 INFO - 'TMOUT': '86400', 22:59:15 INFO - 'USER': 'cltbld', 22:59:15 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1472104282.255875-1638833407', 22:59:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:59:15 INFO - '_': '/tools/buildbot/bin/python'} 22:59:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:59:16 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:59:16 INFO - Cleaning up... 22:59:16 INFO - Return code: 0 22:59:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:59: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')]} 22:59:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:59:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59: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')]} 22:59:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:59:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59: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 0xf01030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10648a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11c2180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c03b0>, '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 0x11c12b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c1740>, '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': '62c568580acbfdaac3eec7be000001dc-1472104282.255875-1638833407', '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 22:59: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 22:59: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 22:59:16 INFO - Using env: {'DISPLAY': ':0', 22:59:16 INFO - 'HOME': '/home/cltbld', 22:59:16 INFO - 'LANG': 'en_US.UTF-8', 22:59:16 INFO - 'LANGUAGE': 'en_US:en', 22:59:16 INFO - 'LOGNAME': 'cltbld', 22:59:16 INFO - 'MAIL': '/var/mail/cltbld', 22:59:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:59:16 INFO - 'MOZ_NO_REMOTE': '1', 22:59:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:59:16 INFO - 'NO_EM_RESTART': '1', 22:59:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:59:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:59:16 INFO - 'PWD': '/builds/slave/test', 22:59:16 INFO - 'SHELL': '/bin/bash', 22:59:16 INFO - 'SHLVL': '1', 22:59:16 INFO - 'TERM': 'linux', 22:59:16 INFO - 'TMOUT': '86400', 22:59:16 INFO - 'USER': 'cltbld', 22:59:16 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1472104282.255875-1638833407', 22:59:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:59:16 INFO - '_': '/tools/buildbot/bin/python'} 22:59:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:59:16 INFO - Downloading/unpacking blobuploader==1.2.4 22:59: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 22:59: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 22:59: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 22:59: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 22:59: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 22:59: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 22:59:18 INFO - Downloading blobuploader-1.2.4.tar.gz 22:59:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:59:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:59: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 22:59: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 22:59: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 22:59: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 22:59: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 22:59: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 22:59:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:59:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:59: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 22:59: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 22:59: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 22:59: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 22:59: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 22:59: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 22:59:18 INFO - Downloading docopt-0.6.1.tar.gz 22:59:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:59:19 INFO - Installing collected packages: blobuploader, requests, docopt 22:59:19 INFO - Running setup.py install for blobuploader 22:59:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:59:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:59:19 INFO - Running setup.py install for requests 22:59:19 INFO - Running setup.py install for docopt 22:59:19 INFO - Successfully installed blobuploader requests docopt 22:59:19 INFO - Cleaning up... 22:59:19 INFO - Return code: 0 22:59:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:59: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')]} 22:59:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:59:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59: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')]} 22:59:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:59:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59: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 0xf01030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10648a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11c2180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c03b0>, '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 0x11c12b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c1740>, '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': '62c568580acbfdaac3eec7be000001dc-1472104282.255875-1638833407', '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 22:59: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 22:59: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 22:59:19 INFO - Using env: {'DISPLAY': ':0', 22:59:19 INFO - 'HOME': '/home/cltbld', 22:59:19 INFO - 'LANG': 'en_US.UTF-8', 22:59:19 INFO - 'LANGUAGE': 'en_US:en', 22:59:19 INFO - 'LOGNAME': 'cltbld', 22:59:19 INFO - 'MAIL': '/var/mail/cltbld', 22:59:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:59:19 INFO - 'MOZ_NO_REMOTE': '1', 22:59:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:59:19 INFO - 'NO_EM_RESTART': '1', 22:59:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:59:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:59:19 INFO - 'PWD': '/builds/slave/test', 22:59:19 INFO - 'SHELL': '/bin/bash', 22:59:19 INFO - 'SHLVL': '1', 22:59:19 INFO - 'TERM': 'linux', 22:59:19 INFO - 'TMOUT': '86400', 22:59:19 INFO - 'USER': 'cltbld', 22:59:19 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1472104282.255875-1638833407', 22:59:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:59:19 INFO - '_': '/tools/buildbot/bin/python'} 22:59:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:59:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:59:19 INFO - Running setup.py (path:/tmp/pip-aA4nTH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:59:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:59:19 INFO - Running setup.py (path:/tmp/pip-h0uR1W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:59:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:59:20 INFO - Running setup.py (path:/tmp/pip-6cVOwk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:59:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:59:20 INFO - Running setup.py (path:/tmp/pip-qYYTPy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:59:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:59:20 INFO - Running setup.py (path:/tmp/pip-oBQKac-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:59:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:59:20 INFO - Running setup.py (path:/tmp/pip-2NP5zy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:59:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:59:20 INFO - Running setup.py (path:/tmp/pip-sIGm62-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:59:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:59:20 INFO - Running setup.py (path:/tmp/pip-aQV9jS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:59:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:59:20 INFO - Running setup.py (path:/tmp/pip-KceeC1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:59:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:59:20 INFO - Running setup.py (path:/tmp/pip-7cHgUd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:59:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:59:20 INFO - Running setup.py (path:/tmp/pip-lIdxZZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:59:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:59:20 INFO - Running setup.py (path:/tmp/pip-6OQ4Vy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:59:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:59:20 INFO - Running setup.py (path:/tmp/pip-yXvhQm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:59:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:59:20 INFO - Running setup.py (path:/tmp/pip-hiL8WD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:59:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:59:20 INFO - Running setup.py (path:/tmp/pip-RSutvV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:59:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:59:20 INFO - Running setup.py (path:/tmp/pip-3mUmtG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:59:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:59:21 INFO - Running setup.py (path:/tmp/pip-J19b0n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:59:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:59:21 INFO - Running setup.py install for manifestparser 22:59:21 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:59:21 INFO - Running setup.py install for mozcrash 22:59:21 INFO - Running setup.py install for mozdebug 22:59:21 INFO - Running setup.py install for mozdevice 22:59:21 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:59:21 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:59:21 INFO - Running setup.py install for mozfile 22:59:21 INFO - Running setup.py install for mozhttpd 22:59:21 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:59:21 INFO - Running setup.py install for mozinfo 22:59:21 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:59:21 INFO - Running setup.py install for mozInstall 22:59:21 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:59:21 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:59:21 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:59:21 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:59:21 INFO - Running setup.py install for mozleak 22:59:21 INFO - Running setup.py install for mozlog 22:59:22 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:59:22 INFO - Running setup.py install for moznetwork 22:59:22 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:59:22 INFO - Running setup.py install for mozprocess 22:59:22 INFO - Running setup.py install for mozprofile 22:59:22 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:59:22 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:59:22 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:59:22 INFO - Running setup.py install for mozrunner 22:59:22 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:59:22 INFO - Running setup.py install for mozscreenshot 22:59:22 INFO - Running setup.py install for moztest 22:59:22 INFO - Running setup.py install for mozversion 22:59:22 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:59:22 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:59:22 INFO - Cleaning up... 22:59:22 INFO - Return code: 0 22:59:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:59:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:59:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:59:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:59:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:59:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59:22 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 0xf01030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10648a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11c2180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c03b0>, '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 0x11c12b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c1740>, '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': '62c568580acbfdaac3eec7be000001dc-1472104282.255875-1638833407', '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 22:59:22 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 22:59:22 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 22:59:22 INFO - Using env: {'DISPLAY': ':0', 22:59:22 INFO - 'HOME': '/home/cltbld', 22:59:22 INFO - 'LANG': 'en_US.UTF-8', 22:59:22 INFO - 'LANGUAGE': 'en_US:en', 22:59:22 INFO - 'LOGNAME': 'cltbld', 22:59:22 INFO - 'MAIL': '/var/mail/cltbld', 22:59:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:59:22 INFO - 'MOZ_NO_REMOTE': '1', 22:59:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:59:22 INFO - 'NO_EM_RESTART': '1', 22:59:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:59:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:59:22 INFO - 'PWD': '/builds/slave/test', 22:59:22 INFO - 'SHELL': '/bin/bash', 22:59:22 INFO - 'SHLVL': '1', 22:59:22 INFO - 'TERM': 'linux', 22:59:22 INFO - 'TMOUT': '86400', 22:59:22 INFO - 'USER': 'cltbld', 22:59:22 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1472104282.255875-1638833407', 22:59:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:59:22 INFO - '_': '/tools/buildbot/bin/python'} 22:59:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:59:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:59:23 INFO - Running setup.py (path:/tmp/pip-xTxwsU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:59: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)) 22:59:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:59:23 INFO - Running setup.py (path:/tmp/pip-bFMy1x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:59: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)) 22:59:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:59:23 INFO - Running setup.py (path:/tmp/pip-QAG1AD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:59: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)) 22:59:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:59:23 INFO - Running setup.py (path:/tmp/pip-qGZrp8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:59: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)) 22:59:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:59:23 INFO - Running setup.py (path:/tmp/pip-2x3sCJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:59: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)) 22:59:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:59:23 INFO - Running setup.py (path:/tmp/pip-O7mIyE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:59: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)) 22:59:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:59:23 INFO - Running setup.py (path:/tmp/pip-I8OQUc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:59: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)) 22:59:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:59:23 INFO - Running setup.py (path:/tmp/pip-iLBvip-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:59: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)) 22:59:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:59:23 INFO - Running setup.py (path:/tmp/pip-ryopqY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:59: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)) 22:59:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:59:23 INFO - Running setup.py (path:/tmp/pip-MhwRLt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:59:23 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)) 22:59:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:59:23 INFO - Running setup.py (path:/tmp/pip-4MHzWr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:59:23 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)) 22:59:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:59:23 INFO - Running setup.py (path:/tmp/pip-RatUfN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:59:23 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)) 22:59:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:59:23 INFO - Running setup.py (path:/tmp/pip-aOMg2l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:59: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)) 22:59:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:59:24 INFO - Running setup.py (path:/tmp/pip-TAFn3M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:59: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)) 22:59:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:59:24 INFO - Running setup.py (path:/tmp/pip-gez8gY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:59: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)) 22:59:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:59:24 INFO - Running setup.py (path:/tmp/pip-iPJnlt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:59: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)) 22:59:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:59:24 INFO - Running setup.py (path:/tmp/pip-rtxYgu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:59: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)) 22:59: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)) 22:59: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)) 22:59: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)) 22:59: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)) 22:59: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)) 22:59:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:59: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 22:59: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 22:59: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 22:59: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 22:59: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 22:59: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 22:59:26 INFO - Downloading blessings-1.6.tar.gz 22:59:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:59:26 INFO - Installing collected packages: blessings 22:59:26 INFO - Running setup.py install for blessings 22:59:26 INFO - Successfully installed blessings 22:59:26 INFO - Cleaning up... 22:59:26 INFO - Return code: 0 22:59:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:59:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:59:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:59:26 INFO - Reading from file tmpfile_stdout 22:59:26 INFO - Current package versions: 22:59:26 INFO - argparse == 1.2.1 22:59:26 INFO - blessings == 1.6 22:59:26 INFO - blobuploader == 1.2.4 22:59:26 INFO - docopt == 0.6.1 22:59:26 INFO - functools32 == 3.2.3-2 22:59:26 INFO - jsonschema == 2.5.1 22:59:26 INFO - manifestparser == 1.1 22:59:26 INFO - mozInstall == 1.12 22:59:26 INFO - mozcrash == 0.17 22:59:26 INFO - mozdebug == 0.1 22:59:26 INFO - mozdevice == 0.48 22:59:26 INFO - mozfile == 1.2 22:59:26 INFO - mozhttpd == 0.7 22:59:26 INFO - mozinfo == 0.9 22:59:26 INFO - mozleak == 0.1 22:59:26 INFO - mozlog == 3.3 22:59:26 INFO - moznetwork == 0.27 22:59:26 INFO - mozprocess == 0.23 22:59:26 INFO - mozprofile == 0.28 22:59:26 INFO - mozrunner == 6.12 22:59:26 INFO - mozscreenshot == 0.1 22:59:26 INFO - mozsystemmonitor == 0.3 22:59:26 INFO - moztest == 0.7 22:59:26 INFO - mozversion == 1.4 22:59:26 INFO - psutil == 3.1.1 22:59:26 INFO - requests == 1.2.3 22:59:26 INFO - wsgiref == 0.1.2 22:59:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:59: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')]} 22:59:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:59:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59: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')]} 22:59:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:59:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59: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 0xf01030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10648a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11c2180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c03b0>, '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 0x11c12b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c1740>, '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': '62c568580acbfdaac3eec7be000001dc-1472104282.255875-1638833407', '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 22:59: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 22:59: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 22:59:26 INFO - Using env: {'DISPLAY': ':0', 22:59:26 INFO - 'HOME': '/home/cltbld', 22:59:26 INFO - 'LANG': 'en_US.UTF-8', 22:59:26 INFO - 'LANGUAGE': 'en_US:en', 22:59:26 INFO - 'LOGNAME': 'cltbld', 22:59:26 INFO - 'MAIL': '/var/mail/cltbld', 22:59:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:59:26 INFO - 'MOZ_NO_REMOTE': '1', 22:59:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:59:26 INFO - 'NO_EM_RESTART': '1', 22:59:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:59:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:59:26 INFO - 'PWD': '/builds/slave/test', 22:59:26 INFO - 'SHELL': '/bin/bash', 22:59:26 INFO - 'SHLVL': '1', 22:59:26 INFO - 'TERM': 'linux', 22:59:26 INFO - 'TMOUT': '86400', 22:59:26 INFO - 'USER': 'cltbld', 22:59:26 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1472104282.255875-1638833407', 22:59:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:59:26 INFO - '_': '/tools/buildbot/bin/python'} 22:59:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:59:26 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)) 22:59:26 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)) 22:59:26 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)) 22:59:26 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)) 22:59:26 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)) 22:59:26 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)) 22:59:26 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)) 22:59:26 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)) 22:59:26 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)) 22:59:26 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)) 22:59:26 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)) 22:59:26 INFO - Cleaning up... 22:59:26 INFO - Return code: 0 22:59:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:59: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')]} 22:59:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:59:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59: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')]} 22:59:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:59:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59:26 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 0xf01030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10648a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11c2180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c03b0>, '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 0x11c12b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c1740>, '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': '62c568580acbfdaac3eec7be000001dc-1472104282.255875-1638833407', '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 22:59:26 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 22:59:26 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 22:59:26 INFO - Using env: {'DISPLAY': ':0', 22:59:26 INFO - 'HOME': '/home/cltbld', 22:59:26 INFO - 'LANG': 'en_US.UTF-8', 22:59:26 INFO - 'LANGUAGE': 'en_US:en', 22:59:26 INFO - 'LOGNAME': 'cltbld', 22:59:26 INFO - 'MAIL': '/var/mail/cltbld', 22:59:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:59:26 INFO - 'MOZ_NO_REMOTE': '1', 22:59:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:59:26 INFO - 'NO_EM_RESTART': '1', 22:59:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:59:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:59:26 INFO - 'PWD': '/builds/slave/test', 22:59:26 INFO - 'SHELL': '/bin/bash', 22:59:26 INFO - 'SHLVL': '1', 22:59:26 INFO - 'TERM': 'linux', 22:59:26 INFO - 'TMOUT': '86400', 22:59:26 INFO - 'USER': 'cltbld', 22:59:26 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1472104282.255875-1638833407', 22:59:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:59:26 INFO - '_': '/tools/buildbot/bin/python'} 22:59:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:59:27 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:59:27 INFO - Cleaning up... 22:59:27 INFO - Return code: 0 22:59:27 INFO - Running post-action listener: _resource_record_post_action 22:59:27 INFO - Running post-action listener: _start_resource_monitoring 22:59:27 INFO - Starting resource monitoring. 22:59:27 INFO - [mozharness: 2016-08-25 05:59:27.141123Z] Finished create-virtualenv step (success) 22:59:27 INFO - [mozharness: 2016-08-25 05:59:27.141587Z] Running install step. 22:59:27 INFO - Running pre-action listener: _resource_record_pre_action 22:59:27 INFO - Running main action method: install 22:59:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:59:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:59:27 INFO - Reading from file tmpfile_stdout 22:59:27 INFO - Detecting whether we're running mozinstall >=1.0... 22:59:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:59:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:59:27 INFO - Reading from file tmpfile_stdout 22:59:27 INFO - Output received: 22:59:27 INFO - Usage: mozinstall [options] installer 22:59:27 INFO - Options: 22:59:27 INFO - -h, --help show this help message and exit 22:59:27 INFO - -d DEST, --destination=DEST 22:59:27 INFO - Directory to install application into. [default: 22:59:27 INFO - "/builds/slave/test"] 22:59:27 INFO - --app=APP Application being installed. [default: firefox] 22:59:27 INFO - mkdir: /builds/slave/test/build/application 22:59: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'] 22:59: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 22:59:44 INFO - Reading from file tmpfile_stdout 22:59:44 INFO - Output received: 22:59:44 INFO - /builds/slave/test/build/application/firefox/firefox 22:59:44 INFO - Running post-action listener: _resource_record_post_action 22:59:44 INFO - [mozharness: 2016-08-25 05:59:44.061088Z] Finished install step (success) 22:59:44 INFO - [mozharness: 2016-08-25 05:59:44.061369Z] Running run-tests step. 22:59:44 INFO - Running pre-action listener: _resource_record_pre_action 22:59:44 INFO - Running main action method: run_tests 22:59:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:59:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:59:44 INFO - Python 2.7.3 22:59:44 INFO - Return code: 0 22:59:44 INFO - grabbing minidump binary from tooltool 22:59: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')]} 22:59: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 0x11c03b0>, '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 0x11c12b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c1740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:59: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 22:59: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 22:59: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 22:59:44 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:59:44 INFO - Return code: 0 22:59:44 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:59:44 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:59:44 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:59:44 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:59:44 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:59: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/FMRHA52ETKKpHoodCIXFOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--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 22:59: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/FMRHA52ETKKpHoodCIXFOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-018 --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 22:59:44 INFO - Using env: {'DISPLAY': ':0', 22:59:44 INFO - 'HOME': '/home/cltbld', 22:59:44 INFO - 'LANG': 'en_US.UTF-8', 22:59:44 INFO - 'LANGUAGE': 'en_US:en', 22:59:44 INFO - 'LOGNAME': 'cltbld', 22:59:44 INFO - 'MAIL': '/var/mail/cltbld', 22:59:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:59:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:59:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:59:44 INFO - 'MOZ_NO_REMOTE': '1', 22:59:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:59:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:59:44 INFO - 'NO_EM_RESTART': '1', 22:59:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:59:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:59:44 INFO - 'PWD': '/builds/slave/test', 22:59:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:59:44 INFO - 'SHELL': '/bin/bash', 22:59:44 INFO - 'SHLVL': '1', 22:59:44 INFO - 'TERM': 'linux', 22:59:44 INFO - 'TMOUT': '86400', 22:59:44 INFO - 'USER': 'cltbld', 22:59:44 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1472104282.255875-1638833407', 22:59:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:59:44 INFO - '_': '/tools/buildbot/bin/python'} 22:59: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/FMRHA52ETKKpHoodCIXFOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--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 22:59:44 INFO - mozversion application_buildid: 20160824195426 22:59:44 INFO - mozversion application_changeset: d560a3e332dd38246c2b624a1c1d36c0a4a8d196 22:59:44 INFO - mozversion application_display_name: FirefoxDeveloperEdition 22:59:44 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:59:44 INFO - mozversion application_name: Firefox 22:59:44 INFO - mozversion application_remotingname: firefox-dev 22:59:44 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 22:59:44 INFO - mozversion application_vendor: Mozilla 22:59:44 INFO - mozversion application_version: 50.0a2 22:59:44 INFO - mozversion platform_buildid: 20160824195426 22:59:44 INFO - mozversion platform_changeset: d560a3e332dd38246c2b624a1c1d36c0a4a8d196 22:59:44 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 22:59:44 INFO - mozversion platform_version: 50.0a2 22:59:44 INFO - using testdate: 1472104784 22:59:44 INFO - actual date: 1472104784 22:59:44 INFO - starting webserver on 'localhost:53411' 22:59:44 INFO - SUITE-START | Running 2 tests 22:59:44 INFO - TEST-START | dromaeo_css 22:59:44 INFO - operating with platform_type : linux_ 22:59:44 INFO - Initialising browser for dromaeo_css test... 22:59:44 INFO - TEST-INFO | started process 6222 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPkpH4b/profile http://localhost:53411/getInfo.html) 22:59:48 INFO - PROCESS | 6222 | __metrics Screen width/height:1600/1200 22:59:48 INFO - PROCESS | 6222 | colorDepth:24 22:59:48 INFO - PROCESS | 6222 | Browser inner width/height: 1024/697 22:59:48 INFO - PROCESS | 6222 | __metrics 22:59:48 INFO - PROCESS | 6222 | 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" 22:59:48 INFO - PROCESS | 6222 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:59:49 INFO - TEST-INFO | 6222: exit 0 22:59:49 INFO - Browser initialized. 22:59:49 INFO - Running cycle 1/1 for dromaeo_css test... 22:59:49 INFO - TEST-INFO | started process 6316 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPkpH4b/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:00:01 INFO - PROCESS | 6316 | RSS: Main: 145666048 23:00:01 INFO - PROCESS | 6316 | 23:00:52 INFO - PROCESS | 6316 | 1472104852505 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] 23:03:01 INFO - PROCESS | 6316 | Cycle 1(1): loaded http://localhost:53411/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53411/tests/dromaeo/cssquery-ext.html) 23:03:02 INFO - PROCESS | 6316 | RSS: Main: 222773248 23:03:02 INFO - PROCESS | 6316 | 23:06:04 INFO - PROCESS | 6316 | Cycle 1(1): loaded http://localhost:53411/tests/dromaeo/cssquery-ext.html (next: http://localhost:53411/tests/dromaeo/cssquery-jquery.html) 23:06:05 INFO - PROCESS | 6316 | RSS: Main: 236834816 23:06:05 INFO - PROCESS | 6316 | 23:09:07 INFO - PROCESS | 6316 | Cycle 1(1): loaded http://localhost:53411/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53411/tests/dromaeo/cssquery-mootools.html) 23:09:07 INFO - PROCESS | 6316 | RSS: Main: 218148864 23:09:07 INFO - PROCESS | 6316 | 23:12:09 INFO - PROCESS | 6316 | Cycle 1(1): loaded http://localhost:53411/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53411/tests/dromaeo/cssquery-prototype.html) 23:12:10 INFO - PROCESS | 6316 | RSS: Main: 201170944 23:12:10 INFO - PROCESS | 6316 | 23:15:12 INFO - PROCESS | 6316 | Cycle 1(1): loaded http://localhost:53411/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53411/tests/dromaeo/cssquery-yui.html) 23:15:13 INFO - PROCESS | 6316 | RSS: Main: 174915584 23:15:13 INFO - PROCESS | 6316 | 23:18:16 INFO - PROCESS | 6316 | Cycle 1(1): loaded http://localhost:53411/tests/dromaeo/cssquery-yui.html (next: http://localhost:53411/tests/dromaeo/cssquery-dojo.html) 23:18:16 INFO - PROCESS | 6316 | RSS: Main: 263151616 23:18:16 INFO - PROCESS | 6316 | 23:18:16 INFO - PROCESS | 6316 | __start_tp_report 23:18:16 INFO - PROCESS | 6316 | _x_x_mozilla_page_load 23:18:16 INFO - PROCESS | 6316 | _x_x_mozilla_page_load_details 23:18:16 INFO - PROCESS | 6316 | |i|pagename|runs| 23:18:16 INFO - PROCESS | 6316 | |0;dojo.html;3305.5702758641382;3326.2017115892195;3333.483310086936;3360.6303306636282;3378.5945686517607;5639.971800140998;5716.657000579969;5739.053056245714;5743.483086522212;5751.309842818868;5704.48659620634;5778.855528611787;5780.277465316835;5783.913241301373;5796.130580412941;5091.84724458265;5229.607779416549;5280.815171468999;5300.204969254604;5341.03861304965;5145.3568303962;5231.738413079331;5265.473452654742;5287.048331300383;5287.259783630276;5190.610704197182;5242.580593552507;5269.683818970855;5277.340332458442;5289.471052894718;5327.121025030865;5339;5345.33183352081;5353.785848566053;5382.381026182;5074.086664400406;5138.049460849717;5149.6910185389;5251.973740131275;5253.185756207794;5623.8594035149035;5630.971845140789;5639.15412688096;5656.688882111483;5662.037453632893;6160.353162917874;6170.876582468325;6171.074338849164;6172.444479996821;6173.47525460332;6011.398860113954;6082.422169893853;6133.754649814002;6147.72335244915;6205.7517699291975;5808.332041815203;5815.476607105381;5828.475437210672;5829.242198514208;5834.708264586754;6286.874262514724;6330.493560515148;6336.239651241881;6343.52423568225;6343.682815859189;5825.504832089235;5914.2311499504785;5952.821415357546;5976.701164941735;6063.696815159225;6105.45050945417;6110.7555697771595;6115.938840611626;6116.235470566179;6118.296395914527;6069.302030266487;6070.2412198475195;6070.969645151746;6080.543959203077;6099.573029887865;5903.675297858659;5936.020556608111;6027.36712645166;6063.605865618721;6084.087386892002;4646.6747327686735;4677.789499472464;4681.789319480631;4683.15703173432;4694;4162.8334866605;4175.707700460938;4182.079942412664;4205.432266643964;4206.915861682749;5606.6916319602815;5774.162746401748;5802.100674395391;5816.272965879265;5893.705314734246;6238.500919926395;6239.032949892774;6240.7503699851495;6241.313455519889;6241.937580624136;5839.970800146057;5840.240768700041;5850.268716410449;5851.736671849777;5877.441643043904;5167.457416971239;5235.031519168966;5257.7896884124975;5265.736713164327;5346.278252435872;6303.779367722108;6312.05390964559;6344.206974128234;6346.174997250328;6358.046293056079;6171.197744293393;6175.5677102602385;6178.320384757583;6185.938140618537;6186.4741496973265;6069.180660610938;6288.937110629018;6305.8108256752375;6336.144620476177;6429.38920802523;6110.7250173741395;6112.419320164578;6113.602615830135;6114.785982490592;6118.724657390339;6224.06639004153;6230.034344676583;6234.189555357774;6234.688265586612;6237.220347456568;5856.94143058564;6004.339522652417;6008.489278411384;6032.849178770566;6125.418085281891;6038.154658347747;6076.72654730547;6088.452039316483;6090.421409966046;6091.299500557316;5961.463468287875;6014.879702405839;6029.397060293935;6030.939690603213;6031.577789554689;5835.94164058354;5892.204552385373;5967.910481342697;5989.460948514655;6040.939590604214;5860.765569377178;5864.208331875313;5867.061270196748;5868.2664666916635;5875.735591898289 23:18:16 INFO - PROCESS | 6316 | |1;ext.html;611.9479844213241;639.6290151712006;647.9319671434499;650.281439009894;653.4282502810041;33388.4991725124;46753.53246467534;47796.52203477964;47870.52129478704;47996.999999999956;49686.50313496864;50091.749541252335;50117.49882501174;50190.49809501904;50245.49754502463;8129.674813007473;9268.443893366402;9382.29632777541;9395.577199026025;9406.15344618984;8091.554964476952;8380.078191398941;11062.22564420491;11267.098632109453;11274.830877536844;11550.53797848085;11604.6518604442;11615.593454229103;11616.303021818718;11647.592334268302;11311.773764524705;11330.263532870336;11336.546538138464;11344.48949797261;11347.148963827745;11210.551577936954;11270.380129092897;11270.661880143609;11288.209825312231;11294.887051129548;46068.3089753654;46203;46277.53722462766;46303.76848115738;46373.76813115913;3837.5586807517216;3845.713973158554;4043.292423825819;4231.259529582341;4589.541045895384;35174.64825351765;35473.11317217064;35561.64438355609;35829.82085089584;36541.45187822185;4009.959900400988;4502.3246513023305;4506.324051392285;4580.862574122748;4640.327152562893;390664;391262.04368978256;391305;391660;392586;63090.36909630937;63268.36731632625;63382;63462;63469.36530634634;39692.40461393081;40125.197496049914;40333.596664032986;42580.14839703189;65996.34003659998;18830.3409380671;19048.52378690544;19062.80937190638;19064.52338691516;19086.904565477085;12528.87471125277;14441.638959026108;14513.492027778977;14574.052686575338;14588.635284117981;19888.801111988694;20963.685544716842;20992.790072099084;21059.473513162295;21114.683279750818;7617.238276172338;7654.192723422078;7693.538387696756;7840.882386764203;7942.126366099725;1013.5641674080216;1128.3568366031284;1132.592266783957;1161.5353858456515;1163.4299193395345;34242.48636270458;35277.8236108823;35285.823570882494;35374;35435.82282088573;1199.3943058755274;1231.4335405713473;1235.0860363331071;1249.7761149475482;1251.6808213905579;17683.20425580852;18488.168032438574;18489;18495.352662657013;18573.16420761069;603381;609857;611068;612005;612482;63431.68284158642;64069.35930640727;64087.67956160189;64104.03843942344;64114.67942660257;1294.8575656677758;1417.1284659934272;1418.6240646228757;1463.9707205855823;1476.195473466963;33493.16267093342;35638.82180589184;35927.64072359243;35982.8200858994;36148.45777313395;54413;54847.17729234145;55030.72484637551;55050.44949550613;55060.72469637626;420209;421931;423299;423357;570620;44388.33417498805;47368.289475657206;47594.762026189645;48075;48997.2650410237;52159;52426.47573524216;52651.73674131605;53092.734536328615;53470.73264633652;44498.3325250128;44562.777186115156;45267.320990185835;46375;49353;45299.32051019171;46174;46184;46255.306170406795;47109.76445117752;45058.774706126256;45556.54443455523;46067.76966115148;46182.76908615435;46249.76875115603;37714;39783.204335913695;39807.896486370846;39962.80018599889;40534.39198411967;29559.704402955696;29779.702202977693;29849.403011940074;30019.699803002564;30607.387852243275 23:18:16 INFO - PROCESS | 6316 | |2;jquery.html;379.3529266485552;420.0901958361649;432.9805158767857;433.0861881430182;434.40703439804656;15624.531264062081;20860;21192.682109768364;21726.239581614645;21749.782502174992;20716;20729.792702072973;20742.792572074275;20758.169673213055;20876.373708788724;5579.553635709154;5855.564081356058;6071.271447426293;6115.357887421824;6119.663418512002;5868.618539794899;6037.2151620289305;6115.480184184327;6124.050772130327;6124.1732366130445;5909.995300798853;5965.224520812289;5966.522678185735;5970.283565972068;5975.611585246967;5813.1979086483325;5900.586958912878;5969.164316995602;5970.074638431029;5981.790637327673;5378.892422151535;5392.460753924615;5400.891982160374;5401.891962160774;5402.837914862561;24126.638100428507;24796.3800904977;25024.874875625686;25060.49879002428;25079.373015674573;2561.257235401731;2668.533006723835;2737.042035287653;2740.438210166911;2969.0053831966316;15472.690546189126;15484.30320635574;15543.844561554353;15703.685926281525;16202.108884011375;2084.395525297677;2144.9463763405884;2148.881811500367;2174.7390313162364;2191.550732099916;53806;78416.60791696006;81131.5943420285;87862;89445;11313.264637798517;11444.313341199555;11543.538258469567;11776.234544754563;11786.233894796811;16708.6658266834;16714.08072555997;16717.749233761504;16718.99686018843;16730.07984560861;9042.954785226031;9125.133112354315;9268.90731092694;9308.767280817898;9356.204722598655;8626.78433039179;8703.695370662123;8721.215090641872;8887.289016878764;9335.813283734424;7007.159140903124;7120.394766444807;7133.7146514139895;7151.4636402269;7246.166690830517;1997.9400617981485;2004.4788355027506;2035.745531808524;2051.9487012824798;2076.946888074528;837.7905523619095;961.4567769210514;999.9750006249902;1052.905238528536;1074.3178081917881;21184.78815211859;21347.893260533907;21477.46306342354;21653.78346216518;21794.346169614935;1077.154433769503;1103.2773533335678;1122.3770807202081;1147.1339138950038;1154.3824054131;11713.1800773945;12315.93842030802;12541.561045363369;12601.495940162291;12604;529718;543139;546512;546723;549584;96773;103483.4825825866;103632.48183759033;104359;107073;1040.1834559870465;1058.666520046183;1068.4711068021224;1085.945702714861;1090.2749671468544;17949.551261218574;18422.723659144856;18553.721694174328;18754;19022.524436889187;45638.77180614076;45947.31079033884;46060.30909536293;47212.527874720814;47671;63699;64953.35046649473;64998.35001649923;65316.67341663261;65715.67142164259;8224.465409748347;8262.752117436487;8329.583520824055;8631.57578999458;8829.64681412762;11417.60038398652;12534.87465125337;12984.545540906023;13163.144395614254;13241.801372979606;11399.145064120059;11480.885191147981;11573.942130289632;11743.88256117428;11760.529578816751;11069.059129974043;11171.608993685182;11586.47860846247;11607.665118511142;11797.587084452003;11636.650900473;11763.941180294043;11873.16887817879;12005.099617528895;12145.757084858431;8201.42590018693;8212.548309842778;8372.372072094495;8591.355648326526;8621.741347759576;8486.0241072275;10778.137748979905;11190.216684831983;11272.549098035986;11646.41767911584 23:18:16 INFO - PROCESS | 6316 | |3;mootools.html;1524.3064405695407;1606.5903194685357;1771.5748220427092;1843.8617103717224;1890.5084677983712;9193.356465047442;10012.799744005115;10043.69868903934;10044.04581564752;10048.196144308456;8468.576571171448;9414.293927955414;9706.66026689066;9734.610615575368;10001.099901008907;2461.273924192363;2503.1489293640157;2505.073122944513;2507.4985002999383;2509.2723110298084;2386.892589833453;2477.2196758021164;2495.962560561593;2501.6497690323367;2503.5118151960373;2203.900824462895;2205.162038425396;2205.614017546931;2392.497575509145;2438.061346381638;2156.1159924430985;2158.8380871434547;2165.317924853666;2165.5560610074895;2203.966940495894;2174.2607513445505;2175.7280339957506;2192.550527141932;2192.967105493419;2194.089452877054;9677.564509597085;9770.951145244228;9775.51122443882;9966.750831229205;10109.191264698806;5609.52319052881;5760.567957403169;5762.250907382056;5763.164341170549;5771.653700777967;3402.1664692150334;3430.519727238189;3613.6747692707518;3618.9276214475826;3619.855205791765;1135.9261647992853;1156.19066653343;1165.1086918507349;1190.7439900421364;1196.748682776618;14265.928670356581;14330.928345358416;14331.355089021019;14337.713245735027;14337.78493322601;4811.4707382187935;4879.292502587176;4921.803127874845;4958.231474121517;5012.949870501248;11961.34212618297;11964.880351196376;11977.041836653047;11981.700457488632;12008.759824803456;5709.172170035405;5711.086226203787;5711.600187986801;5711.914321285184;5712.571557133231;4631.258998560214;5085.4914508548845;5189.040027594907;5222.164453687467;5269.209618557247;4384.714993525411;4447.799849006802;4457.531959144243;4478.820847166076;4738.95261047392;1318.1300341774383;1318.4528420705492;1329.6476433744872;1340.383423625124;1348.8179095821938;1043.3479075577764;1045.8797238317538;1049.8792638846478;1057.349729916096;1060.4220699718671;6314.55798094129;6556.442702370352;6703.095082163943;6835.077264569319;6873.828154296082;433.3088723486035;435.13842591668674;440.15929574513166;441.6025576980743;442.2084468800875;4506.977465112719;4513.729176249435;4543.795529201194;4632.652551058684;4730.905381892343;12241.326727030098;12820.487180512862;12821.166624169586;12839.165454245443;12844.614661560168;11808.70478238051;12268.754624907451;12452.75094498105;12873.356332183354;12903.096783225084;9096.317776166814;9237.117923517415;9246;9363.0168832273;9389.01415351392;6664.966675166593;6723.361280678328;6873.896891546731;6942.687579059056;6977.511574189758;11469.942650286695;11697.415129243334;11833.526658933546;11866;11875.643730688093;11694.2983420995;11739.765204696028;11989.1008174389;11991.880081199077;12086.214396064228;8807.251383632463;8815.515146666994;8875.378723489552;8883.511406872683;8890.73327800167;9638.084381983701;9743.074407931235;9782.41305521671;9800.36297640651;9809.901900980898;8438.53588052663;8520.233179013918;8849.646014159362;8860.734177974671;9031.864522032307;8267.503949763033;8512.70205542803;8547.786305342415;8623.180797824196;8826;7846.17615150401;7942.166072562529;7943.086545047162;7950.920490795018;7964;5701;5777.2200752897825;5872.765089396376;5898.380670029585;5903.498202652822;5774.307083150049;5817.0983497557945;5901.2033375495475;5932.762689492544;6009.489193418608 23:18:16 INFO - PROCESS | 6316 | |4;prototype.html;341.9196488825127;343.70269716695066;351.9929601407972;355.89678993092;364.5825529768415;5434.402215756273;5526.917096243559;5643.520300774434;5674.773009079637;5675.971620141899;5445.401005889349;5525;5527.010276300529;5530;5568.805091821787;4395.6923015388775;4400.845970391045;4403.229434848905;4410.448693913261;4476.507584165739;4152.750834949913;4317.805698743564;4390.178076098431;4451.710638808467;4481.350204220386;4321.61708253359;4327.956720432787;4330.090680956987;4332.371806088116;4344.956550434486;4326.97836510817;4344.522102568731;4362.781860906974;4363.956360436387;4405.867823965286;3778.5465744110884;3831.540215174197;3863.4204869269834;3871.845126194949;3880.5343358796845;5376.677399356051;5390.649607775482;5427.674339539641;5452.391544494344;5481.260029895992;4911.238757992517;4963.1810751226;4964.478729733362;5001.149804533202;5003.474635163292;5686.715664216773;5779.595428320019;5785.2189954356045;5819.79630712923;5828.930729590977;4131.29767939451;4150.709450338478;4153.854615088488;4161.708680392374;4175.91648167035;6083.543734219951;6102.633841969452;6119.571629985903;6155.753769849201;6178.814635560941;4123.134141830189;4307.418498502725;4321.438213032286;4408.575283594075;4410.426644536254;4358.193734159191;4373.322135069069;4383.649308055348;4383.846565370197;4396.340548917688;4994.4755800640405;5003.249512573143;5012.974935125301;5026.748662566857;5029.415734043838;5604.495595396353;5690.487856092889;5825.679587622722;5877.882442351214;5884.411558844081;1473.6536913825244;1496.1247670113105;1496.6108811709034;1499.3103172540536;1504.638886667192;907.6142639378238;949.3829011142813;949.4967667136428;952.8999455057258;955.1517813031805;4994.950050499521;5016.698998060128;5029.421616514074;5042.773075211624;5058.519440653132;5254.553362964114;5268.762905669254;5271.578273738091;5291.523762861284;5300.231466437422;4813.868740845898;4994.825181118644;4995.225740010304;5003.649744517922;5021.84934451967;2764.8064635475725;2793.7485626293324;2808.2027502398482;2811.8734656940487;2833.1925401260955;5403.540699040625;5410.431904650034;5431.51116399518;5444.5916556258435;5467.261919640877;5083.364579427572;5340.279062326614;5340.893182136336;5409.24270602116;5422.593305502103;5426.8371948841595;5448.101063324586;5448.972755136199;5465.278437292241;5477.397486276506;5088.264307062751;5259.39516955555;5338.572914166935;5425.647332923348;5440.945590544044;5026.773795179299;5180.274761533464;5199.4540573239265;5207.6875387476875;5262.657927234717;5335.799445124816;5395.0577791994065;5403;5445.264889239902;5468.671879687232;3796.3879690007257;3865.5168103987003;3865.6134386562244;3908.511436070491;3940.408938659224;5214.897136203953;5282.720995993258;5298.364196296469;5780.826575202751;5878.882422351444;5856.853578660568;5879.764809407576;5900;5900.616459930091;5909.763609455746;5833.79581714638;5871.324797648154;5875.383084776208;5879.647221166743;5918.526517878646;5615;5774.624649397776;5786.826395207982;5794.304683438015;5820.214271073351;5804.564657650609;5845.18167456548;5895.764169433174;5906.844001939347;5908.847774683895;5495.642783219078;5735.164747280997;5737.651651861893;5751.798687045934;5782.306123265067 23:18:16 INFO - PROCESS | 6316 | |5;yui.html;782.9451938364314;827.6689324270291;865.6277800545766;903.8056817784184;951.3863558005085;16553.751693724604;17346;17356.305747770104;17502.562435939108;17513.737293940587;15228.39086436544;15232.923835380807;15297.464588739396;15299.388024479036;15313.693726125472;710.1726488640753;860.5224100624151;860.9053004169537;886.2068448738376;891.4383938118976;879.2658130461041;882.1178821178821;882.6469412235125;882.6645874567687;882.7087061269766;918.4443411735904;918.481058202117;920.7007722490184;921.2491819167357;921.299812142773;734.9118105827281;905.9003509613938;909.5315912305168;918.2424499787702;918.5728636184207;671.8824205763972;676.7496026470237;677.109600874853;677.695037233247;678.1929503890404;15522.456714015068;15576.766348504781;15606.531804045897;15616;15649.217539122998;14260;15632.296546655427;15979.121148336837;16052.919735401249;16112.436064737678;13848.93075534626;14066.226357550331;14154;14168.291585420791;14299.857001429957;4635.675502714811;4637.072585482883;4639.86080417588;4702.083093796711;4727.078219747151;83993;84123.57938210331;85052.14947850505;87079.56460217721;87556.56221718914;12203.521996231031;12423.937880310541;12507.624771256877;12669.873301267055;12787.168834025759;20328.186872524933;21102.2614208502;21201.681974770392;24458.1439649615;24474.14340498074;9175.541222938826;9452.90547094534;9520.143187113194;9604.855927161097;9767.023297670175;4901.754912254444;4901.803927842846;5087.31321271631;5125.436202017775;5196.506331898464;3870.2646497165447;4023.2355852387955;4025.81883815229;4026.3960405939342;4026.4966879140106;630.224823467138;714.1323292199899;716.7957132217397;722.8482018776116;737.6422435118963;724.3372314332327;742.7920182348951;745.668980869146;814.5357146426599;820.7537738678373;10738.463076846127;11065.557377704963;11368.602098926498;11470.942645286721;11564.306141631529;785.1834092543804;794.2454668065361;807.9757607271791;824.3199360527589;837.1210229259253;5334.51989320963;5451.991381594417;5558.972205138948;5622.493975542221;5637.915431268534;100441;103601;104932;121455.39272303582;133277;91686;92441;92799.5360023209;93660.53169734108;97535.51232243935;86722;87078;88303;88812;90003.09996900079;10621.362718236931;10865.945670271598;11095.16786241019;11115.555377784798;11341;52465.7376713114;52847.207291889885;53987.730061349444;54449.18326225189;55371.723141384035;69516.45422885803;76361.61819190868;76568.61715691385;77596.61201693956;77942.6102869482;13670.316484175553;13728.039037267295;13730.51943181984;13882.583522494342;14059.19714558389;14718;14876.18181000012;15656.373745050527;15745.291461884013;16108;10354.741131471774;13152;14280.642983925485;14656;15628.4530041448;15405.614859628151;15597.142157181464;15772.842271577138;15795.21023948821;15815.920920395325;13533.526326578523;13688.384022719201;14020.369083391475;14073.085249458753;14713;11401.315921044765;11632.53469861196;12769.872301276868;12783.105182637126;12914.096013279355;12230.694232644255;12310.876891230973;12495.187812792139;12515.374231288217;12540.24758514492 23:18:16 INFO - PROCESS | 6316 | __end_tp_report 23:18:16 INFO - PROCESS | 6316 | __start_cc_report 23:18:16 INFO - PROCESS | 6316 | _x_x_mozilla_cycle_collect,967 23:18:16 INFO - PROCESS | 6316 | __end_cc_report 23:18:16 INFO - PROCESS | 6316 | __startTimestamp1472105896319__endTimestamp 23:18:16 INFO - PROCESS | 6316 | 23:18:16 INFO - PROCESS | 6316 | ------- Summary: start ------- 23:18:16 INFO - PROCESS | 6316 | Number of tests: 6 23:18:16 INFO - PROCESS | 6316 | 23:18:16 INFO - PROCESS | 6316 | [#0] dojo.html Cycles:165 Average:5707.85 Median:5892.20 stddev:644.83 (10.9%) stddev-sans-first:618.65 23:18:16 INFO - PROCESS | 6316 | Values: 3305.6 3326.2 3333.5 3360.6 3378.6 5640.0 5716.7 5739.1 5743.5 5751.3 5704.5 5778.9 5780.3 5783.9 5796.1 5091.8 5229.6 5280.8 5300.2 5341.0 5145.4 5231.7 5265.5 5287.0 5287.3 5190.6 5242.6 5269.7 5277.3 5289.5 5327.1 5339.0 5345.3 5353.8 5382.4 5074.1 5138.0 5149.7 5252.0 5253.2 5623.9 5631.0 5639.2 5656.7 5662.0 6160.4 6170.9 6171.1 6172.4 6173.5 6011.4 6082.4 6133.8 6147.7 6205.8 5808.3 5815.5 5828.5 5829.2 5834.7 6286.9 6330.5 6336.2 6343.5 6343.7 5825.5 5914.2 5952.8 5976.7 6063.7 6105.5 6110.8 6115.9 6116.2 6118.3 6069.3 6070.2 6071.0 6080.5 6099.6 5903.7 5936.0 6027.4 6063.6 6084.1 4646.7 4677.8 4681.8 4683.2 4694.0 4162.8 4175.7 4182.1 4205.4 4206.9 5606.7 5774.2 5802.1 5816.3 5893.7 6238.5 6239.0 6240.8 6241.3 6241.9 5840.0 5840.2 5850.3 5851.7 5877.4 5167.5 5235.0 5257.8 5265.7 5346.3 6303.8 6312.1 6344.2 6346.2 6358.0 6171.2 6175.6 6178.3 6185.9 6186.5 6069.2 6288.9 6305.8 6336.1 6429.4 6110.7 6112.4 6113.6 6114.8 6118.7 6224.1 6230.0 6234.2 6234.7 6237.2 5856.9 6004.3 6008.5 6032.8 6125.4 6038.2 6076.7 6088.5 6090.4 6091.3 5961.5 6014.9 6029.4 6030.9 6031.6 5835.9 5892.2 5967.9 5989.5 6040.9 5860.8 5864.2 5867.1 5868.3 5875.7 23:18:16 INFO - PROCESS | 6316 | 23:18:16 INFO - PROCESS | 6316 | [#1] ext.html Cycles:180 Average:66176.92 Median:230371.70 stddev:131042.41 (56.9%) stddev-sans-first:131317.55 23:18:16 INFO - PROCESS | 6316 | Values: 611.9 639.6 647.9 650.3 653.4 33388.5 46753.5 47796.5 47870.5 47997.0 49686.5 50091.7 50117.5 50190.5 50245.5 8129.7 9268.4 9382.3 9395.6 9406.2 8091.6 8380.1 11062.2 11267.1 11274.8 11550.5 11604.7 11615.6 11616.3 11647.6 11311.8 11330.3 11336.5 11344.5 11347.1 11210.6 11270.4 11270.7 11288.2 11294.9 46068.3 46203.0 46277.5 46303.8 46373.8 3837.6 3845.7 4043.3 4231.3 4589.5 35174.6 35473.1 35561.6 35829.8 36541.5 4010.0 4502.3 4506.3 4580.9 4640.3 390664.0 391262.0 391305.0 391660.0 392586.0 63090.4 63268.4 63382.0 63462.0 63469.4 39692.4 40125.2 40333.6 42580.1 65996.3 18830.3 19048.5 19062.8 19064.5 19086.9 12528.9 14441.6 14513.5 14574.1 14588.6 19888.8 20963.7 20992.8 21059.5 21114.7 7617.2 7654.2 7693.5 7840.9 7942.1 1013.6 1128.4 1132.6 1161.5 1163.4 34242.5 35277.8 35285.8 35374.0 35435.8 1199.4 1231.4 1235.1 1249.8 1251.7 17683.2 18488.2 18489.0 18495.4 18573.2 603381.0 609857.0 611068.0 612005.0 612482.0 63431.7 64069.4 64087.7 64104.0 64114.7 1294.9 1417.1 1418.6 1464.0 1476.2 33493.2 35638.8 35927.6 35982.8 36148.5 54413.0 54847.2 55030.7 55050.4 55060.7 420209.0 421931.0 423299.0 423357.0 570620.0 44388.3 47368.3 47594.8 48075.0 48997.3 52159.0 52426.5 52651.7 53092.7 53470.7 44498.3 44562.8 45267.3 46375.0 49353.0 45299.3 46174.0 46184.0 46255.3 47109.8 45058.8 45556.5 46067.8 46182.8 46249.8 37714.0 39783.2 39807.9 39962.8 40534.4 29559.7 29779.7 29849.4 30019.7 30607.4 23:18:16 INFO - PROCESS | 6316 | 23:18:16 INFO - PROCESS | 6316 | [#2] jquery.html Cycles:180 Average:31613.05 Median:24461.51 stddev:89483.61 (365.8%) stddev-sans-first:89703.91 23:18:16 INFO - PROCESS | 6316 | Values: 379.4 420.1 433.0 433.1 434.4 15624.5 20860.0 21192.7 21726.2 21749.8 20716.0 20729.8 20742.8 20758.2 20876.4 5579.6 5855.6 6071.3 6115.4 6119.7 5868.6 6037.2 6115.5 6124.1 6124.2 5910.0 5965.2 5966.5 5970.3 5975.6 5813.2 5900.6 5969.2 5970.1 5981.8 5378.9 5392.5 5400.9 5401.9 5402.8 24126.6 24796.4 25024.9 25060.5 25079.4 2561.3 2668.5 2737.0 2740.4 2969.0 15472.7 15484.3 15543.8 15703.7 16202.1 2084.4 2144.9 2148.9 2174.7 2191.6 53806.0 78416.6 81131.6 87862.0 89445.0 11313.3 11444.3 11543.5 11776.2 11786.2 16708.7 16714.1 16717.7 16719.0 16730.1 9043.0 9125.1 9268.9 9308.8 9356.2 8626.8 8703.7 8721.2 8887.3 9335.8 7007.2 7120.4 7133.7 7151.5 7246.2 1997.9 2004.5 2035.7 2051.9 2076.9 837.8 961.5 1000.0 1052.9 1074.3 21184.8 21347.9 21477.5 21653.8 21794.3 1077.2 1103.3 1122.4 1147.1 1154.4 11713.2 12315.9 12541.6 12601.5 12604.0 529718.0 543139.0 546512.0 546723.0 549584.0 96773.0 103483.5 103632.5 104359.0 107073.0 1040.2 1058.7 1068.5 1085.9 1090.3 17949.6 18422.7 18553.7 18754.0 19022.5 45638.8 45947.3 46060.3 47212.5 47671.0 63699.0 64953.4 64998.4 65316.7 65715.7 8224.5 8262.8 8329.6 8631.6 8829.6 11417.6 12534.9 12984.5 13163.1 13241.8 11399.1 11480.9 11573.9 11743.9 11760.5 11069.1 11171.6 11586.5 11607.7 11797.6 11636.7 11763.9 11873.2 12005.1 12145.8 8201.4 8212.5 8372.4 8591.4 8621.7 8486.0 10778.1 11190.2 11272.5 11646.4 23:18:16 INFO - PROCESS | 6316 | 23:18:16 INFO - PROCESS | 6316 | [#3] mootools.html Cycles:180 Average:6500.70 Median:441.91 stddev:3899.35 (882.4%) stddev-sans-first:3892.36 23:18:16 INFO - PROCESS | 6316 | Values: 1524.3 1606.6 1771.6 1843.9 1890.5 9193.4 10012.8 10043.7 10044.0 10048.2 8468.6 9414.3 9706.7 9734.6 10001.1 2461.3 2503.1 2505.1 2507.5 2509.3 2386.9 2477.2 2496.0 2501.6 2503.5 2203.9 2205.2 2205.6 2392.5 2438.1 2156.1 2158.8 2165.3 2165.6 2204.0 2174.3 2175.7 2192.6 2193.0 2194.1 9677.6 9771.0 9775.5 9966.8 10109.2 5609.5 5760.6 5762.3 5763.2 5771.7 3402.2 3430.5 3613.7 3618.9 3619.9 1135.9 1156.2 1165.1 1190.7 1196.7 14265.9 14330.9 14331.4 14337.7 14337.8 4811.5 4879.3 4921.8 4958.2 5012.9 11961.3 11964.9 11977.0 11981.7 12008.8 5709.2 5711.1 5711.6 5711.9 5712.6 4631.3 5085.5 5189.0 5222.2 5269.2 4384.7 4447.8 4457.5 4478.8 4739.0 1318.1 1318.5 1329.6 1340.4 1348.8 1043.3 1045.9 1049.9 1057.3 1060.4 6314.6 6556.4 6703.1 6835.1 6873.8 433.3 435.1 440.2 441.6 442.2 4507.0 4513.7 4543.8 4632.7 4730.9 12241.3 12820.5 12821.2 12839.2 12844.6 11808.7 12268.8 12452.8 12873.4 12903.1 9096.3 9237.1 9246.0 9363.0 9389.0 6665.0 6723.4 6873.9 6942.7 6977.5 11469.9 11697.4 11833.5 11866.0 11875.6 11694.3 11739.8 11989.1 11991.9 12086.2 8807.3 8815.5 8875.4 8883.5 8890.7 9638.1 9743.1 9782.4 9800.4 9809.9 8438.5 8520.2 8849.6 8860.7 9031.9 8267.5 8512.7 8547.8 8623.2 8826.0 7846.2 7942.2 7943.1 7950.9 7964.0 5701.0 5777.2 5872.8 5898.4 5903.5 5774.3 5817.1 5901.2 5932.8 6009.5 23:18:16 INFO - PROCESS | 6316 | 23:18:16 INFO - PROCESS | 6316 | [#4] prototype.html Cycles:180 Average:4716.36 Median:5270.17 stddev:1358.32 (25.8%) stddev-sans-first:1321.85 23:18:16 INFO - PROCESS | 6316 | Values: 341.9 343.7 352.0 355.9 364.6 5434.4 5526.9 5643.5 5674.8 5676.0 5445.4 5525.0 5527.0 5530.0 5568.8 4395.7 4400.8 4403.2 4410.4 4476.5 4152.8 4317.8 4390.2 4451.7 4481.4 4321.6 4328.0 4330.1 4332.4 4345.0 4327.0 4344.5 4362.8 4364.0 4405.9 3778.5 3831.5 3863.4 3871.8 3880.5 5376.7 5390.6 5427.7 5452.4 5481.3 4911.2 4963.2 4964.5 5001.1 5003.5 5686.7 5779.6 5785.2 5819.8 5828.9 4131.3 4150.7 4153.9 4161.7 4175.9 6083.5 6102.6 6119.6 6155.8 6178.8 4123.1 4307.4 4321.4 4408.6 4410.4 4358.2 4373.3 4383.6 4383.8 4396.3 4994.5 5003.2 5013.0 5026.7 5029.4 5604.5 5690.5 5825.7 5877.9 5884.4 1473.7 1496.1 1496.6 1499.3 1504.6 907.6 949.4 949.5 952.9 955.2 4995.0 5016.7 5029.4 5042.8 5058.5 5254.6 5268.8 5271.6 5291.5 5300.2 4813.9 4994.8 4995.2 5003.6 5021.8 2764.8 2793.7 2808.2 2811.9 2833.2 5403.5 5410.4 5431.5 5444.6 5467.3 5083.4 5340.3 5340.9 5409.2 5422.6 5426.8 5448.1 5449.0 5465.3 5477.4 5088.3 5259.4 5338.6 5425.6 5440.9 5026.8 5180.3 5199.5 5207.7 5262.7 5335.8 5395.1 5403.0 5445.3 5468.7 3796.4 3865.5 3865.6 3908.5 3940.4 5214.9 5282.7 5298.4 5780.8 5878.9 5856.9 5879.8 5900.0 5900.6 5909.8 5833.8 5871.3 5875.4 5879.6 5918.5 5615.0 5774.6 5786.8 5794.3 5820.2 5804.6 5845.2 5895.8 5906.8 5908.8 5495.6 5735.2 5737.7 5751.8 5782.3 23:18:16 INFO - PROCESS | 6316 | 23:18:16 INFO - PROCESS | 6316 | [#5] yui.html Cycles:180 Average:21604.99 Median:4636.37 stddev:30008.03 (647.2%) stddev-sans-first:30051.48 23:18:16 INFO - PROCESS | 6316 | Values: 782.9 827.7 865.6 903.8 951.4 16553.8 17346.0 17356.3 17502.6 17513.7 15228.4 15232.9 15297.5 15299.4 15313.7 710.2 860.5 860.9 886.2 891.4 879.3 882.1 882.6 882.7 882.7 918.4 918.5 920.7 921.2 921.3 734.9 905.9 909.5 918.2 918.6 671.9 676.7 677.1 677.7 678.2 15522.5 15576.8 15606.5 15616.0 15649.2 14260.0 15632.3 15979.1 16052.9 16112.4 13848.9 14066.2 14154.0 14168.3 14299.9 4635.7 4637.1 4639.9 4702.1 4727.1 83993.0 84123.6 85052.1 87079.6 87556.6 12203.5 12423.9 12507.6 12669.9 12787.2 20328.2 21102.3 21201.7 24458.1 24474.1 9175.5 9452.9 9520.1 9604.9 9767.0 4901.8 4901.8 5087.3 5125.4 5196.5 3870.3 4023.2 4025.8 4026.4 4026.5 630.2 714.1 716.8 722.8 737.6 724.3 742.8 745.7 814.5 820.8 10738.5 11065.6 11368.6 11470.9 11564.3 785.2 794.2 808.0 824.3 837.1 5334.5 5452.0 5559.0 5622.5 5637.9 100441.0 103601.0 104932.0 121455.4 133277.0 91686.0 92441.0 92799.5 93660.5 97535.5 86722.0 87078.0 88303.0 88812.0 90003.1 10621.4 10865.9 11095.2 11115.6 11341.0 52465.7 52847.2 53987.7 54449.2 55371.7 69516.5 76361.6 76568.6 77596.6 77942.6 13670.3 13728.0 13730.5 13882.6 14059.2 14718.0 14876.2 15656.4 15745.3 16108.0 10354.7 13152.0 14280.6 14656.0 15628.5 15405.6 15597.1 15772.8 15795.2 15815.9 13533.5 13688.4 14020.4 14073.1 14713.0 11401.3 11632.5 12769.9 12783.1 12914.1 12230.7 12310.9 12495.2 12515.4 12540.2 23:18:16 INFO - PROCESS | 6316 | -------- Summary: end -------- 23:18:16 INFO - PROCESS | 6316 | 23:18:17 INFO - TEST-INFO | 6316: exit 0 23:18:17 INFO - TEST-OK | dromaeo_css | took 1112834ms 23:18:17 INFO - TEST-START | kraken 23:18:17 INFO - operating with platform_type : linux_ 23:18:17 INFO - Initialising browser for kraken test... 23:18:17 INFO - TEST-INFO | started process 6523 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQsK9am/profile http://localhost:53411/getInfo.html) 23:18:20 INFO - PROCESS | 6523 | __metrics Screen width/height:1600/1200 23:18:20 INFO - PROCESS | 6523 | colorDepth:24 23:18:20 INFO - PROCESS | 6523 | Browser inner width/height: 1024/697 23:18:20 INFO - PROCESS | 6523 | __metrics 23:18:21 INFO - PROCESS | 6523 | 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" 23:18:21 INFO - PROCESS | 6523 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:18:22 INFO - TEST-INFO | 6523: exit 0 23:18:22 INFO - Browser initialized. 23:18:22 INFO - Running cycle 1/1 for kraken test... 23:18:22 INFO - TEST-INFO | started process 6612 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQsK9am/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:18:34 INFO - PROCESS | 6612 | RSS: Main: 144728064 23:18:34 INFO - PROCESS | 6612 | 23:18:36 INFO - PROCESS | 6612 | Cycle 1(1): loaded http://localhost:53411/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53411/tests/kraken/driver.html?testName=audio-beat-detection) 23:18:37 INFO - PROCESS | 6612 | RSS: Main: 191500288 23:18:37 INFO - PROCESS | 6612 | 23:18:40 INFO - PROCESS | 6612 | Cycle 1(1): loaded http://localhost:53411/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53411/tests/kraken/driver.html?testName=audio-dft) 23:18:40 INFO - PROCESS | 6612 | RSS: Main: 181563392 23:18:40 INFO - PROCESS | 6612 | 23:18:43 INFO - PROCESS | 6612 | Cycle 1(1): loaded http://localhost:53411/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53411/tests/kraken/driver.html?testName=audio-fft) 23:18:44 INFO - PROCESS | 6612 | RSS: Main: 270614528 23:18:44 INFO - PROCESS | 6612 | 23:18:46 INFO - PROCESS | 6612 | Cycle 1(1): loaded http://localhost:53411/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53411/tests/kraken/driver.html?testName=audio-oscillator) 23:18:46 INFO - PROCESS | 6612 | RSS: Main: 174911488 23:18:46 INFO - PROCESS | 6612 | 23:18:49 INFO - PROCESS | 6612 | Cycle 1(1): loaded http://localhost:53411/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53411/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:18:49 INFO - PROCESS | 6612 | RSS: Main: 176316416 23:18:49 INFO - PROCESS | 6612 | 23:18:52 INFO - PROCESS | 6612 | Cycle 1(1): loaded http://localhost:53411/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53411/tests/kraken/driver.html?testName=imaging-darkroom) 23:18:53 INFO - PROCESS | 6612 | RSS: Main: 213184512 23:18:53 INFO - PROCESS | 6612 | 23:18:56 INFO - PROCESS | 6612 | Cycle 1(1): loaded http://localhost:53411/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53411/tests/kraken/driver.html?testName=imaging-desaturate) 23:18:57 INFO - PROCESS | 6612 | RSS: Main: 211488768 23:18:57 INFO - PROCESS | 6612 | 23:19:00 INFO - PROCESS | 6612 | Cycle 1(1): loaded http://localhost:53411/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53411/tests/kraken/driver.html?testName=json-parse-financial) 23:19:00 INFO - PROCESS | 6612 | RSS: Main: 211603456 23:19:00 INFO - PROCESS | 6612 | 23:19:02 INFO - PROCESS | 6612 | Cycle 1(1): loaded http://localhost:53411/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53411/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:19:02 INFO - PROCESS | 6612 | RSS: Main: 175697920 23:19:02 INFO - PROCESS | 6612 | 23:19:04 INFO - PROCESS | 6612 | Cycle 1(1): loaded http://localhost:53411/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53411/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:19:05 INFO - PROCESS | 6612 | RSS: Main: 180113408 23:19:05 INFO - PROCESS | 6612 | 23:19:06 INFO - PROCESS | 6612 | Cycle 1(1): loaded http://localhost:53411/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53411/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:19:07 INFO - PROCESS | 6612 | RSS: Main: 184209408 23:19:07 INFO - PROCESS | 6612 | 23:19:10 INFO - PROCESS | 6612 | Cycle 1(1): loaded http://localhost:53411/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53411/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:19:10 INFO - PROCESS | 6612 | RSS: Main: 180506624 23:19:10 INFO - PROCESS | 6612 | 23:19:13 INFO - PROCESS | 6612 | Cycle 1(1): loaded http://localhost:53411/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53411/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:19:13 INFO - PROCESS | 6612 | RSS: Main: 184254464 23:19:13 INFO - PROCESS | 6612 | 23:19:15 INFO - PROCESS | 6612 | Cycle 1(1): loaded http://localhost:53411/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53411/tests/kraken/driver.html?testName=ai-astar) 23:19:15 INFO - PROCESS | 6612 | RSS: Main: 235352064 23:19:15 INFO - PROCESS | 6612 | 23:19:15 INFO - PROCESS | 6612 | __start_tp_report 23:19:15 INFO - PROCESS | 6612 | _x_x_mozilla_page_load 23:19:15 INFO - PROCESS | 6612 | _x_x_mozilla_page_load_details 23:19:15 INFO - PROCESS | 6612 | |i|pagename|runs| 23:19:15 INFO - PROCESS | 6612 | |0;ai-astar;102;110;111;110;112;111;109;110;109;110 23:19:15 INFO - PROCESS | 6612 | |1;audio-beat-detection;148;115;116;177;115;119;138;113;155;115 23:19:15 INFO - PROCESS | 6612 | |2;audio-dft;159;162;162;162;159;162;160;160;158;168 23:19:15 INFO - PROCESS | 6612 | |3;audio-fft;75;75;75;120;76;76;76;74;101;74 23:19:15 INFO - PROCESS | 6612 | |4;audio-oscillator;82;83;128;87;83;83;110;83;84;127 23:19:15 INFO - PROCESS | 6612 | |5;imaging-gaussian-blur;117;117;117;117;117;116;117;116;116;117 23:19:15 INFO - PROCESS | 6612 | |6;imaging-darkroom;146;146;146;147;145;146;146;147;146;145 23:19:15 INFO - PROCESS | 6612 | |7;imaging-desaturate;95;95;95;94;94;94;95;95;94;94 23:19:15 INFO - PROCESS | 6612 | |8;json-parse-financial;53;49;50;51;50;51;53;51;50;51 23:19:15 INFO - PROCESS | 6612 | |9;json-stringify-tinderbox;59;59;59;59;59;58;59;59;60;59 23:19:15 INFO - PROCESS | 6612 | |10;stanford-crypto-aes;72;72;72;80;72;71;77;73;73;72 23:19:15 INFO - PROCESS | 6612 | |11;stanford-crypto-ccm;118;114;116;118;116;123;123;131;163;116 23:19:15 INFO - PROCESS | 6612 | |12;stanford-crypto-pbkdf2;156;151;161;159;156;153;154;155;152;150 23:19:15 INFO - PROCESS | 6612 | |13;stanford-crypto-sha256-iterative;61;62;63;62;61;60;60;61;60;60 23:19:15 INFO - PROCESS | 6612 | __end_tp_report 23:19:15 INFO - PROCESS | 6612 | __start_cc_report 23:19:15 INFO - PROCESS | 6612 | _x_x_mozilla_cycle_collect,1650 23:19:15 INFO - PROCESS | 6612 | __end_cc_report 23:19:15 INFO - PROCESS | 6612 | __startTimestamp1472105955859__endTimestamp 23:19:15 INFO - PROCESS | 6612 | 23:19:15 INFO - PROCESS | 6612 | ------- Summary: start ------- 23:19:15 INFO - PROCESS | 6612 | Number of tests: 14 23:19:15 INFO - PROCESS | 6612 | 23:19:15 INFO - PROCESS | 6612 | [#0] ai-astar Cycles:10 Average:109.40 Median:110.00 stddev:2.76 (2.5%) stddev-sans-first:0.97 23:19:15 INFO - PROCESS | 6612 | Values: 102.0 110.0 111.0 110.0 112.0 111.0 109.0 110.0 109.0 110.0 23:19:15 INFO - PROCESS | 6612 | 23:19:15 INFO - PROCESS | 6612 | [#1] audio-beat-detection Cycles:10 Average:131.10 Median:128.50 stddev:22.34 (17.4%) stddev-sans-first:22.84 23:19:15 INFO - PROCESS | 6612 | Values: 148.0 115.0 116.0 177.0 115.0 119.0 138.0 113.0 155.0 115.0 23:19:15 INFO - PROCESS | 6612 | 23:19:15 INFO - PROCESS | 6612 | [#2] audio-dft Cycles:10 Average:161.20 Median:162.00 stddev:2.82 (1.7%) stddev-sans-first:2.88 23:19:15 INFO - PROCESS | 6612 | Values: 159.0 162.0 162.0 162.0 159.0 162.0 160.0 160.0 158.0 168.0 23:19:15 INFO - PROCESS | 6612 | 23:19:15 INFO - PROCESS | 6612 | [#3] audio-fft Cycles:10 Average:82.20 Median:75.00 stddev:15.59 (20.8%) stddev-sans-first:16.32 23:19:15 INFO - PROCESS | 6612 | Values: 75.0 75.0 75.0 120.0 76.0 76.0 76.0 74.0 101.0 74.0 23:19:15 INFO - PROCESS | 6612 | 23:19:15 INFO - PROCESS | 6612 | [#4] audio-oscillator Cycles:10 Average:95.00 Median:83.00 stddev:19.06 (23.0%) stddev-sans-first:19.62 23:19:15 INFO - PROCESS | 6612 | Values: 82.0 83.0 128.0 87.0 83.0 83.0 110.0 83.0 84.0 127.0 23:19:15 INFO - PROCESS | 6612 | 23:19:15 INFO - PROCESS | 6612 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 23:19:15 INFO - PROCESS | 6612 | Values: 117.0 117.0 117.0 117.0 117.0 116.0 117.0 116.0 116.0 117.0 23:19:15 INFO - PROCESS | 6612 | 23:19:15 INFO - PROCESS | 6612 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 23:19:15 INFO - PROCESS | 6612 | Values: 146.0 146.0 146.0 147.0 145.0 146.0 146.0 147.0 146.0 145.0 23:19:15 INFO - PROCESS | 6612 | 23:19:15 INFO - PROCESS | 6612 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:95.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 23:19:15 INFO - PROCESS | 6612 | Values: 95.0 95.0 95.0 94.0 94.0 94.0 95.0 95.0 94.0 94.0 23:19:15 INFO - PROCESS | 6612 | 23:19:15 INFO - PROCESS | 6612 | [#8] json-parse-financial Cycles:10 Average:50.90 Median:51.00 stddev:1.29 (2.5%) stddev-sans-first:1.12 23:19:15 INFO - PROCESS | 6612 | Values: 53.0 49.0 50.0 51.0 50.0 51.0 53.0 51.0 50.0 51.0 23:19:15 INFO - PROCESS | 6612 | 23:19:15 INFO - PROCESS | 6612 | [#9] json-stringify-tinderbox Cycles:10 Average:59.00 Median:59.00 stddev:0.47 (0.8%) stddev-sans-first:0.50 23:19:15 INFO - PROCESS | 6612 | Values: 59.0 59.0 59.0 59.0 59.0 58.0 59.0 59.0 60.0 59.0 23:19:15 INFO - PROCESS | 6612 | 23:19:15 INFO - PROCESS | 6612 | [#10] stanford-crypto-aes Cycles:10 Average:73.40 Median:72.50 stddev:2.84 (3.9%) stddev-sans-first:2.96 23:19:15 INFO - PROCESS | 6612 | Values: 72.0 72.0 72.0 80.0 72.0 71.0 77.0 73.0 73.0 72.0 23:19:15 INFO - PROCESS | 6612 | 23:19:15 INFO - PROCESS | 6612 | [#11] stanford-crypto-ccm Cycles:10 Average:123.80 Median:120.50 stddev:14.67 (12.2%) stddev-sans-first:15.40 23:19:15 INFO - PROCESS | 6612 | Values: 118.0 114.0 116.0 118.0 116.0 123.0 123.0 131.0 163.0 116.0 23:19:15 INFO - PROCESS | 6612 | 23:19:15 INFO - PROCESS | 6612 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.70 Median:155.50 stddev:3.47 (2.2%) stddev-sans-first:3.64 23:19:15 INFO - PROCESS | 6612 | Values: 156.0 151.0 161.0 159.0 156.0 153.0 154.0 155.0 152.0 150.0 23:19:15 INFO - PROCESS | 6612 | 23:19:15 INFO - PROCESS | 6612 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.00 Median:61.00 stddev:1.05 (1.7%) stddev-sans-first:1.12 23:19:15 INFO - PROCESS | 6612 | Values: 61.0 62.0 63.0 62.0 61.0 60.0 60.0 61.0 60.0 60.0 23:19:15 INFO - PROCESS | 6612 | -------- Summary: end -------- 23:19:15 INFO - PROCESS | 6612 | 23:19:16 INFO - TEST-INFO | 6612: exit 0 23:19:16 INFO - TEST-OK | kraken | took 59770ms 23:19:16 INFO - SUITE-END | took 1172s 23:19:17 INFO - Completed test suite (00:19:32) 23:19:17 INFO - javascript benchmark 23:19:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3305.5702758641382, 3326.2017115892195, 3333.483310086936, 3360.6303306636282, 3378.5945686517607, 5639.971800140998, 5716.657000579969, 5739.053056245714, 5743.483086522212, 5751.309842818868, 5704.48659620634, 5778.855528611787, 5780.277465316835, 5783.913241301373, 5796.130580412941, 5091.84724458265, 5229.607779416549, 5280.815171468999, 5300.204969254604, 5341.03861304965, 5145.3568303962, 5231.738413079331, 5265.473452654742, 5287.048331300383, 5287.259783630276, 5190.610704197182, 5242.580593552507, 5269.683818970855, 5277.340332458442, 5289.471052894718, 5327.121025030865, 5339.0, 5345.33183352081, 5353.785848566053, 5382.381026182, 5074.086664400406, 5138.049460849717, 5149.6910185389, 5251.973740131275, 5253.185756207794, 5623.8594035149035, 5630.971845140789, 5639.15412688096, 5656.688882111483, 5662.037453632893, 6160.353162917874, 6170.876582468325, 6171.074338849164, 6172.444479996821, 6173.47525460332, 6011.398860113954, 6082.422169893853, 6133.754649814002, 6147.72335244915, 6205.7517699291975, 5808.332041815203, 5815.476607105381, 5828.475437210672, 5829.242198514208, 5834.708264586754, 6286.874262514724, 6330.493560515148, 6336.239651241881, 6343.52423568225, 6343.682815859189, 5825.504832089235, 5914.2311499504785, 5952.821415357546, 5976.701164941735, 6063.696815159225, 6105.45050945417, 6110.7555697771595, 6115.938840611626, 6116.235470566179, 6118.296395914527, 6069.302030266487, 6070.2412198475195, 6070.969645151746, 6080.543959203077, 6099.573029887865, 5903.675297858659, 5936.020556608111, 6027.36712645166, 6063.605865618721, 6084.087386892002, 4646.6747327686735, 4677.789499472464, 4681.789319480631, 4683.15703173432, 4694.0, 4162.8334866605, 4175.707700460938, 4182.079942412664, 4205.432266643964, 4206.915861682749, 5606.6916319602815, 5774.162746401748, 5802.100674395391, 5816.272965879265, 5893.705314734246, 6238.500919926395, 6239.032949892774, 6240.7503699851495, 6241.313455519889, 6241.937580624136, 5839.970800146057, 5840.240768700041, 5850.268716410449, 5851.736671849777, 5877.441643043904, 5167.457416971239, 5235.031519168966, 5257.7896884124975, 5265.736713164327, 5346.278252435872, 6303.779367722108, 6312.05390964559, 6344.206974128234, 6346.174997250328, 6358.046293056079, 6171.197744293393, 6175.5677102602385, 6178.320384757583, 6185.938140618537, 6186.4741496973265, 6069.180660610938, 6288.937110629018, 6305.8108256752375, 6336.144620476177, 6429.38920802523, 6110.7250173741395, 6112.419320164578, 6113.602615830135, 6114.785982490592, 6118.724657390339, 6224.06639004153, 6230.034344676583, 6234.189555357774, 6234.688265586612, 6237.220347456568, 5856.94143058564, 6004.339522652417, 6008.489278411384, 6032.849178770566, 6125.418085281891, 6038.154658347747, 6076.72654730547, 6088.452039316483, 6090.421409966046, 6091.299500557316, 5961.463468287875, 6014.879702405839, 6029.397060293935, 6030.939690603213, 6031.577789554689, 5835.94164058354, 5892.204552385373, 5967.910481342697, 5989.460948514655, 6040.939590604214, 5860.765569377178, 5864.208331875313, 5867.061270196748, 5868.2664666916635, 5875.735591898289], "value": 5664.49546403078, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [611.9479844213241, 639.6290151712006, 647.9319671434499, 650.281439009894, 653.4282502810041, 33388.4991725124, 46753.53246467534, 47796.52203477964, 47870.52129478704, 47996.999999999956, 49686.50313496864, 50091.749541252335, 50117.49882501174, 50190.49809501904, 50245.49754502463, 8129.674813007473, 9268.443893366402, 9382.29632777541, 9395.577199026025, 9406.15344618984, 8091.554964476952, 8380.078191398941, 11062.22564420491, 11267.098632109453, 11274.830877536844, 11550.53797848085, 11604.6518604442, 11615.593454229103, 11616.303021818718, 11647.592334268302, 11311.773764524705, 11330.263532870336, 11336.546538138464, 11344.48949797261, 11347.148963827745, 11210.551577936954, 11270.380129092897, 11270.661880143609, 11288.209825312231, 11294.887051129548, 46068.3089753654, 46203.0, 46277.53722462766, 46303.76848115738, 46373.76813115913, 3837.5586807517216, 3845.713973158554, 4043.292423825819, 4231.259529582341, 4589.541045895384, 35174.64825351765, 35473.11317217064, 35561.64438355609, 35829.82085089584, 36541.45187822185, 4009.959900400988, 4502.3246513023305, 4506.324051392285, 4580.862574122748, 4640.327152562893, 390664.0, 391262.04368978256, 391305.0, 391660.0, 392586.0, 63090.36909630937, 63268.36731632625, 63382.0, 63462.0, 63469.36530634634, 39692.40461393081, 40125.197496049914, 40333.596664032986, 42580.14839703189, 65996.34003659998, 18830.3409380671, 19048.52378690544, 19062.80937190638, 19064.52338691516, 19086.904565477085, 12528.87471125277, 14441.638959026108, 14513.492027778977, 14574.052686575338, 14588.635284117981, 19888.801111988694, 20963.685544716842, 20992.790072099084, 21059.473513162295, 21114.683279750818, 7617.238276172338, 7654.192723422078, 7693.538387696756, 7840.882386764203, 7942.126366099725, 1013.5641674080216, 1128.3568366031284, 1132.592266783957, 1161.5353858456515, 1163.4299193395345, 34242.48636270458, 35277.8236108823, 35285.823570882494, 35374.0, 35435.82282088573, 1199.3943058755274, 1231.4335405713473, 1235.0860363331071, 1249.7761149475482, 1251.6808213905579, 17683.20425580852, 18488.168032438574, 18489.0, 18495.352662657013, 18573.16420761069, 603381.0, 609857.0, 611068.0, 612005.0, 612482.0, 63431.68284158642, 64069.35930640727, 64087.67956160189, 64104.03843942344, 64114.67942660257, 1294.8575656677758, 1417.1284659934272, 1418.6240646228757, 1463.9707205855823, 1476.195473466963, 33493.16267093342, 35638.82180589184, 35927.64072359243, 35982.8200858994, 36148.45777313395, 54413.0, 54847.17729234145, 55030.72484637551, 55050.44949550613, 55060.72469637626, 420209.0, 421931.0, 423299.0, 423357.0, 570620.0, 44388.33417498805, 47368.289475657206, 47594.762026189645, 48075.0, 48997.2650410237, 52159.0, 52426.47573524216, 52651.73674131605, 53092.734536328615, 53470.73264633652, 44498.3325250128, 44562.777186115156, 45267.320990185835, 46375.0, 49353.0, 45299.32051019171, 46174.0, 46184.0, 46255.306170406795, 47109.76445117752, 45058.774706126256, 45556.54443455523, 46067.76966115148, 46182.76908615435, 46249.76875115603, 37714.0, 39783.204335913695, 39807.896486370846, 39962.80018599889, 40534.39198411967, 29559.704402955696, 29779.702202977693, 29849.403011940074, 30019.699803002564, 30607.387852243275], "value": 22324.246553881676, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [379.3529266485552, 420.0901958361649, 432.9805158767857, 433.0861881430182, 434.40703439804656, 15624.531264062081, 20860.0, 21192.682109768364, 21726.239581614645, 21749.782502174992, 20716.0, 20729.792702072973, 20742.792572074275, 20758.169673213055, 20876.373708788724, 5579.553635709154, 5855.564081356058, 6071.271447426293, 6115.357887421824, 6119.663418512002, 5868.618539794899, 6037.2151620289305, 6115.480184184327, 6124.050772130327, 6124.1732366130445, 5909.995300798853, 5965.224520812289, 5966.522678185735, 5970.283565972068, 5975.611585246967, 5813.1979086483325, 5900.586958912878, 5969.164316995602, 5970.074638431029, 5981.790637327673, 5378.892422151535, 5392.460753924615, 5400.891982160374, 5401.891962160774, 5402.837914862561, 24126.638100428507, 24796.3800904977, 25024.874875625686, 25060.49879002428, 25079.373015674573, 2561.257235401731, 2668.533006723835, 2737.042035287653, 2740.438210166911, 2969.0053831966316, 15472.690546189126, 15484.30320635574, 15543.844561554353, 15703.685926281525, 16202.108884011375, 2084.395525297677, 2144.9463763405884, 2148.881811500367, 2174.7390313162364, 2191.550732099916, 53806.0, 78416.60791696006, 81131.5943420285, 87862.0, 89445.0, 11313.264637798517, 11444.313341199555, 11543.538258469567, 11776.234544754563, 11786.233894796811, 16708.6658266834, 16714.08072555997, 16717.749233761504, 16718.99686018843, 16730.07984560861, 9042.954785226031, 9125.133112354315, 9268.90731092694, 9308.767280817898, 9356.204722598655, 8626.78433039179, 8703.695370662123, 8721.215090641872, 8887.289016878764, 9335.813283734424, 7007.159140903124, 7120.394766444807, 7133.7146514139895, 7151.4636402269, 7246.166690830517, 1997.9400617981485, 2004.4788355027506, 2035.745531808524, 2051.9487012824798, 2076.946888074528, 837.7905523619095, 961.4567769210514, 999.9750006249902, 1052.905238528536, 1074.3178081917881, 21184.78815211859, 21347.893260533907, 21477.46306342354, 21653.78346216518, 21794.346169614935, 1077.154433769503, 1103.2773533335678, 1122.3770807202081, 1147.1339138950038, 1154.3824054131, 11713.1800773945, 12315.93842030802, 12541.561045363369, 12601.495940162291, 12604.0, 529718.0, 543139.0, 546512.0, 546723.0, 549584.0, 96773.0, 103483.4825825866, 103632.48183759033, 104359.0, 107073.0, 1040.1834559870465, 1058.666520046183, 1068.4711068021224, 1085.945702714861, 1090.2749671468544, 17949.551261218574, 18422.723659144856, 18553.721694174328, 18754.0, 19022.524436889187, 45638.77180614076, 45947.31079033884, 46060.30909536293, 47212.527874720814, 47671.0, 63699.0, 64953.35046649473, 64998.35001649923, 65316.67341663261, 65715.67142164259, 8224.465409748347, 8262.752117436487, 8329.583520824055, 8631.57578999458, 8829.64681412762, 11417.60038398652, 12534.87465125337, 12984.545540906023, 13163.144395614254, 13241.801372979606, 11399.145064120059, 11480.885191147981, 11573.942130289632, 11743.88256117428, 11760.529578816751, 11069.059129974043, 11171.608993685182, 11586.47860846247, 11607.665118511142, 11797.587084452003, 11636.650900473, 11763.941180294043, 11873.16887817879, 12005.099617528895, 12145.757084858431, 8201.42590018693, 8212.548309842778, 8372.372072094495, 8591.355648326526, 8621.741347759576, 8486.0241072275, 10778.137748979905, 11190.216684831983, 11272.549098035986, 11646.41767911584], "value": 9917.170074436348, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1524.3064405695407, 1606.5903194685357, 1771.5748220427092, 1843.8617103717224, 1890.5084677983712, 9193.356465047442, 10012.799744005115, 10043.69868903934, 10044.04581564752, 10048.196144308456, 8468.576571171448, 9414.293927955414, 9706.66026689066, 9734.610615575368, 10001.099901008907, 2461.273924192363, 2503.1489293640157, 2505.073122944513, 2507.4985002999383, 2509.2723110298084, 2386.892589833453, 2477.2196758021164, 2495.962560561593, 2501.6497690323367, 2503.5118151960373, 2203.900824462895, 2205.162038425396, 2205.614017546931, 2392.497575509145, 2438.061346381638, 2156.1159924430985, 2158.8380871434547, 2165.317924853666, 2165.5560610074895, 2203.966940495894, 2174.2607513445505, 2175.7280339957506, 2192.550527141932, 2192.967105493419, 2194.089452877054, 9677.564509597085, 9770.951145244228, 9775.51122443882, 9966.750831229205, 10109.191264698806, 5609.52319052881, 5760.567957403169, 5762.250907382056, 5763.164341170549, 5771.653700777967, 3402.1664692150334, 3430.519727238189, 3613.6747692707518, 3618.9276214475826, 3619.855205791765, 1135.9261647992853, 1156.19066653343, 1165.1086918507349, 1190.7439900421364, 1196.748682776618, 14265.928670356581, 14330.928345358416, 14331.355089021019, 14337.713245735027, 14337.78493322601, 4811.4707382187935, 4879.292502587176, 4921.803127874845, 4958.231474121517, 5012.949870501248, 11961.34212618297, 11964.880351196376, 11977.041836653047, 11981.700457488632, 12008.759824803456, 5709.172170035405, 5711.086226203787, 5711.600187986801, 5711.914321285184, 5712.571557133231, 4631.258998560214, 5085.4914508548845, 5189.040027594907, 5222.164453687467, 5269.209618557247, 4384.714993525411, 4447.799849006802, 4457.531959144243, 4478.820847166076, 4738.95261047392, 1318.1300341774383, 1318.4528420705492, 1329.6476433744872, 1340.383423625124, 1348.8179095821938, 1043.3479075577764, 1045.8797238317538, 1049.8792638846478, 1057.349729916096, 1060.4220699718671, 6314.55798094129, 6556.442702370352, 6703.095082163943, 6835.077264569319, 6873.828154296082, 433.3088723486035, 435.13842591668674, 440.15929574513166, 441.6025576980743, 442.2084468800875, 4506.977465112719, 4513.729176249435, 4543.795529201194, 4632.652551058684, 4730.905381892343, 12241.326727030098, 12820.487180512862, 12821.166624169586, 12839.165454245443, 12844.614661560168, 11808.70478238051, 12268.754624907451, 12452.75094498105, 12873.356332183354, 12903.096783225084, 9096.317776166814, 9237.117923517415, 9246.0, 9363.0168832273, 9389.01415351392, 6664.966675166593, 6723.361280678328, 6873.896891546731, 6942.687579059056, 6977.511574189758, 11469.942650286695, 11697.415129243334, 11833.526658933546, 11866.0, 11875.643730688093, 11694.2983420995, 11739.765204696028, 11989.1008174389, 11991.880081199077, 12086.214396064228, 8807.251383632463, 8815.515146666994, 8875.378723489552, 8883.511406872683, 8890.73327800167, 9638.084381983701, 9743.074407931235, 9782.41305521671, 9800.36297640651, 9809.901900980898, 8438.53588052663, 8520.233179013918, 8849.646014159362, 8860.734177974671, 9031.864522032307, 8267.503949763033, 8512.70205542803, 8547.786305342415, 8623.180797824196, 8826.0, 7846.17615150401, 7942.166072562529, 7943.086545047162, 7950.920490795018, 7964.0, 5701.0, 5777.2200752897825, 5872.765089396376, 5898.380670029585, 5903.498202652822, 5774.307083150049, 5817.0983497557945, 5901.2033375495475, 5932.762689492544, 6009.489193418608], "value": 4983.498806507638, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [341.9196488825127, 343.70269716695066, 351.9929601407972, 355.89678993092, 364.5825529768415, 5434.402215756273, 5526.917096243559, 5643.520300774434, 5674.773009079637, 5675.971620141899, 5445.401005889349, 5525.0, 5527.010276300529, 5530.0, 5568.805091821787, 4395.6923015388775, 4400.845970391045, 4403.229434848905, 4410.448693913261, 4476.507584165739, 4152.750834949913, 4317.805698743564, 4390.178076098431, 4451.710638808467, 4481.350204220386, 4321.61708253359, 4327.956720432787, 4330.090680956987, 4332.371806088116, 4344.956550434486, 4326.97836510817, 4344.522102568731, 4362.781860906974, 4363.956360436387, 4405.867823965286, 3778.5465744110884, 3831.540215174197, 3863.4204869269834, 3871.845126194949, 3880.5343358796845, 5376.677399356051, 5390.649607775482, 5427.674339539641, 5452.391544494344, 5481.260029895992, 4911.238757992517, 4963.1810751226, 4964.478729733362, 5001.149804533202, 5003.474635163292, 5686.715664216773, 5779.595428320019, 5785.2189954356045, 5819.79630712923, 5828.930729590977, 4131.29767939451, 4150.709450338478, 4153.854615088488, 4161.708680392374, 4175.91648167035, 6083.543734219951, 6102.633841969452, 6119.571629985903, 6155.753769849201, 6178.814635560941, 4123.134141830189, 4307.418498502725, 4321.438213032286, 4408.575283594075, 4410.426644536254, 4358.193734159191, 4373.322135069069, 4383.649308055348, 4383.846565370197, 4396.340548917688, 4994.4755800640405, 5003.249512573143, 5012.974935125301, 5026.748662566857, 5029.415734043838, 5604.495595396353, 5690.487856092889, 5825.679587622722, 5877.882442351214, 5884.411558844081, 1473.6536913825244, 1496.1247670113105, 1496.6108811709034, 1499.3103172540536, 1504.638886667192, 907.6142639378238, 949.3829011142813, 949.4967667136428, 952.8999455057258, 955.1517813031805, 4994.950050499521, 5016.698998060128, 5029.421616514074, 5042.773075211624, 5058.519440653132, 5254.553362964114, 5268.762905669254, 5271.578273738091, 5291.523762861284, 5300.231466437422, 4813.868740845898, 4994.825181118644, 4995.225740010304, 5003.649744517922, 5021.84934451967, 2764.8064635475725, 2793.7485626293324, 2808.2027502398482, 2811.8734656940487, 2833.1925401260955, 5403.540699040625, 5410.431904650034, 5431.51116399518, 5444.5916556258435, 5467.261919640877, 5083.364579427572, 5340.279062326614, 5340.893182136336, 5409.24270602116, 5422.593305502103, 5426.8371948841595, 5448.101063324586, 5448.972755136199, 5465.278437292241, 5477.397486276506, 5088.264307062751, 5259.39516955555, 5338.572914166935, 5425.647332923348, 5440.945590544044, 5026.773795179299, 5180.274761533464, 5199.4540573239265, 5207.6875387476875, 5262.657927234717, 5335.799445124816, 5395.0577791994065, 5403.0, 5445.264889239902, 5468.671879687232, 3796.3879690007257, 3865.5168103987003, 3865.6134386562244, 3908.511436070491, 3940.408938659224, 5214.897136203953, 5282.720995993258, 5298.364196296469, 5780.826575202751, 5878.882422351444, 5856.853578660568, 5879.764809407576, 5900.0, 5900.616459930091, 5909.763609455746, 5833.79581714638, 5871.324797648154, 5875.383084776208, 5879.647221166743, 5918.526517878646, 5615.0, 5774.624649397776, 5786.826395207982, 5794.304683438015, 5820.214271073351, 5804.564657650609, 5845.18167456548, 5895.764169433174, 5906.844001939347, 5908.847774683895, 5495.642783219078, 5735.164747280997, 5737.651651861893, 5751.798687045934, 5782.306123265067], "value": 4286.829392036748, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [782.9451938364314, 827.6689324270291, 865.6277800545766, 903.8056817784184, 951.3863558005085, 16553.751693724604, 17346.0, 17356.305747770104, 17502.562435939108, 17513.737293940587, 15228.39086436544, 15232.923835380807, 15297.464588739396, 15299.388024479036, 15313.693726125472, 710.1726488640753, 860.5224100624151, 860.9053004169537, 886.2068448738376, 891.4383938118976, 879.2658130461041, 882.1178821178821, 882.6469412235125, 882.6645874567687, 882.7087061269766, 918.4443411735904, 918.481058202117, 920.7007722490184, 921.2491819167357, 921.299812142773, 734.9118105827281, 905.9003509613938, 909.5315912305168, 918.2424499787702, 918.5728636184207, 671.8824205763972, 676.7496026470237, 677.109600874853, 677.695037233247, 678.1929503890404, 15522.456714015068, 15576.766348504781, 15606.531804045897, 15616.0, 15649.217539122998, 14260.0, 15632.296546655427, 15979.121148336837, 16052.919735401249, 16112.436064737678, 13848.93075534626, 14066.226357550331, 14154.0, 14168.291585420791, 14299.857001429957, 4635.675502714811, 4637.072585482883, 4639.86080417588, 4702.083093796711, 4727.078219747151, 83993.0, 84123.57938210331, 85052.14947850505, 87079.56460217721, 87556.56221718914, 12203.521996231031, 12423.937880310541, 12507.624771256877, 12669.873301267055, 12787.168834025759, 20328.186872524933, 21102.2614208502, 21201.681974770392, 24458.1439649615, 24474.14340498074, 9175.541222938826, 9452.90547094534, 9520.143187113194, 9604.855927161097, 9767.023297670175, 4901.754912254444, 4901.803927842846, 5087.31321271631, 5125.436202017775, 5196.506331898464, 3870.2646497165447, 4023.2355852387955, 4025.81883815229, 4026.3960405939342, 4026.4966879140106, 630.224823467138, 714.1323292199899, 716.7957132217397, 722.8482018776116, 737.6422435118963, 724.3372314332327, 742.7920182348951, 745.668980869146, 814.5357146426599, 820.7537738678373, 10738.463076846127, 11065.557377704963, 11368.602098926498, 11470.942645286721, 11564.306141631529, 785.1834092543804, 794.2454668065361, 807.9757607271791, 824.3199360527589, 837.1210229259253, 5334.51989320963, 5451.991381594417, 5558.972205138948, 5622.493975542221, 5637.915431268534, 100441.0, 103601.0, 104932.0, 121455.39272303582, 133277.0, 91686.0, 92441.0, 92799.5360023209, 93660.53169734108, 97535.51232243935, 86722.0, 87078.0, 88303.0, 88812.0, 90003.09996900079, 10621.362718236931, 10865.945670271598, 11095.16786241019, 11115.555377784798, 11341.0, 52465.7376713114, 52847.207291889885, 53987.730061349444, 54449.18326225189, 55371.723141384035, 69516.45422885803, 76361.61819190868, 76568.61715691385, 77596.61201693956, 77942.6102869482, 13670.316484175553, 13728.039037267295, 13730.51943181984, 13882.583522494342, 14059.19714558389, 14718.0, 14876.18181000012, 15656.373745050527, 15745.291461884013, 16108.0, 10354.741131471774, 13152.0, 14280.642983925485, 14656.0, 15628.4530041448, 15405.614859628151, 15597.142157181464, 15772.842271577138, 15795.21023948821, 15815.920920395325, 13533.526326578523, 13688.384022719201, 14020.369083391475, 14073.085249458753, 14713.0, 11401.315921044765, 11632.53469861196, 12769.872301276868, 12783.105182637126, 12914.096013279355, 12230.694232644255, 12310.876891230973, 12495.187812792139, 12515.374231288217, 12540.24758514492], "value": 8190.308684959912, "unit": "score"}], "name": "dromaeo_css", "value": 7766.471458929109, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 110.0, 111.0, 110.0, 112.0, 111.0, 109.0, 110.0, 109.0, 110.0], "value": 109.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 115.0, 116.0, 177.0, 115.0, 119.0, 138.0, 113.0, 155.0, 115.0], "value": 131.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 162.0, 162.0, 162.0, 159.0, 162.0, 160.0, 160.0, 158.0, 168.0], "value": 161.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 120.0, 76.0, 76.0, 76.0, 74.0, 101.0, 74.0], "value": 82.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 128.0, 87.0, 83.0, 83.0, 110.0, 83.0, 84.0, 127.0], "value": 95.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 116.0, 116.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 147.0, 145.0, 146.0, 146.0, 147.0, 146.0, 145.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 94.0, 94.0, 94.0, 95.0, 95.0, 94.0, 94.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 49.0, 50.0, 51.0, 50.0, 51.0, 53.0, 51.0, 50.0, 51.0], "value": 50.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 59.0, 59.0, 59.0, 58.0, 59.0, 59.0, 60.0, 59.0], "value": 59.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 72.0, 72.0, 80.0, 72.0, 71.0, 77.0, 73.0, 73.0, 72.0], "value": 73.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 114.0, 116.0, 118.0, 116.0, 123.0, 123.0, 131.0, 163.0, 116.0], "value": 123.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 151.0, 161.0, 159.0, 156.0, 153.0, 154.0, 155.0, 152.0, 150.0], "value": 154.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 63.0, 62.0, 61.0, 60.0, 60.0, 61.0, 60.0, 60.0], "value": 61.0, "unit": "score"}], "name": "kraken", "value": 1458.9, "alertThreshold": 2.0}]} 23:19:17 INFO - Return code: 0 23:19:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:19:17 INFO - # TBPL SUCCESS # 23:19:17 INFO - Running post-action listener: _resource_record_post_action 23:19:17 INFO - [mozharness: 2016-08-25 06:19:17.179420Z] Finished run-tests step (success) 23:19:17 INFO - Running post-run listener: _resource_record_post_run 23:19:17 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 266240; Write bytes: 329478144; Read time: 16; Write time: 260664 23:19:17 INFO - TinderboxPrint: CPU usage
13.0% 23:19:17 INFO - TinderboxPrint: I/O read bytes / time
266,240 / 16 23:19:17 INFO - TinderboxPrint: I/O write bytes / time
329,478,144 / 260,664 23:19:17 INFO - TinderboxPrint: CPU idle
8,254.9 (87.0%) 23:19:17 INFO - TinderboxPrint: CPU user
1,189.9 (12.5%) 23:19:17 INFO - TinderboxPrint: Swap in / out
0 / 0 23:19:17 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 78233600; Read time: 0; Write time: 48408 23:19:17 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 266240; Write bytes: 250982400; Read time: 16; Write time: 212236 23:19:17 INFO - Running post-run listener: _upload_blobber_files 23:19:17 INFO - Blob upload gear active. 23:19:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:19:17 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 23:19:17 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'] 23:19:17 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 23:19:18 INFO - (blobuploader) - INFO - Open directory for files ... 23:19:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:19:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:19:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:19:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:19:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:19:18 INFO - (blobuploader) - INFO - Done attempting. 23:19:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:19:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:19:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:19:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:19:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:19:20 INFO - (blobuploader) - INFO - Done attempting. 23:19:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:19:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:19:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:19:23 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:19:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:19:23 INFO - (blobuploader) - INFO - Done attempting. 23:19:23 INFO - (blobuploader) - INFO - Iteration through files over. 23:19:23 INFO - Return code: 0 23:19:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:19:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:19:23 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b8c29323d135b746e06139ad92ed0a81256bf6b8d5107eb5efdbcb2902294dbfb17c2fcd1e60110c350f3df8a094e6fe34e83c1f19305558b0f3e946203a3cba", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7335acbc2c3aac72eca29246d3748e4c8636aa3778ad7f58340e6c3d1e443135a87fca2219426f0f1db3af95b12a9087b1bb9c0a343aabfb32ae22e708d7160d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1844723c7cd162c3301c85200550aedf3d242b724e95951cce7b417208054e0575cf589efcc66f441e268f604283f4909ced71b2d59500ed49adaef58ea26237"} 23:19:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:19:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:19:23 INFO - Contents: 23:19:23 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b8c29323d135b746e06139ad92ed0a81256bf6b8d5107eb5efdbcb2902294dbfb17c2fcd1e60110c350f3df8a094e6fe34e83c1f19305558b0f3e946203a3cba", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7335acbc2c3aac72eca29246d3748e4c8636aa3778ad7f58340e6c3d1e443135a87fca2219426f0f1db3af95b12a9087b1bb9c0a343aabfb32ae22e708d7160d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1844723c7cd162c3301c85200550aedf3d242b724e95951cce7b417208054e0575cf589efcc66f441e268f604283f4909ced71b2d59500ed49adaef58ea26237"} 23:19:23 INFO - Running post-run listener: copy_logs_to_upload_dir 23:19:23 INFO - Copying logs to upload dir... 23:19:23 INFO - mkdir: /builds/slave/test/build/upload/logs 23:19:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1677.671464 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 57 secs) (at 2016-08-24 23:19:23.568538) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-24 23:19:23.572134) ========= 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=62c568580acbfdaac3eec7be000001dc-1472104282.255875-1638833407 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/b8c29323d135b746e06139ad92ed0a81256bf6b8d5107eb5efdbcb2902294dbfb17c2fcd1e60110c350f3df8a094e6fe34e83c1f19305558b0f3e946203a3cba", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7335acbc2c3aac72eca29246d3748e4c8636aa3778ad7f58340e6c3d1e443135a87fca2219426f0f1db3af95b12a9087b1bb9c0a343aabfb32ae22e708d7160d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1844723c7cd162c3301c85200550aedf3d242b724e95951cce7b417208054e0575cf589efcc66f441e268f604283f4909ced71b2d59500ed49adaef58ea26237"} build_url:https://queue.taskcluster.net/v1/task/FMRHA52ETKKpHoodCIXFOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011323 build_url: 'https://queue.taskcluster.net/v1/task/FMRHA52ETKKpHoodCIXFOQ/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/b8c29323d135b746e06139ad92ed0a81256bf6b8d5107eb5efdbcb2902294dbfb17c2fcd1e60110c350f3df8a094e6fe34e83c1f19305558b0f3e946203a3cba", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7335acbc2c3aac72eca29246d3748e4c8636aa3778ad7f58340e6c3d1e443135a87fca2219426f0f1db3af95b12a9087b1bb9c0a343aabfb32ae22e708d7160d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1844723c7cd162c3301c85200550aedf3d242b724e95951cce7b417208054e0575cf589efcc66f441e268f604283f4909ced71b2d59500ed49adaef58ea26237"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-24 23:19:23.601423) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-24 23:19:23.602075) ========= 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=62c568580acbfdaac3eec7be000001dc-1472104282.255875-1638833407 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004402 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-24 23:19:23.686217) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-24 23:19:23.686862) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-24 23:19:23.687463) ========= ========= Total master_lag: 0.17 =========