builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-057 starttime: 1474525689.32 results: success (0) buildid: 20160921203459 builduid: 68b677bd275041d299979cf37acbf90a revision: 49788f46f9b6ffb93bb524556f59b20aa2c7dc8a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-21 23:28:09.321310) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-21 23:28:09.321904) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-21 23:28:09.322223) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-21 23:28:09.348426) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 23:28:09.348972) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1474524331.565006-1876900845 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-21 23:28:09-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 45.8M=0s 2016-09-21 23:28:09 (45.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.067966 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 23:28:09.435553) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 23:28:09.435911) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1474524331.565006-1876900845 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020167 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 23:28:09.489879) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-21 23:28:09.490309) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 49788f46f9b6ffb93bb524556f59b20aa2c7dc8a --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-beta --rev 49788f46f9b6ffb93bb524556f59b20aa2c7dc8a --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=934fc3100db09dcb9af4d4b6000001ab-1474524331.565006-1876900845 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-21 23:28:09,585 truncating revision to first 12 chars 2016-09-21 23:28:09,585 Setting DEBUG logging. 2016-09-21 23:28:09,585 attempt 1/10 2016-09-21 23:28:09,585 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/49788f46f9b6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-21 23:28:10,950 unpacking tar archive at: mozilla-beta-49788f46f9b6/testing/mozharness/ program finished with exit code 0 elapsedTime=1.707476 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-21 23:28:11.212153) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-21 23:28:11.212482) ========= script_repo_revision: 49788f46f9b6ffb93bb524556f59b20aa2c7dc8a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-21 23:28:11.212872) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-21 23:28:11.213148) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-21 23:28:11.222764) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 56 secs) (at 2016-09-21 23:28:11.223054) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] 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=934fc3100db09dcb9af4d4b6000001ab-1474524331.565006-1876900845 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 23:28:11 INFO - MultiFileLogger online at 20160921 23:28:11 in /builds/slave/test 23:28:11 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 23:28:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:28:11 INFO - {'append_to_log': False, 23:28:11 INFO - 'base_work_dir': '/builds/slave/test', 23:28:11 INFO - 'blob_upload_branch': 'Mozilla-Beta', 23:28:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:28:11 INFO - 'branch': 'Mozilla-Beta', 23:28:11 INFO - 'buildbot_json_path': 'buildprops.json', 23:28:11 INFO - 'config_files': ('talos/linux_config.py',), 23:28:11 INFO - 'default_actions': ('clobber', 23:28:11 INFO - 'read-buildbot-config', 23:28:11 INFO - 'download-and-extract', 23:28:11 INFO - 'populate-webroot', 23:28:11 INFO - 'create-virtualenv', 23:28:11 INFO - 'install', 23:28:11 INFO - 'run-tests'), 23:28:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:28:11 INFO - 'download_minidump_stackwalk': True, 23:28:11 INFO - 'download_symbols': 'ondemand', 23:28:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:28:11 INFO - 'tooltool.py': '/tools/tooltool.py', 23:28:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:28:11 INFO - '/tools/misc-python/virtualenv.py')}, 23:28:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:28:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:28:11 INFO - 'installer_path': 'installer.exe', 23:28:11 INFO - 'log_level': 'info', 23:28:11 INFO - 'log_name': 'talos', 23:28:11 INFO - 'log_to_console': True, 23:28:11 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 23:28:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 23:28:11 INFO - 'opt_config_files': (), 23:28:11 INFO - 'pip_index': False, 23:28:11 INFO - 'sps_profile': False, 23:28:11 INFO - 'sps_profile_interval': 0, 23:28:11 INFO - 'suite': 'dromaeojs', 23:28:11 INFO - 'system_bits': '32', 23:28:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:28:11 INFO - 'title': 'talos-linux64-ix-057', 23:28:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:28:11 INFO - 'use_talos_json': True, 23:28:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:28:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:28:11 INFO - 'work_dir': 'build'} 23:28:11 INFO - [mozharness: 2016-09-22 06:28:11.413822Z] Running clobber step. 23:28:11 INFO - Running pre-action listener: _resource_record_pre_action 23:28:11 INFO - Running main action method: clobber 23:28:11 INFO - rmtree: /builds/slave/test/build 23:28:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:28:12 INFO - Running post-action listener: _resource_record_post_action 23:28:12 INFO - [mozharness: 2016-09-22 06:28:12.373130Z] Finished clobber step (success) 23:28:12 INFO - [mozharness: 2016-09-22 06:28:12.373231Z] Running read-buildbot-config step. 23:28:12 INFO - Running pre-action listener: _resource_record_pre_action 23:28:12 INFO - Running main action method: read_buildbot_config 23:28:12 INFO - Using buildbot properties: 23:28:12 INFO - { 23:28:12 INFO - "project": "", 23:28:12 INFO - "product": "firefox", 23:28:12 INFO - "script_repo_revision": "production", 23:28:12 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 23:28:12 INFO - "repository": "", 23:28:12 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 23:28:12 INFO - "buildid": "20160921203459", 23:28:12 INFO - "pgo_build": "True", 23:28:12 INFO - "basedir": "/builds/slave/test", 23:28:12 INFO - "buildnumber": 396, 23:28:12 INFO - "slavename": "talos-linux64-ix-057", 23:28:12 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 23:28:12 INFO - "platform": "ubuntu64_hw", 23:28:12 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 23:28:12 INFO - "branch": "mozilla-beta", 23:28:12 INFO - "repo_path": "releases/mozilla-beta", 23:28:12 INFO - "revision": "49788f46f9b6ffb93bb524556f59b20aa2c7dc8a", 23:28:12 INFO - "stage_platform": "linux64-pgo", 23:28:12 INFO - "builduid": "68b677bd275041d299979cf37acbf90a", 23:28:12 INFO - "slavebuilddir": "test" 23:28:12 INFO - } 23:28:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 23:28:12 INFO - Running post-action listener: _resource_record_post_action 23:28:12 INFO - [mozharness: 2016-09-22 06:28:12.375648Z] Finished read-buildbot-config step (success) 23:28:12 INFO - [mozharness: 2016-09-22 06:28:12.375737Z] Running download-and-extract step. 23:28:12 INFO - Running pre-action listener: _resource_record_pre_action 23:28:12 INFO - Running main action method: download_and_extract 23:28:12 INFO - mkdir: /builds/slave/test/build/tests 23:28:12 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:28:12 INFO - https://queue.taskcluster.net/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 23:28:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 23:28:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 23:28:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 23:28:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 23:28:12 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 23:28:12 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 23:28:12 INFO - retry: Failed, sleeping 30 seconds before retrying 23:28:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #2 23:28:42 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 23:28:42 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 23:28:42 INFO - retry: Failed, sleeping 60 seconds before retrying 23:29:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #3 23:29:42 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 23:29:42 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 23:29:42 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json! 23:29:42 INFO - Caught exception: HTTP Error 404: Not Found 23:29:42 INFO - Caught exception: HTTP Error 404: Not Found 23:29:42 INFO - Caught exception: HTTP Error 404: Not Found 23:29:42 INFO - trying https://queue.taskcluster.net/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 23:29:42 INFO - Downloading https://queue.taskcluster.net/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 23:29:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 23:29:43 INFO - Downloaded 1327 bytes. 23:29:43 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 23:29:43 INFO - Using the following test package requirements: 23:29:43 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 23:29:43 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 23:29:43 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 23:29:43 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 23:29:43 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 23:29:43 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 23:29:43 INFO - u'jsshell-linux-x86_64.zip'], 23:29:43 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 23:29:43 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 23:29:43 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 23:29:43 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 23:29:43 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 23:29:43 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 23:29:43 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 23:29:43 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 23:29:43 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 23:29:43 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 23:29:43 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 23:29:43 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 23:29:43 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:29:43 INFO - https://queue.taskcluster.net/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 23:29:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 23:29:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 23:29:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 23:29:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 23:29:43 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 23:29:43 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 23:29:43 INFO - retry: Failed, sleeping 30 seconds before retrying 23:30:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #2 23:30:13 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 23:30:13 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 23:30:13 INFO - retry: Failed, sleeping 60 seconds before retrying 23:31:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #3 23:31:13 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 23:31:13 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 23:31:13 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip! 23:31:13 INFO - Caught exception: HTTP Error 404: Not Found 23:31:13 INFO - Caught exception: HTTP Error 404: Not Found 23:31:13 INFO - Caught exception: HTTP Error 404: Not Found 23:31:13 INFO - trying https://queue.taskcluster.net/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 23:31:13 INFO - Downloading https://queue.taskcluster.net/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 23:31:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 23:31:14 INFO - Downloaded 21770403 bytes. 23:31:14 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 23:31:14 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 23:31:15 INFO - Return code: 0 23:31:15 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 23:31:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:31:15 INFO - https://queue.taskcluster.net/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 23:31:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 23:31:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 23:31:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 23:31:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 23:31:15 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 23:31:15 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 23:31:15 INFO - retry: Failed, sleeping 30 seconds before retrying 23:31:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #2 23:31:45 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 23:31:45 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 23:31:45 INFO - retry: Failed, sleeping 60 seconds before retrying 23:32:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #3 23:32:45 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 23:32:45 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 23:32:45 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip! 23:32:45 INFO - Caught exception: HTTP Error 404: Not Found 23:32:45 INFO - Caught exception: HTTP Error 404: Not Found 23:32:45 INFO - Caught exception: HTTP Error 404: Not Found 23:32:45 INFO - trying https://queue.taskcluster.net/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 23:32:45 INFO - Downloading https://queue.taskcluster.net/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 23:32:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 23:32:46 INFO - Downloaded 21770403 bytes. 23:32:46 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 23:32:46 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 23:32:47 INFO - Return code: 0 23:32:47 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:32:47 INFO - https://queue.taskcluster.net/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 23:32:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 23:32:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 23:32:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 23:32:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 23:32:47 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 23:32:47 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 23:32:47 INFO - retry: Failed, sleeping 30 seconds before retrying 23:33:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 23:33:17 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 23:33:17 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 23:33:17 INFO - retry: Failed, sleeping 60 seconds before retrying 23:34:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 23:34:17 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 23:34:17 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 23:34:17 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip! 23:34:17 INFO - Caught exception: HTTP Error 404: Not Found 23:34:17 INFO - Caught exception: HTTP Error 404: Not Found 23:34:17 INFO - Caught exception: HTTP Error 404: Not Found 23:34:17 INFO - trying https://queue.taskcluster.net/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 23:34:17 INFO - Downloading https://queue.taskcluster.net/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 23:34:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 23:34:19 INFO - Downloaded 14413740 bytes. 23:34:19 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 23:34:19 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 23:34:19 INFO - Return code: 0 23:34: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:34:19 INFO - https://queue.taskcluster.net/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 23:34:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 23:34:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 23:34:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 23:34:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 23:34:19 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 23:34:19 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 23:34:19 INFO - retry: Failed, sleeping 30 seconds before retrying 23:34:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #2 23:34:49 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 23:34:49 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 23:34:49 INFO - retry: Failed, sleeping 60 seconds before retrying 23:35:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #3 23:35:49 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 23:35:49 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 23:35:49 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2! 23:35:49 INFO - Caught exception: HTTP Error 404: Not Found 23:35:49 INFO - Caught exception: HTTP Error 404: Not Found 23:35:49 INFO - Caught exception: HTTP Error 404: Not Found 23:35:49 INFO - trying https://queue.taskcluster.net/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 23:35:49 INFO - Downloading https://queue.taskcluster.net/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 23:35:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 23:35:51 INFO - Downloaded 56782180 bytes. 23:35:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 23:35:51 INFO - mkdir: /builds/slave/test/properties 23:35:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:35:51 INFO - Writing to file /builds/slave/test/properties/build_url 23:35:51 INFO - Contents: 23:35:51 INFO - build_url:https://queue.taskcluster.net/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 23:35:52 INFO - Running post-action listener: _resource_record_post_action 23:35:52 INFO - Running post-action listener: set_extra_try_arguments 23:35:52 INFO - [mozharness: 2016-09-22 06:35:52.413330Z] Finished download-and-extract step (success) 23:35:52 INFO - [mozharness: 2016-09-22 06:35:52.413603Z] Running populate-webroot step. 23:35:52 INFO - Running pre-action listener: _resource_record_pre_action 23:35:52 INFO - Running main action method: populate_webroot 23:35:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:35:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:35:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:35:52 INFO - '--tppagecycles', 23:35:52 INFO - '7'], 23:35:52 INFO - 'tests': ['tsvgm']}}, 23:35:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:35:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:35:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:35:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:35:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:35:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:35:52 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:35:52 INFO - 'talos_options': ['--disable-e10s'], 23:35:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:35:52 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:35:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:35:52 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:35:52 INFO - 'talos_options': ['--disable-e10s'], 23:35:52 INFO - 'tests': ['damp', 'tps']}, 23:35:52 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:35:52 INFO - 'tests': ['damp', 'tps']}, 23:35:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:35:52 INFO - 'tests': ['dromaeo_dom']}, 23:35:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:35:52 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:35:52 INFO - 'tests': ['basic_compositor_video']}, 23:35:52 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 23:35:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:35:52 INFO - 'tests': ['a11yr', 23:35:52 INFO - 'ts_paint', 23:35:52 INFO - 'tpaint', 23:35:52 INFO - 'sessionrestore', 23:35:52 INFO - 'sessionrestore_no_auto_restore', 23:35:52 INFO - 'tabpaint']}, 23:35:52 INFO - 'other-e10s': {'tests': ['a11yr', 23:35:52 INFO - 'ts_paint', 23:35:52 INFO - 'tpaint', 23:35:52 INFO - 'sessionrestore', 23:35:52 INFO - 'sessionrestore_no_auto_restore', 23:35:52 INFO - 'tabpaint']}, 23:35:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:35:52 INFO - 'ts_paint', 23:35:52 INFO - 'tpaint', 23:35:52 INFO - 'sessionrestore', 23:35:52 INFO - 'sessionrestore_no_auto_restore', 23:35:52 INFO - 'tabpaint']}, 23:35:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:35:52 INFO - 'ts_paint', 23:35:52 INFO - 'tpaint', 23:35:52 INFO - 'sessionrestore', 23:35:52 INFO - 'sessionrestore_no_auto_restore', 23:35:52 INFO - 'tabpaint']}, 23:35:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:35:52 INFO - 'tests': ['a11yr', 23:35:52 INFO - 'ts_paint', 23:35:52 INFO - 'tpaint', 23:35:52 INFO - 'sessionrestore', 23:35:52 INFO - 'sessionrestore_no_auto_restore', 23:35:52 INFO - 'tabpaint']}, 23:35:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:35:52 INFO - 'tests': ['a11yr', 23:35:52 INFO - 'ts_paint', 23:35:52 INFO - 'tpaint', 23:35:52 INFO - 'sessionrestore', 23:35:52 INFO - 'sessionrestore_no_auto_restore', 23:35:52 INFO - 'tabpaint']}, 23:35:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:35:52 INFO - 'tests': ['tsvgx', 23:35:52 INFO - 'tsvgr_opacity', 23:35:52 INFO - 'tart', 23:35:52 INFO - 'tscrollx', 23:35:52 INFO - 'cart']}, 23:35:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:35:52 INFO - 'tsvgr_opacity', 23:35:52 INFO - 'tart', 23:35:52 INFO - 'tscrollx', 23:35:52 INFO - 'cart']}, 23:35:52 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:35:52 INFO - 'talos_options': ['--disable-e10s'], 23:35:52 INFO - 'tests': ['tp5o']}, 23:35:52 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:35:52 INFO - 'tests': ['tp5o']}, 23:35:52 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:35:52 INFO - 'talos_options': ['--disable-e10s', 23:35:52 INFO - '--xperf_path', 23:35:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:35:52 INFO - 'tests': ['tp5n']}, 23:35:52 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:35:52 INFO - 'talos_options': ['--xperf_path', 23:35:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:35:52 INFO - 'tests': ['tp5n']}}, 23:35:52 INFO - 'talos.zip': {'path': '', 23:35:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:35:52 INFO - Running post-action listener: _resource_record_post_action 23:35:52 INFO - [mozharness: 2016-09-22 06:35:52.434329Z] Finished populate-webroot step (success) 23:35:52 INFO - [mozharness: 2016-09-22 06:35:52.434418Z] Running create-virtualenv step. 23:35:52 INFO - Running pre-action listener: _resource_record_pre_action 23:35:52 INFO - Running main action method: create_virtualenv 23:35:52 INFO - Creating virtualenv /builds/slave/test/build/venv 23:35:52 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:35:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:35:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:35:52 INFO - Using real prefix '/usr' 23:35:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:35:53 INFO - Installing distribute.............................................................................................................................................................................................done. 23:35:55 INFO - Installing pip.................done. 23:35:55 INFO - Return code: 0 23:35:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:35:55 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:35:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:35:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:35:55 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:35:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:35:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:35:55 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 0x1886030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19e98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b47180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b453b0>, '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 0x1b462b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b46740>, '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': '934fc3100db09dcb9af4d4b6000001ab-1474524331.565006-1876900845', '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:35:55 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:35:55 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:35:55 INFO - Using env: {'DISPLAY': ':0', 23:35:55 INFO - 'HOME': '/home/cltbld', 23:35:55 INFO - 'LANG': 'en_US.UTF-8', 23:35:55 INFO - 'LANGUAGE': 'en_US:en', 23:35:55 INFO - 'LOGNAME': 'cltbld', 23:35:55 INFO - 'MAIL': '/var/mail/cltbld', 23:35:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:35:55 INFO - 'MOZ_NO_REMOTE': '1', 23:35:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:35:55 INFO - 'NO_EM_RESTART': '1', 23:35:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:35:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:35:55 INFO - 'PWD': '/builds/slave/test', 23:35:55 INFO - 'SHELL': '/bin/bash', 23:35:55 INFO - 'SHLVL': '1', 23:35:55 INFO - 'TERM': 'linux', 23:35:55 INFO - 'TMOUT': '86400', 23:35:55 INFO - 'USER': 'cltbld', 23:35:55 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1474524331.565006-1876900845', 23:35:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:35:55 INFO - '_': '/tools/buildbot/bin/python'} 23:35:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:35:55 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:35:55 INFO - Cleaning up... 23:35:55 INFO - Return code: 0 23:35:55 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:35:55 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:35:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:35:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:35:55 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:35:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:35:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:35:55 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 0x1886030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19e98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b47180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b453b0>, '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 0x1b462b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b46740>, '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': '934fc3100db09dcb9af4d4b6000001ab-1474524331.565006-1876900845', '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:35:55 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:35:55 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:35:55 INFO - Using env: {'DISPLAY': ':0', 23:35:55 INFO - 'HOME': '/home/cltbld', 23:35:55 INFO - 'LANG': 'en_US.UTF-8', 23:35:55 INFO - 'LANGUAGE': 'en_US:en', 23:35:55 INFO - 'LOGNAME': 'cltbld', 23:35:55 INFO - 'MAIL': '/var/mail/cltbld', 23:35:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:35:55 INFO - 'MOZ_NO_REMOTE': '1', 23:35:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:35:55 INFO - 'NO_EM_RESTART': '1', 23:35:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:35:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:35:55 INFO - 'PWD': '/builds/slave/test', 23:35:55 INFO - 'SHELL': '/bin/bash', 23:35:55 INFO - 'SHLVL': '1', 23:35:55 INFO - 'TERM': 'linux', 23:35:55 INFO - 'TMOUT': '86400', 23:35:55 INFO - 'USER': 'cltbld', 23:35:55 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1474524331.565006-1876900845', 23:35:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:35:55 INFO - '_': '/tools/buildbot/bin/python'} 23:35:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:35:55 INFO - Downloading/unpacking psutil>=3.1.1 23:35:55 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:35:55 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:35:55 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:35:55 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:35:55 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:35:55 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:35:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:35:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:35:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:35:57 INFO - Installing collected packages: psutil 23:35:57 INFO - Running setup.py install for psutil 23:35:57 INFO - building 'psutil._psutil_linux' extension 23:35:57 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:35:57 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:35:57 INFO - building 'psutil._psutil_posix' extension 23:35:57 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:35:57 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:35:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:35:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:35:57 INFO - Successfully installed psutil 23:35:57 INFO - Cleaning up... 23:35:58 INFO - Return code: 0 23:35:58 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:35:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:35:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:35:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:35:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:35:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:35:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:35:58 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 0x1886030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19e98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b47180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b453b0>, '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 0x1b462b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b46740>, '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': '934fc3100db09dcb9af4d4b6000001ab-1474524331.565006-1876900845', '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:35:58 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:35:58 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:35:58 INFO - Using env: {'DISPLAY': ':0', 23:35:58 INFO - 'HOME': '/home/cltbld', 23:35:58 INFO - 'LANG': 'en_US.UTF-8', 23:35:58 INFO - 'LANGUAGE': 'en_US:en', 23:35:58 INFO - 'LOGNAME': 'cltbld', 23:35:58 INFO - 'MAIL': '/var/mail/cltbld', 23:35:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:35:58 INFO - 'MOZ_NO_REMOTE': '1', 23:35:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:35:58 INFO - 'NO_EM_RESTART': '1', 23:35:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:35:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:35:58 INFO - 'PWD': '/builds/slave/test', 23:35:58 INFO - 'SHELL': '/bin/bash', 23:35:58 INFO - 'SHLVL': '1', 23:35:58 INFO - 'TERM': 'linux', 23:35:58 INFO - 'TMOUT': '86400', 23:35:58 INFO - 'USER': 'cltbld', 23:35:58 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1474524331.565006-1876900845', 23:35:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:35:58 INFO - '_': '/tools/buildbot/bin/python'} 23:35:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:35:58 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:35:58 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:35:58 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:35:58 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:35:58 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:35:58 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:35:58 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:36:00 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:36:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:36:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:36:00 INFO - Installing collected packages: mozsystemmonitor 23:36:00 INFO - Running setup.py install for mozsystemmonitor 23:36:00 INFO - Successfully installed mozsystemmonitor 23:36:00 INFO - Cleaning up... 23:36:00 INFO - Return code: 0 23:36:00 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:36:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:36:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:36:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:36:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:36:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:36:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:36:00 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 0x1886030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19e98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b47180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b453b0>, '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 0x1b462b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b46740>, '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': '934fc3100db09dcb9af4d4b6000001ab-1474524331.565006-1876900845', '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:36:00 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:36:00 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:36:00 INFO - Using env: {'DISPLAY': ':0', 23:36:00 INFO - 'HOME': '/home/cltbld', 23:36:00 INFO - 'LANG': 'en_US.UTF-8', 23:36:00 INFO - 'LANGUAGE': 'en_US:en', 23:36:00 INFO - 'LOGNAME': 'cltbld', 23:36:00 INFO - 'MAIL': '/var/mail/cltbld', 23:36:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:36:00 INFO - 'MOZ_NO_REMOTE': '1', 23:36:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:36:00 INFO - 'NO_EM_RESTART': '1', 23:36:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:36:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:36:00 INFO - 'PWD': '/builds/slave/test', 23:36:00 INFO - 'SHELL': '/bin/bash', 23:36:00 INFO - 'SHLVL': '1', 23:36:00 INFO - 'TERM': 'linux', 23:36:00 INFO - 'TMOUT': '86400', 23:36:00 INFO - 'USER': 'cltbld', 23:36:00 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1474524331.565006-1876900845', 23:36:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:36:00 INFO - '_': '/tools/buildbot/bin/python'} 23:36:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:36:00 INFO - Downloading/unpacking jsonschema==2.5.1 23:36:00 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:36:00 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:36:00 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:36:00 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:36:00 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:36:00 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:36:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:36:02 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:36:02 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:36:02 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:36:02 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:36:02 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:36:02 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:36:02 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:36:02 INFO - Downloading functools32-3.2.3-2.tar.gz 23:36:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:36:02 INFO - warning: no files found matching '*.txt' 23:36:02 INFO - no previously-included directories found matching 'build' 23:36:02 INFO - no previously-included directories found matching 'dist' 23:36:02 INFO - no previously-included directories found matching '.git*' 23:36:02 INFO - Installing collected packages: jsonschema, functools32 23:36:02 INFO - Running setup.py install for functools32 23:36:02 INFO - warning: no files found matching '*.txt' 23:36:02 INFO - no previously-included directories found matching 'build' 23:36:02 INFO - no previously-included directories found matching 'dist' 23:36:02 INFO - no previously-included directories found matching '.git*' 23:36:02 INFO - Successfully installed jsonschema functools32 23:36:02 INFO - Cleaning up... 23:36:02 INFO - Return code: 0 23:36:02 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 23:36:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:36:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:36:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:36:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:36:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:36:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:36:02 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 0x1886030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19e98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b47180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b453b0>, '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 0x1b462b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b46740>, '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': '934fc3100db09dcb9af4d4b6000001ab-1474524331.565006-1876900845', '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:36:02 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:36:02 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:36:02 INFO - Using env: {'DISPLAY': ':0', 23:36:02 INFO - 'HOME': '/home/cltbld', 23:36:02 INFO - 'LANG': 'en_US.UTF-8', 23:36:02 INFO - 'LANGUAGE': 'en_US:en', 23:36:02 INFO - 'LOGNAME': 'cltbld', 23:36:02 INFO - 'MAIL': '/var/mail/cltbld', 23:36:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:36:02 INFO - 'MOZ_NO_REMOTE': '1', 23:36:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:36:02 INFO - 'NO_EM_RESTART': '1', 23:36:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:36:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:36:02 INFO - 'PWD': '/builds/slave/test', 23:36:02 INFO - 'SHELL': '/bin/bash', 23:36:02 INFO - 'SHLVL': '1', 23:36:02 INFO - 'TERM': 'linux', 23:36:02 INFO - 'TMOUT': '86400', 23:36:02 INFO - 'USER': 'cltbld', 23:36:02 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1474524331.565006-1876900845', 23:36:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:36:02 INFO - '_': '/tools/buildbot/bin/python'} 23:36:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:36:03 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 23:36:03 INFO - Cleaning up... 23:36:03 INFO - Return code: 0 23:36:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:36:03 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:36:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:36:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:36:03 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:36:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:36:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:36:03 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 0x1886030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19e98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b47180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b453b0>, '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 0x1b462b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b46740>, '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': '934fc3100db09dcb9af4d4b6000001ab-1474524331.565006-1876900845', '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:36:03 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:36:03 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:36:03 INFO - Using env: {'DISPLAY': ':0', 23:36:03 INFO - 'HOME': '/home/cltbld', 23:36:03 INFO - 'LANG': 'en_US.UTF-8', 23:36:03 INFO - 'LANGUAGE': 'en_US:en', 23:36:03 INFO - 'LOGNAME': 'cltbld', 23:36:03 INFO - 'MAIL': '/var/mail/cltbld', 23:36:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:36:03 INFO - 'MOZ_NO_REMOTE': '1', 23:36:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:36:03 INFO - 'NO_EM_RESTART': '1', 23:36:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:36:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:36:03 INFO - 'PWD': '/builds/slave/test', 23:36:03 INFO - 'SHELL': '/bin/bash', 23:36:03 INFO - 'SHLVL': '1', 23:36:03 INFO - 'TERM': 'linux', 23:36:03 INFO - 'TMOUT': '86400', 23:36:03 INFO - 'USER': 'cltbld', 23:36:03 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1474524331.565006-1876900845', 23:36:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:36:03 INFO - '_': '/tools/buildbot/bin/python'} 23:36:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:36:03 INFO - Downloading/unpacking blobuploader==1.2.4 23:36:03 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:36:03 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:36:03 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:36:03 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:36:03 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:36:03 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:36:05 INFO - Downloading blobuploader-1.2.4.tar.gz 23:36:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:36:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:36:05 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:36:05 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:36:05 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:36:05 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:36:05 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:36:05 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:36:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:36:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:36:05 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:36:05 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:36:05 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:36:05 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:36:05 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:36:05 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:36:05 INFO - Downloading docopt-0.6.1.tar.gz 23:36:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:36:06 INFO - Installing collected packages: blobuploader, requests, docopt 23:36:06 INFO - Running setup.py install for blobuploader 23:36:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:36:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:36:06 INFO - Running setup.py install for requests 23:36:06 INFO - Running setup.py install for docopt 23:36:06 INFO - Successfully installed blobuploader requests docopt 23:36:06 INFO - Cleaning up... 23:36:06 INFO - Return code: 0 23:36:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:36: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')]} 23:36:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:36:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:36: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')]} 23:36:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:36:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:36:06 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 0x1886030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19e98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b47180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b453b0>, '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 0x1b462b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b46740>, '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': '934fc3100db09dcb9af4d4b6000001ab-1474524331.565006-1876900845', '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:36:06 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:36:06 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:36:06 INFO - Using env: {'DISPLAY': ':0', 23:36:06 INFO - 'HOME': '/home/cltbld', 23:36:06 INFO - 'LANG': 'en_US.UTF-8', 23:36:06 INFO - 'LANGUAGE': 'en_US:en', 23:36:06 INFO - 'LOGNAME': 'cltbld', 23:36:06 INFO - 'MAIL': '/var/mail/cltbld', 23:36:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:36:06 INFO - 'MOZ_NO_REMOTE': '1', 23:36:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:36:06 INFO - 'NO_EM_RESTART': '1', 23:36:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:36:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:36:06 INFO - 'PWD': '/builds/slave/test', 23:36:06 INFO - 'SHELL': '/bin/bash', 23:36:06 INFO - 'SHLVL': '1', 23:36:06 INFO - 'TERM': 'linux', 23:36:06 INFO - 'TMOUT': '86400', 23:36:06 INFO - 'USER': 'cltbld', 23:36:06 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1474524331.565006-1876900845', 23:36:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:36:06 INFO - '_': '/tools/buildbot/bin/python'} 23:36:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:36:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:36:06 INFO - Running setup.py (path:/tmp/pip-_gjPZr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:36:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:36:06 INFO - Running setup.py (path:/tmp/pip-uoONnX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:36:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:36:07 INFO - Running setup.py (path:/tmp/pip-K1l0Zw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:36:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:36:07 INFO - Running setup.py (path:/tmp/pip-RwDy5C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:36:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:36:07 INFO - Running setup.py (path:/tmp/pip-_UGqpX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:36:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:36:07 INFO - Running setup.py (path:/tmp/pip-MYOGP7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:36:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:36:07 INFO - Running setup.py (path:/tmp/pip-Pddrws-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:36:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:36:07 INFO - Running setup.py (path:/tmp/pip-JnELZd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:36:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:36:07 INFO - Running setup.py (path:/tmp/pip-noOhSi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:36:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:36:07 INFO - Running setup.py (path:/tmp/pip-ISMVbG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:36:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:36:07 INFO - Running setup.py (path:/tmp/pip-LWbDRi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:36:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:36:07 INFO - Running setup.py (path:/tmp/pip-OA98vZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:36:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:36:07 INFO - Running setup.py (path:/tmp/pip-NpzjUU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:36:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:36:07 INFO - Running setup.py (path:/tmp/pip-mJdsUF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:36:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:36:07 INFO - Running setup.py (path:/tmp/pip-fFriDZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:36:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:36:07 INFO - Running setup.py (path:/tmp/pip-XhXQxT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:36:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:36:08 INFO - Running setup.py (path:/tmp/pip-Ro23mP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:36:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:36:08 INFO - Running setup.py install for manifestparser 23:36:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:36:08 INFO - Running setup.py install for mozcrash 23:36:08 INFO - Running setup.py install for mozdebug 23:36:08 INFO - Running setup.py install for mozdevice 23:36:08 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:36:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:36:08 INFO - Running setup.py install for mozfile 23:36:08 INFO - Running setup.py install for mozhttpd 23:36:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:36:08 INFO - Running setup.py install for mozinfo 23:36:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:36:08 INFO - Running setup.py install for mozInstall 23:36:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:36:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:36:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:36:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:36:08 INFO - Running setup.py install for mozleak 23:36:08 INFO - Running setup.py install for mozlog 23:36:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:36:09 INFO - Running setup.py install for moznetwork 23:36:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:36:09 INFO - Running setup.py install for mozprocess 23:36:09 INFO - Running setup.py install for mozprofile 23:36:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:36:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:36:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:36:09 INFO - Running setup.py install for mozrunner 23:36:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:36:09 INFO - Running setup.py install for mozscreenshot 23:36:09 INFO - Running setup.py install for moztest 23:36:09 INFO - Running setup.py install for mozversion 23:36:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:36:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:36:09 INFO - Cleaning up... 23:36:09 INFO - Return code: 0 23:36:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:36:09 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:36:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:36:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:36:09 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:36:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:36:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:36:09 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 0x1886030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19e98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b47180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b453b0>, '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 0x1b462b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b46740>, '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': '934fc3100db09dcb9af4d4b6000001ab-1474524331.565006-1876900845', '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:36:09 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:36:09 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:36:09 INFO - Using env: {'DISPLAY': ':0', 23:36:09 INFO - 'HOME': '/home/cltbld', 23:36:09 INFO - 'LANG': 'en_US.UTF-8', 23:36:09 INFO - 'LANGUAGE': 'en_US:en', 23:36:09 INFO - 'LOGNAME': 'cltbld', 23:36:09 INFO - 'MAIL': '/var/mail/cltbld', 23:36:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:36:09 INFO - 'MOZ_NO_REMOTE': '1', 23:36:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:36:09 INFO - 'NO_EM_RESTART': '1', 23:36:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:36:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:36:09 INFO - 'PWD': '/builds/slave/test', 23:36:09 INFO - 'SHELL': '/bin/bash', 23:36:09 INFO - 'SHLVL': '1', 23:36:09 INFO - 'TERM': 'linux', 23:36:09 INFO - 'TMOUT': '86400', 23:36:09 INFO - 'USER': 'cltbld', 23:36:09 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1474524331.565006-1876900845', 23:36:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:36:09 INFO - '_': '/tools/buildbot/bin/python'} 23:36:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:36:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:36:10 INFO - Running setup.py (path:/tmp/pip-IiURz7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:36:10 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:36:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:36:10 INFO - Running setup.py (path:/tmp/pip-CzrmHe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:36:10 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:36:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:36:10 INFO - Running setup.py (path:/tmp/pip-U96ZIY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:36:10 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:36:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:36:10 INFO - Running setup.py (path:/tmp/pip-5so_qb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:36:10 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:36:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:36:10 INFO - Running setup.py (path:/tmp/pip-6HT6iw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:36:10 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:36:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:36:10 INFO - Running setup.py (path:/tmp/pip-fwQbHa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:36:10 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:36:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:36:10 INFO - Running setup.py (path:/tmp/pip-Egg6rA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:36:10 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:36:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:36:10 INFO - Running setup.py (path:/tmp/pip-b0LmEP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:36:10 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:36:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:36:10 INFO - Running setup.py (path:/tmp/pip-WKuHP3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:36:10 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:36:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:36:10 INFO - Running setup.py (path:/tmp/pip-RlwTAB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:36:10 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:36:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:36:10 INFO - Running setup.py (path:/tmp/pip-DajXIU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:36:10 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:36:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:36:10 INFO - Running setup.py (path:/tmp/pip-mVABmR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:36:10 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:36:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:36:10 INFO - Running setup.py (path:/tmp/pip-Uo42wb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:36:11 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:36:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:36:11 INFO - Running setup.py (path:/tmp/pip-rRo5FI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:36:11 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:36:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:36:11 INFO - Running setup.py (path:/tmp/pip-drlbxb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:36:11 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:36:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:36:11 INFO - Running setup.py (path:/tmp/pip-OHbMIW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:36:11 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:36:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:36:11 INFO - Running setup.py (path:/tmp/pip-72r9k1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:36:11 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:36:11 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:36:11 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:36:11 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:36:11 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:36:11 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:36:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:36:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:36:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:36:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:36:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:36:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:36:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:36:13 INFO - Downloading blessings-1.6.tar.gz 23:36:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:36:13 INFO - Installing collected packages: blessings 23:36:13 INFO - Running setup.py install for blessings 23:36:13 INFO - Successfully installed blessings 23:36:13 INFO - Cleaning up... 23:36:13 INFO - Return code: 0 23:36:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:36:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:36:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:36:13 INFO - Reading from file tmpfile_stdout 23:36:13 INFO - Current package versions: 23:36:13 INFO - argparse == 1.2.1 23:36:13 INFO - blessings == 1.6 23:36:13 INFO - blobuploader == 1.2.4 23:36:13 INFO - docopt == 0.6.1 23:36:13 INFO - functools32 == 3.2.3-2 23:36:13 INFO - jsonschema == 2.5.1 23:36:13 INFO - manifestparser == 1.1 23:36:13 INFO - mozInstall == 1.12 23:36:13 INFO - mozcrash == 0.17 23:36:13 INFO - mozdebug == 0.1 23:36:13 INFO - mozdevice == 0.48 23:36:13 INFO - mozfile == 1.2 23:36:13 INFO - mozhttpd == 0.7 23:36:13 INFO - mozinfo == 0.9 23:36:13 INFO - mozleak == 0.1 23:36:13 INFO - mozlog == 3.3 23:36:13 INFO - moznetwork == 0.27 23:36:13 INFO - mozprocess == 0.23 23:36:13 INFO - mozprofile == 0.28 23:36:13 INFO - mozrunner == 6.12 23:36:13 INFO - mozscreenshot == 0.1 23:36:13 INFO - mozsystemmonitor == 0.3 23:36:13 INFO - moztest == 0.7 23:36:13 INFO - mozversion == 1.4 23:36:13 INFO - psutil == 3.1.1 23:36:13 INFO - requests == 1.2.3 23:36:13 INFO - wsgiref == 0.1.2 23:36:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:36:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:36:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:36:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:36:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:36:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:36:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:36:13 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 0x1886030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19e98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b47180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b453b0>, '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 0x1b462b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b46740>, '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': '934fc3100db09dcb9af4d4b6000001ab-1474524331.565006-1876900845', '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:36:13 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:36:13 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:36:13 INFO - Using env: {'DISPLAY': ':0', 23:36:13 INFO - 'HOME': '/home/cltbld', 23:36:13 INFO - 'LANG': 'en_US.UTF-8', 23:36:13 INFO - 'LANGUAGE': 'en_US:en', 23:36:13 INFO - 'LOGNAME': 'cltbld', 23:36:13 INFO - 'MAIL': '/var/mail/cltbld', 23:36:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:36:13 INFO - 'MOZ_NO_REMOTE': '1', 23:36:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:36:13 INFO - 'NO_EM_RESTART': '1', 23:36:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:36:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:36:13 INFO - 'PWD': '/builds/slave/test', 23:36:13 INFO - 'SHELL': '/bin/bash', 23:36:13 INFO - 'SHLVL': '1', 23:36:13 INFO - 'TERM': 'linux', 23:36:13 INFO - 'TMOUT': '86400', 23:36:13 INFO - 'USER': 'cltbld', 23:36:13 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1474524331.565006-1876900845', 23:36:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:36:13 INFO - '_': '/tools/buildbot/bin/python'} 23:36:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:36:13 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:36:13 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:36:13 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:36:13 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:36:13 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:36:13 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:36:13 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:36:13 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:36:13 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:36:13 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:36:13 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:36:13 INFO - Cleaning up... 23:36:13 INFO - Return code: 0 23:36:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:36:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:36:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:36:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:36:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:36:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:36:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:36:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1886030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19e98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b47180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b453b0>, '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 0x1b462b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b46740>, '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': '934fc3100db09dcb9af4d4b6000001ab-1474524331.565006-1876900845', '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:36:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 23:36:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 23:36:13 INFO - Using env: {'DISPLAY': ':0', 23:36:13 INFO - 'HOME': '/home/cltbld', 23:36:13 INFO - 'LANG': 'en_US.UTF-8', 23:36:13 INFO - 'LANGUAGE': 'en_US:en', 23:36:13 INFO - 'LOGNAME': 'cltbld', 23:36:13 INFO - 'MAIL': '/var/mail/cltbld', 23:36:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:36:13 INFO - 'MOZ_NO_REMOTE': '1', 23:36:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:36:13 INFO - 'NO_EM_RESTART': '1', 23:36:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:36:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:36:13 INFO - 'PWD': '/builds/slave/test', 23:36:13 INFO - 'SHELL': '/bin/bash', 23:36:13 INFO - 'SHLVL': '1', 23:36:13 INFO - 'TERM': 'linux', 23:36:13 INFO - 'TMOUT': '86400', 23:36:13 INFO - 'USER': 'cltbld', 23:36:13 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1474524331.565006-1876900845', 23:36:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:36:13 INFO - '_': '/tools/buildbot/bin/python'} 23:36:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:36:14 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:36:14 INFO - Cleaning up... 23:36:14 INFO - Return code: 0 23:36:14 INFO - Running post-action listener: _resource_record_post_action 23:36:14 INFO - Running post-action listener: _start_resource_monitoring 23:36:14 INFO - Starting resource monitoring. 23:36:14 INFO - [mozharness: 2016-09-22 06:36:14.174910Z] Finished create-virtualenv step (success) 23:36:14 INFO - [mozharness: 2016-09-22 06:36:14.175339Z] Running install step. 23:36:14 INFO - Running pre-action listener: _resource_record_pre_action 23:36:14 INFO - Running main action method: install 23:36:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:36:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:36:14 INFO - Reading from file tmpfile_stdout 23:36:14 INFO - Detecting whether we're running mozinstall >=1.0... 23:36:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:36:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:36:14 INFO - Reading from file tmpfile_stdout 23:36:14 INFO - Output received: 23:36:14 INFO - Usage: mozinstall [options] installer 23:36:14 INFO - Options: 23:36:14 INFO - -h, --help show this help message and exit 23:36:14 INFO - -d DEST, --destination=DEST 23:36:14 INFO - Directory to install application into. [default: 23:36:14 INFO - "/builds/slave/test"] 23:36:14 INFO - --app=APP Application being installed. [default: firefox] 23:36:14 INFO - mkdir: /builds/slave/test/build/application 23:36:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 23:36:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 23:36:27 INFO - Reading from file tmpfile_stdout 23:36:27 INFO - Output received: 23:36:27 INFO - /builds/slave/test/build/application/firefox/firefox 23:36:27 INFO - Running post-action listener: _resource_record_post_action 23:36:27 INFO - [mozharness: 2016-09-22 06:36:27.967068Z] Finished install step (success) 23:36:27 INFO - [mozharness: 2016-09-22 06:36:27.967341Z] Running run-tests step. 23:36:27 INFO - Running pre-action listener: _resource_record_pre_action 23:36:27 INFO - Running main action method: run_tests 23:36:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:36:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:36:27 INFO - Python 2.7.3 23:36:27 INFO - Return code: 0 23:36:27 INFO - grabbing minidump binary from tooltool 23:36:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:36:27 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 0x1b453b0>, '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 0x1b462b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b46740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:36:27 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:36:27 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:36:27 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:36:28 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:36:28 INFO - Return code: 0 23:36:28 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 23:36:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:36:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:36:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 23:36:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:36:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-057', '--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:36:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-057 --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:36:28 INFO - Using env: {'DISPLAY': ':0', 23:36:28 INFO - 'HOME': '/home/cltbld', 23:36:28 INFO - 'LANG': 'en_US.UTF-8', 23:36:28 INFO - 'LANGUAGE': 'en_US:en', 23:36:28 INFO - 'LOGNAME': 'cltbld', 23:36:28 INFO - 'MAIL': '/var/mail/cltbld', 23:36:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:36:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 23:36:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:36:28 INFO - 'MOZ_NO_REMOTE': '1', 23:36:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:36:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:36:28 INFO - 'NO_EM_RESTART': '1', 23:36:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:36:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:36:28 INFO - 'PWD': '/builds/slave/test', 23:36:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:36:28 INFO - 'SHELL': '/bin/bash', 23:36:28 INFO - 'SHLVL': '1', 23:36:28 INFO - 'TERM': 'linux', 23:36:28 INFO - 'TMOUT': '86400', 23:36:28 INFO - 'USER': 'cltbld', 23:36:28 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1474524331.565006-1876900845', 23:36:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:36:28 INFO - '_': '/tools/buildbot/bin/python'} 23:36:28 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-057', '--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:36:28 INFO - mozversion application_buildid: 20160921203459 23:36:28 INFO - mozversion application_changeset: 49788f46f9b6ffb93bb524556f59b20aa2c7dc8a 23:36:28 INFO - mozversion application_display_name: Firefox 23:36:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:36:28 INFO - mozversion application_name: Firefox 23:36:28 INFO - mozversion application_remotingname: firefox 23:36:28 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 23:36:28 INFO - mozversion application_vendor: Mozilla 23:36:28 INFO - mozversion application_version: 50.0 23:36:28 INFO - mozversion platform_buildid: 20160921203459 23:36:28 INFO - mozversion platform_changeset: 49788f46f9b6ffb93bb524556f59b20aa2c7dc8a 23:36:28 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 23:36:28 INFO - mozversion platform_version: 50.0 23:36:28 INFO - using testdate: 1474526188 23:36:28 INFO - actual date: 1474526188 23:36:28 INFO - starting webserver on 'localhost:44316' 23:36:28 INFO - SUITE-START | Running 2 tests 23:36:28 INFO - TEST-START | dromaeo_css 23:36:28 INFO - operating with platform_type : linux_ 23:36:28 INFO - Initialising browser for dromaeo_css test... 23:36:28 INFO - TEST-INFO | started process 21713 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpuoO0OA/profile http://localhost:44316/getInfo.html) 23:36:32 INFO - PROCESS | 21713 | __metrics Screen width/height:1600/1200 23:36:32 INFO - PROCESS | 21713 | colorDepth:24 23:36:32 INFO - PROCESS | 21713 | Browser inner width/height: 1024/697 23:36:32 INFO - PROCESS | 21713 | __metrics 23:36:33 INFO - PROCESS | 21713 | 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:36:33 INFO - PROCESS | 21713 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:36:34 INFO - TEST-INFO | 21713: exit 0 23:36:34 INFO - Browser initialized. 23:36:34 INFO - Running cycle 1/1 for dromaeo_css test... 23:36:34 INFO - TEST-INFO | started process 21802 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpuoO0OA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:36:46 INFO - PROCESS | 21802 | RSS: Main: 143351808 23:36:46 INFO - PROCESS | 21802 | 23:37:36 INFO - PROCESS | 21802 | 1474526256805 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:39:45 INFO - PROCESS | 21802 | Cycle 1(1): loaded http://localhost:44316/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44316/tests/dromaeo/cssquery-ext.html) 23:39:46 INFO - PROCESS | 21802 | RSS: Main: 273670144 23:39:46 INFO - PROCESS | 21802 | 23:42:49 INFO - PROCESS | 21802 | Cycle 1(1): loaded http://localhost:44316/tests/dromaeo/cssquery-ext.html (next: http://localhost:44316/tests/dromaeo/cssquery-jquery.html) 23:42:49 INFO - PROCESS | 21802 | RSS: Main: 234762240 23:42:49 INFO - PROCESS | 21802 | 23:45:51 INFO - PROCESS | 21802 | Cycle 1(1): loaded http://localhost:44316/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44316/tests/dromaeo/cssquery-mootools.html) 23:45:52 INFO - PROCESS | 21802 | RSS: Main: 176939008 23:45:52 INFO - PROCESS | 21802 | 23:48:54 INFO - PROCESS | 21802 | Cycle 1(1): loaded http://localhost:44316/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44316/tests/dromaeo/cssquery-prototype.html) 23:48:54 INFO - PROCESS | 21802 | RSS: Main: 175411200 23:48:54 INFO - PROCESS | 21802 | 23:51:57 INFO - PROCESS | 21802 | Cycle 1(1): loaded http://localhost:44316/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44316/tests/dromaeo/cssquery-yui.html) 23:51:57 INFO - PROCESS | 21802 | RSS: Main: 176832512 23:51:57 INFO - PROCESS | 21802 | 23:55:00 INFO - PROCESS | 21802 | Cycle 1(1): loaded http://localhost:44316/tests/dromaeo/cssquery-yui.html (next: http://localhost:44316/tests/dromaeo/cssquery-dojo.html) 23:55:00 INFO - PROCESS | 21802 | RSS: Main: 259518464 23:55:00 INFO - PROCESS | 21802 | 23:55:00 INFO - PROCESS | 21802 | __start_tp_report 23:55:00 INFO - PROCESS | 21802 | _x_x_mozilla_page_load 23:55:00 INFO - PROCESS | 21802 | _x_x_mozilla_page_load_details 23:55:00 INFO - PROCESS | 21802 | |i|pagename|runs| 23:55:00 INFO - PROCESS | 21802 | |0;dojo.html;3205.4069997050547;3238.093333866517;3251;3257.625373082093;3302.2239773653187;5634.464725851043;5707.286589176353;5707.486326230638;5709.800156994495;5731.713414329288;5697.658140511572;5740.684262365568;5754.424557544244;5755.913661295073;5762.884742305151;5002.099622068026;5065.1642478990925;5126.2054381570915;5174.094533456649;5231.790728370861;5125.077486052509;5174.767135478913;5181.01560703467;5181.196914478243;5186.170212765958;5036.84889453317;5072.26452164436;5075.9492405076035;5106.131957567222;5112.846614601549;5183.377994640629;5198.818041368571;5201.973990130063;5204.323437953042;5221.451747566527;4917;4997.400311962551;5004.524570165829;5109.974450127763;5113.028524580318;5515.944840551583;5522.116461366162;5527.55779537636;5549.861253468655;5556.305461817273;6010.948084085311;6022.126791615235;6022.5483088768515;6023.216981792383;6032.396760323976;5861.413858614147;5947.256592925884;5955.52355811534;6009.969950150221;6053.727582258809;5652.406497317805;5660.094384898438;5671.120976248647;5705.771769129271;5713.114467257582;6226.346233645492;6229.408206220402;6232.283287421914;6232.781852635136;6235.937640623536;5746.339170995306;5860.648361098262;5941.108833674897;5942.970285148633;6036.3360030396625;6082.330943596201;6085.995810691186;6091.81505639555;6117.571769976059;6125.142480052797;6002.129691194752;6003.969980150071;6004.789832355847;6064.332923378425;6077.270727512727;5796.82609521715;5826.504747096545;5920.555958303058;5976.701164941823;5987.491063259672;4587.4036375271;4613.169629466661;4621.537846215351;4631.235846085426;4679.415073115861;4141.565135660712;4150.169966006811;4167.999680076759;4173.791310434527;4188.246115699142;5455.263539422207;5665.461781130786;5750.827475175752;5754.136879468113;5795.275590551181;6112.169143789106;6117.522930757332;6118.558266176196;6119.624418755179;6132.906245166512;5808.041673123887;5809.390014048464;5816.534677225812;5837.3287071986415;5840.678762668009;5115.897682046412;5163.741812909339;5194.714290714047;5264.868378290496;5268.81559145428;6315.494760419156;6333.651649159248;6334.619922804554;6337.112804207414;6346.555741098171;6052.757889684363;6086.269647642312;6093.482054025458;6094.9085763714465;6105.511559075352;6093.329733729198;6121.4834587478645;6144.846378840565;6254.749810007731;6301.40136687014;5930.495907847881;5934.7922822701;5935.109733540004;5951.28584569837;5974.462298393166;6028.246469191351;6034.939650603438;6037.547183961133;6038.064100064497;6038.909416358846;5775.826725198251;5813.157092221689;5826.329972053268;5881.176635271151;5886.411358864079;5842.211301474246;5887.558433117448;5890.381509941566;5912.379200183918;5923.466887980103;5834.329052159056;5837.241158649518;5844.211031510692;5847.532197424111;5850.297964244318;5806.796762113306;5847;5908.852278693067;5912.408759124225;5947.197128387786;5844.590878638454;5848.444397782204;5851.063829787384;5872.354041055566;5885.588008839341 23:55:00 INFO - PROCESS | 21802 | |1;ext.html;591.9467247947683;643.7489379142133;669.1902797614886;682.4915437998692;687.7420967137324;33862.15344616386;37441;46823.29765053523;47583.28625070627;47912.52087479124;49488.75255623726;49857;49967.75016124914;50006.49993500064;50025.24962125571;8555.786105347353;9236.630534778618;9343.299252556086;9401.05989401058;9416.858747118798;8401.15988401158;8661.826763464727;8662.220400163984;10956.12351011921;11229.32624042556;11423.086153107773;11483.770324593503;11493.942530287337;11498.42507874603;11532.827007594893;11268.154888383364;11339.546418143264;11341.26281791681;11341.886581134248;11346.48940797662;11224.158188135842;11265.15511336653;11271.830922536168;11287.717807054807;11294.435278236055;46128.76935615334;46380.30429543526;46463.53536464626;46510.53489465096;46566;3829.253295607358;3836.4245363195464;3843.1545060086737;3895.3183192941124;3899.2396482685886;35424;35632.64367356345;35700.464493032625;36115.638843611494;37554.43668344977;4367.126574685076;4376.518582955872;4664.502002563663;4683.672142949995;4749.715017098951;397397;399222;401208;401923;402015;66671.66664166746;67499.63751268682;67638.3236167632;67706.66146669333;67792;39739.60260397417;40161.799191003855;40226.59773402228;41811.163776724294;41938;16765.24556394965;16873.32506699743;16910.40813571519;16913.40803071911;16915.746263806053;13883.791743123862;14017.299135043208;14020.369083391271;14028.929855350658;14035.78946315806;18563.535911602048;19052.14265358062;20244.493887652927;20984.685229721566;21046.684299735516;7510.812229694301;7648.541087534766;7674.783425425423;7734.1492435832015;7807.375409967189;1029.8764148302253;1142.3378983578873;1149.408054851752;1151.7293436042523;1172.454808514045;35253.64746352555;35903.58579952045;37510.62489375071;38319.42520862189;38868.22263554713;1131.1968502363413;1188.2157775868059;1232.7472868062025;1246.364354179357;1294.779887419141;18184.63630727378;18279.45161645126;18351.44945651606;18363;18474.81525184758;651716;654349;657299;660268;660332;65783.67108164428;66379.33620663828;66393.33606663968;66495.33504664892;66525;1332.8733770291806;1456.1336005076962;1459.7080583883267;1466.8093147890706;1492.604459818149;33226.833865830515;34963.65036349604;35229.647703522634;35294.117647058;35654;56652.716736416056;57315.42684573101;57377.713111434176;57476.71261643665;57487.42512574821;462604;476062;478243;490836;879617;30867.228319292197;31680.841595791873;31761;32558.186045349055;32580.348393031534;52748.73625631847;52943.47056529386;52962.47037529729;52972.47027529676;53264.73367633137;43870.29686140781;45393;45985.540144598126;46288;47140;45031.77484112558;45328;45779.31331029971;45785.313220302385;46441.30338044865;43716.781416092716;44129.11741765211;44342.556574433846;45081;45107.54892451034;38101;39520.01199969909;39638.20723585454;40293.79853100716;40621.593784061784;29110.27224319409;29834;30182.84908575443;30298.545521817632;30305.69694303029 23:55:00 INFO - PROCESS | 21802 | |2;jquery.html;428.52861851962865;430.91051782380435;432.50694208602187;435.37958409266787;435.7037214694009;20600.278990235343;20693.793062069395;21352.786472135274;21487;21606.351809445703;19817.306394276202;20757.688634670492;20799.584008319864;20853.895730521326;20893.686594701092;5846;6106.511479081685;6107.847303817418;6118.541109416789;6120.26556813183;5863.296404431453;6003.069524223731;6016.4885984691355;6040.063790112517;6048.516118710492;6015.75936962521;6016.789412370568;6024.638521688691;6025.728842202089;6026.96986515069;5835.620684655505;5909.586328956974;5962.672053037081;5978.133170690247;5979.641221526722;5293.523582877521;5355.598330125217;5371.731413429313;5377.354717433933;5387.78448862045;24383.878080609662;24478.265652030466;24627.261182164562;24706.752932470627;24752.876235618885;2511.510255500178;3192.7605429592872;3217.099212220582;3256.625488068879;3338.165458635341;14801.259937003108;15255;15271.160086195367;15501.612459688598;15957.44148954781;2040.6530889748778;2055.126571207246;2068.88621125838;2096.6959790830397;2121.363590922717;55636.721816391066;69103.30896691019;76016;88749;90410.54794726051;11301.434928253555;11329.09367250618;11339.149563782585;11385.886141138482;11449.885501144881;15926.681466370608;16040.67918641644;16047.277872495766;16056.91971540135;16078.43725469603;8153.817696433984;8222.342212622976;8346.749597511964;8367.778405313877;8374.204450577185;7670.309672129535;7799.53202807822;7850.8429831403055;7954.164812694699;7979.241972012649;6935.237123916365;6954.930450695429;6962.582245065312;7011.544249623758;7034.648267586703;2038.8063134002246;2067.3074520035957;2071.7721050684413;2075.2529089527757;2099.370188943311;846.9110743371981;940.3558562384789;945.4421891084294;1037.1288117980932;1087.7770057138268;19284.517887052934;20258.79741202569;20504.589908201753;21489;21507.56984860294;1031.7884833609091;1069.9144068474504;1089.727568107973;1095.446799366315;1130.5901262459095;10834.566617335377;11508.712282193012;11554;11676.591319303783;11703.88296117045;444208.11583768146;444645;448850;596260;666691;102697;108679;108973.45513272383;109480.4525977381;110428;1078.9028987391173;1094.1247002398209;1108.661858133261;1129.768397478515;1129.8757136714955;18108.185131668844;18366.357177498725;18597;18627;18782.71825922585;45088.77455612832;45173.77413113045;45818.541814582764;46004.309935351666;47415.28877066778;62215.688921555105;64397.67801160964;64905.35094648993;65046;66502.33497664961;8415.747527574182;8450.408471406943;8558.486490810572;8874.866876996979;8936.151065648754;12628.368581571069;13573.932130339284;13683.24742139192;13819.101758385672;13859.514916977858;11360.204785664924;11505.309681419141;11788.764224715285;11809.409529523318;12163.513459461523;11371.772564548828;11678.941605291919;11897.762044759229;12114.394280285775;12295.50817967307;11550.306981581132;11612.76774464489;11761.353125578175;12243;12504.749905001667;8424.957875210584;8509.659613615386;8564.400491965502;8657.82684346297;8815;11234;11256.549738010388;11299.604513841976;11583.131265155316;11684.18210725241 23:55:00 INFO - PROCESS | 21802 | |3;mootools.html;1532.226225755993;1565.4207943061067;1712.4263371770455;1814.328698381599;1834.119622581161;9122.407043542173;9866.654667086654;9880.456574888378;9889.456079915612;9889.703308900736;8411.747647570568;8595.742127736163;8621.482711037343;8779.736607901774;9597.712068637935;2430.2952143878256;2505.624156376549;2508.234988328559;2510.57320255557;2516.3709072731817;2489.7385774493687;2514.6102354135137;2514.7233804281514;2517.6601158843505;2518.874056297187;2486.11742831295;2486.3038349262238;2490.663760392351;2561.398071453207;2566.2301309607137;2454.803615710739;2493.7381574934643;2499.6750422445148;2500.9374765630823;2509.8956459158094;2475.3192871960173;2478.7893029092547;2496.7752902238885;2499.2252401755513;2499.512595043967;9664.03359664028;9670.516474176333;9762.463064531448;9792.21662267017;9793.755156121082;5547.667139971574;5684.062129748587;5685.033544297438;5686.744096515667;5691.430856914317;3640.4357324614725;3685.0418891088243;3745.887623371303;3787.0532366908274;3789.7915614641183;1202.7774861650623;1224.7550489902055;1226.7546490701895;1263.204181365743;1265.33569875815;13769.31153442334;13788.655283617889;13827.79258311126;13835.51575694856;13861.514846980308;4905.3623029006;5019.7239151847;5218.147557617067;5231;5257.579393648499;11633.243839150427;11641.708957276136;11642.476088576035;11645.6506304811;11649.184557081093;5878.970605146947;5906.793262235801;5953.374895635896;5955.434233747787;5958.791442299499;4357.085012147484;5284.128118860422;5301.204819277139;5363.651362661492;5374.892502149935;4418.536053714313;4438.378626992224;4451.643868490513;4489.1021795641;4516.81932722694;1326.529082175833;1361.2785223831188;1369.075873785191;1384.1072508232335;1387.5975966969668;1005.8290090684601;1022.1107636356418;1036.4558606731525;1046.40877903984;1049.5486940615456;6207.937920620826;6367.204099487564;6368.681565921685;6386.584871983399;6654.567453115629;498.70077953227775;500.88552969642257;505.02782144371935;510.1837060702846;510.8441925212846;4367.956320436819;4422.447194100738;4474.910501789946;4649.442066951988;4700.177468942921;11581.710457238636;12128.636140915787;12130.87869121315;12143.392830358624;12154.87845121555;11451.141164412702;11858.347790871412;11859.46632401544;12073;12076.09429292789;8959.10408959112;9151.908480915106;9168.81662366749;9191.77020574491;9192.264618830477;6576.605403675795;6872.6907289173105;6878.533322716768;6910.44716422695;6921.850557995999;11048.337099774039;11201.66395008151;11217.831732523855;11336.263142895685;11428.942855286003;10935.398553079656;11272.718182045515;11567.88432115668;11606.767864642828;11675.766484670428;7977.361811055218;8484.236418722345;8660.307175426076;8814.823703525766;8944.910550894409;9516.666916657883;9611.471369074769;9614.278929080207;9616.567254473426;9624;8520.914790852261;8589.098144694717;8638.78403039929;8718.17177368149;8935.285177185939;8189.467684600482;8308.459950103224;8319.708810191856;8364;8577.95711021441;7686.884696729442;7871.645775940211;7930.722424715107;8108.189181081844;8108.2297181767635;5706.8573285666525;5759.596828222152;5811.680357580373;5899.351071382059;5916.083007133901;5862.648241105548;5960.910586341295;5972.850678733066;6047.274327080779;6121.785737499166 23:55:00 INFO - PROCESS | 21802 | |4;prototype.html;356.718192627824;356.958949720782;358.28343313373256;364.266004001936;368.4454895382449;5535.5294799942;5554.7222638868;5561.944380556199;5591.189277554762;5646.576506761989;5470.699111548864;5479.643823151502;5553.527950124234;5564.8330550083465;5586.441355864412;4140.378943158527;4299.3550967354995;4337.63130133939;4359.825606975717;4372.540883207265;4274.465691788527;4301.924518870283;4315.287977483711;4319.222539942809;4338.132373525292;4261.957380426203;4273.487181538204;4277.679174061957;4281.593248641374;4287.356896465524;4107.9935415823165;4281.52903180653;4299.216689313415;4323.632491238217;4334.978325108385;3781.8676346327748;3787.772733635991;3790.223004284115;3798.1454172810895;3798.6581207691165;5357;5401.891962160774;5427.43011983744;5436.906790885498;5462.6995515246645;4835.153848076572;4922.384701912261;4938.925916111261;4949.901001979976;4988.90022199554;5719.656820590778;5725.312962444492;5727.742251598688;5728.68492232927;5728.828135155952;4188.4136220929095;4198.706090573661;4201.2017716633745;4216.85241016566;4238.470191226097;5940.97029514854;5941.851453713649;5969;6008.36912124225;6032.195029311348;4256.893577660583;4268.594483524123;4287.399764033038;4312.849050283225;4317.41714868495;4341.218580655449;4362.520122786491;4363.345498175299;4382.780860957003;4394.692371533962;4780.235162374003;4902.3872015998;4977.278294647256;4977.850664480072;4997.325361076281;5716.485516303553;5748.425157484218;5750.453706897838;5760.971195144081;5771.0378471319955;1463.9707205855823;1495.467146175179;1501.7371959906973;1502.3389708528211;1506.5480355893187;919.6827094652334;949.7435692363023;950.2872845365976;961.1541843177959;982.8107989332844;4924.020967314795;4953.678010929278;4963.8014479421145;4969.925451118236;4970.826021089245;5158.117287190149;5197.8440646780655;5211.296474975906;5211.661242019257;5220.59852467869;4606.239970404913;4864.902701945941;4963.925541116886;5004.299398084271;5005.623453550303;2746.6429364182527;2768.806183567131;2789.4978903797105;2798.2930412448795;2810.690824009357;5326.121190003683;5329.653572517774;5335.679859208382;5342.0885617338645;5364.471125729148;5282.286891269706;5301.6818990860675;5763.904858076952;5771.797987070432;6042.969785151134;5967.850803729854;6180.536459765535;6182.907256391158;6205.82089403012;6211.378862113843;5957.761689532544;5959.493443057216;5984.700764961822;5994.520438365008;5999.760009599741;5593.216949626974;5847.7252738464895;5859.853503662272;5917.201177840936;5917.349091600006;6150.938490615036;6151.200343955257;6152.784652537319;6158.414950579688;6178.474829639531;4097.09863829957;4324.762138082436;4347.739155644827;4354.499232588293;4370.36629688687;5792;5845.649261044351;5878.2064421304285;5887.293524777054;5889.676067816311;5761.251037365116;5771.624844385102;5776.537876969916;5779.162021506942;5788.247527821524;5643.35101463337;5725.2843394575675;5753.1370294456165;5769.884602307847;5776.249087618583;5691.975444420044;5717.742701578356;5721.8569535761935;5733.283339582552;5739.9139012914;5564.221009058654;5743.59794814359;5757.683327416865;5766.538676905921;5771.163181338767;5597.580181486323;5630.3537337451335;5638.154276858504;5643.52030077448;5654.971725141348 23:55:00 INFO - PROCESS | 21802 | |5;yui.html;863.6631713631684;886.769439945614;895.8611415230639;922.1516205091317;942.4345392764346;17028.574285642848;18480.722789158175;18502.907485462554;18757.437276881716;18917.527061823443;16347.83652163478;16993.66012679746;17085.31658733652;17216.56958576036;17230.483085507454;891.652255620307;904.3624244907357;904.5929331800683;905.0316161706976;907.9591418386188;750.9774706758806;884.1512148337604;910.6767097680328;917.2432742987029;919.7056941778634;957.8228027814877;963.6049219819876;964.9964037401095;967.0522887570186;968.2302569457298;960.6685693435753;961.6441916490908;961.8653388525612;962.0523784072683;962.3311798300184;706.4983861458371;706.9222385537587;708.1572928215408;708.88303429934;709.0675761373777;16289.91855040729;16301.836981630151;16305.673886522321;16339.83660163407;16349.754753678704;14457.277136143255;14796.40814367424;14919;15156.1664108474;15173.696526069416;11488.195826292163;11499;11717.179797414185;13705.725885482334;14966.102033877893;3948.2498325318097;3983.3029219886403;3989.860354887565;4110.424540564323;4173.5409104998425;86180.56909715412;86247;87274;88014;88494.5575272126;11160.720981975353;11700.824487632692;12516.74966500665;12518.81221781674;12771.361431928366;23630.527389452458;23637.88181059118;23644.527109457715;23736;23957.760422395553;8375.706850260334;8402.41183117176;8782.956085219661;9033.77415564616;9048.321375896834;4591.471980722193;4977.122977701122;5162.819301324435;5259.21111833228;5370.70461124634;3818.8281527331337;3929.1159489115294;3933.3116704576582;3935.8819235422984;3936.1537269486626;594.7353427724621;673.7069374821968;682.1200651159949;683.1563019670803;683.361057411322;751.0349201276333;790.715342476708;832.1304237072153;842.1830824100736;867.8090820019586;11082.889171108347;11094;11451.312921224753;11701;12032.699182520508;663.9468842492589;739.6043116932416;820.7578764264556;841.5161282262724;867.2931560777946;5057.797688092435;5366.6779993200535;5541.27963364768;5623.971880140573;5659.197808958631;69109.65445172742;69802.65098674475;69919.65040174869;95819;131369;69905.65047174731;87473.56263218643;106564.46717766517;111169.44415277871;111524;95619;98082;98419;99939;100894;10410.27128101056;10805;10928.945355273172;11113.499892504695;11130.10959123252;58111.418885812294;58167;58581.70709146427;59907;60703;83958.58020709857;85605.57197213975;86809.56595216984;87754;90281.54859225925;13982.230977296347;14286.42854285817;14407.78388324197;14699.779503307245;14976;15409.84590154084;16189.67620647604;16399.098049607386;16640.084795336374;16723.74914376261;14829.184394857955;14910.627234319229;15200.16399098016;15736.527904162893;15973.920130399274;15522.456714014956;15702.293396796944;15904.363825446853;15972.920135399714;16005.119718415599;13847.930760346133;14002.859971400156;15389.461368852037;15772.211389430711;15995.200239988188;12381.62855114348;12578.622641320775;12620.873791261969;12643.178193417398;13426.597202083953;12300.200486968677;12572.811407829073;12832.101752877208;12964.5462408819;13094.607161785161 23:55:00 INFO - PROCESS | 21802 | __end_tp_report 23:55:00 INFO - PROCESS | 21802 | __start_cc_report 23:55:00 INFO - PROCESS | 21802 | _x_x_mozilla_cycle_collect,1111 23:55:00 INFO - PROCESS | 21802 | __end_cc_report 23:55:00 INFO - PROCESS | 21802 | __startTimestamp1474527300538__endTimestamp 23:55:00 INFO - PROCESS | 21802 | 23:55:00 INFO - PROCESS | 21802 | ------- Summary: start ------- 23:55:00 INFO - PROCESS | 21802 | Number of tests: 6 23:55:00 INFO - PROCESS | 21802 | 23:55:00 INFO - PROCESS | 21802 | [#0] dojo.html Cycles:165 Average:5611.60 Median:5837.33 stddev:639.00 (10.9%) stddev-sans-first:612.44 23:55:00 INFO - PROCESS | 21802 | Values: 3205.4 3238.1 3251.0 3257.6 3302.2 5634.5 5707.3 5707.5 5709.8 5731.7 5697.7 5740.7 5754.4 5755.9 5762.9 5002.1 5065.2 5126.2 5174.1 5231.8 5125.1 5174.8 5181.0 5181.2 5186.2 5036.8 5072.3 5075.9 5106.1 5112.8 5183.4 5198.8 5202.0 5204.3 5221.5 4917.0 4997.4 5004.5 5110.0 5113.0 5515.9 5522.1 5527.6 5549.9 5556.3 6010.9 6022.1 6022.5 6023.2 6032.4 5861.4 5947.3 5955.5 6010.0 6053.7 5652.4 5660.1 5671.1 5705.8 5713.1 6226.3 6229.4 6232.3 6232.8 6235.9 5746.3 5860.6 5941.1 5943.0 6036.3 6082.3 6086.0 6091.8 6117.6 6125.1 6002.1 6004.0 6004.8 6064.3 6077.3 5796.8 5826.5 5920.6 5976.7 5987.5 4587.4 4613.2 4621.5 4631.2 4679.4 4141.6 4150.2 4168.0 4173.8 4188.2 5455.3 5665.5 5750.8 5754.1 5795.3 6112.2 6117.5 6118.6 6119.6 6132.9 5808.0 5809.4 5816.5 5837.3 5840.7 5115.9 5163.7 5194.7 5264.9 5268.8 6315.5 6333.7 6334.6 6337.1 6346.6 6052.8 6086.3 6093.5 6094.9 6105.5 6093.3 6121.5 6144.8 6254.7 6301.4 5930.5 5934.8 5935.1 5951.3 5974.5 6028.2 6034.9 6037.5 6038.1 6038.9 5775.8 5813.2 5826.3 5881.2 5886.4 5842.2 5887.6 5890.4 5912.4 5923.5 5834.3 5837.2 5844.2 5847.5 5850.3 5806.8 5847.0 5908.9 5912.4 5947.2 5844.6 5848.4 5851.1 5872.4 5885.6 23:55:00 INFO - PROCESS | 21802 | 23:55:00 INFO - PROCESS | 21802 | [#1] ext.html Cycles:180 Average:70180.14 Median:220684.90 stddev:148530.31 (67.3%) stddev-sans-first:148855.08 23:55:00 INFO - PROCESS | 21802 | Values: 591.9 643.7 669.2 682.5 687.7 33862.2 37441.0 46823.3 47583.3 47912.5 49488.8 49857.0 49967.8 50006.5 50025.2 8555.8 9236.6 9343.3 9401.1 9416.9 8401.2 8661.8 8662.2 10956.1 11229.3 11423.1 11483.8 11493.9 11498.4 11532.8 11268.2 11339.5 11341.3 11341.9 11346.5 11224.2 11265.2 11271.8 11287.7 11294.4 46128.8 46380.3 46463.5 46510.5 46566.0 3829.3 3836.4 3843.2 3895.3 3899.2 35424.0 35632.6 35700.5 36115.6 37554.4 4367.1 4376.5 4664.5 4683.7 4749.7 397397.0 399222.0 401208.0 401923.0 402015.0 66671.7 67499.6 67638.3 67706.7 67792.0 39739.6 40161.8 40226.6 41811.2 41938.0 16765.2 16873.3 16910.4 16913.4 16915.7 13883.8 14017.3 14020.4 14028.9 14035.8 18563.5 19052.1 20244.5 20984.7 21046.7 7510.8 7648.5 7674.8 7734.1 7807.4 1029.9 1142.3 1149.4 1151.7 1172.5 35253.6 35903.6 37510.6 38319.4 38868.2 1131.2 1188.2 1232.7 1246.4 1294.8 18184.6 18279.5 18351.4 18363.0 18474.8 651716.0 654349.0 657299.0 660268.0 660332.0 65783.7 66379.3 66393.3 66495.3 66525.0 1332.9 1456.1 1459.7 1466.8 1492.6 33226.8 34963.7 35229.6 35294.1 35654.0 56652.7 57315.4 57377.7 57476.7 57487.4 462604.0 476062.0 478243.0 490836.0 879617.0 30867.2 31680.8 31761.0 32558.2 32580.3 52748.7 52943.5 52962.5 52972.5 53264.7 43870.3 45393.0 45985.5 46288.0 47140.0 45031.8 45328.0 45779.3 45785.3 46441.3 43716.8 44129.1 44342.6 45081.0 45107.5 38101.0 39520.0 39638.2 40293.8 40621.6 29110.3 29834.0 30182.8 30298.5 30305.7 23:55:00 INFO - PROCESS | 21802 | 23:55:00 INFO - PROCESS | 21802 | [#2] jquery.html Cycles:180 Average:30982.89 Median:21556.96 stddev:87309.94 (405.0%) stddev-sans-first:87524.72 23:55:00 INFO - PROCESS | 21802 | Values: 428.5 430.9 432.5 435.4 435.7 20600.3 20693.8 21352.8 21487.0 21606.4 19817.3 20757.7 20799.6 20853.9 20893.7 5846.0 6106.5 6107.8 6118.5 6120.3 5863.3 6003.1 6016.5 6040.1 6048.5 6015.8 6016.8 6024.6 6025.7 6027.0 5835.6 5909.6 5962.7 5978.1 5979.6 5293.5 5355.6 5371.7 5377.4 5387.8 24383.9 24478.3 24627.3 24706.8 24752.9 2511.5 3192.8 3217.1 3256.6 3338.2 14801.3 15255.0 15271.2 15501.6 15957.4 2040.7 2055.1 2068.9 2096.7 2121.4 55636.7 69103.3 76016.0 88749.0 90410.5 11301.4 11329.1 11339.1 11385.9 11449.9 15926.7 16040.7 16047.3 16056.9 16078.4 8153.8 8222.3 8346.7 8367.8 8374.2 7670.3 7799.5 7850.8 7954.2 7979.2 6935.2 6954.9 6962.6 7011.5 7034.6 2038.8 2067.3 2071.8 2075.3 2099.4 846.9 940.4 945.4 1037.1 1087.8 19284.5 20258.8 20504.6 21489.0 21507.6 1031.8 1069.9 1089.7 1095.4 1130.6 10834.6 11508.7 11554.0 11676.6 11703.9 444208.1 444645.0 448850.0 596260.0 666691.0 102697.0 108679.0 108973.5 109480.5 110428.0 1078.9 1094.1 1108.7 1129.8 1129.9 18108.2 18366.4 18597.0 18627.0 18782.7 45088.8 45173.8 45818.5 46004.3 47415.3 62215.7 64397.7 64905.4 65046.0 66502.3 8415.7 8450.4 8558.5 8874.9 8936.2 12628.4 13573.9 13683.2 13819.1 13859.5 11360.2 11505.3 11788.8 11809.4 12163.5 11371.8 11678.9 11897.8 12114.4 12295.5 11550.3 11612.8 11761.4 12243.0 12504.7 8425.0 8509.7 8564.4 8657.8 8815.0 11234.0 11256.5 11299.6 11583.1 11684.2 23:55:00 INFO - PROCESS | 21802 | 23:55:00 INFO - PROCESS | 21802 | [#3] mootools.html Cycles:180 Average:6402.80 Median:4802.77 stddev:3692.67 (76.9%) stddev-sans-first:3684.89 23:55:00 INFO - PROCESS | 21802 | Values: 1532.2 1565.4 1712.4 1814.3 1834.1 9122.4 9866.7 9880.5 9889.5 9889.7 8411.7 8595.7 8621.5 8779.7 9597.7 2430.3 2505.6 2508.2 2510.6 2516.4 2489.7 2514.6 2514.7 2517.7 2518.9 2486.1 2486.3 2490.7 2561.4 2566.2 2454.8 2493.7 2499.7 2500.9 2509.9 2475.3 2478.8 2496.8 2499.2 2499.5 9664.0 9670.5 9762.5 9792.2 9793.8 5547.7 5684.1 5685.0 5686.7 5691.4 3640.4 3685.0 3745.9 3787.1 3789.8 1202.8 1224.8 1226.8 1263.2 1265.3 13769.3 13788.7 13827.8 13835.5 13861.5 4905.4 5019.7 5218.1 5231.0 5257.6 11633.2 11641.7 11642.5 11645.7 11649.2 5879.0 5906.8 5953.4 5955.4 5958.8 4357.1 5284.1 5301.2 5363.7 5374.9 4418.5 4438.4 4451.6 4489.1 4516.8 1326.5 1361.3 1369.1 1384.1 1387.6 1005.8 1022.1 1036.5 1046.4 1049.5 6207.9 6367.2 6368.7 6386.6 6654.6 498.7 500.9 505.0 510.2 510.8 4368.0 4422.4 4474.9 4649.4 4700.2 11581.7 12128.6 12130.9 12143.4 12154.9 11451.1 11858.3 11859.5 12073.0 12076.1 8959.1 9151.9 9168.8 9191.8 9192.3 6576.6 6872.7 6878.5 6910.4 6921.9 11048.3 11201.7 11217.8 11336.3 11428.9 10935.4 11272.7 11567.9 11606.8 11675.8 7977.4 8484.2 8660.3 8814.8 8944.9 9516.7 9611.5 9614.3 9616.6 9624.0 8520.9 8589.1 8638.8 8718.2 8935.3 8189.5 8308.5 8319.7 8364.0 8578.0 7686.9 7871.6 7930.7 8108.2 8108.2 5706.9 5759.6 5811.7 5899.4 5916.1 5862.6 5960.9 5972.9 6047.3 6121.8 23:55:00 INFO - PROCESS | 21802 | 23:55:00 INFO - PROCESS | 21802 | [#4] prototype.html Cycles:180 Average:4780.91 Median:5349.54 stddev:1399.45 (26.2%) stddev-sans-first:1363.40 23:55:00 INFO - PROCESS | 21802 | Values: 356.7 357.0 358.3 364.3 368.4 5535.5 5554.7 5561.9 5591.2 5646.6 5470.7 5479.6 5553.5 5564.8 5586.4 4140.4 4299.4 4337.6 4359.8 4372.5 4274.5 4301.9 4315.3 4319.2 4338.1 4262.0 4273.5 4277.7 4281.6 4287.4 4108.0 4281.5 4299.2 4323.6 4335.0 3781.9 3787.8 3790.2 3798.1 3798.7 5357.0 5401.9 5427.4 5436.9 5462.7 4835.2 4922.4 4938.9 4949.9 4988.9 5719.7 5725.3 5727.7 5728.7 5728.8 4188.4 4198.7 4201.2 4216.9 4238.5 5941.0 5941.9 5969.0 6008.4 6032.2 4256.9 4268.6 4287.4 4312.8 4317.4 4341.2 4362.5 4363.3 4382.8 4394.7 4780.2 4902.4 4977.3 4977.9 4997.3 5716.5 5748.4 5750.5 5761.0 5771.0 1464.0 1495.5 1501.7 1502.3 1506.5 919.7 949.7 950.3 961.2 982.8 4924.0 4953.7 4963.8 4969.9 4970.8 5158.1 5197.8 5211.3 5211.7 5220.6 4606.2 4864.9 4963.9 5004.3 5005.6 2746.6 2768.8 2789.5 2798.3 2810.7 5326.1 5329.7 5335.7 5342.1 5364.5 5282.3 5301.7 5763.9 5771.8 6043.0 5967.9 6180.5 6182.9 6205.8 6211.4 5957.8 5959.5 5984.7 5994.5 5999.8 5593.2 5847.7 5859.9 5917.2 5917.3 6150.9 6151.2 6152.8 6158.4 6178.5 4097.1 4324.8 4347.7 4354.5 4370.4 5792.0 5845.6 5878.2 5887.3 5889.7 5761.3 5771.6 5776.5 5779.2 5788.2 5643.4 5725.3 5753.1 5769.9 5776.2 5692.0 5717.7 5721.9 5733.3 5739.9 5564.2 5743.6 5757.7 5766.5 5771.2 5597.6 5630.4 5638.2 5643.5 5655.0 23:55:00 INFO - PROCESS | 21802 | 23:55:00 INFO - PROCESS | 21802 | [#5] yui.html Cycles:180 Average:21969.83 Median:3965.78 stddev:30294.60 (763.9%) stddev-sans-first:30338.13 23:55:00 INFO - PROCESS | 21802 | Values: 863.7 886.8 895.9 922.2 942.4 17028.6 18480.7 18502.9 18757.4 18917.5 16347.8 16993.7 17085.3 17216.6 17230.5 891.7 904.4 904.6 905.0 908.0 751.0 884.2 910.7 917.2 919.7 957.8 963.6 965.0 967.1 968.2 960.7 961.6 961.9 962.1 962.3 706.5 706.9 708.2 708.9 709.1 16289.9 16301.8 16305.7 16339.8 16349.8 14457.3 14796.4 14919.0 15156.2 15173.7 11488.2 11499.0 11717.2 13705.7 14966.1 3948.2 3983.3 3989.9 4110.4 4173.5 86180.6 86247.0 87274.0 88014.0 88494.6 11160.7 11700.8 12516.7 12518.8 12771.4 23630.5 23637.9 23644.5 23736.0 23957.8 8375.7 8402.4 8783.0 9033.8 9048.3 4591.5 4977.1 5162.8 5259.2 5370.7 3818.8 3929.1 3933.3 3935.9 3936.2 594.7 673.7 682.1 683.2 683.4 751.0 790.7 832.1 842.2 867.8 11082.9 11094.0 11451.3 11701.0 12032.7 663.9 739.6 820.8 841.5 867.3 5057.8 5366.7 5541.3 5624.0 5659.2 69109.7 69802.7 69919.7 95819.0 131369.0 69905.7 87473.6 106564.5 111169.4 111524.0 95619.0 98082.0 98419.0 99939.0 100894.0 10410.3 10805.0 10928.9 11113.5 11130.1 58111.4 58167.0 58581.7 59907.0 60703.0 83958.6 85605.6 86809.6 87754.0 90281.5 13982.2 14286.4 14407.8 14699.8 14976.0 15409.8 16189.7 16399.1 16640.1 16723.7 14829.2 14910.6 15200.2 15736.5 15973.9 15522.5 15702.3 15904.4 15972.9 16005.1 13847.9 14002.9 15389.5 15772.2 15995.2 12381.6 12578.6 12620.9 12643.2 13426.6 12300.2 12572.8 12832.1 12964.5 13094.6 23:55:00 INFO - PROCESS | 21802 | -------- Summary: end -------- 23:55:00 INFO - PROCESS | 21802 | 23:55:01 INFO - TEST-INFO | 21802: exit 0 23:55:01 INFO - TEST-OK | dromaeo_css | took 1113079ms 23:55:01 INFO - TEST-START | kraken 23:55:01 INFO - operating with platform_type : linux_ 23:55:01 INFO - Initialising browser for kraken test... 23:55:01 INFO - TEST-INFO | started process 21990 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzEuiw_/profile http://localhost:44316/getInfo.html) 23:55:05 INFO - PROCESS | 21990 | __metrics Screen width/height:1600/1200 23:55:05 INFO - PROCESS | 21990 | colorDepth:24 23:55:05 INFO - PROCESS | 21990 | Browser inner width/height: 1024/697 23:55:05 INFO - PROCESS | 21990 | __metrics 23:55:06 INFO - PROCESS | 21990 | 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:55:06 INFO - PROCESS | 21990 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:55:06 INFO - PROCESS | 21990 | console.error: PushService: 23:55:06 INFO - PROCESS | 21990 | stateChangeProcessEnqueue: Error transitioning state 23:55:06 INFO - PROCESS | 21990 | UnknownError 23:55:07 INFO - TEST-INFO | 21990: exit 0 23:55:07 INFO - Browser initialized. 23:55:07 INFO - Running cycle 1/1 for kraken test... 23:55:07 INFO - TEST-INFO | started process 22079 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzEuiw_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:55:18 INFO - PROCESS | 22079 | RSS: Main: 144003072 23:55:18 INFO - PROCESS | 22079 | 23:55:21 INFO - PROCESS | 22079 | Cycle 1(1): loaded http://localhost:44316/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44316/tests/kraken/driver.html?testName=audio-beat-detection) 23:55:22 INFO - PROCESS | 22079 | RSS: Main: 199024640 23:55:22 INFO - PROCESS | 22079 | 23:55:24 INFO - PROCESS | 22079 | Cycle 1(1): loaded http://localhost:44316/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44316/tests/kraken/driver.html?testName=audio-dft) 23:55:25 INFO - PROCESS | 22079 | RSS: Main: 182784000 23:55:25 INFO - PROCESS | 22079 | 23:55:28 INFO - PROCESS | 22079 | Cycle 1(1): loaded http://localhost:44316/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44316/tests/kraken/driver.html?testName=audio-fft) 23:55:28 INFO - PROCESS | 22079 | RSS: Main: 274214912 23:55:28 INFO - PROCESS | 22079 | 23:55:30 INFO - PROCESS | 22079 | Cycle 1(1): loaded http://localhost:44316/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44316/tests/kraken/driver.html?testName=audio-oscillator) 23:55:31 INFO - PROCESS | 22079 | RSS: Main: 179851264 23:55:31 INFO - PROCESS | 22079 | 23:55:33 INFO - PROCESS | 22079 | Cycle 1(1): loaded http://localhost:44316/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44316/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:55:33 INFO - PROCESS | 22079 | RSS: Main: 178327552 23:55:33 INFO - PROCESS | 22079 | 23:55:36 INFO - PROCESS | 22079 | Cycle 1(1): loaded http://localhost:44316/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44316/tests/kraken/driver.html?testName=imaging-darkroom) 23:55:37 INFO - PROCESS | 22079 | RSS: Main: 214482944 23:55:37 INFO - PROCESS | 22079 | 23:55:40 INFO - PROCESS | 22079 | Cycle 1(1): loaded http://localhost:44316/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44316/tests/kraken/driver.html?testName=imaging-desaturate) 23:55:41 INFO - PROCESS | 22079 | RSS: Main: 213876736 23:55:41 INFO - PROCESS | 22079 | 23:55:44 INFO - PROCESS | 22079 | Cycle 1(1): loaded http://localhost:44316/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44316/tests/kraken/driver.html?testName=json-parse-financial) 23:55:44 INFO - PROCESS | 22079 | RSS: Main: 213368832 23:55:44 INFO - PROCESS | 22079 | 23:55:46 INFO - PROCESS | 22079 | Cycle 1(1): loaded http://localhost:44316/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44316/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:55:46 INFO - PROCESS | 22079 | RSS: Main: 175677440 23:55:46 INFO - PROCESS | 22079 | 23:55:48 INFO - PROCESS | 22079 | Cycle 1(1): loaded http://localhost:44316/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44316/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:55:49 INFO - PROCESS | 22079 | RSS: Main: 182702080 23:55:49 INFO - PROCESS | 22079 | 23:55:51 INFO - PROCESS | 22079 | Cycle 1(1): loaded http://localhost:44316/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44316/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:55:51 INFO - PROCESS | 22079 | RSS: Main: 183570432 23:55:51 INFO - PROCESS | 22079 | 23:55:54 INFO - PROCESS | 22079 | Cycle 1(1): loaded http://localhost:44316/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44316/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:55:54 INFO - PROCESS | 22079 | RSS: Main: 184799232 23:55:54 INFO - PROCESS | 22079 | 23:55:57 INFO - PROCESS | 22079 | Cycle 1(1): loaded http://localhost:44316/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44316/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:55:58 INFO - PROCESS | 22079 | RSS: Main: 183234560 23:55:58 INFO - PROCESS | 22079 | 23:55:59 INFO - PROCESS | 22079 | Cycle 1(1): loaded http://localhost:44316/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44316/tests/kraken/driver.html?testName=ai-astar) 23:56:00 INFO - PROCESS | 22079 | RSS: Main: 234192896 23:56:00 INFO - PROCESS | 22079 | 23:56:00 INFO - PROCESS | 22079 | __start_tp_report 23:56:00 INFO - PROCESS | 22079 | _x_x_mozilla_page_load 23:56:00 INFO - PROCESS | 22079 | _x_x_mozilla_page_load_details 23:56:00 INFO - PROCESS | 22079 | |i|pagename|runs| 23:56:00 INFO - PROCESS | 22079 | |0;ai-astar;109;110;119;109;109;109;110;109;110;109 23:56:00 INFO - PROCESS | 22079 | |1;audio-beat-detection;148;114;136;164;117;148;155;116;164;116 23:56:00 INFO - PROCESS | 22079 | |2;audio-dft;160;160;161;159;161;161;161;163;162;160 23:56:00 INFO - PROCESS | 22079 | |3;audio-fft;75;75;75;177;76;75;74;74;169;75 23:56:00 INFO - PROCESS | 22079 | |4;audio-oscillator;82;84;159;82;82;85;142;83;82;133 23:56:00 INFO - PROCESS | 22079 | |5;imaging-gaussian-blur;116;117;117;117;117;117;118;118;117;117 23:56:00 INFO - PROCESS | 22079 | |6;imaging-darkroom;147;146;145;145;146;146;146;145;146;146 23:56:00 INFO - PROCESS | 22079 | |7;imaging-desaturate;94;95;95;96;94;95;95;95;94;95 23:56:00 INFO - PROCESS | 22079 | |8;json-parse-financial;54;51;52;51;51;52;51;50;52;54 23:56:00 INFO - PROCESS | 22079 | |9;json-stringify-tinderbox;59;59;59;61;59;59;59;59;60;61 23:56:00 INFO - PROCESS | 22079 | |10;stanford-crypto-aes;71;78;73;72;76;71;72;72;72;73 23:56:00 INFO - PROCESS | 22079 | |11;stanford-crypto-ccm;119;117;123;123;117;115;126;115;154;116 23:56:00 INFO - PROCESS | 22079 | |12;stanford-crypto-pbkdf2;151;150;150;153;147;148;158;150;151;148 23:56:00 INFO - PROCESS | 22079 | |13;stanford-crypto-sha256-iterative;62;60;64;62;61;62;60;63;61;62 23:56:00 INFO - PROCESS | 22079 | __end_tp_report 23:56:00 INFO - PROCESS | 22079 | __start_cc_report 23:56:00 INFO - PROCESS | 22079 | _x_x_mozilla_cycle_collect,1670 23:56:00 INFO - PROCESS | 22079 | __end_cc_report 23:56:00 INFO - PROCESS | 22079 | __startTimestamp1474527360036__endTimestamp 23:56:00 INFO - PROCESS | 22079 | 23:56:00 INFO - PROCESS | 22079 | ------- Summary: start ------- 23:56:00 INFO - PROCESS | 22079 | Number of tests: 14 23:56:00 INFO - PROCESS | 22079 | 23:56:00 INFO - PROCESS | 22079 | [#0] ai-astar Cycles:10 Average:110.30 Median:109.50 stddev:3.09 (2.8%) stddev-sans-first:3.24 23:56:00 INFO - PROCESS | 22079 | Values: 109.0 110.0 119.0 109.0 109.0 109.0 110.0 109.0 110.0 109.0 23:56:00 INFO - PROCESS | 22079 | 23:56:00 INFO - PROCESS | 22079 | [#1] audio-beat-detection Cycles:10 Average:137.80 Median:148.00 stddev:20.63 (13.9%) stddev-sans-first:21.55 23:56:00 INFO - PROCESS | 22079 | Values: 148.0 114.0 136.0 164.0 117.0 148.0 155.0 116.0 164.0 116.0 23:56:00 INFO - PROCESS | 22079 | 23:56:00 INFO - PROCESS | 22079 | [#2] audio-dft Cycles:10 Average:160.80 Median:161.00 stddev:1.14 (0.7%) stddev-sans-first:1.17 23:56:00 INFO - PROCESS | 22079 | Values: 160.0 160.0 161.0 159.0 161.0 161.0 161.0 163.0 162.0 160.0 23:56:00 INFO - PROCESS | 22079 | 23:56:00 INFO - PROCESS | 22079 | [#3] audio-fft Cycles:10 Average:94.50 Median:75.00 stddev:41.42 (55.2%) stddev-sans-first:43.33 23:56:00 INFO - PROCESS | 22079 | Values: 75.0 75.0 75.0 177.0 76.0 75.0 74.0 74.0 169.0 75.0 23:56:00 INFO - PROCESS | 22079 | 23:56:00 INFO - PROCESS | 22079 | [#4] audio-oscillator Cycles:10 Average:101.40 Median:82.00 stddev:30.51 (37.2%) stddev-sans-first:31.55 23:56:00 INFO - PROCESS | 22079 | Values: 82.0 84.0 159.0 82.0 82.0 85.0 142.0 83.0 82.0 133.0 23:56:00 INFO - PROCESS | 22079 | 23:56:00 INFO - PROCESS | 22079 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.44 23:56:00 INFO - PROCESS | 22079 | Values: 116.0 117.0 117.0 117.0 117.0 117.0 118.0 118.0 117.0 117.0 23:56:00 INFO - PROCESS | 22079 | 23:56:00 INFO - PROCESS | 22079 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.63 (0.4%) stddev-sans-first:0.50 23:56:00 INFO - PROCESS | 22079 | Values: 147.0 146.0 145.0 145.0 146.0 146.0 146.0 145.0 146.0 146.0 23:56:00 INFO - PROCESS | 22079 | 23:56:00 INFO - PROCESS | 22079 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.60 23:56:00 INFO - PROCESS | 22079 | Values: 94.0 95.0 95.0 96.0 94.0 95.0 95.0 95.0 94.0 95.0 23:56:00 INFO - PROCESS | 22079 | 23:56:00 INFO - PROCESS | 22079 | [#8] json-parse-financial Cycles:10 Average:51.80 Median:52.00 stddev:1.32 (2.5%) stddev-sans-first:1.13 23:56:00 INFO - PROCESS | 22079 | Values: 54.0 51.0 52.0 51.0 51.0 52.0 51.0 50.0 52.0 54.0 23:56:00 INFO - PROCESS | 22079 | 23:56:00 INFO - PROCESS | 22079 | [#9] json-stringify-tinderbox Cycles:10 Average:59.50 Median:59.00 stddev:0.85 (1.4%) stddev-sans-first:0.88 23:56:00 INFO - PROCESS | 22079 | Values: 59.0 59.0 59.0 61.0 59.0 59.0 59.0 59.0 60.0 61.0 23:56:00 INFO - PROCESS | 22079 | 23:56:00 INFO - PROCESS | 22079 | [#10] stanford-crypto-aes Cycles:10 Average:73.00 Median:72.50 stddev:2.26 (3.1%) stddev-sans-first:2.28 23:56:00 INFO - PROCESS | 22079 | Values: 71.0 78.0 73.0 72.0 76.0 71.0 72.0 72.0 72.0 73.0 23:56:00 INFO - PROCESS | 22079 | 23:56:00 INFO - PROCESS | 22079 | [#11] stanford-crypto-ccm Cycles:10 Average:122.50 Median:121.00 stddev:11.70 (9.7%) stddev-sans-first:12.34 23:56:00 INFO - PROCESS | 22079 | Values: 119.0 117.0 123.0 123.0 117.0 115.0 126.0 115.0 154.0 116.0 23:56:00 INFO - PROCESS | 22079 | 23:56:00 INFO - PROCESS | 22079 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:150.60 Median:150.50 stddev:3.13 (2.1%) stddev-sans-first:3.32 23:56:00 INFO - PROCESS | 22079 | Values: 151.0 150.0 150.0 153.0 147.0 148.0 158.0 150.0 151.0 148.0 23:56:00 INFO - PROCESS | 22079 | 23:56:00 INFO - PROCESS | 22079 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.70 Median:62.00 stddev:1.25 (2.0%) stddev-sans-first:1.32 23:56:00 INFO - PROCESS | 22079 | Values: 62.0 60.0 64.0 62.0 61.0 62.0 60.0 63.0 61.0 62.0 23:56:00 INFO - PROCESS | 22079 | -------- Summary: end -------- 23:56:00 INFO - PROCESS | 22079 | 23:56:01 INFO - TEST-INFO | 22079: exit 0 23:56:01 INFO - TEST-OK | kraken | took 59841ms 23:56:01 INFO - SUITE-END | took 1172s 23:56:01 INFO - Completed test suite (00:19:32) 23:56:01 INFO - javascript benchmark 23:56:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3205.4069997050547, 3238.093333866517, 3251.0, 3257.625373082093, 3302.2239773653187, 5634.464725851043, 5707.286589176353, 5707.486326230638, 5709.800156994495, 5731.713414329288, 5697.658140511572, 5740.684262365568, 5754.424557544244, 5755.913661295073, 5762.884742305151, 5002.099622068026, 5065.1642478990925, 5126.2054381570915, 5174.094533456649, 5231.790728370861, 5125.077486052509, 5174.767135478913, 5181.01560703467, 5181.196914478243, 5186.170212765958, 5036.84889453317, 5072.26452164436, 5075.9492405076035, 5106.131957567222, 5112.846614601549, 5183.377994640629, 5198.818041368571, 5201.973990130063, 5204.323437953042, 5221.451747566527, 4917.0, 4997.400311962551, 5004.524570165829, 5109.974450127763, 5113.028524580318, 5515.944840551583, 5522.116461366162, 5527.55779537636, 5549.861253468655, 5556.305461817273, 6010.948084085311, 6022.126791615235, 6022.5483088768515, 6023.216981792383, 6032.396760323976, 5861.413858614147, 5947.256592925884, 5955.52355811534, 6009.969950150221, 6053.727582258809, 5652.406497317805, 5660.094384898438, 5671.120976248647, 5705.771769129271, 5713.114467257582, 6226.346233645492, 6229.408206220402, 6232.283287421914, 6232.781852635136, 6235.937640623536, 5746.339170995306, 5860.648361098262, 5941.108833674897, 5942.970285148633, 6036.3360030396625, 6082.330943596201, 6085.995810691186, 6091.81505639555, 6117.571769976059, 6125.142480052797, 6002.129691194752, 6003.969980150071, 6004.789832355847, 6064.332923378425, 6077.270727512727, 5796.82609521715, 5826.504747096545, 5920.555958303058, 5976.701164941823, 5987.491063259672, 4587.4036375271, 4613.169629466661, 4621.537846215351, 4631.235846085426, 4679.415073115861, 4141.565135660712, 4150.169966006811, 4167.999680076759, 4173.791310434527, 4188.246115699142, 5455.263539422207, 5665.461781130786, 5750.827475175752, 5754.136879468113, 5795.275590551181, 6112.169143789106, 6117.522930757332, 6118.558266176196, 6119.624418755179, 6132.906245166512, 5808.041673123887, 5809.390014048464, 5816.534677225812, 5837.3287071986415, 5840.678762668009, 5115.897682046412, 5163.741812909339, 5194.714290714047, 5264.868378290496, 5268.81559145428, 6315.494760419156, 6333.651649159248, 6334.619922804554, 6337.112804207414, 6346.555741098171, 6052.757889684363, 6086.269647642312, 6093.482054025458, 6094.9085763714465, 6105.511559075352, 6093.329733729198, 6121.4834587478645, 6144.846378840565, 6254.749810007731, 6301.40136687014, 5930.495907847881, 5934.7922822701, 5935.109733540004, 5951.28584569837, 5974.462298393166, 6028.246469191351, 6034.939650603438, 6037.547183961133, 6038.064100064497, 6038.909416358846, 5775.826725198251, 5813.157092221689, 5826.329972053268, 5881.176635271151, 5886.411358864079, 5842.211301474246, 5887.558433117448, 5890.381509941566, 5912.379200183918, 5923.466887980103, 5834.329052159056, 5837.241158649518, 5844.211031510692, 5847.532197424111, 5850.297964244318, 5806.796762113306, 5847.0, 5908.852278693067, 5912.408759124225, 5947.197128387786, 5844.590878638454, 5848.444397782204, 5851.063829787384, 5872.354041055566, 5885.588008839341], "value": 5568.239908541581, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [591.9467247947683, 643.7489379142133, 669.1902797614886, 682.4915437998692, 687.7420967137324, 33862.15344616386, 37441.0, 46823.29765053523, 47583.28625070627, 47912.52087479124, 49488.75255623726, 49857.0, 49967.75016124914, 50006.49993500064, 50025.24962125571, 8555.786105347353, 9236.630534778618, 9343.299252556086, 9401.05989401058, 9416.858747118798, 8401.15988401158, 8661.826763464727, 8662.220400163984, 10956.12351011921, 11229.32624042556, 11423.086153107773, 11483.770324593503, 11493.942530287337, 11498.42507874603, 11532.827007594893, 11268.154888383364, 11339.546418143264, 11341.26281791681, 11341.886581134248, 11346.48940797662, 11224.158188135842, 11265.15511336653, 11271.830922536168, 11287.717807054807, 11294.435278236055, 46128.76935615334, 46380.30429543526, 46463.53536464626, 46510.53489465096, 46566.0, 3829.253295607358, 3836.4245363195464, 3843.1545060086737, 3895.3183192941124, 3899.2396482685886, 35424.0, 35632.64367356345, 35700.464493032625, 36115.638843611494, 37554.43668344977, 4367.126574685076, 4376.518582955872, 4664.502002563663, 4683.672142949995, 4749.715017098951, 397397.0, 399222.0, 401208.0, 401923.0, 402015.0, 66671.66664166746, 67499.63751268682, 67638.3236167632, 67706.66146669333, 67792.0, 39739.60260397417, 40161.799191003855, 40226.59773402228, 41811.163776724294, 41938.0, 16765.24556394965, 16873.32506699743, 16910.40813571519, 16913.40803071911, 16915.746263806053, 13883.791743123862, 14017.299135043208, 14020.369083391271, 14028.929855350658, 14035.78946315806, 18563.535911602048, 19052.14265358062, 20244.493887652927, 20984.685229721566, 21046.684299735516, 7510.812229694301, 7648.541087534766, 7674.783425425423, 7734.1492435832015, 7807.375409967189, 1029.8764148302253, 1142.3378983578873, 1149.408054851752, 1151.7293436042523, 1172.454808514045, 35253.64746352555, 35903.58579952045, 37510.62489375071, 38319.42520862189, 38868.22263554713, 1131.1968502363413, 1188.2157775868059, 1232.7472868062025, 1246.364354179357, 1294.779887419141, 18184.63630727378, 18279.45161645126, 18351.44945651606, 18363.0, 18474.81525184758, 651716.0, 654349.0, 657299.0, 660268.0, 660332.0, 65783.67108164428, 66379.33620663828, 66393.33606663968, 66495.33504664892, 66525.0, 1332.8733770291806, 1456.1336005076962, 1459.7080583883267, 1466.8093147890706, 1492.604459818149, 33226.833865830515, 34963.65036349604, 35229.647703522634, 35294.117647058, 35654.0, 56652.716736416056, 57315.42684573101, 57377.713111434176, 57476.71261643665, 57487.42512574821, 462604.0, 476062.0, 478243.0, 490836.0, 879617.0, 30867.228319292197, 31680.841595791873, 31761.0, 32558.186045349055, 32580.348393031534, 52748.73625631847, 52943.47056529386, 52962.47037529729, 52972.47027529676, 53264.73367633137, 43870.29686140781, 45393.0, 45985.540144598126, 46288.0, 47140.0, 45031.77484112558, 45328.0, 45779.31331029971, 45785.313220302385, 46441.30338044865, 43716.781416092716, 44129.11741765211, 44342.556574433846, 45081.0, 45107.54892451034, 38101.0, 39520.01199969909, 39638.20723585454, 40293.79853100716, 40621.593784061784, 29110.27224319409, 29834.0, 30182.84908575443, 30298.545521817632, 30305.69694303029], "value": 22124.469201799864, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [428.52861851962865, 430.91051782380435, 432.50694208602187, 435.37958409266787, 435.7037214694009, 20600.278990235343, 20693.793062069395, 21352.786472135274, 21487.0, 21606.351809445703, 19817.306394276202, 20757.688634670492, 20799.584008319864, 20853.895730521326, 20893.686594701092, 5846.0, 6106.511479081685, 6107.847303817418, 6118.541109416789, 6120.26556813183, 5863.296404431453, 6003.069524223731, 6016.4885984691355, 6040.063790112517, 6048.516118710492, 6015.75936962521, 6016.789412370568, 6024.638521688691, 6025.728842202089, 6026.96986515069, 5835.620684655505, 5909.586328956974, 5962.672053037081, 5978.133170690247, 5979.641221526722, 5293.523582877521, 5355.598330125217, 5371.731413429313, 5377.354717433933, 5387.78448862045, 24383.878080609662, 24478.265652030466, 24627.261182164562, 24706.752932470627, 24752.876235618885, 2511.510255500178, 3192.7605429592872, 3217.099212220582, 3256.625488068879, 3338.165458635341, 14801.259937003108, 15255.0, 15271.160086195367, 15501.612459688598, 15957.44148954781, 2040.6530889748778, 2055.126571207246, 2068.88621125838, 2096.6959790830397, 2121.363590922717, 55636.721816391066, 69103.30896691019, 76016.0, 88749.0, 90410.54794726051, 11301.434928253555, 11329.09367250618, 11339.149563782585, 11385.886141138482, 11449.885501144881, 15926.681466370608, 16040.67918641644, 16047.277872495766, 16056.91971540135, 16078.43725469603, 8153.817696433984, 8222.342212622976, 8346.749597511964, 8367.778405313877, 8374.204450577185, 7670.309672129535, 7799.53202807822, 7850.8429831403055, 7954.164812694699, 7979.241972012649, 6935.237123916365, 6954.930450695429, 6962.582245065312, 7011.544249623758, 7034.648267586703, 2038.8063134002246, 2067.3074520035957, 2071.7721050684413, 2075.2529089527757, 2099.370188943311, 846.9110743371981, 940.3558562384789, 945.4421891084294, 1037.1288117980932, 1087.7770057138268, 19284.517887052934, 20258.79741202569, 20504.589908201753, 21489.0, 21507.56984860294, 1031.7884833609091, 1069.9144068474504, 1089.727568107973, 1095.446799366315, 1130.5901262459095, 10834.566617335377, 11508.712282193012, 11554.0, 11676.591319303783, 11703.88296117045, 444208.11583768146, 444645.0, 448850.0, 596260.0, 666691.0, 102697.0, 108679.0, 108973.45513272383, 109480.4525977381, 110428.0, 1078.9028987391173, 1094.1247002398209, 1108.661858133261, 1129.768397478515, 1129.8757136714955, 18108.185131668844, 18366.357177498725, 18597.0, 18627.0, 18782.71825922585, 45088.77455612832, 45173.77413113045, 45818.541814582764, 46004.309935351666, 47415.28877066778, 62215.688921555105, 64397.67801160964, 64905.35094648993, 65046.0, 66502.33497664961, 8415.747527574182, 8450.408471406943, 8558.486490810572, 8874.866876996979, 8936.151065648754, 12628.368581571069, 13573.932130339284, 13683.24742139192, 13819.101758385672, 13859.514916977858, 11360.204785664924, 11505.309681419141, 11788.764224715285, 11809.409529523318, 12163.513459461523, 11371.772564548828, 11678.941605291919, 11897.762044759229, 12114.394280285775, 12295.50817967307, 11550.306981581132, 11612.76774464489, 11761.353125578175, 12243.0, 12504.749905001667, 8424.957875210584, 8509.659613615386, 8564.400491965502, 8657.82684346297, 8815.0, 11234.0, 11256.549738010388, 11299.604513841976, 11583.131265155316, 11684.18210725241], "value": 9891.617250064499, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1532.226225755993, 1565.4207943061067, 1712.4263371770455, 1814.328698381599, 1834.119622581161, 9122.407043542173, 9866.654667086654, 9880.456574888378, 9889.456079915612, 9889.703308900736, 8411.747647570568, 8595.742127736163, 8621.482711037343, 8779.736607901774, 9597.712068637935, 2430.2952143878256, 2505.624156376549, 2508.234988328559, 2510.57320255557, 2516.3709072731817, 2489.7385774493687, 2514.6102354135137, 2514.7233804281514, 2517.6601158843505, 2518.874056297187, 2486.11742831295, 2486.3038349262238, 2490.663760392351, 2561.398071453207, 2566.2301309607137, 2454.803615710739, 2493.7381574934643, 2499.6750422445148, 2500.9374765630823, 2509.8956459158094, 2475.3192871960173, 2478.7893029092547, 2496.7752902238885, 2499.2252401755513, 2499.512595043967, 9664.03359664028, 9670.516474176333, 9762.463064531448, 9792.21662267017, 9793.755156121082, 5547.667139971574, 5684.062129748587, 5685.033544297438, 5686.744096515667, 5691.430856914317, 3640.4357324614725, 3685.0418891088243, 3745.887623371303, 3787.0532366908274, 3789.7915614641183, 1202.7774861650623, 1224.7550489902055, 1226.7546490701895, 1263.204181365743, 1265.33569875815, 13769.31153442334, 13788.655283617889, 13827.79258311126, 13835.51575694856, 13861.514846980308, 4905.3623029006, 5019.7239151847, 5218.147557617067, 5231.0, 5257.579393648499, 11633.243839150427, 11641.708957276136, 11642.476088576035, 11645.6506304811, 11649.184557081093, 5878.970605146947, 5906.793262235801, 5953.374895635896, 5955.434233747787, 5958.791442299499, 4357.085012147484, 5284.128118860422, 5301.204819277139, 5363.651362661492, 5374.892502149935, 4418.536053714313, 4438.378626992224, 4451.643868490513, 4489.1021795641, 4516.81932722694, 1326.529082175833, 1361.2785223831188, 1369.075873785191, 1384.1072508232335, 1387.5975966969668, 1005.8290090684601, 1022.1107636356418, 1036.4558606731525, 1046.40877903984, 1049.5486940615456, 6207.937920620826, 6367.204099487564, 6368.681565921685, 6386.584871983399, 6654.567453115629, 498.70077953227775, 500.88552969642257, 505.02782144371935, 510.1837060702846, 510.8441925212846, 4367.956320436819, 4422.447194100738, 4474.910501789946, 4649.442066951988, 4700.177468942921, 11581.710457238636, 12128.636140915787, 12130.87869121315, 12143.392830358624, 12154.87845121555, 11451.141164412702, 11858.347790871412, 11859.46632401544, 12073.0, 12076.09429292789, 8959.10408959112, 9151.908480915106, 9168.81662366749, 9191.77020574491, 9192.264618830477, 6576.605403675795, 6872.6907289173105, 6878.533322716768, 6910.44716422695, 6921.850557995999, 11048.337099774039, 11201.66395008151, 11217.831732523855, 11336.263142895685, 11428.942855286003, 10935.398553079656, 11272.718182045515, 11567.88432115668, 11606.767864642828, 11675.766484670428, 7977.361811055218, 8484.236418722345, 8660.307175426076, 8814.823703525766, 8944.910550894409, 9516.666916657883, 9611.471369074769, 9614.278929080207, 9616.567254473426, 9624.0, 8520.914790852261, 8589.098144694717, 8638.78403039929, 8718.17177368149, 8935.285177185939, 8189.467684600482, 8308.459950103224, 8319.708810191856, 8364.0, 8577.95711021441, 7686.884696729442, 7871.645775940211, 7930.722424715107, 8108.189181081844, 8108.2297181767635, 5706.8573285666525, 5759.596828222152, 5811.680357580373, 5899.351071382059, 5916.083007133901, 5862.648241105548, 5960.910586341295, 5972.850678733066, 6047.274327080779, 6121.785737499166], "value": 5011.113057144215, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [356.718192627824, 356.958949720782, 358.28343313373256, 364.266004001936, 368.4454895382449, 5535.5294799942, 5554.7222638868, 5561.944380556199, 5591.189277554762, 5646.576506761989, 5470.699111548864, 5479.643823151502, 5553.527950124234, 5564.8330550083465, 5586.441355864412, 4140.378943158527, 4299.3550967354995, 4337.63130133939, 4359.825606975717, 4372.540883207265, 4274.465691788527, 4301.924518870283, 4315.287977483711, 4319.222539942809, 4338.132373525292, 4261.957380426203, 4273.487181538204, 4277.679174061957, 4281.593248641374, 4287.356896465524, 4107.9935415823165, 4281.52903180653, 4299.216689313415, 4323.632491238217, 4334.978325108385, 3781.8676346327748, 3787.772733635991, 3790.223004284115, 3798.1454172810895, 3798.6581207691165, 5357.0, 5401.891962160774, 5427.43011983744, 5436.906790885498, 5462.6995515246645, 4835.153848076572, 4922.384701912261, 4938.925916111261, 4949.901001979976, 4988.90022199554, 5719.656820590778, 5725.312962444492, 5727.742251598688, 5728.68492232927, 5728.828135155952, 4188.4136220929095, 4198.706090573661, 4201.2017716633745, 4216.85241016566, 4238.470191226097, 5940.97029514854, 5941.851453713649, 5969.0, 6008.36912124225, 6032.195029311348, 4256.893577660583, 4268.594483524123, 4287.399764033038, 4312.849050283225, 4317.41714868495, 4341.218580655449, 4362.520122786491, 4363.345498175299, 4382.780860957003, 4394.692371533962, 4780.235162374003, 4902.3872015998, 4977.278294647256, 4977.850664480072, 4997.325361076281, 5716.485516303553, 5748.425157484218, 5750.453706897838, 5760.971195144081, 5771.0378471319955, 1463.9707205855823, 1495.467146175179, 1501.7371959906973, 1502.3389708528211, 1506.5480355893187, 919.6827094652334, 949.7435692363023, 950.2872845365976, 961.1541843177959, 982.8107989332844, 4924.020967314795, 4953.678010929278, 4963.8014479421145, 4969.925451118236, 4970.826021089245, 5158.117287190149, 5197.8440646780655, 5211.296474975906, 5211.661242019257, 5220.59852467869, 4606.239970404913, 4864.902701945941, 4963.925541116886, 5004.299398084271, 5005.623453550303, 2746.6429364182527, 2768.806183567131, 2789.4978903797105, 2798.2930412448795, 2810.690824009357, 5326.121190003683, 5329.653572517774, 5335.679859208382, 5342.0885617338645, 5364.471125729148, 5282.286891269706, 5301.6818990860675, 5763.904858076952, 5771.797987070432, 6042.969785151134, 5967.850803729854, 6180.536459765535, 6182.907256391158, 6205.82089403012, 6211.378862113843, 5957.761689532544, 5959.493443057216, 5984.700764961822, 5994.520438365008, 5999.760009599741, 5593.216949626974, 5847.7252738464895, 5859.853503662272, 5917.201177840936, 5917.349091600006, 6150.938490615036, 6151.200343955257, 6152.784652537319, 6158.414950579688, 6178.474829639531, 4097.09863829957, 4324.762138082436, 4347.739155644827, 4354.499232588293, 4370.36629688687, 5792.0, 5845.649261044351, 5878.2064421304285, 5887.293524777054, 5889.676067816311, 5761.251037365116, 5771.624844385102, 5776.537876969916, 5779.162021506942, 5788.247527821524, 5643.35101463337, 5725.2843394575675, 5753.1370294456165, 5769.884602307847, 5776.249087618583, 5691.975444420044, 5717.742701578356, 5721.8569535761935, 5733.283339582552, 5739.9139012914, 5564.221009058654, 5743.59794814359, 5757.683327416865, 5766.538676905921, 5771.163181338767, 5597.580181486323, 5630.3537337451335, 5638.154276858504, 5643.52030077448, 5654.971725141348], "value": 4340.0431689072675, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [863.6631713631684, 886.769439945614, 895.8611415230639, 922.1516205091317, 942.4345392764346, 17028.574285642848, 18480.722789158175, 18502.907485462554, 18757.437276881716, 18917.527061823443, 16347.83652163478, 16993.66012679746, 17085.31658733652, 17216.56958576036, 17230.483085507454, 891.652255620307, 904.3624244907357, 904.5929331800683, 905.0316161706976, 907.9591418386188, 750.9774706758806, 884.1512148337604, 910.6767097680328, 917.2432742987029, 919.7056941778634, 957.8228027814877, 963.6049219819876, 964.9964037401095, 967.0522887570186, 968.2302569457298, 960.6685693435753, 961.6441916490908, 961.8653388525612, 962.0523784072683, 962.3311798300184, 706.4983861458371, 706.9222385537587, 708.1572928215408, 708.88303429934, 709.0675761373777, 16289.91855040729, 16301.836981630151, 16305.673886522321, 16339.83660163407, 16349.754753678704, 14457.277136143255, 14796.40814367424, 14919.0, 15156.1664108474, 15173.696526069416, 11488.195826292163, 11499.0, 11717.179797414185, 13705.725885482334, 14966.102033877893, 3948.2498325318097, 3983.3029219886403, 3989.860354887565, 4110.424540564323, 4173.5409104998425, 86180.56909715412, 86247.0, 87274.0, 88014.0, 88494.5575272126, 11160.720981975353, 11700.824487632692, 12516.74966500665, 12518.81221781674, 12771.361431928366, 23630.527389452458, 23637.88181059118, 23644.527109457715, 23736.0, 23957.760422395553, 8375.706850260334, 8402.41183117176, 8782.956085219661, 9033.77415564616, 9048.321375896834, 4591.471980722193, 4977.122977701122, 5162.819301324435, 5259.21111833228, 5370.70461124634, 3818.8281527331337, 3929.1159489115294, 3933.3116704576582, 3935.8819235422984, 3936.1537269486626, 594.7353427724621, 673.7069374821968, 682.1200651159949, 683.1563019670803, 683.361057411322, 751.0349201276333, 790.715342476708, 832.1304237072153, 842.1830824100736, 867.8090820019586, 11082.889171108347, 11094.0, 11451.312921224753, 11701.0, 12032.699182520508, 663.9468842492589, 739.6043116932416, 820.7578764264556, 841.5161282262724, 867.2931560777946, 5057.797688092435, 5366.6779993200535, 5541.27963364768, 5623.971880140573, 5659.197808958631, 69109.65445172742, 69802.65098674475, 69919.65040174869, 95819.0, 131369.0, 69905.65047174731, 87473.56263218643, 106564.46717766517, 111169.44415277871, 111524.0, 95619.0, 98082.0, 98419.0, 99939.0, 100894.0, 10410.27128101056, 10805.0, 10928.945355273172, 11113.499892504695, 11130.10959123252, 58111.418885812294, 58167.0, 58581.70709146427, 59907.0, 60703.0, 83958.58020709857, 85605.57197213975, 86809.56595216984, 87754.0, 90281.54859225925, 13982.230977296347, 14286.42854285817, 14407.78388324197, 14699.779503307245, 14976.0, 15409.84590154084, 16189.67620647604, 16399.098049607386, 16640.084795336374, 16723.74914376261, 14829.184394857955, 14910.627234319229, 15200.16399098016, 15736.527904162893, 15973.920130399274, 15522.456714014956, 15702.293396796944, 15904.363825446853, 15972.920135399714, 16005.119718415599, 13847.930760346133, 14002.859971400156, 15389.461368852037, 15772.211389430711, 15995.200239988188, 12381.62855114348, 12578.622641320775, 12620.873791261969, 12643.178193417398, 13426.597202083953, 12300.200486968677, 12572.811407829073, 12832.101752877208, 12964.5462408819, 13094.607161785161], "value": 8328.261777878974, "unit": "score"}], "name": "dromaeo_css", "value": 7774.055829468117, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 110.0, 119.0, 109.0, 109.0, 109.0, 110.0, 109.0, 110.0, 109.0], "value": 110.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 114.0, 136.0, 164.0, 117.0, 148.0, 155.0, 116.0, 164.0, 116.0], "value": 137.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 161.0, 159.0, 161.0, 161.0, 161.0, 163.0, 162.0, 160.0], "value": 160.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 177.0, 76.0, 75.0, 74.0, 74.0, 169.0, 75.0], "value": 94.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 84.0, 159.0, 82.0, 82.0, 85.0, 142.0, 83.0, 82.0, 133.0], "value": 101.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 118.0, 118.0, 117.0, 117.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 145.0, 145.0, 146.0, 146.0, 146.0, 145.0, 146.0, 146.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 96.0, 94.0, 95.0, 95.0, 95.0, 94.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 51.0, 52.0, 51.0, 51.0, 52.0, 51.0, 50.0, 52.0, 54.0], "value": 51.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 59.0, 61.0, 59.0, 59.0, 59.0, 59.0, 60.0, 61.0], "value": 59.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 78.0, 73.0, 72.0, 76.0, 71.0, 72.0, 72.0, 72.0, 73.0], "value": 73.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 117.0, 123.0, 123.0, 117.0, 115.0, 126.0, 115.0, 154.0, 116.0], "value": 122.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 150.0, 150.0, 153.0, 147.0, 148.0, 158.0, 150.0, 151.0, 148.0], "value": 150.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 60.0, 64.0, 62.0, 61.0, 62.0, 60.0, 63.0, 61.0, 62.0], "value": 61.7, "unit": "score"}], "name": "kraken", "value": 1481.6, "alertThreshold": 2.0}]} 23:56:01 INFO - Return code: 0 23:56:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:56:01 INFO - # TBPL SUCCESS # 23:56:01 INFO - Running post-action listener: _resource_record_post_action 23:56:01 INFO - [mozharness: 2016-09-22 06:56:01.320937Z] Finished run-tests step (success) 23:56:01 INFO - Running post-run listener: _resource_record_post_run 23:56:01 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 8192; Write bytes: 290226176; Read time: 40; Write time: 173928 23:56:01 INFO - TinderboxPrint: CPU usage
12.9% 23:56:01 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 40 23:56:01 INFO - TinderboxPrint: I/O write bytes / time
290,226,176 / 173,928 23:56:01 INFO - TinderboxPrint: CPU idle
8,251.0 (87.1%) 23:56:01 INFO - TinderboxPrint: CPU user
1,181.3 (12.5%) 23:56:01 INFO - TinderboxPrint: Swap in / out
0 / 0 23:56:01 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 71331840; Read time: 0; Write time: 35676 23:56:01 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 8192; Write bytes: 218894336; Read time: 40; Write time: 138252 23:56:01 INFO - Running post-run listener: _upload_blobber_files 23:56:01 INFO - Blob upload gear active. 23:56:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:56:01 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:56:01 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-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:56:01 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-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:56:02 INFO - (blobuploader) - INFO - Open directory for files ... 23:56:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:56:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:56:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:56:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:56:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:56:03 INFO - (blobuploader) - INFO - Done attempting. 23:56:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:56:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:56:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:56:06 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:56:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:56:06 INFO - (blobuploader) - INFO - Done attempting. 23:56:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:56:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:56:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:56:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:56:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:56:07 INFO - (blobuploader) - INFO - Done attempting. 23:56:07 INFO - (blobuploader) - INFO - Iteration through files over. 23:56:07 INFO - Return code: 0 23:56:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:56:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:56:07 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6f64417fb6d7895ebe5ce1908b6ea113a2cbea608c0044e4cd0499fe45b2bc43870ac9fe8d14769109e2d69f13751141a4b4dcca80d0e08ad2e555f6553bbae8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3bdb27e876ed8fc1405233993fbdacfbdc2bca3634b3ef6b7a2d37ba8fa8f44877e02277409067a5a82bc6e2b05456c7a13494f14670041fb0354db194a08783", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8a07b0cc60e3ab4b756efa69eccc43b93f8d677f8c8529dfda58854659d83de277e6370af7dc112fb7e6a7df308e59aa18075648d409591531a9dc8d671a404a"} 23:56:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:56:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:56:07 INFO - Contents: 23:56:07 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6f64417fb6d7895ebe5ce1908b6ea113a2cbea608c0044e4cd0499fe45b2bc43870ac9fe8d14769109e2d69f13751141a4b4dcca80d0e08ad2e555f6553bbae8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3bdb27e876ed8fc1405233993fbdacfbdc2bca3634b3ef6b7a2d37ba8fa8f44877e02277409067a5a82bc6e2b05456c7a13494f14670041fb0354db194a08783", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8a07b0cc60e3ab4b756efa69eccc43b93f8d677f8c8529dfda58854659d83de277e6370af7dc112fb7e6a7df308e59aa18075648d409591531a9dc8d671a404a"} 23:56:07 INFO - Running post-run listener: copy_logs_to_upload_dir 23:56:07 INFO - Copying logs to upload dir... 23:56:07 INFO - mkdir: /builds/slave/test/build/upload/logs 23:56:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1676.340654 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 56 secs) (at 2016-09-21 23:56:07.571316) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-21 23:56:07.575118) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1474524331.565006-1876900845 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-Beta/sha512/6f64417fb6d7895ebe5ce1908b6ea113a2cbea608c0044e4cd0499fe45b2bc43870ac9fe8d14769109e2d69f13751141a4b4dcca80d0e08ad2e555f6553bbae8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3bdb27e876ed8fc1405233993fbdacfbdc2bca3634b3ef6b7a2d37ba8fa8f44877e02277409067a5a82bc6e2b05456c7a13494f14670041fb0354db194a08783", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8a07b0cc60e3ab4b756efa69eccc43b93f8d677f8c8529dfda58854659d83de277e6370af7dc112fb7e6a7df308e59aa18075648d409591531a9dc8d671a404a"} build_url:https://queue.taskcluster.net/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011286 build_url: 'https://queue.taskcluster.net/v1/task/f3DI3Z3VShSQAvb9seMTsA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6f64417fb6d7895ebe5ce1908b6ea113a2cbea608c0044e4cd0499fe45b2bc43870ac9fe8d14769109e2d69f13751141a4b4dcca80d0e08ad2e555f6553bbae8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3bdb27e876ed8fc1405233993fbdacfbdc2bca3634b3ef6b7a2d37ba8fa8f44877e02277409067a5a82bc6e2b05456c7a13494f14670041fb0354db194a08783", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8a07b0cc60e3ab4b756efa69eccc43b93f8d677f8c8529dfda58854659d83de277e6370af7dc112fb7e6a7df308e59aa18075648d409591531a9dc8d671a404a"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-21 23:56:07.599947) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 23:56:07.600282) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1474524331.565006-1876900845 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004468 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 23:56:07.684390) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-21 23:56:07.684701) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-21 23:56:07.684969) ========= ========= Total master_lag: 0.17 =========