builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-062 starttime: 1472104533.76 results: success (0) buildid: 20160824201226 builduid: 9588af9f17174cd1bf1e88124384d3f9 revision: 8a0abccd95639178abc9c36aebd9e0c7cf9ea5d5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-24 22:55:33.756054) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-24 22:55:33.756578) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-24 22:55:33.757088) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-24 22:55:33.772608) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-24 22:55:33.773073) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1472104533.377457-1069707519 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-24 22:55:34-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.9M=0s 2016-08-24 22:55:34 (46.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.130284 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-24 22:55:33.921872) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-24 22:55:33.922688) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1472104533.377457-1069707519 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019675 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-24 22:55:33.977345) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-24 22:55:33.978182) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 8a0abccd95639178abc9c36aebd9e0c7cf9ea5d5 --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 8a0abccd95639178abc9c36aebd9e0c7cf9ea5d5 --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=a4ce256d2ec601f24a60a71e000001bc-1472104533.377457-1069707519 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-24 22:55:34,519 truncating revision to first 12 chars 2016-08-24 22:55:34,519 Setting DEBUG logging. 2016-08-24 22:55:34,519 attempt 1/10 2016-08-24 22:55:34,519 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/8a0abccd9563?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-24 22:55:34,783 unpacking tar archive at: mozilla-aurora-8a0abccd9563/testing/mozharness/ program finished with exit code 0 elapsedTime=0.559670 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-24 22:55:34.555558) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-24 22:55:34.556297) ========= script_repo_revision: 8a0abccd95639178abc9c36aebd9e0c7cf9ea5d5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-24 22:55:34.557220) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-24 22:55:34.557831) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-24 22:55:34.569501) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 0 secs) (at 2016-08-24 22:55:34.570147) ========= /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=a4ce256d2ec601f24a60a71e000001bc-1472104533.377457-1069707519 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:55:35 INFO - MultiFileLogger online at 20160824 22:55:35 in /builds/slave/test 22:55:35 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:55:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:55:35 INFO - {'append_to_log': False, 22:55:35 INFO - 'base_work_dir': '/builds/slave/test', 22:55:35 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 22:55:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:55:35 INFO - 'branch': 'Mozilla-Aurora', 22:55:35 INFO - 'buildbot_json_path': 'buildprops.json', 22:55:35 INFO - 'config_files': ('talos/linux_config.py',), 22:55:35 INFO - 'default_actions': ('clobber', 22:55:35 INFO - 'read-buildbot-config', 22:55:35 INFO - 'download-and-extract', 22:55:35 INFO - 'populate-webroot', 22:55:35 INFO - 'create-virtualenv', 22:55:35 INFO - 'install', 22:55:35 INFO - 'run-tests'), 22:55:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:55:35 INFO - 'download_minidump_stackwalk': True, 22:55:35 INFO - 'download_symbols': 'ondemand', 22:55:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:55:35 INFO - 'tooltool.py': '/tools/tooltool.py', 22:55:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:55:35 INFO - '/tools/misc-python/virtualenv.py')}, 22:55:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:55:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:55:35 INFO - 'installer_path': 'installer.exe', 22:55:35 INFO - 'log_level': 'info', 22:55:35 INFO - 'log_name': 'talos', 22:55:35 INFO - 'log_to_console': True, 22:55:35 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:55:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:55:35 INFO - 'opt_config_files': (), 22:55:35 INFO - 'pip_index': False, 22:55:35 INFO - 'sps_profile': False, 22:55:35 INFO - 'sps_profile_interval': 0, 22:55:35 INFO - 'suite': 'dromaeojs', 22:55:35 INFO - 'system_bits': '32', 22:55:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:55:35 INFO - 'title': 'talos-linux64-ix-062', 22:55:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:55:35 INFO - 'use_talos_json': True, 22:55:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:55:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:55:35 INFO - 'work_dir': 'build'} 22:55:35 INFO - [mozharness: 2016-08-25 05:55:35.207679Z] Running clobber step. 22:55:35 INFO - Running pre-action listener: _resource_record_pre_action 22:55:35 INFO - Running main action method: clobber 22:55:35 INFO - rmtree: /builds/slave/test/build 22:55:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:55:36 INFO - Running post-action listener: _resource_record_post_action 22:55:36 INFO - [mozharness: 2016-08-25 05:55:36.199812Z] Finished clobber step (success) 22:55:36 INFO - [mozharness: 2016-08-25 05:55:36.199912Z] Running read-buildbot-config step. 22:55:36 INFO - Running pre-action listener: _resource_record_pre_action 22:55:36 INFO - Running main action method: read_buildbot_config 22:55:36 INFO - Using buildbot properties: 22:55:36 INFO - { 22:55:36 INFO - "project": "", 22:55:36 INFO - "product": "firefox", 22:55:36 INFO - "script_repo_revision": "production", 22:55:36 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 22:55:36 INFO - "repository": "", 22:55:36 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 22:55:36 INFO - "buildid": "20160824201226", 22:55:36 INFO - "pgo_build": "True", 22:55:36 INFO - "basedir": "/builds/slave/test", 22:55:36 INFO - "buildnumber": 699, 22:55:36 INFO - "slavename": "talos-linux64-ix-062", 22:55:36 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 22:55:36 INFO - "platform": "ubuntu64_hw", 22:55:36 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 22:55:36 INFO - "branch": "mozilla-aurora", 22:55:36 INFO - "repo_path": "releases/mozilla-aurora", 22:55:36 INFO - "revision": "8a0abccd95639178abc9c36aebd9e0c7cf9ea5d5", 22:55:36 INFO - "stage_platform": "linux64-pgo", 22:55:36 INFO - "builduid": "9588af9f17174cd1bf1e88124384d3f9", 22:55:36 INFO - "slavebuilddir": "test" 22:55:36 INFO - } 22:55:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/SgsUDC-MSc2iWDIuBjyqwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 22:55:36 INFO - Running post-action listener: _resource_record_post_action 22:55:36 INFO - [mozharness: 2016-08-25 05:55:36.202344Z] Finished read-buildbot-config step (success) 22:55:36 INFO - [mozharness: 2016-08-25 05:55:36.202794Z] Running download-and-extract step. 22:55:36 INFO - Running pre-action listener: _resource_record_pre_action 22:55:36 INFO - Running main action method: download_and_extract 22:55:36 INFO - mkdir: /builds/slave/test/build/tests 22:55:36 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:55:36 INFO - https://queue.taskcluster.net/v1/task/SgsUDC-MSc2iWDIuBjyqwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 22:55:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgsUDC-MSc2iWDIuBjyqwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 22:55:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgsUDC-MSc2iWDIuBjyqwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 22:55:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgsUDC-MSc2iWDIuBjyqwA/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:55:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgsUDC-MSc2iWDIuBjyqwA/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:55:36 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgsUDC-MSc2iWDIuBjyqwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 22:55:36 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 22:55:36 INFO - retry: Failed, sleeping 30 seconds before retrying 22:56:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgsUDC-MSc2iWDIuBjyqwA/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:56:06 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgsUDC-MSc2iWDIuBjyqwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 22:56:06 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 22:56:06 INFO - retry: Failed, sleeping 60 seconds before retrying 22:57:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgsUDC-MSc2iWDIuBjyqwA/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:57:06 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgsUDC-MSc2iWDIuBjyqwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 22:57:06 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 22:57:06 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgsUDC-MSc2iWDIuBjyqwA/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:57:06 INFO - Caught exception: HTTP Error 404: Not Found 22:57:06 INFO - Caught exception: HTTP Error 404: Not Found 22:57:06 INFO - Caught exception: HTTP Error 404: Not Found 22:57:06 INFO - trying https://queue.taskcluster.net/v1/task/SgsUDC-MSc2iWDIuBjyqwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 22:57:06 INFO - Downloading https://queue.taskcluster.net/v1/task/SgsUDC-MSc2iWDIuBjyqwA/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:57:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SgsUDC-MSc2iWDIuBjyqwA/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:57:06 INFO - Downloaded 1361 bytes. 22:57:06 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 22:57:06 INFO - Using the following test package requirements: 22:57:06 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 22:57:06 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 22:57:06 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 22:57:06 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 22:57:06 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 22:57:06 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 22:57:06 INFO - u'jsshell-linux-x86_64.zip'], 22:57:06 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 22:57:06 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 22:57:06 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 22:57:06 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 22:57:06 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 22:57:06 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 22:57:06 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 22:57:06 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 22:57:06 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 22:57:06 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 22:57:06 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 22:57:06 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:57:06 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:06 INFO - https://queue.taskcluster.net/v1/task/SgsUDC-MSc2iWDIuBjyqwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:57:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgsUDC-MSc2iWDIuBjyqwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 22:57:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgsUDC-MSc2iWDIuBjyqwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 22:57:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgsUDC-MSc2iWDIuBjyqwA/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:57:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgsUDC-MSc2iWDIuBjyqwA/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:57:06 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgsUDC-MSc2iWDIuBjyqwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 22:57:06 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 22:57:06 INFO - retry: Failed, sleeping 30 seconds before retrying 22:57:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgsUDC-MSc2iWDIuBjyqwA/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:57:36 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgsUDC-MSc2iWDIuBjyqwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 22:57:36 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 22:57:36 INFO - retry: Failed, sleeping 60 seconds before retrying 22:58:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgsUDC-MSc2iWDIuBjyqwA/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:58:36 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgsUDC-MSc2iWDIuBjyqwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 22:58:36 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 22:58:36 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgsUDC-MSc2iWDIuBjyqwA/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:58:36 INFO - Caught exception: HTTP Error 404: Not Found 22:58:36 INFO - Caught exception: HTTP Error 404: Not Found 22:58:36 INFO - Caught exception: HTTP Error 404: Not Found 22:58:36 INFO - trying https://queue.taskcluster.net/v1/task/SgsUDC-MSc2iWDIuBjyqwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 22:58:36 INFO - Downloading https://queue.taskcluster.net/v1/task/SgsUDC-MSc2iWDIuBjyqwA/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:58:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SgsUDC-MSc2iWDIuBjyqwA/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:58:37 INFO - Downloaded 21769366 bytes. 22:58:37 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:58:37 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:58:38 INFO - Return code: 0 22:58:38 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:58:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:58:38 INFO - https://queue.taskcluster.net/v1/task/SgsUDC-MSc2iWDIuBjyqwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:58:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgsUDC-MSc2iWDIuBjyqwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 22:58:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgsUDC-MSc2iWDIuBjyqwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 22:58:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgsUDC-MSc2iWDIuBjyqwA/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:58:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgsUDC-MSc2iWDIuBjyqwA/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:58:39 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgsUDC-MSc2iWDIuBjyqwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 22:58:39 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 22:58:39 INFO - retry: Failed, sleeping 30 seconds before retrying 22:59:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgsUDC-MSc2iWDIuBjyqwA/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:59:09 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgsUDC-MSc2iWDIuBjyqwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 22:59:09 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 22:59:09 INFO - retry: Failed, sleeping 60 seconds before retrying 23:00:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgsUDC-MSc2iWDIuBjyqwA/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 23:00:09 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgsUDC-MSc2iWDIuBjyqwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 23:00:09 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 23:00:09 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgsUDC-MSc2iWDIuBjyqwA/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! 23:00:09 INFO - Caught exception: HTTP Error 404: Not Found 23:00:09 INFO - Caught exception: HTTP Error 404: Not Found 23:00:09 INFO - Caught exception: HTTP Error 404: Not Found 23:00:09 INFO - trying https://queue.taskcluster.net/v1/task/SgsUDC-MSc2iWDIuBjyqwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 23:00:09 INFO - Downloading https://queue.taskcluster.net/v1/task/SgsUDC-MSc2iWDIuBjyqwA/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 23:00:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SgsUDC-MSc2iWDIuBjyqwA/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 23:00:09 INFO - Downloaded 21769366 bytes. 23:00:09 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'] 23:00:09 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 23:00:11 INFO - Return code: 0 23:00: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')]} 23:00:11 INFO - https://queue.taskcluster.net/v1/task/SgsUDC-MSc2iWDIuBjyqwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 23:00:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgsUDC-MSc2iWDIuBjyqwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 23:00:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgsUDC-MSc2iWDIuBjyqwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 23:00:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgsUDC-MSc2iWDIuBjyqwA/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 23:00:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgsUDC-MSc2iWDIuBjyqwA/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 23:00:11 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgsUDC-MSc2iWDIuBjyqwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 23:00:11 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 23:00:11 INFO - retry: Failed, sleeping 30 seconds before retrying 23:00:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgsUDC-MSc2iWDIuBjyqwA/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 23:00:41 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgsUDC-MSc2iWDIuBjyqwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 23:00:41 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 23:00:41 INFO - retry: Failed, sleeping 60 seconds before retrying 23:01:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgsUDC-MSc2iWDIuBjyqwA/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 23:01:41 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgsUDC-MSc2iWDIuBjyqwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 23:01:41 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 23:01:41 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgsUDC-MSc2iWDIuBjyqwA/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! 23:01:41 INFO - Caught exception: HTTP Error 404: Not Found 23:01:41 INFO - Caught exception: HTTP Error 404: Not Found 23:01:41 INFO - Caught exception: HTTP Error 404: Not Found 23:01:41 INFO - trying https://queue.taskcluster.net/v1/task/SgsUDC-MSc2iWDIuBjyqwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 23:01:41 INFO - Downloading https://queue.taskcluster.net/v1/task/SgsUDC-MSc2iWDIuBjyqwA/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 23:01:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SgsUDC-MSc2iWDIuBjyqwA/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 23:01:42 INFO - Downloaded 14413723 bytes. 23:01:42 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'] 23:01:42 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 23:01:42 INFO - Return code: 0 23:01:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:01:42 INFO - https://queue.taskcluster.net/v1/task/SgsUDC-MSc2iWDIuBjyqwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 23:01:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgsUDC-MSc2iWDIuBjyqwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 23:01:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgsUDC-MSc2iWDIuBjyqwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 23:01:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgsUDC-MSc2iWDIuBjyqwA/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 23:01:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgsUDC-MSc2iWDIuBjyqwA/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 23:01:42 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgsUDC-MSc2iWDIuBjyqwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 23:01:42 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 23:01:42 INFO - retry: Failed, sleeping 30 seconds before retrying 23:02:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgsUDC-MSc2iWDIuBjyqwA/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 23:02:12 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgsUDC-MSc2iWDIuBjyqwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 23:02:12 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 23:02:12 INFO - retry: Failed, sleeping 60 seconds before retrying 23:03:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgsUDC-MSc2iWDIuBjyqwA/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 23:03:12 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgsUDC-MSc2iWDIuBjyqwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 23:03:12 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 23:03:12 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgsUDC-MSc2iWDIuBjyqwA/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! 23:03:12 INFO - Caught exception: HTTP Error 404: Not Found 23:03:12 INFO - Caught exception: HTTP Error 404: Not Found 23:03:12 INFO - Caught exception: HTTP Error 404: Not Found 23:03:12 INFO - trying https://queue.taskcluster.net/v1/task/SgsUDC-MSc2iWDIuBjyqwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 23:03:12 INFO - Downloading https://queue.taskcluster.net/v1/task/SgsUDC-MSc2iWDIuBjyqwA/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 23:03:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SgsUDC-MSc2iWDIuBjyqwA/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 23:03:13 INFO - Downloaded 63753543 bytes. 23:03:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SgsUDC-MSc2iWDIuBjyqwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 23:03:13 INFO - mkdir: /builds/slave/test/properties 23:03:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:03:13 INFO - Writing to file /builds/slave/test/properties/build_url 23:03:13 INFO - Contents: 23:03:13 INFO - build_url:https://queue.taskcluster.net/v1/task/SgsUDC-MSc2iWDIuBjyqwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 23:03:14 INFO - Running post-action listener: _resource_record_post_action 23:03:14 INFO - Running post-action listener: set_extra_try_arguments 23:03:14 INFO - [mozharness: 2016-08-25 06:03:14.324892Z] Finished download-and-extract step (success) 23:03:14 INFO - [mozharness: 2016-08-25 06:03:14.325124Z] Running populate-webroot step. 23:03:14 INFO - Running pre-action listener: _resource_record_pre_action 23:03:14 INFO - Running main action method: populate_webroot 23:03:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:03:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:03:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:03:14 INFO - '--tppagecycles', 23:03:14 INFO - '7'], 23:03:14 INFO - 'tests': ['tsvgm']}}, 23:03:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:03:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:03:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:03:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:03:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:03:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:03:14 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:03:14 INFO - 'talos_options': ['--disable-e10s'], 23:03:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:03:14 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:03:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:03:14 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:03:14 INFO - 'talos_options': ['--disable-e10s'], 23:03:14 INFO - 'tests': ['damp', 'tps']}, 23:03:14 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:03:14 INFO - 'tests': ['damp', 'tps']}, 23:03:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:03:14 INFO - 'tests': ['dromaeo_dom']}, 23:03:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:03:14 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:03:14 INFO - 'tests': ['basic_compositor_video']}, 23:03:14 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 23:03:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:03:14 INFO - 'tests': ['a11yr', 23:03:14 INFO - 'ts_paint', 23:03:14 INFO - 'tpaint', 23:03:14 INFO - 'sessionrestore', 23:03:14 INFO - 'sessionrestore_no_auto_restore', 23:03:14 INFO - 'tabpaint']}, 23:03:14 INFO - 'other-e10s': {'tests': ['a11yr', 23:03:14 INFO - 'ts_paint', 23:03:14 INFO - 'tpaint', 23:03:14 INFO - 'sessionrestore', 23:03:14 INFO - 'sessionrestore_no_auto_restore', 23:03:14 INFO - 'tabpaint']}, 23:03:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:03:14 INFO - 'ts_paint', 23:03:14 INFO - 'tpaint', 23:03:14 INFO - 'sessionrestore', 23:03:14 INFO - 'sessionrestore_no_auto_restore', 23:03:14 INFO - 'tabpaint']}, 23:03:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:03:14 INFO - 'ts_paint', 23:03:14 INFO - 'tpaint', 23:03:14 INFO - 'sessionrestore', 23:03:14 INFO - 'sessionrestore_no_auto_restore', 23:03:14 INFO - 'tabpaint']}, 23:03:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:03:14 INFO - 'tests': ['a11yr', 23:03:14 INFO - 'ts_paint', 23:03:14 INFO - 'tpaint', 23:03:14 INFO - 'sessionrestore', 23:03:14 INFO - 'sessionrestore_no_auto_restore', 23:03:14 INFO - 'tabpaint']}, 23:03:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:03:14 INFO - 'tests': ['a11yr', 23:03:14 INFO - 'ts_paint', 23:03:14 INFO - 'tpaint', 23:03:14 INFO - 'sessionrestore', 23:03:14 INFO - 'sessionrestore_no_auto_restore', 23:03:14 INFO - 'tabpaint']}, 23:03:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:03:14 INFO - 'tests': ['tsvgx', 23:03:14 INFO - 'tsvgr_opacity', 23:03:14 INFO - 'tart', 23:03:14 INFO - 'tscrollx', 23:03:14 INFO - 'cart']}, 23:03:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:03:14 INFO - 'tsvgr_opacity', 23:03:14 INFO - 'tart', 23:03:14 INFO - 'tscrollx', 23:03:14 INFO - 'cart']}, 23:03:14 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:03:14 INFO - 'talos_options': ['--disable-e10s'], 23:03:14 INFO - 'tests': ['tp5o']}, 23:03:14 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:03:14 INFO - 'tests': ['tp5o']}, 23:03:14 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:03:14 INFO - 'talos_options': ['--disable-e10s', 23:03:14 INFO - '--xperf_path', 23:03:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:03:14 INFO - 'tests': ['tp5n']}, 23:03:14 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:03:14 INFO - 'talos_options': ['--xperf_path', 23:03:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:03:14 INFO - 'tests': ['tp5n']}}, 23:03:14 INFO - 'talos.zip': {'path': '', 23:03:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:03:14 INFO - Running post-action listener: _resource_record_post_action 23:03:14 INFO - [mozharness: 2016-08-25 06:03:14.346915Z] Finished populate-webroot step (success) 23:03:14 INFO - [mozharness: 2016-08-25 06:03:14.347005Z] Running create-virtualenv step. 23:03:14 INFO - Running pre-action listener: _resource_record_pre_action 23:03:14 INFO - Running main action method: create_virtualenv 23:03:14 INFO - Creating virtualenv /builds/slave/test/build/venv 23:03:14 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 23:03:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:03:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:03:14 INFO - Using real prefix '/usr' 23:03:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:03:14 INFO - Installing distribute.............................................................................................................................................................................................done. 23:03:16 INFO - Installing pip.................done. 23:03:16 INFO - Return code: 0 23:03:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:03: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')]} 23:03:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:03:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:03: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')]} 23:03:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:03:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:03: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd7e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xee18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d3b0>, '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 0x103e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e740>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1472104533.377457-1069707519', '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 23:03: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', 'pip>=1.5'] in /builds/slave/test/build 23:03: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 pip>=1.5 23:03:16 INFO - Using env: {'DISPLAY': ':0', 23:03:16 INFO - 'HOME': '/home/cltbld', 23:03:16 INFO - 'LANG': 'en_US.UTF-8', 23:03:16 INFO - 'LANGUAGE': 'en_US:en', 23:03:16 INFO - 'LOGNAME': 'cltbld', 23:03:16 INFO - 'MAIL': '/var/mail/cltbld', 23:03:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:03:16 INFO - 'MOZ_NO_REMOTE': '1', 23:03:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:03:16 INFO - 'NO_EM_RESTART': '1', 23:03:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:03:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:03:16 INFO - 'PWD': '/builds/slave/test', 23:03:16 INFO - 'SHELL': '/bin/bash', 23:03:16 INFO - 'SHLVL': '1', 23:03:16 INFO - 'TERM': 'linux', 23:03:16 INFO - 'TMOUT': '86400', 23:03:16 INFO - 'USER': 'cltbld', 23:03:16 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1472104533.377457-1069707519', 23:03:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:03:16 INFO - '_': '/tools/buildbot/bin/python'} 23:03:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:03:17 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 23:03:17 INFO - Cleaning up... 23:03:17 INFO - Return code: 0 23:03:17 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:03:17 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')]} 23:03:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:03:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:03:17 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')]} 23:03:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:03:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:03:17 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 0xd7e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xee18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d3b0>, '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 0x103e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e740>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1472104533.377457-1069707519', '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 23:03:17 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 23:03:17 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 23:03:17 INFO - Using env: {'DISPLAY': ':0', 23:03:17 INFO - 'HOME': '/home/cltbld', 23:03:17 INFO - 'LANG': 'en_US.UTF-8', 23:03:17 INFO - 'LANGUAGE': 'en_US:en', 23:03:17 INFO - 'LOGNAME': 'cltbld', 23:03:17 INFO - 'MAIL': '/var/mail/cltbld', 23:03:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:03:17 INFO - 'MOZ_NO_REMOTE': '1', 23:03:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:03:17 INFO - 'NO_EM_RESTART': '1', 23:03:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:03:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:03:17 INFO - 'PWD': '/builds/slave/test', 23:03:17 INFO - 'SHELL': '/bin/bash', 23:03:17 INFO - 'SHLVL': '1', 23:03:17 INFO - 'TERM': 'linux', 23:03:17 INFO - 'TMOUT': '86400', 23:03:17 INFO - 'USER': 'cltbld', 23:03:17 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1472104533.377457-1069707519', 23:03:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:03:17 INFO - '_': '/tools/buildbot/bin/python'} 23:03:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:03:17 INFO - Downloading/unpacking psutil>=3.1.1 23:03:17 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 23:03:17 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 23:03:17 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 23:03:17 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 23:03:17 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 23:03:17 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 23:03:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:03:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:03:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:03:19 INFO - Installing collected packages: psutil 23:03:19 INFO - Running setup.py install for psutil 23:03:19 INFO - building 'psutil._psutil_linux' extension 23:03:19 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 23:03:19 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 23:03:19 INFO - building 'psutil._psutil_posix' extension 23:03:19 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 23:03:19 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 23:03:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:03:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:03:19 INFO - Successfully installed psutil 23:03:19 INFO - Cleaning up... 23:03:19 INFO - Return code: 0 23:03:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:03: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')]} 23:03:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:03:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:03: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')]} 23:03:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:03:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:03:19 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 0xd7e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xee18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d3b0>, '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 0x103e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e740>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1472104533.377457-1069707519', '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 23:03:19 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 23:03:19 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 23:03:19 INFO - Using env: {'DISPLAY': ':0', 23:03:19 INFO - 'HOME': '/home/cltbld', 23:03:19 INFO - 'LANG': 'en_US.UTF-8', 23:03:19 INFO - 'LANGUAGE': 'en_US:en', 23:03:19 INFO - 'LOGNAME': 'cltbld', 23:03:19 INFO - 'MAIL': '/var/mail/cltbld', 23:03:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:03:19 INFO - 'MOZ_NO_REMOTE': '1', 23:03:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:03:19 INFO - 'NO_EM_RESTART': '1', 23:03:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:03:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:03:19 INFO - 'PWD': '/builds/slave/test', 23:03:19 INFO - 'SHELL': '/bin/bash', 23:03:19 INFO - 'SHLVL': '1', 23:03:19 INFO - 'TERM': 'linux', 23:03:19 INFO - 'TMOUT': '86400', 23:03:19 INFO - 'USER': 'cltbld', 23:03:19 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1472104533.377457-1069707519', 23:03:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:03:19 INFO - '_': '/tools/buildbot/bin/python'} 23:03:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:03:20 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:03:20 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 23:03:20 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 23:03:20 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 23:03:20 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 23:03:20 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 23:03:20 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 23:03:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:03:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:03:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:03:22 INFO - Installing collected packages: mozsystemmonitor 23:03:22 INFO - Running setup.py install for mozsystemmonitor 23:03:22 INFO - Successfully installed mozsystemmonitor 23:03:22 INFO - Cleaning up... 23:03:22 INFO - Return code: 0 23:03:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:03: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')]} 23:03:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:03:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:03: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')]} 23:03:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:03:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:03:22 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 0xd7e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xee18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d3b0>, '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 0x103e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e740>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1472104533.377457-1069707519', '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 23:03:22 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 23:03:22 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 23:03:22 INFO - Using env: {'DISPLAY': ':0', 23:03:22 INFO - 'HOME': '/home/cltbld', 23:03:22 INFO - 'LANG': 'en_US.UTF-8', 23:03:22 INFO - 'LANGUAGE': 'en_US:en', 23:03:22 INFO - 'LOGNAME': 'cltbld', 23:03:22 INFO - 'MAIL': '/var/mail/cltbld', 23:03:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:03:22 INFO - 'MOZ_NO_REMOTE': '1', 23:03:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:03:22 INFO - 'NO_EM_RESTART': '1', 23:03:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:03:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:03:22 INFO - 'PWD': '/builds/slave/test', 23:03:22 INFO - 'SHELL': '/bin/bash', 23:03:22 INFO - 'SHLVL': '1', 23:03:22 INFO - 'TERM': 'linux', 23:03:22 INFO - 'TMOUT': '86400', 23:03:22 INFO - 'USER': 'cltbld', 23:03:22 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1472104533.377457-1069707519', 23:03:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:03:22 INFO - '_': '/tools/buildbot/bin/python'} 23:03:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:03:22 INFO - Downloading/unpacking jsonschema==2.5.1 23:03:22 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 23:03:22 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 23:03:22 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 23:03:22 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 23:03:22 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 23:03:22 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 23:03:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:03:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:03: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 23:03: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 23:03: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 23:03: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 23:03: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 23:03: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 23:03:24 INFO - Downloading functools32-3.2.3-2.tar.gz 23:03:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:03:24 INFO - warning: no files found matching '*.txt' 23:03:24 INFO - no previously-included directories found matching 'build' 23:03:24 INFO - no previously-included directories found matching 'dist' 23:03:24 INFO - no previously-included directories found matching '.git*' 23:03:24 INFO - Installing collected packages: jsonschema, functools32 23:03:24 INFO - Running setup.py install for functools32 23:03:24 INFO - warning: no files found matching '*.txt' 23:03:24 INFO - no previously-included directories found matching 'build' 23:03:24 INFO - no previously-included directories found matching 'dist' 23:03:24 INFO - no previously-included directories found matching '.git*' 23:03:24 INFO - Successfully installed jsonschema functools32 23:03:24 INFO - Cleaning up... 23:03:24 INFO - Return code: 0 23:03:24 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 23:03:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:03:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:03:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:03:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:03:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:03:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:03:24 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 0xd7e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xee18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d3b0>, '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 0x103e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e740>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1472104533.377457-1069707519', '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 23:03:24 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 23:03:24 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 23:03:24 INFO - Using env: {'DISPLAY': ':0', 23:03:24 INFO - 'HOME': '/home/cltbld', 23:03:24 INFO - 'LANG': 'en_US.UTF-8', 23:03:24 INFO - 'LANGUAGE': 'en_US:en', 23:03:24 INFO - 'LOGNAME': 'cltbld', 23:03:24 INFO - 'MAIL': '/var/mail/cltbld', 23:03:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:03:24 INFO - 'MOZ_NO_REMOTE': '1', 23:03:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:03:24 INFO - 'NO_EM_RESTART': '1', 23:03:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:03:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:03:24 INFO - 'PWD': '/builds/slave/test', 23:03:24 INFO - 'SHELL': '/bin/bash', 23:03:24 INFO - 'SHLVL': '1', 23:03:24 INFO - 'TERM': 'linux', 23:03:24 INFO - 'TMOUT': '86400', 23:03:24 INFO - 'USER': 'cltbld', 23:03:24 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1472104533.377457-1069707519', 23:03:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:03:24 INFO - '_': '/tools/buildbot/bin/python'} 23:03:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:03:24 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 23:03:24 INFO - Cleaning up... 23:03:25 INFO - Return code: 0 23:03:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:03:25 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')]} 23:03:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:03:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:03:25 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')]} 23:03:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:03:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:03:25 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 0xd7e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xee18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d3b0>, '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 0x103e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e740>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1472104533.377457-1069707519', '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 23:03:25 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 23:03:25 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 23:03:25 INFO - Using env: {'DISPLAY': ':0', 23:03:25 INFO - 'HOME': '/home/cltbld', 23:03:25 INFO - 'LANG': 'en_US.UTF-8', 23:03:25 INFO - 'LANGUAGE': 'en_US:en', 23:03:25 INFO - 'LOGNAME': 'cltbld', 23:03:25 INFO - 'MAIL': '/var/mail/cltbld', 23:03:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:03:25 INFO - 'MOZ_NO_REMOTE': '1', 23:03:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:03:25 INFO - 'NO_EM_RESTART': '1', 23:03:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:03:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:03:25 INFO - 'PWD': '/builds/slave/test', 23:03:25 INFO - 'SHELL': '/bin/bash', 23:03:25 INFO - 'SHLVL': '1', 23:03:25 INFO - 'TERM': 'linux', 23:03:25 INFO - 'TMOUT': '86400', 23:03:25 INFO - 'USER': 'cltbld', 23:03:25 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1472104533.377457-1069707519', 23:03:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:03:25 INFO - '_': '/tools/buildbot/bin/python'} 23:03:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:03:25 INFO - Downloading/unpacking blobuploader==1.2.4 23:03:25 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 23:03:25 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 23:03:25 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 23:03:25 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 23:03:25 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 23:03:25 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 23:03:27 INFO - Downloading blobuploader-1.2.4.tar.gz 23:03:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:03:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:03:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:03:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:03:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:03:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:03:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:03:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:03:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:03:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:03:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:03:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:03:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:03:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:03:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:03:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:03:27 INFO - Downloading docopt-0.6.1.tar.gz 23:03:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:03:27 INFO - Installing collected packages: blobuploader, requests, docopt 23:03:27 INFO - Running setup.py install for blobuploader 23:03:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:03:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:03:27 INFO - Running setup.py install for requests 23:03:28 INFO - Running setup.py install for docopt 23:03:28 INFO - Successfully installed blobuploader requests docopt 23:03:28 INFO - Cleaning up... 23:03:28 INFO - Return code: 0 23:03:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:03:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:03:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:03:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:03:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:03:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:03:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:03:28 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 0xd7e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xee18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d3b0>, '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 0x103e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e740>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1472104533.377457-1069707519', '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 23:03:28 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 23:03:28 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 23:03:28 INFO - Using env: {'DISPLAY': ':0', 23:03:28 INFO - 'HOME': '/home/cltbld', 23:03:28 INFO - 'LANG': 'en_US.UTF-8', 23:03:28 INFO - 'LANGUAGE': 'en_US:en', 23:03:28 INFO - 'LOGNAME': 'cltbld', 23:03:28 INFO - 'MAIL': '/var/mail/cltbld', 23:03:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:03:28 INFO - 'MOZ_NO_REMOTE': '1', 23:03:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:03:28 INFO - 'NO_EM_RESTART': '1', 23:03:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:03:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:03:28 INFO - 'PWD': '/builds/slave/test', 23:03:28 INFO - 'SHELL': '/bin/bash', 23:03:28 INFO - 'SHLVL': '1', 23:03:28 INFO - 'TERM': 'linux', 23:03:28 INFO - 'TMOUT': '86400', 23:03:28 INFO - 'USER': 'cltbld', 23:03:28 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1472104533.377457-1069707519', 23:03:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:03:28 INFO - '_': '/tools/buildbot/bin/python'} 23:03:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:03:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:03:28 INFO - Running setup.py (path:/tmp/pip-RAc62w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:03:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:03:28 INFO - Running setup.py (path:/tmp/pip-EHyJgE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:03:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:03:28 INFO - Running setup.py (path:/tmp/pip-JbOMJg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:03:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:03:28 INFO - Running setup.py (path:/tmp/pip-DdjY20-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:03:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:03:28 INFO - Running setup.py (path:/tmp/pip-UKT5k8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:03:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:03:29 INFO - Running setup.py (path:/tmp/pip-dgcxzj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:03:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:03:29 INFO - Running setup.py (path:/tmp/pip-iaxjqT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:03:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:03:29 INFO - Running setup.py (path:/tmp/pip-5knlHc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:03:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:03:29 INFO - Running setup.py (path:/tmp/pip-3JZEFS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:03:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:03:29 INFO - Running setup.py (path:/tmp/pip-8M6oTR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:03:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:03:29 INFO - Running setup.py (path:/tmp/pip-yS0d6l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:03:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:03:29 INFO - Running setup.py (path:/tmp/pip-yiOTQ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:03:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:03:29 INFO - Running setup.py (path:/tmp/pip-GepdTR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:03:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:03:29 INFO - Running setup.py (path:/tmp/pip-IC_oKo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:03:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:03:29 INFO - Running setup.py (path:/tmp/pip-geGEom-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:03:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:03:29 INFO - Running setup.py (path:/tmp/pip-kVwCgs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:03:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:03:29 INFO - Running setup.py (path:/tmp/pip-W4LO35-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:03:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:03:29 INFO - Running setup.py install for manifestparser 23:03:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:03:30 INFO - Running setup.py install for mozcrash 23:03:30 INFO - Running setup.py install for mozdebug 23:03:30 INFO - Running setup.py install for mozdevice 23:03:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:03:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:03:30 INFO - Running setup.py install for mozfile 23:03:30 INFO - Running setup.py install for mozhttpd 23:03:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:03:30 INFO - Running setup.py install for mozinfo 23:03:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:03:30 INFO - Running setup.py install for mozInstall 23:03:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:03:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:03:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:03:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:03:30 INFO - Running setup.py install for mozleak 23:03:30 INFO - Running setup.py install for mozlog 23:03:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:03:30 INFO - Running setup.py install for moznetwork 23:03:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:03:31 INFO - Running setup.py install for mozprocess 23:03:31 INFO - Running setup.py install for mozprofile 23:03:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:03:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:03:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:03:31 INFO - Running setup.py install for mozrunner 23:03:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:03:31 INFO - Running setup.py install for mozscreenshot 23:03:31 INFO - Running setup.py install for moztest 23:03:31 INFO - Running setup.py install for mozversion 23:03:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:03:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:03:31 INFO - Cleaning up... 23:03:31 INFO - Return code: 0 23:03:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:03:31 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')]} 23:03:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:03:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:03:31 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')]} 23:03:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:03:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:03:31 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 0xd7e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xee18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d3b0>, '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 0x103e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e740>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1472104533.377457-1069707519', '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 23:03:31 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 23:03:31 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 23:03:31 INFO - Using env: {'DISPLAY': ':0', 23:03:31 INFO - 'HOME': '/home/cltbld', 23:03:31 INFO - 'LANG': 'en_US.UTF-8', 23:03:31 INFO - 'LANGUAGE': 'en_US:en', 23:03:31 INFO - 'LOGNAME': 'cltbld', 23:03:31 INFO - 'MAIL': '/var/mail/cltbld', 23:03:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:03:31 INFO - 'MOZ_NO_REMOTE': '1', 23:03:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:03:31 INFO - 'NO_EM_RESTART': '1', 23:03:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:03:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:03:31 INFO - 'PWD': '/builds/slave/test', 23:03:31 INFO - 'SHELL': '/bin/bash', 23:03:31 INFO - 'SHLVL': '1', 23:03:31 INFO - 'TERM': 'linux', 23:03:31 INFO - 'TMOUT': '86400', 23:03:31 INFO - 'USER': 'cltbld', 23:03:31 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1472104533.377457-1069707519', 23:03:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:03:31 INFO - '_': '/tools/buildbot/bin/python'} 23:03:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:03:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:03:31 INFO - Running setup.py (path:/tmp/pip-_OOQIk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:03:32 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)) 23:03:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:03:32 INFO - Running setup.py (path:/tmp/pip-GnpfYy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:03:32 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)) 23:03:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:03:32 INFO - Running setup.py (path:/tmp/pip-MaqGer-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:03:32 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)) 23:03:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:03:32 INFO - Running setup.py (path:/tmp/pip-JViz5r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:03:32 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)) 23:03:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:03:32 INFO - Running setup.py (path:/tmp/pip-iYTPft-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:03:32 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)) 23:03:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:03:32 INFO - Running setup.py (path:/tmp/pip-SeoeXR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:03:32 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)) 23:03:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:03:32 INFO - Running setup.py (path:/tmp/pip-gSJk9u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:03:32 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)) 23:03:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:03:32 INFO - Running setup.py (path:/tmp/pip-RIEQCv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:03:32 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)) 23:03:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:03:32 INFO - Running setup.py (path:/tmp/pip-YGrymG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:03:32 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)) 23:03:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:03:32 INFO - Running setup.py (path:/tmp/pip-6O7E5R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:03:32 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)) 23:03:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:03:32 INFO - Running setup.py (path:/tmp/pip-l_YN8g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:03:32 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)) 23:03:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:03:32 INFO - Running setup.py (path:/tmp/pip-UkS0X3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:03:32 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)) 23:03:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:03:32 INFO - Running setup.py (path:/tmp/pip-JmiYVu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:03:32 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)) 23:03:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:03:32 INFO - Running setup.py (path:/tmp/pip-N7et6u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:03:32 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)) 23:03:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:03:32 INFO - Running setup.py (path:/tmp/pip-EozG8y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:03:33 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)) 23:03:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:03:33 INFO - Running setup.py (path:/tmp/pip-AlUq88-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:03:33 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)) 23:03:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:03:33 INFO - Running setup.py (path:/tmp/pip-KJ6Sl4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:03:33 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)) 23:03:33 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)) 23:03:33 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)) 23:03:33 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)) 23:03:33 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)) 23:03:33 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)) 23:03:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:03:33 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 23:03:33 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 23:03:33 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 23:03:33 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 23:03:33 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 23:03:33 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 23:03:35 INFO - Downloading blessings-1.6.tar.gz 23:03:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:03:35 INFO - Installing collected packages: blessings 23:03:35 INFO - Running setup.py install for blessings 23:03:35 INFO - Successfully installed blessings 23:03:35 INFO - Cleaning up... 23:03:35 INFO - Return code: 0 23:03:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:03:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:03:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:03:35 INFO - Reading from file tmpfile_stdout 23:03:35 INFO - Current package versions: 23:03:35 INFO - argparse == 1.2.1 23:03:35 INFO - blessings == 1.6 23:03:35 INFO - blobuploader == 1.2.4 23:03:35 INFO - docopt == 0.6.1 23:03:35 INFO - functools32 == 3.2.3-2 23:03:35 INFO - jsonschema == 2.5.1 23:03:35 INFO - manifestparser == 1.1 23:03:35 INFO - mozInstall == 1.12 23:03:35 INFO - mozcrash == 0.17 23:03:35 INFO - mozdebug == 0.1 23:03:35 INFO - mozdevice == 0.48 23:03:35 INFO - mozfile == 1.2 23:03:35 INFO - mozhttpd == 0.7 23:03:35 INFO - mozinfo == 0.9 23:03:35 INFO - mozleak == 0.1 23:03:35 INFO - mozlog == 3.3 23:03:35 INFO - moznetwork == 0.27 23:03:35 INFO - mozprocess == 0.23 23:03:35 INFO - mozprofile == 0.28 23:03:35 INFO - mozrunner == 6.12 23:03:35 INFO - mozscreenshot == 0.1 23:03:35 INFO - mozsystemmonitor == 0.3 23:03:35 INFO - moztest == 0.7 23:03:35 INFO - mozversion == 1.4 23:03:35 INFO - psutil == 3.1.1 23:03:35 INFO - requests == 1.2.3 23:03:35 INFO - wsgiref == 0.1.2 23:03:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:03:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:03:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:03:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:03:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:03:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:03:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:03:35 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 0xd7e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xee18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d3b0>, '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 0x103e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e740>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1472104533.377457-1069707519', '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 23:03:35 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 23:03:35 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 23:03:35 INFO - Using env: {'DISPLAY': ':0', 23:03:35 INFO - 'HOME': '/home/cltbld', 23:03:35 INFO - 'LANG': 'en_US.UTF-8', 23:03:35 INFO - 'LANGUAGE': 'en_US:en', 23:03:35 INFO - 'LOGNAME': 'cltbld', 23:03:35 INFO - 'MAIL': '/var/mail/cltbld', 23:03:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:03:35 INFO - 'MOZ_NO_REMOTE': '1', 23:03:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:03:35 INFO - 'NO_EM_RESTART': '1', 23:03:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:03:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:03:35 INFO - 'PWD': '/builds/slave/test', 23:03:35 INFO - 'SHELL': '/bin/bash', 23:03:35 INFO - 'SHLVL': '1', 23:03:35 INFO - 'TERM': 'linux', 23:03:35 INFO - 'TMOUT': '86400', 23:03:35 INFO - 'USER': 'cltbld', 23:03:35 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1472104533.377457-1069707519', 23:03:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:03:35 INFO - '_': '/tools/buildbot/bin/python'} 23:03:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:03:35 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)) 23:03:35 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)) 23:03:35 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)) 23:03:35 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)) 23:03:35 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)) 23:03:35 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)) 23:03:35 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)) 23:03:35 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)) 23:03:35 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)) 23:03:35 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)) 23:03:35 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)) 23:03:35 INFO - Cleaning up... 23:03:35 INFO - Return code: 0 23:03:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:03:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:03:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:03:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:03:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:03:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:03:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:03:35 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 0xd7e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xee18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d3b0>, '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 0x103e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e740>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1472104533.377457-1069707519', '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 23:03:35 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 23:03:35 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 23:03:35 INFO - Using env: {'DISPLAY': ':0', 23:03:35 INFO - 'HOME': '/home/cltbld', 23:03:35 INFO - 'LANG': 'en_US.UTF-8', 23:03:35 INFO - 'LANGUAGE': 'en_US:en', 23:03:35 INFO - 'LOGNAME': 'cltbld', 23:03:35 INFO - 'MAIL': '/var/mail/cltbld', 23:03:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:03:35 INFO - 'MOZ_NO_REMOTE': '1', 23:03:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:03:35 INFO - 'NO_EM_RESTART': '1', 23:03:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:03:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:03:35 INFO - 'PWD': '/builds/slave/test', 23:03:35 INFO - 'SHELL': '/bin/bash', 23:03:35 INFO - 'SHLVL': '1', 23:03:35 INFO - 'TERM': 'linux', 23:03:35 INFO - 'TMOUT': '86400', 23:03:35 INFO - 'USER': 'cltbld', 23:03:35 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1472104533.377457-1069707519', 23:03:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:03:35 INFO - '_': '/tools/buildbot/bin/python'} 23:03:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:03:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:03:36 INFO - Cleaning up... 23:03:36 INFO - Return code: 0 23:03:36 INFO - Running post-action listener: _resource_record_post_action 23:03:36 INFO - Running post-action listener: _start_resource_monitoring 23:03:36 INFO - Starting resource monitoring. 23:03:36 INFO - [mozharness: 2016-08-25 06:03:36.073160Z] Finished create-virtualenv step (success) 23:03:36 INFO - [mozharness: 2016-08-25 06:03:36.073722Z] Running install step. 23:03:36 INFO - Running pre-action listener: _resource_record_pre_action 23:03:36 INFO - Running main action method: install 23:03:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:03:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:03:36 INFO - Reading from file tmpfile_stdout 23:03:36 INFO - Detecting whether we're running mozinstall >=1.0... 23:03:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:03:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:03:36 INFO - Reading from file tmpfile_stdout 23:03:36 INFO - Output received: 23:03:36 INFO - Usage: mozinstall [options] installer 23:03:36 INFO - Options: 23:03:36 INFO - -h, --help show this help message and exit 23:03:36 INFO - -d DEST, --destination=DEST 23:03:36 INFO - Directory to install application into. [default: 23:03:36 INFO - "/builds/slave/test"] 23:03:36 INFO - --app=APP Application being installed. [default: firefox] 23:03:36 INFO - mkdir: /builds/slave/test/build/application 23:03:36 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'] 23:03:36 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 23:03:53 INFO - Reading from file tmpfile_stdout 23:03:53 INFO - Output received: 23:03:53 INFO - /builds/slave/test/build/application/firefox/firefox 23:03:53 INFO - Running post-action listener: _resource_record_post_action 23:03:53 INFO - [mozharness: 2016-08-25 06:03:53.008926Z] Finished install step (success) 23:03:53 INFO - [mozharness: 2016-08-25 06:03:53.009206Z] Running run-tests step. 23:03:53 INFO - Running pre-action listener: _resource_record_pre_action 23:03:53 INFO - Running main action method: run_tests 23:03:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:03:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:03:53 INFO - Python 2.7.3 23:03:53 INFO - Return code: 0 23:03:53 INFO - grabbing minidump binary from tooltool 23:03:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:03:53 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 0x103d3b0>, '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 0x103e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:03:53 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 23:03:53 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 23:03:53 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 23:03:53 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:03:53 INFO - Return code: 0 23:03:53 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 23:03:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:03:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:03:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 23:03:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:03:53 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/SgsUDC-MSc2iWDIuBjyqwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-062', '--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 23:03:53 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/SgsUDC-MSc2iWDIuBjyqwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-062 --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 23:03:53 INFO - Using env: {'DISPLAY': ':0', 23:03:53 INFO - 'HOME': '/home/cltbld', 23:03:53 INFO - 'LANG': 'en_US.UTF-8', 23:03:53 INFO - 'LANGUAGE': 'en_US:en', 23:03:53 INFO - 'LOGNAME': 'cltbld', 23:03:53 INFO - 'MAIL': '/var/mail/cltbld', 23:03:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:03:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 23:03:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:03:53 INFO - 'MOZ_NO_REMOTE': '1', 23:03:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:03:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:03:53 INFO - 'NO_EM_RESTART': '1', 23:03:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:03:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:03:53 INFO - 'PWD': '/builds/slave/test', 23:03:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:03:53 INFO - 'SHELL': '/bin/bash', 23:03:53 INFO - 'SHLVL': '1', 23:03:53 INFO - 'TERM': 'linux', 23:03:53 INFO - 'TMOUT': '86400', 23:03:53 INFO - 'USER': 'cltbld', 23:03:53 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1472104533.377457-1069707519', 23:03:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:03:53 INFO - '_': '/tools/buildbot/bin/python'} 23:03:53 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/SgsUDC-MSc2iWDIuBjyqwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-062', '--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 23:03:53 INFO - mozversion application_buildid: 20160824201226 23:03:53 INFO - mozversion application_changeset: 8a0abccd95639178abc9c36aebd9e0c7cf9ea5d5 23:03:53 INFO - mozversion application_display_name: FirefoxDeveloperEdition 23:03:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:03:53 INFO - mozversion application_name: Firefox 23:03:53 INFO - mozversion application_remotingname: firefox-dev 23:03:53 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 23:03:53 INFO - mozversion application_vendor: Mozilla 23:03:53 INFO - mozversion application_version: 50.0a2 23:03:53 INFO - mozversion platform_buildid: 20160824201226 23:03:53 INFO - mozversion platform_changeset: 8a0abccd95639178abc9c36aebd9e0c7cf9ea5d5 23:03:53 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 23:03:53 INFO - mozversion platform_version: 50.0a2 23:03:53 INFO - using testdate: 1472105033 23:03:53 INFO - actual date: 1472105033 23:03:53 INFO - starting webserver on 'localhost:54350' 23:03:53 INFO - SUITE-START | Running 2 tests 23:03:53 INFO - TEST-START | dromaeo_css 23:03:53 INFO - operating with platform_type : linux_ 23:03:53 INFO - Initialising browser for dromaeo_css test... 23:03:53 INFO - TEST-INFO | started process 24650 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpD7m7mB/profile http://localhost:54350/getInfo.html) 23:03:56 INFO - PROCESS | 24650 | __metrics Screen width/height:1600/1200 23:03:56 INFO - PROCESS | 24650 | colorDepth:24 23:03:56 INFO - PROCESS | 24650 | Browser inner width/height: 1024/697 23:03:56 INFO - PROCESS | 24650 | __metrics 23:03:57 INFO - PROCESS | 24650 | 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:03:57 INFO - PROCESS | 24650 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:03:58 INFO - TEST-INFO | 24650: exit 0 23:03:58 INFO - Browser initialized. 23:03:58 INFO - Running cycle 1/1 for dromaeo_css test... 23:03:58 INFO - TEST-INFO | started process 24737 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpD7m7mB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:04:10 INFO - PROCESS | 24737 | RSS: Main: 145862656 23:04:10 INFO - PROCESS | 24737 | 23:05:00 INFO - PROCESS | 24737 | 1472105100974 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:07:09 INFO - PROCESS | 24737 | Cycle 1(1): loaded http://localhost:54350/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54350/tests/dromaeo/cssquery-ext.html) 23:07:10 INFO - PROCESS | 24737 | RSS: Main: 224440320 23:07:10 INFO - PROCESS | 24737 | 23:10:13 INFO - PROCESS | 24737 | Cycle 1(1): loaded http://localhost:54350/tests/dromaeo/cssquery-ext.html (next: http://localhost:54350/tests/dromaeo/cssquery-jquery.html) 23:10:13 INFO - PROCESS | 24737 | RSS: Main: 228630528 23:10:13 INFO - PROCESS | 24737 | 23:13:15 INFO - PROCESS | 24737 | Cycle 1(1): loaded http://localhost:54350/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54350/tests/dromaeo/cssquery-mootools.html) 23:13:16 INFO - PROCESS | 24737 | RSS: Main: 175030272 23:13:16 INFO - PROCESS | 24737 | 23:16:18 INFO - PROCESS | 24737 | Cycle 1(1): loaded http://localhost:54350/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54350/tests/dromaeo/cssquery-prototype.html) 23:16:18 INFO - PROCESS | 24737 | RSS: Main: 201883648 23:16:18 INFO - PROCESS | 24737 | 23:19:21 INFO - PROCESS | 24737 | Cycle 1(1): loaded http://localhost:54350/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54350/tests/dromaeo/cssquery-yui.html) 23:19:21 INFO - PROCESS | 24737 | RSS: Main: 177582080 23:19:21 INFO - PROCESS | 24737 | 23:22:24 INFO - PROCESS | 24737 | Cycle 1(1): loaded http://localhost:54350/tests/dromaeo/cssquery-yui.html (next: http://localhost:54350/tests/dromaeo/cssquery-dojo.html) 23:22:24 INFO - PROCESS | 24737 | RSS: Main: 265715712 23:22:24 INFO - PROCESS | 24737 | 23:22:24 INFO - PROCESS | 24737 | __start_tp_report 23:22:24 INFO - PROCESS | 24737 | _x_x_mozilla_page_load 23:22:24 INFO - PROCESS | 24737 | _x_x_mozilla_page_load_details 23:22:24 INFO - PROCESS | 24737 | |i|pagename|runs| 23:22:24 INFO - PROCESS | 24737 | |0;dojo.html;3232.159638493992;3248.0905346502977;3262.673732626739;3288.917777055575;3311.6191637961642;5456.317960254968;5561.221428999943;5573.944260557393;5585.469380408855;5587.692676902769;5565.6104072714925;5628.155776633507;5632;5638.577106717003;5668.546516278698;5023.095842748304;5064.493550644943;5079.339685840845;5130.230465430178;5189.065968125736;5106.514881086291;5150.7424628768595;5189.42916279209;5190.7664155112925;5200.453952335007;5045.798168073273;5098.668586541881;5117.462666420028;5131.435542090367;5141.0489059523925;5200.687958722451;5219.060569097561;5221.686698798084;5236.581073514109;5259.500347467004;4940.7035577865445;4966.602671786249;5002.824901128443;5110.872228194287;5116.488351164891;5443;5472.91790623141;5498.147787093007;5513.724313784295;5539.335279766454;5929.495992840571;5934.139549765303;5963.761449542013;6004.939950600482;6009.09863520471;5856.648601083906;5907.054871220627;5939.316978547436;6034.818955431344;6053.394660533955;5557.527610153142;5602.943970560324;5612.803551875665;5619.887602247973;5621.437856214346;5929.792457263975;6083.228398393025;6091.866868864193;6104.145419641254;6129.693515324216;5715.085586306171;5747.568932330092;5775.768969241268;5903.645781253139;5914.4381283777975;5939.703014849327;5950.9702451487465;5964.1650168976375;5972.074328479006;5986.5210783137245;5753.453421924911;5924.7926322578505;5931.584789064724;5933.376995415506;5935.317438494542;5801.7389217485315;5844.503217226584;5854.356020837705;5918.526517878559;5936.376680448577;4557.680962332605;4559.224931761609;4560.703554268962;4561.29299958507;4599.103174880933;4043.454133691973;4083.1017176221185;4098.877033688994;4106.753594784323;4136.338185890243;5517.917231241535;5536.861578460571;5574.219609254707;5628.380878103405;5661.943380566142;5887.0580707086665;5917.082852157922;5923.763049477972;5930.7924222652;5932.555058370639;5463.125899856004;5627.52166065889;5697.829065127969;5700.743466544015;5711.914321285184;5004.299398084271;5041.521055499722;5051;5070.873228169252;5089.312942752755;6112.205413296243;6120.755169793248;6133.202683651097;6135.509159267248;6147.784827531104;5644.8024319148635;5833.4749872511675;5846.853828654148;5904.675242861684;5918.822435326947;5974.820755377345;6014.158017877413;6072.423119803611;6128.778133445034;6136.7948077883175;5781.21953536284;5791.189233507396;5805.970970145122;5807.216025836458;5807.303123625023;5707.1724599933605;5898.852528686817;5913.083472061838;5917.704114794329;5932.881342373042;5694.345150307602;5771.826845194651;5782.710864456676;5822.068469045102;5858.8242352729485;5806.129080637938;5825.650460972355;5826.096954971986;5833.533317334688;5833.649981001154;5594.524465420485;5817.592768506333;5832.300123985149;5843.152742852178;5848.941510584839;5735.9139612905;5743.483086522233;5776.42235776419;5863.765449381977;5898.144769008384;5644.520215781705;5671.461211184931;5699.230603868425;5705.429457054262;5713.342965558848 23:22:24 INFO - PROCESS | 24737 | |1;ext.html;606.0878378041048;632.3234139470766;642.919635045619;656.0847617573485;665.2084020016177;34909.82545087274;37983.620163798354;38034;38073.42889856654;38120.80939595305;38385;47457;48317.51682483174;48464.75767621166;53028.73485632576;9215;9324.627014919395;9328;9328.393654412475;9330.160285574297;8570.657173713074;9152.542372881362;9250.797396338467;9370;9379;11129.443527823576;11422.143339249547;11430.37132957687;11849.644510664693;11850.229735067192;11235.21353505255;11283.984441400231;11321;11322.980931716143;11324.490397932113;8288;10719.249652524326;11097.667069987914;11206.831897521544;11208.887911120866;46138.76930615359;46834.76582617065;46851.29723054157;46879.29681054787;46905.53094469046;3812.6377994090517;3979.641832235113;4456.420665313489;4525.230710779176;4706.858794236178;34458;34899.476507852145;34940;37636;37658.24683506342;4689.88275293117;4757.976210118962;4762.190427627312;4779.450363208241;4831.251156070814;396026;397690.9999999971;397923;399645;401304;54337.72831135819;54722;54814.177787333225;54894.17658735122;57489;71600.28399715936;71837.6408117956;71906.64046679733;71919.56160876858;71998;19236.903815481113;19383.127759250867;19405.32081377145;19429.514262143275;19445.708314375297;14544;14545.563633091024;14637.414503419957;14648;14688.779668304984;18593.25626974933;20471.283505077252;20710.482237944172;21019.26432574883;21186.15255389767;7372.336489715844;7521.623918804147;7694.730684426018;7712.807179820549;7728.6522106505345;927.4435338796668;1000.8949060348705;1023.6007956896816;1073.7959787640323;1106.9889301107046;35248.471272930925;35669.46495802565;35749.46375804365;35799.64200357963;35902.84582925033;1105.867295924494;1149.4712432281078;1153.135148638521;1179.8466199394024;1208.178438661701;18832.81167188338;18874.622507550048;18880.339188128626;18960;18996.240150394107;420592;626884;629640;630269;630553;68428;68909;68917.31082689109;68984.31015689779;69157.30842691509;1332.3072002558615;1425.201886943315;1438.9927051064087;1473.8894582906325;1506.540505145941;29904.250408263484;35004.82497587598;35095.47356789701;35114.82442587873;35128.64871351356;52020.479795201565;52435.47564524459;52516.47483525116;52628.736856315474;52726.73636631792;405927;409762;410510;411238;411437;30187.547186791777;30941.226469337547;31684.683153168175;31776.523352150198;33081.17297067496;53290;53566.464335357705;53943.73028134834;54351;55008.449915500336;44916.10167796691;45063;45355.11214724698;46073.53926460692;46680;44409.15720465255;45076.549234507234;46927.76536117298;46955.76522117368;47106;44088.11823763571;44358.55641443544;44557;44593;44846.77576612096;38273.61726382701;39680;40248.396274055325;40589.39115913204;41227.58772412357;29828.403431931674;30579.847100764353;30796;30844.691553084183;31054 23:22:24 INFO - PROCESS | 24737 | |2;jquery.html;398.81056498163395;412.10367450794513;422.38121152511576;425.10939581576594;440.4384409877406;15407.614809629751;20419.183232670694;20447.693284600726;20510.69233961488;21284.57430851382;20358.694619580718;20386.082626281815;20390.388288351336;20391.49021274465;20632.1747130115;4400.955990440103;5711.4643232972085;5954.4640982311585;5969.4925931295675;6468.223813142429;6572.21133463985;6746.257911629741;6748.6625668716615;6752.932470675304;6758.966205168992;6598.241202261754;6699.564528305669;6715.32846715327;6719.193696756396;6752.561083529555;6643.534952553323;6671.566348187353;6722.495812814059;6722.630255335955;6733.797986060377;5828.300603927513;5904.084866845646;5929.051351783737;5930.8813823723285;5936.376680448534;23975.640365394534;24730.381740456454;24869.87565062163;24898.1285655003;25137.120200792884;2600.2719238613217;2618;2664.40050988528;2850.586664933594;2958.511845545504;15320.616984575363;15519.767203491956;15716.764248536281;15792.210389480595;16329.510114696579;1964.7838737738837;2013.8388928885654;2115.851890367675;2137.6900349449397;2180.8800515971616;75946;85361;85440.57279713624;86766.5661671694;88186;11427.942860285646;11429.428528573539;11709.180357374902;11755.764884702257;11786.882131178578;16297.185140742915;16311.510654680378;16344.836551634331;16407.507774766775;16549.6690066198;8648;9000.954995224982;9007.279417646572;9198.954005230065;9227.723168304961;8778.166074222938;8818;8833.116688331116;9008.774780630536;9024.819503609891;4544.7045942013665;4855;4915.18899381598;5088.185890257541;5112.846614601568;1868.4581471373422;1968;2075.885826279539;2175.706279652258;2188.682641017044;928.5635751196927;998.5157198758742;1001.3341128149699;1038.8182068138046;1085.5983286184005;20355.185792568132;20553.280635177696;20714.48213794667;20833;20850.89574552148;1046.931949423285;1053.578568572577;1060.0724366179593;1086.5436516663021;1087.6791346552786;9897.950510247403;11422.714432139097;11463.484143213574;11467.655970320904;11614.361210133355;535299;535844;543531;551277;553880;91608;91800.54099729592;93122;105141;112888.99999999836;1063.0698139128262;1065.169168048923;1096.6709987004026;1101.300674071954;1111.5831563163813;17746;18174.36389726353;18565.190018886926;18579.16393762311;18894.433167005012;30807;35472;43903.560964389944;46114.30828537642;46627.53372466368;62677.68661156665;63500.68249658722;63503.36496634974;64733.676331618044;67254;7889.605519724106;8679.435836670596;8733.170348816851;8967.730968070968;9301.116393942564;12104.818427723416;13479.663008424868;14056.859431405965;14082.507112251022;14168.291585420893;5917.644941303535;12163.939180304042;12300.261984280973;12620.747585048064;12660.936695316464;12086.818697719717;12418.937905310415;12627.93686031564;12707.809382859079;12789.168704034208;12017.519299228283;12187.573434929735;12229.266244025386;12246.44890979926;12665.176763510342;8283.50298982063;8290.751277461686;8296.336293096658;8311.293540049164;8586.098459661645;11463;12089.214201076902;12127.81808272859;12399.442025108712;12683.936580317039 23:22:24 INFO - PROCESS | 24737 | |3;mootools.html;1440.4958264607383;1495.7382458069837;1569.089927841852;1762.7091529897568;1880.9247630094796;8112.391570632204;9021.639134434623;9361.578728957196;9374.953125234364;9591.52042397879;9368.906310936889;9457.33798634096;9463.905360946406;9466.905330946689;9469.905300946988;2500.2999160235163;2508.3352911478473;2508.347829564308;2509.2346834215555;2528.709198442175;2407.4703565215623;2460.0897667862914;2461.0770960889668;2463.2363967170236;2469.3085935937966;2445.6942882139733;2461.2985299189736;2491.7134529529153;2507.147569826259;2507.5110353481077;2346.5658853112145;2395.2694428199393;2399.2802159352213;2431.8419302745265;2438.171021852561;2395.0898658509655;2417.697787776528;2421.9152329668555;2430.6354046893107;2432.1365915100237;9188;9369.812603747887;9399.154076133182;9490.90509094914;9491.66779162726;5447.918281225785;5636.436356364372;5639.520640745501;5651.1523271509195;5654.37801841797;3546.0957455848593;3607.963920360789;3610.7833529988284;3617.1680513481783;3650.7444478886487;1243.0428570001138;1259.93070381129;1263.816746571751;1266.8226448297246;1302.3553341096217;13310.866891331156;13412.664683382798;13426.462941482328;13434.86565134346;13446.596602101952;4737.128834110507;4808.687435316693;4825.30033145199;4840.588549973221;4930.978298897435;10379;10387.584496620202;10392.636257730943;10403.84394234072;10404;5499.50504454601;5552;5555.166724991283;5556.555475561945;5576.247206627135;4642.744649044335;4905.681130726492;5074.112030394666;5145.742712864342;5277.261183434322;4102;4131.710780245354;4191.350340697197;4207.579242075829;4246.7027308088755;1248.2884755689352;1259.7102666386781;1264.291996481973;1265.1207410849372;1282.8268183795255;936.8641546975787;965.5461932891529;1001.8897921228659;1032.1897310611134;1036.6216331039268;6396.648184349812;6452.322506136882;6518.934810651833;6656.434203092695;6757.966210169016;481.1531704200632;508.9567386772166;510.2397427832577;511.0545490841987;515.8220413957179;4283.678724095706;4296.935545966813;4304.16068866568;4329.25946089032;4409.206342858316;11583.420828958519;11820.763584728258;11881.465334059989;11898.762024759457;11915.761684766256;11829;11849.644510664693;11864.525418983145;11870.584529541424;11874.228175168586;8441.788955276044;8710.216080552655;8764.517951512727;8775.999999999873;8871.290296776242;6358.872822543616;6431.742730290736;6610.4050635443045;6622.602643841192;6717.462602991846;10747.731306717395;10852.67441976742;11051.115910727283;11055.22613417053;11168.609098681507;9911.752206194902;10583.259171858204;10732.302400343953;10774.407407592658;11153.609623663133;7939.325157361458;8138.267555919995;8162.959185204036;8300.958495207728;8313.584320783815;8821.294296456397;8981.955090224506;8982.955085224532;8985.910140898768;9000.324975626723;7989.600519974094;8027.237412445808;8091.271785539329;8096.190380961857;8355.916440835757;7995.400344974267;8062.1534738853925;8097;8127.593620319079;8469.661213551635;7568.886466702894;7580.431467639839;7603.315701586884;7614.390848732199;7676.80807979805;5672.14917762339;5755.827325180251;5833.416658334133;5884.676342801187;5897.5576831736935;5538.750756215899;5634.380218176079;5675;5705.686187259741;5809.651420914759 23:22:24 INFO - PROCESS | 24737 | |4;prototype.html;355.124617817081;359.48952487467795;361.567926328038;362.7062079715432;363.9745217834749;5389.757460914258;5443.265159203506;5508.504234618884;5578.30271216098;5587.7485513151905;5455.863603409916;5456.154296084103;5480;5502.58730595206;5533.944660553393;4377;4379.474463064436;4410.206162890679;4413.117376524692;4416.183006143858;4155.189738001091;4307.6123148916595;4360.193364227613;4392.648588112943;4401.05377343871;4274.487061552602;4289.849855255066;4298.978505107486;4309.612134907858;4310.978445107786;4286.335617979218;4297.892552686192;4299.50555686097;4333.371661109153;4337.522872484024;3761.54861416629;3796.3925771876366;3821.8916514210846;3835.2137811748526;3841.8271177797064;5301.920471192935;5314.388845282765;5317.1492561190025;5362.51737343641;5371.623986320959;4892.165437960764;4909.705417674951;4913.385826771653;4919.188333924898;4942.5304596063315;5422.701751403672;5651.40660230674;5690.430956904318;5690.743916523725;5725.885482290372;4121.278776214151;4140.171965606891;4140.792960352001;4144.70987030908;4150.294449943517;5984.790532331391;6016.518678505709;6019.879602407971;6031.668258245794;6043.365446628085;4132.958670413257;4153.7507749535125;4166.895827604334;4289.03496713237;4291.699581029298;4192.224438478891;4193.050231642301;4197.307444271661;4201.6008479194425;4210.831566737357;4813.181759100961;4819.903601928012;4834.661573689808;4841.346418233564;4844.055409195244;5553.694546799884;5707.600467967285;5773.855653608609;5784.855378615484;5789.189513468118;1473.1234915225423;1489.2181604657642;1489.9851001490063;1490.6720521485256;1494.5142828580754;950.1025623535243;954.2652157838564;955.7145639115379;958.4153666263574;976.1946394224793;4967.503249675004;4972.428170760337;4986.501349864984;4990.3762029746285;5006.599472042228;5145.794168233304;5161.4322424533275;5179.161397703836;5184.7926082956255;5207;4663.178776840031;4943.307936888838;4945.604351651859;5023.422306434733;5048.419431765394;2775.4865349910333;2777.4722802667834;2792.46943080814;2799.230211691801;2802.6776920654384;5394.946050539523;5415.241866138744;5461.317335333083;5464.808731694372;5473.370562385377;5070.04337205246;5136.5890728741615;5209.765560549784;5248.6588371755715;5253.817890974577;5338.759755810997;5339.051246295152;5364.7317634118135;5412;5417.349918009864;5125.743712814419;5143.5113664201845;5199.948000520098;5234.895302094013;5241.554467870121;4921.4832442593015;5047.369078865142;5068.619853511075;5086.338775959101;5164.612654050886;5385.007547207401;5388.541973932103;5414.214938833926;5431.511163995259;5440;4039.5960403960544;4106;4178.749275043507;4222.171145332573;4222.619964203236;5877.735501902339;5888.146218798336;5892.646441213541;5903.852403689943;5908.763649453974;5491.231227628055;5720.227769251098;5745;5763.279590051244;5773.653580785166;5651.891459697068;5707.486326230659;5721.685307308138;5728.140778883201;5747.281589801275;5656.264685590847;5707.514861236676;5707.971460142673;5721.170430287668;5730.71346432685;5523.420040895809;5660.15097735343;5693.743781529924;5709.629688874557;5728.484436400744;5561.916571251515;5564.527015203753;5570.5265052470995;5599.524040456607;5634.267545219095 23:22:24 INFO - PROCESS | 24737 | |5;yui.html;759.8746206875866;765.6171914042978;809.3403875841188;830.2901019628217;856.2251162697761;16405.179741012947;17474.126293685313;17502.649947001068;17860.106994650247;18676.719849202273;17788.822111778878;17827.465176044738;17968.191431385585;17984.10079496023;17985.7302140468;732.582428016031;871.2376670413372;896.7533928169773;897.9730608081768;898.1961144775422;864.8789169516273;895.3598177303227;898.4025622960756;904.9411788233776;906.247814314121;945.4327403557879;945.8533927241289;946.8532377481467;947.8815148106487;948.800751842114;759.1004659478535;942.7171848445507;945.7682867697424;945.8013817098417;947.147567189528;669.0532895952222;677.2313424263447;679.9014142949245;680.0547239337325;682.5597489619167;15972.520824375288;15999;16026.919865400714;16027.35890564376;16039.27823247945;14482.493112741004;14555.345009474599;14779.778303325458;14872.70254594902;14903.478378256708;12856.357182140855;13249.07256492046;13250.3374831259;13381.531646392426;13422.395992180278;4332.9566704332865;4342.348647702838;4386.912261754747;4395.450568678915;4437.2900335946415;89841.55079224627;90399.99999999935;91276.54361728215;92886;93499.53250233705;11643.59247426336;11693;11810.291382516905;11953.282803031672;12007.87992120085;22614.208502702655;22621.2082577109;22644.660330095063;22656;22672.433189170402;8942.46345219289;8974.685885993958;9132.76247319411;9167.220786233245;9259.814803703888;4925.38432695913;5232.816851410258;5327.014502317006;5330.040592693274;5333.973330133324;3828.425736139601;3956.1494278729638;3957.960420395817;3959.584243654375;3964.8810535683974;636.3254949753276;641.0512441586478;655.7737580534708;658.5982550644103;659.446065305146;668.4451904919002;708.3907839258234;763.9618019099134;775.6121939030485;793.7063286584001;9357;9694.418334899787;9896.950515247521;10040.146587539995;10214.131798797185;637.6333608175318;751.3388218367911;759.9278068583476;786.2137862137862;804.6218277409608;4779.904401912012;4829.517048295213;4872.4883887192045;4880.41435027799;4946.826861059845;75962.24037759552;102098;129349;134937;139208;118015.40992295156;118709.40645296719;120343;122404;124267;97665.58170464142;97949;98055.50972245236;99401;100264.49867750761;9089.045650206634;9431.764205894908;9435.811283774423;9448.631022209707;9512.191463725385;57333;57459.71270143623;58272;58849.11726324194;59904.700476499085;46731.29903051525;46738.766306169615;47473;51208;78572.60713696395;12848.807267891176;13022.80465792995;13084.869151308365;13109.410076546768;13242.933785331012;14760.852391475948;14771.630709231926;15291.235438228267;15314.387424502895;15392.076475411512;9725.513724313614;13073.411696473491;14048.438062477795;14075.577732668036;14609.123452592878;13512.324383780575;13517;13655.590332290047;13796.517121900686;13969.092009019381;13700.90392768596;13782.517611883535;13911.791323129959;14252.073615214978;14366.353514091681;11304.773904522028;11553.537858485566;11688.47401866935;12063.698407539881;12212.267263964191;11543.826842597537;11637.53449861996;11819.231749936227;11891.405429728306;12122.878771212176 23:22:24 INFO - PROCESS | 24737 | __end_tp_report 23:22:24 INFO - PROCESS | 24737 | __start_cc_report 23:22:24 INFO - PROCESS | 24737 | _x_x_mozilla_cycle_collect,1045 23:22:24 INFO - PROCESS | 24737 | __end_cc_report 23:22:24 INFO - PROCESS | 24737 | __startTimestamp1472106144628__endTimestamp 23:22:24 INFO - PROCESS | 24737 | 23:22:24 INFO - PROCESS | 24737 | ------- Summary: start ------- 23:22:24 INFO - PROCESS | 24737 | Number of tests: 6 23:22:24 INFO - PROCESS | 24737 | 23:22:24 INFO - PROCESS | 24737 | [#0] dojo.html Cycles:165 Average:5519.62 Median:5711.91 stddev:601.68 (10.5%) stddev-sans-first:576.15 23:22:24 INFO - PROCESS | 24737 | Values: 3232.2 3248.1 3262.7 3288.9 3311.6 5456.3 5561.2 5573.9 5585.5 5587.7 5565.6 5628.2 5632.0 5638.6 5668.5 5023.1 5064.5 5079.3 5130.2 5189.1 5106.5 5150.7 5189.4 5190.8 5200.5 5045.8 5098.7 5117.5 5131.4 5141.0 5200.7 5219.1 5221.7 5236.6 5259.5 4940.7 4966.6 5002.8 5110.9 5116.5 5443.0 5472.9 5498.1 5513.7 5539.3 5929.5 5934.1 5963.8 6004.9 6009.1 5856.6 5907.1 5939.3 6034.8 6053.4 5557.5 5602.9 5612.8 5619.9 5621.4 5929.8 6083.2 6091.9 6104.1 6129.7 5715.1 5747.6 5775.8 5903.6 5914.4 5939.7 5951.0 5964.2 5972.1 5986.5 5753.5 5924.8 5931.6 5933.4 5935.3 5801.7 5844.5 5854.4 5918.5 5936.4 4557.7 4559.2 4560.7 4561.3 4599.1 4043.5 4083.1 4098.9 4106.8 4136.3 5517.9 5536.9 5574.2 5628.4 5661.9 5887.1 5917.1 5923.8 5930.8 5932.6 5463.1 5627.5 5697.8 5700.7 5711.9 5004.3 5041.5 5051.0 5070.9 5089.3 6112.2 6120.8 6133.2 6135.5 6147.8 5644.8 5833.5 5846.9 5904.7 5918.8 5974.8 6014.2 6072.4 6128.8 6136.8 5781.2 5791.2 5806.0 5807.2 5807.3 5707.2 5898.9 5913.1 5917.7 5932.9 5694.3 5771.8 5782.7 5822.1 5858.8 5806.1 5825.7 5826.1 5833.5 5833.6 5594.5 5817.6 5832.3 5843.2 5848.9 5735.9 5743.5 5776.4 5863.8 5898.1 5644.5 5671.5 5699.2 5705.4 5713.3 23:22:24 INFO - PROCESS | 24737 | 23:22:24 INFO - PROCESS | 24737 | [#1] ext.html Cycles:180 Average:64434.81 Median:407844.50 stddev:126118.30 (30.9%) stddev-sans-first:126381.04 23:22:24 INFO - PROCESS | 24737 | Values: 606.1 632.3 642.9 656.1 665.2 34909.8 37983.6 38034.0 38073.4 38120.8 38385.0 47457.0 48317.5 48464.8 53028.7 9215.0 9324.6 9328.0 9328.4 9330.2 8570.7 9152.5 9250.8 9370.0 9379.0 11129.4 11422.1 11430.4 11849.6 11850.2 11235.2 11284.0 11321.0 11323.0 11324.5 8288.0 10719.2 11097.7 11206.8 11208.9 46138.8 46834.8 46851.3 46879.3 46905.5 3812.6 3979.6 4456.4 4525.2 4706.9 34458.0 34899.5 34940.0 37636.0 37658.2 4689.9 4758.0 4762.2 4779.5 4831.3 396026.0 397691.0 397923.0 399645.0 401304.0 54337.7 54722.0 54814.2 54894.2 57489.0 71600.3 71837.6 71906.6 71919.6 71998.0 19236.9 19383.1 19405.3 19429.5 19445.7 14544.0 14545.6 14637.4 14648.0 14688.8 18593.3 20471.3 20710.5 21019.3 21186.2 7372.3 7521.6 7694.7 7712.8 7728.7 927.4 1000.9 1023.6 1073.8 1107.0 35248.5 35669.5 35749.5 35799.6 35902.8 1105.9 1149.5 1153.1 1179.8 1208.2 18832.8 18874.6 18880.3 18960.0 18996.2 420592.0 626884.0 629640.0 630269.0 630553.0 68428.0 68909.0 68917.3 68984.3 69157.3 1332.3 1425.2 1439.0 1473.9 1506.5 29904.3 35004.8 35095.5 35114.8 35128.6 52020.5 52435.5 52516.5 52628.7 52726.7 405927.0 409762.0 410510.0 411238.0 411437.0 30187.5 30941.2 31684.7 31776.5 33081.2 53290.0 53566.5 53943.7 54351.0 55008.4 44916.1 45063.0 45355.1 46073.5 46680.0 44409.2 45076.5 46927.8 46955.8 47106.0 44088.1 44358.6 44557.0 44593.0 44846.8 38273.6 39680.0 40248.4 40589.4 41227.6 29828.4 30579.8 30796.0 30844.7 31054.0 23:22:24 INFO - PROCESS | 24737 | 23:22:24 INFO - PROCESS | 24737 | [#2] jquery.html Cycles:180 Average:31516.39 Median:24884.00 stddev:89583.42 (360.0%) stddev-sans-first:89804.26 23:22:24 INFO - PROCESS | 24737 | Values: 398.8 412.1 422.4 425.1 440.4 15407.6 20419.2 20447.7 20510.7 21284.6 20358.7 20386.1 20390.4 20391.5 20632.2 4401.0 5711.5 5954.5 5969.5 6468.2 6572.2 6746.3 6748.7 6752.9 6759.0 6598.2 6699.6 6715.3 6719.2 6752.6 6643.5 6671.6 6722.5 6722.6 6733.8 5828.3 5904.1 5929.1 5930.9 5936.4 23975.6 24730.4 24869.9 24898.1 25137.1 2600.3 2618.0 2664.4 2850.6 2958.5 15320.6 15519.8 15716.8 15792.2 16329.5 1964.8 2013.8 2115.9 2137.7 2180.9 75946.0 85361.0 85440.6 86766.6 88186.0 11427.9 11429.4 11709.2 11755.8 11786.9 16297.2 16311.5 16344.8 16407.5 16549.7 8648.0 9001.0 9007.3 9199.0 9227.7 8778.2 8818.0 8833.1 9008.8 9024.8 4544.7 4855.0 4915.2 5088.2 5112.8 1868.5 1968.0 2075.9 2175.7 2188.7 928.6 998.5 1001.3 1038.8 1085.6 20355.2 20553.3 20714.5 20833.0 20850.9 1046.9 1053.6 1060.1 1086.5 1087.7 9898.0 11422.7 11463.5 11467.7 11614.4 535299.0 535844.0 543531.0 551277.0 553880.0 91608.0 91800.5 93122.0 105141.0 112889.0 1063.1 1065.2 1096.7 1101.3 1111.6 17746.0 18174.4 18565.2 18579.2 18894.4 30807.0 35472.0 43903.6 46114.3 46627.5 62677.7 63500.7 63503.4 64733.7 67254.0 7889.6 8679.4 8733.2 8967.7 9301.1 12104.8 13479.7 14056.9 14082.5 14168.3 5917.6 12163.9 12300.3 12620.7 12660.9 12086.8 12418.9 12627.9 12707.8 12789.2 12017.5 12187.6 12229.3 12246.4 12665.2 8283.5 8290.8 8296.3 8311.3 8586.1 11463.0 12089.2 12127.8 12399.4 12683.9 23:22:24 INFO - PROCESS | 24737 | 23:22:24 INFO - PROCESS | 24737 | [#3] mootools.html Cycles:180 Average:6175.89 Median:2653.23 stddev:3549.08 (133.8%) stddev-sans-first:3541.20 23:22:24 INFO - PROCESS | 24737 | Values: 1440.5 1495.7 1569.1 1762.7 1880.9 8112.4 9021.6 9361.6 9375.0 9591.5 9368.9 9457.3 9463.9 9466.9 9469.9 2500.3 2508.3 2508.3 2509.2 2528.7 2407.5 2460.1 2461.1 2463.2 2469.3 2445.7 2461.3 2491.7 2507.1 2507.5 2346.6 2395.3 2399.3 2431.8 2438.2 2395.1 2417.7 2421.9 2430.6 2432.1 9188.0 9369.8 9399.2 9490.9 9491.7 5447.9 5636.4 5639.5 5651.2 5654.4 3546.1 3608.0 3610.8 3617.2 3650.7 1243.0 1259.9 1263.8 1266.8 1302.4 13310.9 13412.7 13426.5 13434.9 13446.6 4737.1 4808.7 4825.3 4840.6 4931.0 10379.0 10387.6 10392.6 10403.8 10404.0 5499.5 5552.0 5555.2 5556.6 5576.2 4642.7 4905.7 5074.1 5145.7 5277.3 4102.0 4131.7 4191.4 4207.6 4246.7 1248.3 1259.7 1264.3 1265.1 1282.8 936.9 965.5 1001.9 1032.2 1036.6 6396.6 6452.3 6518.9 6656.4 6758.0 481.2 509.0 510.2 511.1 515.8 4283.7 4296.9 4304.2 4329.3 4409.2 11583.4 11820.8 11881.5 11898.8 11915.8 11829.0 11849.6 11864.5 11870.6 11874.2 8441.8 8710.2 8764.5 8776.0 8871.3 6358.9 6431.7 6610.4 6622.6 6717.5 10747.7 10852.7 11051.1 11055.2 11168.6 9911.8 10583.3 10732.3 10774.4 11153.6 7939.3 8138.3 8163.0 8301.0 8313.6 8821.3 8982.0 8983.0 8985.9 9000.3 7989.6 8027.2 8091.3 8096.2 8355.9 7995.4 8062.2 8097.0 8127.6 8469.7 7568.9 7580.4 7603.3 7614.4 7676.8 5672.1 5755.8 5833.4 5884.7 5897.6 5538.8 5634.4 5675.0 5705.7 5809.7 23:22:24 INFO - PROCESS | 24737 | 23:22:24 INFO - PROCESS | 24737 | [#4] prototype.html Cycles:180 Average:4663.43 Median:5171.89 stddev:1329.92 (25.7%) stddev-sans-first:1293.74 23:22:24 INFO - PROCESS | 24737 | Values: 355.1 359.5 361.6 362.7 364.0 5389.8 5443.3 5508.5 5578.3 5587.7 5455.9 5456.2 5480.0 5502.6 5533.9 4377.0 4379.5 4410.2 4413.1 4416.2 4155.2 4307.6 4360.2 4392.6 4401.1 4274.5 4289.8 4299.0 4309.6 4311.0 4286.3 4297.9 4299.5 4333.4 4337.5 3761.5 3796.4 3821.9 3835.2 3841.8 5301.9 5314.4 5317.1 5362.5 5371.6 4892.2 4909.7 4913.4 4919.2 4942.5 5422.7 5651.4 5690.4 5690.7 5725.9 4121.3 4140.2 4140.8 4144.7 4150.3 5984.8 6016.5 6019.9 6031.7 6043.4 4133.0 4153.8 4166.9 4289.0 4291.7 4192.2 4193.1 4197.3 4201.6 4210.8 4813.2 4819.9 4834.7 4841.3 4844.1 5553.7 5707.6 5773.9 5784.9 5789.2 1473.1 1489.2 1490.0 1490.7 1494.5 950.1 954.3 955.7 958.4 976.2 4967.5 4972.4 4986.5 4990.4 5006.6 5145.8 5161.4 5179.2 5184.8 5207.0 4663.2 4943.3 4945.6 5023.4 5048.4 2775.5 2777.5 2792.5 2799.2 2802.7 5394.9 5415.2 5461.3 5464.8 5473.4 5070.0 5136.6 5209.8 5248.7 5253.8 5338.8 5339.1 5364.7 5412.0 5417.3 5125.7 5143.5 5199.9 5234.9 5241.6 4921.5 5047.4 5068.6 5086.3 5164.6 5385.0 5388.5 5414.2 5431.5 5440.0 4039.6 4106.0 4178.7 4222.2 4222.6 5877.7 5888.1 5892.6 5903.9 5908.8 5491.2 5720.2 5745.0 5763.3 5773.7 5651.9 5707.5 5721.7 5728.1 5747.3 5656.3 5707.5 5708.0 5721.2 5730.7 5523.4 5660.2 5693.7 5709.6 5728.5 5561.9 5564.5 5570.5 5599.5 5634.3 23:22:24 INFO - PROCESS | 24737 | 23:22:24 INFO - PROCESS | 24737 | [#5] yui.html Cycles:180 Average:22228.48 Median:25584.29 stddev:33023.52 (129.1%) stddev-sans-first:33076.82 23:22:24 INFO - PROCESS | 24737 | Values: 759.9 765.6 809.3 830.3 856.2 16405.2 17474.1 17502.6 17860.1 18676.7 17788.8 17827.5 17968.2 17984.1 17985.7 732.6 871.2 896.8 898.0 898.2 864.9 895.4 898.4 904.9 906.2 945.4 945.9 946.9 947.9 948.8 759.1 942.7 945.8 945.8 947.1 669.1 677.2 679.9 680.1 682.6 15972.5 15999.0 16026.9 16027.4 16039.3 14482.5 14555.3 14779.8 14872.7 14903.5 12856.4 13249.1 13250.3 13381.5 13422.4 4333.0 4342.3 4386.9 4395.5 4437.3 89841.6 90400.0 91276.5 92886.0 93499.5 11643.6 11693.0 11810.3 11953.3 12007.9 22614.2 22621.2 22644.7 22656.0 22672.4 8942.5 8974.7 9132.8 9167.2 9259.8 4925.4 5232.8 5327.0 5330.0 5334.0 3828.4 3956.1 3958.0 3959.6 3964.9 636.3 641.1 655.8 658.6 659.4 668.4 708.4 764.0 775.6 793.7 9357.0 9694.4 9897.0 10040.1 10214.1 637.6 751.3 759.9 786.2 804.6 4779.9 4829.5 4872.5 4880.4 4946.8 75962.2 102098.0 129349.0 134937.0 139208.0 118015.4 118709.4 120343.0 122404.0 124267.0 97665.6 97949.0 98055.5 99401.0 100264.5 9089.0 9431.8 9435.8 9448.6 9512.2 57333.0 57459.7 58272.0 58849.1 59904.7 46731.3 46738.8 47473.0 51208.0 78572.6 12848.8 13022.8 13084.9 13109.4 13242.9 14760.9 14771.6 15291.2 15314.4 15392.1 9725.5 13073.4 14048.4 14075.6 14609.1 13512.3 13517.0 13655.6 13796.5 13969.1 13700.9 13782.5 13911.8 14252.1 14366.4 11304.8 11553.5 11688.5 12063.7 12212.3 11543.8 11637.5 11819.2 11891.4 12122.9 23:22:24 INFO - PROCESS | 24737 | -------- Summary: end -------- 23:22:24 INFO - PROCESS | 24737 | 23:22:25 INFO - TEST-INFO | 24737: exit 0 23:22:25 INFO - TEST-OK | dromaeo_css | took 1112120ms 23:22:25 INFO - TEST-START | kraken 23:22:25 INFO - operating with platform_type : linux_ 23:22:25 INFO - Initialising browser for kraken test... 23:22:25 INFO - TEST-INFO | started process 24951 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2re5iU/profile http://localhost:54350/getInfo.html) 23:22:28 INFO - PROCESS | 24951 | __metrics Screen width/height:1600/1200 23:22:28 INFO - PROCESS | 24951 | colorDepth:24 23:22:28 INFO - PROCESS | 24951 | Browser inner width/height: 1024/697 23:22:28 INFO - PROCESS | 24951 | __metrics 23:22:29 INFO - PROCESS | 24951 | 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:22:29 INFO - PROCESS | 24951 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:22:30 INFO - TEST-INFO | 24951: exit 0 23:22:30 INFO - Browser initialized. 23:22:30 INFO - Running cycle 1/1 for kraken test... 23:22:30 INFO - TEST-INFO | started process 25038 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2re5iU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:22:42 INFO - PROCESS | 25038 | RSS: Main: 144691200 23:22:42 INFO - PROCESS | 25038 | 23:22:44 INFO - PROCESS | 25038 | Cycle 1(1): loaded http://localhost:54350/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54350/tests/kraken/driver.html?testName=audio-beat-detection) 23:22:45 INFO - PROCESS | 25038 | RSS: Main: 191401984 23:22:45 INFO - PROCESS | 25038 | 23:22:48 INFO - PROCESS | 25038 | Cycle 1(1): loaded http://localhost:54350/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54350/tests/kraken/driver.html?testName=audio-dft) 23:22:48 INFO - PROCESS | 25038 | RSS: Main: 182038528 23:22:48 INFO - PROCESS | 25038 | 23:22:51 INFO - PROCESS | 25038 | Cycle 1(1): loaded http://localhost:54350/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54350/tests/kraken/driver.html?testName=audio-fft) 23:22:52 INFO - PROCESS | 25038 | RSS: Main: 268599296 23:22:52 INFO - PROCESS | 25038 | 23:22:54 INFO - PROCESS | 25038 | Cycle 1(1): loaded http://localhost:54350/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54350/tests/kraken/driver.html?testName=audio-oscillator) 23:22:54 INFO - PROCESS | 25038 | RSS: Main: 174985216 23:22:54 INFO - PROCESS | 25038 | 23:22:57 INFO - PROCESS | 25038 | Cycle 1(1): loaded http://localhost:54350/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54350/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:22:57 INFO - PROCESS | 25038 | RSS: Main: 176451584 23:22:57 INFO - PROCESS | 25038 | 23:23:00 INFO - PROCESS | 25038 | Cycle 1(1): loaded http://localhost:54350/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54350/tests/kraken/driver.html?testName=imaging-darkroom) 23:23:01 INFO - PROCESS | 25038 | RSS: Main: 212574208 23:23:01 INFO - PROCESS | 25038 | 23:23:04 INFO - PROCESS | 25038 | Cycle 1(1): loaded http://localhost:54350/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54350/tests/kraken/driver.html?testName=imaging-desaturate) 23:23:05 INFO - PROCESS | 25038 | RSS: Main: 211349504 23:23:05 INFO - PROCESS | 25038 | 23:23:08 INFO - PROCESS | 25038 | Cycle 1(1): loaded http://localhost:54350/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54350/tests/kraken/driver.html?testName=json-parse-financial) 23:23:08 INFO - PROCESS | 25038 | RSS: Main: 210997248 23:23:08 INFO - PROCESS | 25038 | 23:23:10 INFO - PROCESS | 25038 | Cycle 1(1): loaded http://localhost:54350/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54350/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:23:10 INFO - PROCESS | 25038 | RSS: Main: 175808512 23:23:10 INFO - PROCESS | 25038 | 23:23:12 INFO - PROCESS | 25038 | Cycle 1(1): loaded http://localhost:54350/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54350/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:23:13 INFO - PROCESS | 25038 | RSS: Main: 181559296 23:23:13 INFO - PROCESS | 25038 | 23:23:14 INFO - PROCESS | 25038 | Cycle 1(1): loaded http://localhost:54350/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54350/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:23:15 INFO - PROCESS | 25038 | RSS: Main: 186191872 23:23:15 INFO - PROCESS | 25038 | 23:23:18 INFO - PROCESS | 25038 | Cycle 1(1): loaded http://localhost:54350/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54350/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:23:18 INFO - PROCESS | 25038 | RSS: Main: 180858880 23:23:18 INFO - PROCESS | 25038 | 23:23:21 INFO - PROCESS | 25038 | Cycle 1(1): loaded http://localhost:54350/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54350/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:23:22 INFO - PROCESS | 25038 | RSS: Main: 184225792 23:23:22 INFO - PROCESS | 25038 | 23:23:23 INFO - PROCESS | 25038 | Cycle 1(1): loaded http://localhost:54350/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54350/tests/kraken/driver.html?testName=ai-astar) 23:23:23 INFO - PROCESS | 25038 | RSS: Main: 235163648 23:23:23 INFO - PROCESS | 25038 | 23:23:23 INFO - PROCESS | 25038 | __start_tp_report 23:23:23 INFO - PROCESS | 25038 | _x_x_mozilla_page_load 23:23:23 INFO - PROCESS | 25038 | _x_x_mozilla_page_load_details 23:23:23 INFO - PROCESS | 25038 | |i|pagename|runs| 23:23:23 INFO - PROCESS | 25038 | |0;ai-astar;100;111;110;112;112;111;109;111;111;109 23:23:23 INFO - PROCESS | 25038 | |1;audio-beat-detection;144;115;117;174;121;119;140;118;150;121 23:23:23 INFO - PROCESS | 25038 | |2;audio-dft;162;162;162;161;162;161;161;161;162;159 23:23:23 INFO - PROCESS | 25038 | |3;audio-fft;76;76;75;116;75;76;76;77;100;74 23:23:23 INFO - PROCESS | 25038 | |4;audio-oscillator;84;84;143;83;83;83;131;84;84;134 23:23:23 INFO - PROCESS | 25038 | |5;imaging-gaussian-blur;117;118;117;116;117;117;117;118;116;117 23:23:23 INFO - PROCESS | 25038 | |6;imaging-darkroom;145;147;146;149;147;147;146;147;146;146 23:23:23 INFO - PROCESS | 25038 | |7;imaging-desaturate;95;95;95;95;96;95;95;95;94;94 23:23:23 INFO - PROCESS | 25038 | |8;json-parse-financial;52;52;51;52;50;51;50;49;51;53 23:23:23 INFO - PROCESS | 25038 | |9;json-stringify-tinderbox;61;59;59;58;60;59;60;60;60;60 23:23:23 INFO - PROCESS | 25038 | |10;stanford-crypto-aes;72;74;73;71;73;72;72;73;72;72 23:23:23 INFO - PROCESS | 25038 | |11;stanford-crypto-ccm;121;118;116;123;118;119;127;125;169;135 23:23:23 INFO - PROCESS | 25038 | |12;stanford-crypto-pbkdf2;156;161;158;152;159;156;159;155;151;153 23:23:23 INFO - PROCESS | 25038 | |13;stanford-crypto-sha256-iterative;65;63;65;63;62;62;62;61;62;64 23:23:23 INFO - PROCESS | 25038 | __end_tp_report 23:23:23 INFO - PROCESS | 25038 | __start_cc_report 23:23:23 INFO - PROCESS | 25038 | _x_x_mozilla_cycle_collect,1639 23:23:23 INFO - PROCESS | 25038 | __end_cc_report 23:23:23 INFO - PROCESS | 25038 | __startTimestamp1472106203933__endTimestamp 23:23:23 INFO - PROCESS | 25038 | 23:23:23 INFO - PROCESS | 25038 | ------- Summary: start ------- 23:23:23 INFO - PROCESS | 25038 | Number of tests: 14 23:23:23 INFO - PROCESS | 25038 | 23:23:23 INFO - PROCESS | 25038 | [#0] ai-astar Cycles:10 Average:109.60 Median:111.00 stddev:3.53 (3.2%) stddev-sans-first:1.12 23:23:23 INFO - PROCESS | 25038 | Values: 100.0 111.0 110.0 112.0 112.0 111.0 109.0 111.0 111.0 109.0 23:23:23 INFO - PROCESS | 25038 | 23:23:23 INFO - PROCESS | 25038 | [#1] audio-beat-detection Cycles:10 Average:131.90 Median:130.50 stddev:19.48 (14.9%) stddev-sans-first:20.17 23:23:23 INFO - PROCESS | 25038 | Values: 144.0 115.0 117.0 174.0 121.0 119.0 140.0 118.0 150.0 121.0 23:23:23 INFO - PROCESS | 25038 | 23:23:23 INFO - PROCESS | 25038 | [#2] audio-dft Cycles:10 Average:161.30 Median:162.00 stddev:0.95 (0.6%) stddev-sans-first:0.97 23:23:23 INFO - PROCESS | 25038 | Values: 162.0 162.0 162.0 161.0 162.0 161.0 161.0 161.0 162.0 159.0 23:23:23 INFO - PROCESS | 25038 | 23:23:23 INFO - PROCESS | 25038 | [#3] audio-fft Cycles:10 Average:82.10 Median:76.00 stddev:14.18 (18.7%) stddev-sans-first:14.87 23:23:23 INFO - PROCESS | 25038 | Values: 76.0 76.0 75.0 116.0 75.0 76.0 76.0 77.0 100.0 74.0 23:23:23 INFO - PROCESS | 25038 | 23:23:23 INFO - PROCESS | 25038 | [#4] audio-oscillator Cycles:10 Average:99.30 Median:83.50 stddev:25.50 (30.5%) stddev-sans-first:26.44 23:23:23 INFO - PROCESS | 25038 | Values: 84.0 84.0 143.0 83.0 83.0 83.0 131.0 84.0 84.0 134.0 23:23:23 INFO - PROCESS | 25038 | 23:23:23 INFO - PROCESS | 25038 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.67 (0.6%) stddev-sans-first:0.71 23:23:23 INFO - PROCESS | 25038 | Values: 117.0 118.0 117.0 116.0 117.0 117.0 117.0 118.0 116.0 117.0 23:23:23 INFO - PROCESS | 25038 | 23:23:23 INFO - PROCESS | 25038 | [#6] imaging-darkroom Cycles:10 Average:146.60 Median:147.00 stddev:1.07 (0.7%) stddev-sans-first:0.97 23:23:23 INFO - PROCESS | 25038 | Values: 145.0 147.0 146.0 149.0 147.0 147.0 146.0 147.0 146.0 146.0 23:23:23 INFO - PROCESS | 25038 | 23:23:23 INFO - PROCESS | 25038 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 23:23:23 INFO - PROCESS | 25038 | Values: 95.0 95.0 95.0 95.0 96.0 95.0 95.0 95.0 94.0 94.0 23:23:23 INFO - PROCESS | 25038 | 23:23:23 INFO - PROCESS | 25038 | [#8] json-parse-financial Cycles:10 Average:51.10 Median:51.50 stddev:1.20 (2.3%) stddev-sans-first:1.22 23:23:23 INFO - PROCESS | 25038 | Values: 52.0 52.0 51.0 52.0 50.0 51.0 50.0 49.0 51.0 53.0 23:23:23 INFO - PROCESS | 25038 | 23:23:23 INFO - PROCESS | 25038 | [#9] json-stringify-tinderbox Cycles:10 Average:59.60 Median:60.00 stddev:0.84 (1.4%) stddev-sans-first:0.73 23:23:23 INFO - PROCESS | 25038 | Values: 61.0 59.0 59.0 58.0 60.0 59.0 60.0 60.0 60.0 60.0 23:23:23 INFO - PROCESS | 25038 | 23:23:23 INFO - PROCESS | 25038 | [#10] stanford-crypto-aes Cycles:10 Average:72.40 Median:72.50 stddev:0.84 (1.2%) stddev-sans-first:0.88 23:23:23 INFO - PROCESS | 25038 | Values: 72.0 74.0 73.0 71.0 73.0 72.0 72.0 73.0 72.0 72.0 23:23:23 INFO - PROCESS | 25038 | 23:23:23 INFO - PROCESS | 25038 | [#11] stanford-crypto-ccm Cycles:10 Average:127.10 Median:124.00 stddev:15.74 (12.7%) stddev-sans-first:16.54 23:23:23 INFO - PROCESS | 25038 | Values: 121.0 118.0 116.0 123.0 118.0 119.0 127.0 125.0 169.0 135.0 23:23:23 INFO - PROCESS | 25038 | 23:23:23 INFO - PROCESS | 25038 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:156.00 Median:157.00 stddev:3.30 (2.1%) stddev-sans-first:3.50 23:23:23 INFO - PROCESS | 25038 | Values: 156.0 161.0 158.0 152.0 159.0 156.0 159.0 155.0 151.0 153.0 23:23:23 INFO - PROCESS | 25038 | 23:23:23 INFO - PROCESS | 25038 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.90 Median:63.00 stddev:1.37 (2.2%) stddev-sans-first:1.22 23:23:23 INFO - PROCESS | 25038 | Values: 65.0 63.0 65.0 63.0 62.0 62.0 62.0 61.0 62.0 64.0 23:23:23 INFO - PROCESS | 25038 | -------- Summary: end -------- 23:23:23 INFO - PROCESS | 25038 | 23:23:24 INFO - TEST-INFO | 25038: exit 0 23:23:24 INFO - TEST-OK | kraken | took 59566ms 23:23:24 INFO - SUITE-END | took 1171s 23:23:25 INFO - Completed test suite (00:19:31) 23:23:25 INFO - javascript benchmark 23:23:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3232.159638493992, 3248.0905346502977, 3262.673732626739, 3288.917777055575, 3311.6191637961642, 5456.317960254968, 5561.221428999943, 5573.944260557393, 5585.469380408855, 5587.692676902769, 5565.6104072714925, 5628.155776633507, 5632.0, 5638.577106717003, 5668.546516278698, 5023.095842748304, 5064.493550644943, 5079.339685840845, 5130.230465430178, 5189.065968125736, 5106.514881086291, 5150.7424628768595, 5189.42916279209, 5190.7664155112925, 5200.453952335007, 5045.798168073273, 5098.668586541881, 5117.462666420028, 5131.435542090367, 5141.0489059523925, 5200.687958722451, 5219.060569097561, 5221.686698798084, 5236.581073514109, 5259.500347467004, 4940.7035577865445, 4966.602671786249, 5002.824901128443, 5110.872228194287, 5116.488351164891, 5443.0, 5472.91790623141, 5498.147787093007, 5513.724313784295, 5539.335279766454, 5929.495992840571, 5934.139549765303, 5963.761449542013, 6004.939950600482, 6009.09863520471, 5856.648601083906, 5907.054871220627, 5939.316978547436, 6034.818955431344, 6053.394660533955, 5557.527610153142, 5602.943970560324, 5612.803551875665, 5619.887602247973, 5621.437856214346, 5929.792457263975, 6083.228398393025, 6091.866868864193, 6104.145419641254, 6129.693515324216, 5715.085586306171, 5747.568932330092, 5775.768969241268, 5903.645781253139, 5914.4381283777975, 5939.703014849327, 5950.9702451487465, 5964.1650168976375, 5972.074328479006, 5986.5210783137245, 5753.453421924911, 5924.7926322578505, 5931.584789064724, 5933.376995415506, 5935.317438494542, 5801.7389217485315, 5844.503217226584, 5854.356020837705, 5918.526517878559, 5936.376680448577, 4557.680962332605, 4559.224931761609, 4560.703554268962, 4561.29299958507, 4599.103174880933, 4043.454133691973, 4083.1017176221185, 4098.877033688994, 4106.753594784323, 4136.338185890243, 5517.917231241535, 5536.861578460571, 5574.219609254707, 5628.380878103405, 5661.943380566142, 5887.0580707086665, 5917.082852157922, 5923.763049477972, 5930.7924222652, 5932.555058370639, 5463.125899856004, 5627.52166065889, 5697.829065127969, 5700.743466544015, 5711.914321285184, 5004.299398084271, 5041.521055499722, 5051.0, 5070.873228169252, 5089.312942752755, 6112.205413296243, 6120.755169793248, 6133.202683651097, 6135.509159267248, 6147.784827531104, 5644.8024319148635, 5833.4749872511675, 5846.853828654148, 5904.675242861684, 5918.822435326947, 5974.820755377345, 6014.158017877413, 6072.423119803611, 6128.778133445034, 6136.7948077883175, 5781.21953536284, 5791.189233507396, 5805.970970145122, 5807.216025836458, 5807.303123625023, 5707.1724599933605, 5898.852528686817, 5913.083472061838, 5917.704114794329, 5932.881342373042, 5694.345150307602, 5771.826845194651, 5782.710864456676, 5822.068469045102, 5858.8242352729485, 5806.129080637938, 5825.650460972355, 5826.096954971986, 5833.533317334688, 5833.649981001154, 5594.524465420485, 5817.592768506333, 5832.300123985149, 5843.152742852178, 5848.941510584839, 5735.9139612905, 5743.483086522233, 5776.42235776419, 5863.765449381977, 5898.144769008384, 5644.520215781705, 5671.461211184931, 5699.230603868425, 5705.429457054262, 5713.342965558848], "value": 5480.617727902164, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [606.0878378041048, 632.3234139470766, 642.919635045619, 656.0847617573485, 665.2084020016177, 34909.82545087274, 37983.620163798354, 38034.0, 38073.42889856654, 38120.80939595305, 38385.0, 47457.0, 48317.51682483174, 48464.75767621166, 53028.73485632576, 9215.0, 9324.627014919395, 9328.0, 9328.393654412475, 9330.160285574297, 8570.657173713074, 9152.542372881362, 9250.797396338467, 9370.0, 9379.0, 11129.443527823576, 11422.143339249547, 11430.37132957687, 11849.644510664693, 11850.229735067192, 11235.21353505255, 11283.984441400231, 11321.0, 11322.980931716143, 11324.490397932113, 8288.0, 10719.249652524326, 11097.667069987914, 11206.831897521544, 11208.887911120866, 46138.76930615359, 46834.76582617065, 46851.29723054157, 46879.29681054787, 46905.53094469046, 3812.6377994090517, 3979.641832235113, 4456.420665313489, 4525.230710779176, 4706.858794236178, 34458.0, 34899.476507852145, 34940.0, 37636.0, 37658.24683506342, 4689.88275293117, 4757.976210118962, 4762.190427627312, 4779.450363208241, 4831.251156070814, 396026.0, 397690.9999999971, 397923.0, 399645.0, 401304.0, 54337.72831135819, 54722.0, 54814.177787333225, 54894.17658735122, 57489.0, 71600.28399715936, 71837.6408117956, 71906.64046679733, 71919.56160876858, 71998.0, 19236.903815481113, 19383.127759250867, 19405.32081377145, 19429.514262143275, 19445.708314375297, 14544.0, 14545.563633091024, 14637.414503419957, 14648.0, 14688.779668304984, 18593.25626974933, 20471.283505077252, 20710.482237944172, 21019.26432574883, 21186.15255389767, 7372.336489715844, 7521.623918804147, 7694.730684426018, 7712.807179820549, 7728.6522106505345, 927.4435338796668, 1000.8949060348705, 1023.6007956896816, 1073.7959787640323, 1106.9889301107046, 35248.471272930925, 35669.46495802565, 35749.46375804365, 35799.64200357963, 35902.84582925033, 1105.867295924494, 1149.4712432281078, 1153.135148638521, 1179.8466199394024, 1208.178438661701, 18832.81167188338, 18874.622507550048, 18880.339188128626, 18960.0, 18996.240150394107, 420592.0, 626884.0, 629640.0, 630269.0, 630553.0, 68428.0, 68909.0, 68917.31082689109, 68984.31015689779, 69157.30842691509, 1332.3072002558615, 1425.201886943315, 1438.9927051064087, 1473.8894582906325, 1506.540505145941, 29904.250408263484, 35004.82497587598, 35095.47356789701, 35114.82442587873, 35128.64871351356, 52020.479795201565, 52435.47564524459, 52516.47483525116, 52628.736856315474, 52726.73636631792, 405927.0, 409762.0, 410510.0, 411238.0, 411437.0, 30187.547186791777, 30941.226469337547, 31684.683153168175, 31776.523352150198, 33081.17297067496, 53290.0, 53566.464335357705, 53943.73028134834, 54351.0, 55008.449915500336, 44916.10167796691, 45063.0, 45355.11214724698, 46073.53926460692, 46680.0, 44409.15720465255, 45076.549234507234, 46927.76536117298, 46955.76522117368, 47106.0, 44088.11823763571, 44358.55641443544, 44557.0, 44593.0, 44846.77576612096, 38273.61726382701, 39680.0, 40248.396274055325, 40589.39115913204, 41227.58772412357, 29828.403431931674, 30579.847100764353, 30796.0, 30844.691553084183, 31054.0], "value": 21993.86786156082, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [398.81056498163395, 412.10367450794513, 422.38121152511576, 425.10939581576594, 440.4384409877406, 15407.614809629751, 20419.183232670694, 20447.693284600726, 20510.69233961488, 21284.57430851382, 20358.694619580718, 20386.082626281815, 20390.388288351336, 20391.49021274465, 20632.1747130115, 4400.955990440103, 5711.4643232972085, 5954.4640982311585, 5969.4925931295675, 6468.223813142429, 6572.21133463985, 6746.257911629741, 6748.6625668716615, 6752.932470675304, 6758.966205168992, 6598.241202261754, 6699.564528305669, 6715.32846715327, 6719.193696756396, 6752.561083529555, 6643.534952553323, 6671.566348187353, 6722.495812814059, 6722.630255335955, 6733.797986060377, 5828.300603927513, 5904.084866845646, 5929.051351783737, 5930.8813823723285, 5936.376680448534, 23975.640365394534, 24730.381740456454, 24869.87565062163, 24898.1285655003, 25137.120200792884, 2600.2719238613217, 2618.0, 2664.40050988528, 2850.586664933594, 2958.511845545504, 15320.616984575363, 15519.767203491956, 15716.764248536281, 15792.210389480595, 16329.510114696579, 1964.7838737738837, 2013.8388928885654, 2115.851890367675, 2137.6900349449397, 2180.8800515971616, 75946.0, 85361.0, 85440.57279713624, 86766.5661671694, 88186.0, 11427.942860285646, 11429.428528573539, 11709.180357374902, 11755.764884702257, 11786.882131178578, 16297.185140742915, 16311.510654680378, 16344.836551634331, 16407.507774766775, 16549.6690066198, 8648.0, 9000.954995224982, 9007.279417646572, 9198.954005230065, 9227.723168304961, 8778.166074222938, 8818.0, 8833.116688331116, 9008.774780630536, 9024.819503609891, 4544.7045942013665, 4855.0, 4915.18899381598, 5088.185890257541, 5112.846614601568, 1868.4581471373422, 1968.0, 2075.885826279539, 2175.706279652258, 2188.682641017044, 928.5635751196927, 998.5157198758742, 1001.3341128149699, 1038.8182068138046, 1085.5983286184005, 20355.185792568132, 20553.280635177696, 20714.48213794667, 20833.0, 20850.89574552148, 1046.931949423285, 1053.578568572577, 1060.0724366179593, 1086.5436516663021, 1087.6791346552786, 9897.950510247403, 11422.714432139097, 11463.484143213574, 11467.655970320904, 11614.361210133355, 535299.0, 535844.0, 543531.0, 551277.0, 553880.0, 91608.0, 91800.54099729592, 93122.0, 105141.0, 112888.99999999836, 1063.0698139128262, 1065.169168048923, 1096.6709987004026, 1101.300674071954, 1111.5831563163813, 17746.0, 18174.36389726353, 18565.190018886926, 18579.16393762311, 18894.433167005012, 30807.0, 35472.0, 43903.560964389944, 46114.30828537642, 46627.53372466368, 62677.68661156665, 63500.68249658722, 63503.36496634974, 64733.676331618044, 67254.0, 7889.605519724106, 8679.435836670596, 8733.170348816851, 8967.730968070968, 9301.116393942564, 12104.818427723416, 13479.663008424868, 14056.859431405965, 14082.507112251022, 14168.291585420893, 5917.644941303535, 12163.939180304042, 12300.261984280973, 12620.747585048064, 12660.936695316464, 12086.818697719717, 12418.937905310415, 12627.93686031564, 12707.809382859079, 12789.168704034208, 12017.519299228283, 12187.573434929735, 12229.266244025386, 12246.44890979926, 12665.176763510342, 8283.50298982063, 8290.751277461686, 8296.336293096658, 8311.293540049164, 8586.098459661645, 11463.0, 12089.214201076902, 12127.81808272859, 12399.442025108712, 12683.936580317039], "value": 9905.28565375799, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1440.4958264607383, 1495.7382458069837, 1569.089927841852, 1762.7091529897568, 1880.9247630094796, 8112.391570632204, 9021.639134434623, 9361.578728957196, 9374.953125234364, 9591.52042397879, 9368.906310936889, 9457.33798634096, 9463.905360946406, 9466.905330946689, 9469.905300946988, 2500.2999160235163, 2508.3352911478473, 2508.347829564308, 2509.2346834215555, 2528.709198442175, 2407.4703565215623, 2460.0897667862914, 2461.0770960889668, 2463.2363967170236, 2469.3085935937966, 2445.6942882139733, 2461.2985299189736, 2491.7134529529153, 2507.147569826259, 2507.5110353481077, 2346.5658853112145, 2395.2694428199393, 2399.2802159352213, 2431.8419302745265, 2438.171021852561, 2395.0898658509655, 2417.697787776528, 2421.9152329668555, 2430.6354046893107, 2432.1365915100237, 9188.0, 9369.812603747887, 9399.154076133182, 9490.90509094914, 9491.66779162726, 5447.918281225785, 5636.436356364372, 5639.520640745501, 5651.1523271509195, 5654.37801841797, 3546.0957455848593, 3607.963920360789, 3610.7833529988284, 3617.1680513481783, 3650.7444478886487, 1243.0428570001138, 1259.93070381129, 1263.816746571751, 1266.8226448297246, 1302.3553341096217, 13310.866891331156, 13412.664683382798, 13426.462941482328, 13434.86565134346, 13446.596602101952, 4737.128834110507, 4808.687435316693, 4825.30033145199, 4840.588549973221, 4930.978298897435, 10379.0, 10387.584496620202, 10392.636257730943, 10403.84394234072, 10404.0, 5499.50504454601, 5552.0, 5555.166724991283, 5556.555475561945, 5576.247206627135, 4642.744649044335, 4905.681130726492, 5074.112030394666, 5145.742712864342, 5277.261183434322, 4102.0, 4131.710780245354, 4191.350340697197, 4207.579242075829, 4246.7027308088755, 1248.2884755689352, 1259.7102666386781, 1264.291996481973, 1265.1207410849372, 1282.8268183795255, 936.8641546975787, 965.5461932891529, 1001.8897921228659, 1032.1897310611134, 1036.6216331039268, 6396.648184349812, 6452.322506136882, 6518.934810651833, 6656.434203092695, 6757.966210169016, 481.1531704200632, 508.9567386772166, 510.2397427832577, 511.0545490841987, 515.8220413957179, 4283.678724095706, 4296.935545966813, 4304.16068866568, 4329.25946089032, 4409.206342858316, 11583.420828958519, 11820.763584728258, 11881.465334059989, 11898.762024759457, 11915.761684766256, 11829.0, 11849.644510664693, 11864.525418983145, 11870.584529541424, 11874.228175168586, 8441.788955276044, 8710.216080552655, 8764.517951512727, 8775.999999999873, 8871.290296776242, 6358.872822543616, 6431.742730290736, 6610.4050635443045, 6622.602643841192, 6717.462602991846, 10747.731306717395, 10852.67441976742, 11051.115910727283, 11055.22613417053, 11168.609098681507, 9911.752206194902, 10583.259171858204, 10732.302400343953, 10774.407407592658, 11153.609623663133, 7939.325157361458, 8138.267555919995, 8162.959185204036, 8300.958495207728, 8313.584320783815, 8821.294296456397, 8981.955090224506, 8982.955085224532, 8985.910140898768, 9000.324975626723, 7989.600519974094, 8027.237412445808, 8091.271785539329, 8096.190380961857, 8355.916440835757, 7995.400344974267, 8062.1534738853925, 8097.0, 8127.593620319079, 8469.661213551635, 7568.886466702894, 7580.431467639839, 7603.315701586884, 7614.390848732199, 7676.80807979805, 5672.14917762339, 5755.827325180251, 5833.416658334133, 5884.676342801187, 5897.5576831736935, 5538.750756215899, 5634.380218176079, 5675.0, 5705.686187259741, 5809.651420914759], "value": 4847.7031363769065, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [355.124617817081, 359.48952487467795, 361.567926328038, 362.7062079715432, 363.9745217834749, 5389.757460914258, 5443.265159203506, 5508.504234618884, 5578.30271216098, 5587.7485513151905, 5455.863603409916, 5456.154296084103, 5480.0, 5502.58730595206, 5533.944660553393, 4377.0, 4379.474463064436, 4410.206162890679, 4413.117376524692, 4416.183006143858, 4155.189738001091, 4307.6123148916595, 4360.193364227613, 4392.648588112943, 4401.05377343871, 4274.487061552602, 4289.849855255066, 4298.978505107486, 4309.612134907858, 4310.978445107786, 4286.335617979218, 4297.892552686192, 4299.50555686097, 4333.371661109153, 4337.522872484024, 3761.54861416629, 3796.3925771876366, 3821.8916514210846, 3835.2137811748526, 3841.8271177797064, 5301.920471192935, 5314.388845282765, 5317.1492561190025, 5362.51737343641, 5371.623986320959, 4892.165437960764, 4909.705417674951, 4913.385826771653, 4919.188333924898, 4942.5304596063315, 5422.701751403672, 5651.40660230674, 5690.430956904318, 5690.743916523725, 5725.885482290372, 4121.278776214151, 4140.171965606891, 4140.792960352001, 4144.70987030908, 4150.294449943517, 5984.790532331391, 6016.518678505709, 6019.879602407971, 6031.668258245794, 6043.365446628085, 4132.958670413257, 4153.7507749535125, 4166.895827604334, 4289.03496713237, 4291.699581029298, 4192.224438478891, 4193.050231642301, 4197.307444271661, 4201.6008479194425, 4210.831566737357, 4813.181759100961, 4819.903601928012, 4834.661573689808, 4841.346418233564, 4844.055409195244, 5553.694546799884, 5707.600467967285, 5773.855653608609, 5784.855378615484, 5789.189513468118, 1473.1234915225423, 1489.2181604657642, 1489.9851001490063, 1490.6720521485256, 1494.5142828580754, 950.1025623535243, 954.2652157838564, 955.7145639115379, 958.4153666263574, 976.1946394224793, 4967.503249675004, 4972.428170760337, 4986.501349864984, 4990.3762029746285, 5006.599472042228, 5145.794168233304, 5161.4322424533275, 5179.161397703836, 5184.7926082956255, 5207.0, 4663.178776840031, 4943.307936888838, 4945.604351651859, 5023.422306434733, 5048.419431765394, 2775.4865349910333, 2777.4722802667834, 2792.46943080814, 2799.230211691801, 2802.6776920654384, 5394.946050539523, 5415.241866138744, 5461.317335333083, 5464.808731694372, 5473.370562385377, 5070.04337205246, 5136.5890728741615, 5209.765560549784, 5248.6588371755715, 5253.817890974577, 5338.759755810997, 5339.051246295152, 5364.7317634118135, 5412.0, 5417.349918009864, 5125.743712814419, 5143.5113664201845, 5199.948000520098, 5234.895302094013, 5241.554467870121, 4921.4832442593015, 5047.369078865142, 5068.619853511075, 5086.338775959101, 5164.612654050886, 5385.007547207401, 5388.541973932103, 5414.214938833926, 5431.511163995259, 5440.0, 4039.5960403960544, 4106.0, 4178.749275043507, 4222.171145332573, 4222.619964203236, 5877.735501902339, 5888.146218798336, 5892.646441213541, 5903.852403689943, 5908.763649453974, 5491.231227628055, 5720.227769251098, 5745.0, 5763.279590051244, 5773.653580785166, 5651.891459697068, 5707.486326230659, 5721.685307308138, 5728.140778883201, 5747.281589801275, 5656.264685590847, 5707.514861236676, 5707.971460142673, 5721.170430287668, 5730.71346432685, 5523.420040895809, 5660.15097735343, 5693.743781529924, 5709.629688874557, 5728.484436400744, 5561.916571251515, 5564.527015203753, 5570.5265052470995, 5599.524040456607, 5634.267545219095], "value": 4248.166993876533, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [759.8746206875866, 765.6171914042978, 809.3403875841188, 830.2901019628217, 856.2251162697761, 16405.179741012947, 17474.126293685313, 17502.649947001068, 17860.106994650247, 18676.719849202273, 17788.822111778878, 17827.465176044738, 17968.191431385585, 17984.10079496023, 17985.7302140468, 732.582428016031, 871.2376670413372, 896.7533928169773, 897.9730608081768, 898.1961144775422, 864.8789169516273, 895.3598177303227, 898.4025622960756, 904.9411788233776, 906.247814314121, 945.4327403557879, 945.8533927241289, 946.8532377481467, 947.8815148106487, 948.800751842114, 759.1004659478535, 942.7171848445507, 945.7682867697424, 945.8013817098417, 947.147567189528, 669.0532895952222, 677.2313424263447, 679.9014142949245, 680.0547239337325, 682.5597489619167, 15972.520824375288, 15999.0, 16026.919865400714, 16027.35890564376, 16039.27823247945, 14482.493112741004, 14555.345009474599, 14779.778303325458, 14872.70254594902, 14903.478378256708, 12856.357182140855, 13249.07256492046, 13250.3374831259, 13381.531646392426, 13422.395992180278, 4332.9566704332865, 4342.348647702838, 4386.912261754747, 4395.450568678915, 4437.2900335946415, 89841.55079224627, 90399.99999999935, 91276.54361728215, 92886.0, 93499.53250233705, 11643.59247426336, 11693.0, 11810.291382516905, 11953.282803031672, 12007.87992120085, 22614.208502702655, 22621.2082577109, 22644.660330095063, 22656.0, 22672.433189170402, 8942.46345219289, 8974.685885993958, 9132.76247319411, 9167.220786233245, 9259.814803703888, 4925.38432695913, 5232.816851410258, 5327.014502317006, 5330.040592693274, 5333.973330133324, 3828.425736139601, 3956.1494278729638, 3957.960420395817, 3959.584243654375, 3964.8810535683974, 636.3254949753276, 641.0512441586478, 655.7737580534708, 658.5982550644103, 659.446065305146, 668.4451904919002, 708.3907839258234, 763.9618019099134, 775.6121939030485, 793.7063286584001, 9357.0, 9694.418334899787, 9896.950515247521, 10040.146587539995, 10214.131798797185, 637.6333608175318, 751.3388218367911, 759.9278068583476, 786.2137862137862, 804.6218277409608, 4779.904401912012, 4829.517048295213, 4872.4883887192045, 4880.41435027799, 4946.826861059845, 75962.24037759552, 102098.0, 129349.0, 134937.0, 139208.0, 118015.40992295156, 118709.40645296719, 120343.0, 122404.0, 124267.0, 97665.58170464142, 97949.0, 98055.50972245236, 99401.0, 100264.49867750761, 9089.045650206634, 9431.764205894908, 9435.811283774423, 9448.631022209707, 9512.191463725385, 57333.0, 57459.71270143623, 58272.0, 58849.11726324194, 59904.700476499085, 46731.29903051525, 46738.766306169615, 47473.0, 51208.0, 78572.60713696395, 12848.807267891176, 13022.80465792995, 13084.869151308365, 13109.410076546768, 13242.933785331012, 14760.852391475948, 14771.630709231926, 15291.235438228267, 15314.387424502895, 15392.076475411512, 9725.513724313614, 13073.411696473491, 14048.438062477795, 14075.577732668036, 14609.123452592878, 13512.324383780575, 13517.0, 13655.590332290047, 13796.517121900686, 13969.092009019381, 13700.90392768596, 13782.517611883535, 13911.791323129959, 14252.073615214978, 14366.353514091681, 11304.773904522028, 11553.537858485566, 11688.47401866935, 12063.698407539881, 12212.267263964191, 11543.826842597537, 11637.53449861996, 11819.231749936227, 11891.405429728306, 12122.878771212176], "value": 8016.873635790863, "unit": "score"}], "name": "dromaeo_css", "value": 7628.948242958517, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 111.0, 110.0, 112.0, 112.0, 111.0, 109.0, 111.0, 111.0, 109.0], "value": 109.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 115.0, 117.0, 174.0, 121.0, 119.0, 140.0, 118.0, 150.0, 121.0], "value": 131.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 162.0, 161.0, 162.0, 161.0, 161.0, 161.0, 162.0, 159.0], "value": 161.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 75.0, 116.0, 75.0, 76.0, 76.0, 77.0, 100.0, 74.0], "value": 82.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 143.0, 83.0, 83.0, 83.0, 131.0, 84.0, 84.0, 134.0], "value": 99.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 117.0, 116.0, 117.0, 117.0, 117.0, 118.0, 116.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 147.0, 146.0, 149.0, 147.0, 147.0, 146.0, 147.0, 146.0, 146.0], "value": 146.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 94.0, 94.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 52.0, 51.0, 52.0, 50.0, 51.0, 50.0, 49.0, 51.0, 53.0], "value": 51.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 59.0, 59.0, 58.0, 60.0, 59.0, 60.0, 60.0, 60.0, 60.0], "value": 59.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 74.0, 73.0, 71.0, 73.0, 72.0, 72.0, 73.0, 72.0, 72.0], "value": 72.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 118.0, 116.0, 123.0, 118.0, 119.0, 127.0, 125.0, 169.0, 135.0], "value": 127.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 161.0, 158.0, 152.0, 159.0, 156.0, 159.0, 155.0, 151.0, 153.0], "value": 156.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 63.0, 65.0, 63.0, 62.0, 62.0, 62.0, 61.0, 62.0, 64.0], "value": 62.9, "unit": "score"}], "name": "kraken", "value": 1471.8, "alertThreshold": 2.0}]} 23:23:25 INFO - Return code: 0 23:23:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:23:25 INFO - # TBPL SUCCESS # 23:23:25 INFO - Running post-action listener: _resource_record_post_action 23:23:25 INFO - [mozharness: 2016-08-25 06:23:25.250949Z] Finished run-tests step (success) 23:23:25 INFO - Running post-run listener: _resource_record_post_run 23:23:25 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 274432; Write bytes: 330313728; Read time: 108; Write time: 187660 23:23:25 INFO - TinderboxPrint: CPU usage
13.0% 23:23:25 INFO - TinderboxPrint: I/O read bytes / time
274,432 / 108 23:23:25 INFO - TinderboxPrint: I/O write bytes / time
330,313,728 / 187,660 23:23:25 INFO - TinderboxPrint: CPU idle
8,251.2 (87.0%) 23:23:25 INFO - TinderboxPrint: CPU user
1,189.6 (12.5%) 23:23:25 INFO - TinderboxPrint: Swap in / out
0 / 0 23:23:25 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 77053952; Read time: 0; Write time: 54060 23:23:25 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 274432; Write bytes: 250810368; Read time: 108; Write time: 133344 23:23:25 INFO - Running post-run listener: _upload_blobber_files 23:23:25 INFO - Blob upload gear active. 23:23:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:23:25 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:23:25 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:23:25 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:23:26 INFO - (blobuploader) - INFO - Open directory for files ... 23:23:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:23:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:23:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:23:34 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:23:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:23:34 INFO - (blobuploader) - INFO - Done attempting. 23:23:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:23:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:23:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:23:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:23:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:23:35 INFO - (blobuploader) - INFO - Done attempting. 23:23:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:23:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:23:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:23:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:23:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:23:35 INFO - (blobuploader) - INFO - Done attempting. 23:23:35 INFO - (blobuploader) - INFO - Iteration through files over. 23:23:35 INFO - Return code: 0 23:23:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:23:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:23:35 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f889bd208f8a8a99187eaa7ca9b6195b24a090531e3166d31f3c5aee2ac8944488a926f8c20768d966fa343c7dce688013f68289f9b632769e0abf45ee681248", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/18fb3e52f3168e1926d2c36e16c2668a61e41044cb0332546390b31c893f9e6ebf00952d1cfbd2db76836abf1b4986caf8ecb7474c42527d02c287399f6d9add", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e1cb32a8c0f152ef78fc502a3d0c16525a9b0c4a758680f00d9159b817512b10688c6e29b94d154260e43c24162844b1d30a974950f01d31bd7f472fbcb3cfda"} 23:23:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:23:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:23:35 INFO - Contents: 23:23:35 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f889bd208f8a8a99187eaa7ca9b6195b24a090531e3166d31f3c5aee2ac8944488a926f8c20768d966fa343c7dce688013f68289f9b632769e0abf45ee681248", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/18fb3e52f3168e1926d2c36e16c2668a61e41044cb0332546390b31c893f9e6ebf00952d1cfbd2db76836abf1b4986caf8ecb7474c42527d02c287399f6d9add", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e1cb32a8c0f152ef78fc502a3d0c16525a9b0c4a758680f00d9159b817512b10688c6e29b94d154260e43c24162844b1d30a974950f01d31bd7f472fbcb3cfda"} 23:23:35 INFO - Running post-run listener: copy_logs_to_upload_dir 23:23:35 INFO - Copying logs to upload dir... 23:23:35 INFO - mkdir: /builds/slave/test/build/upload/logs 23:23:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1680.918359 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 0 secs) (at 2016-08-24 23:23:35.508560) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-24 23:23:35.512328) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1472104533.377457-1069707519 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/f889bd208f8a8a99187eaa7ca9b6195b24a090531e3166d31f3c5aee2ac8944488a926f8c20768d966fa343c7dce688013f68289f9b632769e0abf45ee681248", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/18fb3e52f3168e1926d2c36e16c2668a61e41044cb0332546390b31c893f9e6ebf00952d1cfbd2db76836abf1b4986caf8ecb7474c42527d02c287399f6d9add", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e1cb32a8c0f152ef78fc502a3d0c16525a9b0c4a758680f00d9159b817512b10688c6e29b94d154260e43c24162844b1d30a974950f01d31bd7f472fbcb3cfda"} build_url:https://queue.taskcluster.net/v1/task/SgsUDC-MSc2iWDIuBjyqwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011249 build_url: 'https://queue.taskcluster.net/v1/task/SgsUDC-MSc2iWDIuBjyqwA/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/f889bd208f8a8a99187eaa7ca9b6195b24a090531e3166d31f3c5aee2ac8944488a926f8c20768d966fa343c7dce688013f68289f9b632769e0abf45ee681248", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/18fb3e52f3168e1926d2c36e16c2668a61e41044cb0332546390b31c893f9e6ebf00952d1cfbd2db76836abf1b4986caf8ecb7474c42527d02c287399f6d9add", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e1cb32a8c0f152ef78fc502a3d0c16525a9b0c4a758680f00d9159b817512b10688c6e29b94d154260e43c24162844b1d30a974950f01d31bd7f472fbcb3cfda"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-24 23:23:35.537941) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-24 23:23:35.538266) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1472104533.377457-1069707519 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004500 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-24 23:23:35.623473) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-24 23:23:35.623796) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-24 23:23:35.624097) ========= ========= Total master_lag: 0.19 =========