builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-012 starttime: 1474121986.76 results: success (0) buildid: 20160917004009 builduid: 39c0864104644d618a468a713b19f1a0 revision: b9499912b32324acd84792bf02b533b40e98e496 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-17 07:19:46.762978) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-17 07:19:46.763484) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-17 07:19:46.763814) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-17 07:19:46.780650) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:19:46.780990) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1474121892.991980-143782126 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-17 07:19:48-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 37.9M=0s 2016-09-17 07:19:48 (37.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.133752 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:19:46.930065) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:19:46.930382) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1474121892.991980-143782126 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020010 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:19:46.979572) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:19:46.979962) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev b9499912b32324acd84792bf02b533b40e98e496 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev b9499912b32324acd84792bf02b533b40e98e496 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=2c5dd2b0a310ecf02fd87158000001b4-1474121892.991980-143782126 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-17 07:19:48,452 truncating revision to first 12 chars 2016-09-17 07:19:48,452 Setting DEBUG logging. 2016-09-17 07:19:48,452 attempt 1/10 2016-09-17 07:19:48,452 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/b9499912b323?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-17 07:19:48,766 unpacking tar archive at: mozilla-aurora-b9499912b323/testing/mozharness/ program finished with exit code 0 elapsedTime=0.753220 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:19:47.751403) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-17 07:19:47.751764) ========= script_repo_revision: b9499912b32324acd84792bf02b533b40e98e496 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-17 07:19:47.752189) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-17 07:19:47.752491) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-17 07:19:47.763598) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 57 secs) (at 2016-09-17 07:19:47.763924) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=2c5dd2b0a310ecf02fd87158000001b4-1474121892.991980-143782126 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:19:49 INFO - MultiFileLogger online at 20160917 07:19:49 in /builds/slave/test 07:19:49 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 07:19:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:19:49 INFO - {'append_to_log': False, 07:19:49 INFO - 'base_work_dir': '/builds/slave/test', 07:19:49 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 07:19:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:19:49 INFO - 'branch': 'Mozilla-Aurora', 07:19:49 INFO - 'buildbot_json_path': 'buildprops.json', 07:19:49 INFO - 'config_files': ('talos/linux_config.py',), 07:19:49 INFO - 'default_actions': ('clobber', 07:19:49 INFO - 'read-buildbot-config', 07:19:49 INFO - 'download-and-extract', 07:19:49 INFO - 'populate-webroot', 07:19:49 INFO - 'create-virtualenv', 07:19:49 INFO - 'install', 07:19:49 INFO - 'run-tests'), 07:19:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:19:49 INFO - 'download_minidump_stackwalk': True, 07:19:49 INFO - 'download_symbols': 'ondemand', 07:19:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:19:49 INFO - 'tooltool.py': '/tools/tooltool.py', 07:19:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:19:49 INFO - '/tools/misc-python/virtualenv.py')}, 07:19:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:19:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:19:49 INFO - 'installer_path': 'installer.exe', 07:19:49 INFO - 'log_level': 'info', 07:19:49 INFO - 'log_name': 'talos', 07:19:49 INFO - 'log_to_console': True, 07:19:49 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:19:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:19:49 INFO - 'opt_config_files': (), 07:19:49 INFO - 'pip_index': False, 07:19:49 INFO - 'sps_profile': False, 07:19:49 INFO - 'sps_profile_interval': 0, 07:19:49 INFO - 'suite': 'dromaeojs', 07:19:49 INFO - 'system_bits': '32', 07:19:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:19:49 INFO - 'title': 'talos-linux64-ix-012', 07:19:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:19:49 INFO - 'use_talos_json': True, 07:19:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:19:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:19:49 INFO - 'work_dir': 'build'} 07:19:49 INFO - [mozharness: 2016-09-17 14:19:49.330148Z] Running clobber step. 07:19:49 INFO - Running pre-action listener: _resource_record_pre_action 07:19:49 INFO - Running main action method: clobber 07:19:49 INFO - rmtree: /builds/slave/test/build 07:19:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:19:49 INFO - Running post-action listener: _resource_record_post_action 07:19:49 INFO - [mozharness: 2016-09-17 14:19:49.798871Z] Finished clobber step (success) 07:19:49 INFO - [mozharness: 2016-09-17 14:19:49.798970Z] Running read-buildbot-config step. 07:19:49 INFO - Running pre-action listener: _resource_record_pre_action 07:19:49 INFO - Running main action method: read_buildbot_config 07:19:49 INFO - Using buildbot properties: 07:19:49 INFO - { 07:19:49 INFO - "project": "", 07:19:49 INFO - "product": "firefox", 07:19:49 INFO - "script_repo_revision": "production", 07:19:49 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 07:19:49 INFO - "repository": "", 07:19:49 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 07:19:49 INFO - "buildid": "20160917004009", 07:19:49 INFO - "stage_platform": "linux64-pgo", 07:19:49 INFO - "basedir": "/builds/slave/test", 07:19:49 INFO - "nightly_build": "True", 07:19:49 INFO - "buildnumber": 523, 07:19:49 INFO - "slavename": "talos-linux64-ix-012", 07:19:49 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 07:19:49 INFO - "platform": "ubuntu64_hw", 07:19:49 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 07:19:49 INFO - "branch": "mozilla-aurora", 07:19:49 INFO - "repo_path": "releases/mozilla-aurora", 07:19:49 INFO - "revision": "b9499912b32324acd84792bf02b533b40e98e496", 07:19:49 INFO - "pgo_build": "True", 07:19:49 INFO - "builduid": "39c0864104644d618a468a713b19f1a0", 07:19:49 INFO - "slavebuilddir": "test" 07:19:49 INFO - } 07:19:49 INFO - Found installer url https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 07:19:49 INFO - Running post-action listener: _resource_record_post_action 07:19:49 INFO - [mozharness: 2016-09-17 14:19:49.801503Z] Finished read-buildbot-config step (success) 07:19:49 INFO - [mozharness: 2016-09-17 14:19:49.801592Z] Running download-and-extract step. 07:19:49 INFO - Running pre-action listener: _resource_record_pre_action 07:19:49 INFO - Running main action method: download_and_extract 07:19:49 INFO - mkdir: /builds/slave/test/build/tests 07:19:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:19:49 INFO - https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 07:19:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 07:19:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 07:19:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 07:19:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 07:19:49 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 07:19:49 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 07:19:49 INFO - retry: Failed, sleeping 30 seconds before retrying 07:20:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #2 07:20:19 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 07:20:19 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 07:20:19 INFO - retry: Failed, sleeping 60 seconds before retrying 07:21:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #3 07:21:19 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 07:21:19 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 07:21:19 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json! 07:21:19 INFO - Caught exception: HTTP Error 404: Not Found 07:21:19 INFO - Caught exception: HTTP Error 404: Not Found 07:21:19 INFO - Caught exception: HTTP Error 404: Not Found 07:21:19 INFO - trying https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 07:21:19 INFO - Downloading https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 07:21:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 07:21:21 INFO - Downloaded 1361 bytes. 07:21:21 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 07:21:21 INFO - Using the following test package requirements: 07:21:21 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 07:21:21 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 07:21:21 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 07:21:21 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 07:21:21 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 07:21:21 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 07:21:21 INFO - u'jsshell-linux-x86_64.zip'], 07:21:21 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 07:21:21 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 07:21:21 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 07:21:21 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 07:21:21 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 07:21:21 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 07:21:21 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 07:21:21 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 07:21:21 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 07:21:21 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 07:21:21 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 07:21:21 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 07:21:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:21:21 INFO - https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 07:21:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:21:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:21:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:21:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:21:21 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:21:21 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 07:21:21 INFO - retry: Failed, sleeping 30 seconds before retrying 07:21:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 07:21:51 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:21:51 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 07:21:51 INFO - retry: Failed, sleeping 60 seconds before retrying 07:22:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 07:22:51 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:22:51 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 07:22:51 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 07:22:51 INFO - Caught exception: HTTP Error 404: Not Found 07:22:51 INFO - Caught exception: HTTP Error 404: Not Found 07:22:51 INFO - Caught exception: HTTP Error 404: Not Found 07:22:51 INFO - trying https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:22:51 INFO - Downloading https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:22:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:22:52 INFO - Downloaded 21771059 bytes. 07:22:52 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:22:52 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 07:22:53 INFO - Return code: 0 07:22:53 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 07:22:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:22:53 INFO - https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 07:22:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:22:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:22:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:22:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:22:53 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:22:53 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 07:22:53 INFO - retry: Failed, sleeping 30 seconds before retrying 07:23:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 07:23:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:23:23 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 07:23:23 INFO - retry: Failed, sleeping 60 seconds before retrying 07:24:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 07:24:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:24:23 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 07:24:23 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 07:24:23 INFO - Caught exception: HTTP Error 404: Not Found 07:24:23 INFO - Caught exception: HTTP Error 404: Not Found 07:24:23 INFO - Caught exception: HTTP Error 404: Not Found 07:24:23 INFO - trying https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:24:23 INFO - Downloading https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:24:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:24:24 INFO - Downloaded 21771059 bytes. 07:24:24 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:24:24 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 07:24:26 INFO - Return code: 0 07:24:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:24:26 INFO - https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 07:24:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 07:24:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 07:24:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 07:24:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 07:24:26 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 07:24:26 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 07:24:26 INFO - retry: Failed, sleeping 30 seconds before retrying 07:24:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 07:24:56 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 07:24:56 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 07:24:56 INFO - retry: Failed, sleeping 60 seconds before retrying 07:25:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 07:25:56 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 07:25:56 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 07:25:56 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip! 07:25:56 INFO - Caught exception: HTTP Error 404: Not Found 07:25:56 INFO - Caught exception: HTTP Error 404: Not Found 07:25:56 INFO - Caught exception: HTTP Error 404: Not Found 07:25:56 INFO - trying https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 07:25:56 INFO - Downloading https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 07:25:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 07:25:57 INFO - Downloaded 14413740 bytes. 07:25:57 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:25:57 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 07:25:57 INFO - Return code: 0 07:25:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:25:57 INFO - https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 07:25:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:25:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:25:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:25:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 07:25:57 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:25:57 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 07:25:57 INFO - retry: Failed, sleeping 30 seconds before retrying 07:26:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #2 07:26:27 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:26:27 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 07:26:27 INFO - retry: Failed, sleeping 60 seconds before retrying 07:27:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #3 07:27:27 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:27:27 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 07:27:27 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2! 07:27:27 INFO - Caught exception: HTTP Error 404: Not Found 07:27:27 INFO - Caught exception: HTTP Error 404: Not Found 07:27:27 INFO - Caught exception: HTTP Error 404: Not Found 07:27:27 INFO - trying https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:27:27 INFO - Downloading https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:27:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 07:27:28 INFO - Downloaded 63891050 bytes. 07:27:28 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:27:28 INFO - mkdir: /builds/slave/test/properties 07:27:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:27:28 INFO - Writing to file /builds/slave/test/properties/build_url 07:27:28 INFO - Contents: 07:27:28 INFO - build_url:https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:27:29 INFO - Running post-action listener: _resource_record_post_action 07:27:29 INFO - Running post-action listener: set_extra_try_arguments 07:27:29 INFO - [mozharness: 2016-09-17 14:27:29.248417Z] Finished download-and-extract step (success) 07:27:29 INFO - [mozharness: 2016-09-17 14:27:29.248651Z] Running populate-webroot step. 07:27:29 INFO - Running pre-action listener: _resource_record_pre_action 07:27:29 INFO - Running main action method: populate_webroot 07:27:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:27:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:27:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:27:29 INFO - '--tppagecycles', 07:27:29 INFO - '7'], 07:27:29 INFO - 'tests': ['tsvgm']}}, 07:27:29 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:27:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:27:29 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:27:29 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:27:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:27:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:27:29 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:27:29 INFO - 'talos_options': ['--disable-e10s'], 07:27:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:27:29 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:27:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:27:29 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:27:29 INFO - 'talos_options': ['--disable-e10s'], 07:27:29 INFO - 'tests': ['damp', 'tps']}, 07:27:29 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:27:29 INFO - 'tests': ['damp', 'tps']}, 07:27:29 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:27:29 INFO - 'tests': ['dromaeo_dom']}, 07:27:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:27:29 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:27:29 INFO - 'tests': ['basic_compositor_video']}, 07:27:29 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:27:29 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:27:29 INFO - 'tests': ['a11yr', 07:27:29 INFO - 'ts_paint', 07:27:29 INFO - 'tpaint', 07:27:29 INFO - 'sessionrestore', 07:27:29 INFO - 'sessionrestore_no_auto_restore', 07:27:29 INFO - 'tabpaint']}, 07:27:29 INFO - 'other-e10s': {'tests': ['a11yr', 07:27:29 INFO - 'ts_paint', 07:27:29 INFO - 'tpaint', 07:27:29 INFO - 'sessionrestore', 07:27:29 INFO - 'sessionrestore_no_auto_restore', 07:27:29 INFO - 'tabpaint']}, 07:27:29 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:27:29 INFO - 'ts_paint', 07:27:29 INFO - 'tpaint', 07:27:29 INFO - 'sessionrestore', 07:27:29 INFO - 'sessionrestore_no_auto_restore', 07:27:29 INFO - 'tabpaint']}, 07:27:29 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:27:29 INFO - 'ts_paint', 07:27:29 INFO - 'tpaint', 07:27:29 INFO - 'sessionrestore', 07:27:29 INFO - 'sessionrestore_no_auto_restore', 07:27:29 INFO - 'tabpaint']}, 07:27:29 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:27:29 INFO - 'tests': ['a11yr', 07:27:29 INFO - 'ts_paint', 07:27:29 INFO - 'tpaint', 07:27:29 INFO - 'sessionrestore', 07:27:29 INFO - 'sessionrestore_no_auto_restore', 07:27:29 INFO - 'tabpaint']}, 07:27:29 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:27:29 INFO - 'tests': ['a11yr', 07:27:29 INFO - 'ts_paint', 07:27:29 INFO - 'tpaint', 07:27:29 INFO - 'sessionrestore', 07:27:29 INFO - 'sessionrestore_no_auto_restore', 07:27:29 INFO - 'tabpaint']}, 07:27:29 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:27:29 INFO - 'tests': ['tsvgx', 07:27:29 INFO - 'tsvgr_opacity', 07:27:29 INFO - 'tart', 07:27:29 INFO - 'tscrollx', 07:27:29 INFO - 'cart']}, 07:27:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:27:29 INFO - 'tsvgr_opacity', 07:27:29 INFO - 'tart', 07:27:29 INFO - 'tscrollx', 07:27:29 INFO - 'cart']}, 07:27:29 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:27:29 INFO - 'talos_options': ['--disable-e10s'], 07:27:29 INFO - 'tests': ['tp5o']}, 07:27:29 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:27:29 INFO - 'tests': ['tp5o']}, 07:27:29 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:27:29 INFO - 'talos_options': ['--disable-e10s', 07:27:29 INFO - '--xperf_path', 07:27:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:27:29 INFO - 'tests': ['tp5n']}, 07:27:29 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:27:29 INFO - 'talos_options': ['--xperf_path', 07:27:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:27:29 INFO - 'tests': ['tp5n']}}, 07:27:29 INFO - 'talos.zip': {'path': '', 07:27:29 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:27:29 INFO - Running post-action listener: _resource_record_post_action 07:27:29 INFO - [mozharness: 2016-09-17 14:27:29.264713Z] Finished populate-webroot step (success) 07:27:29 INFO - [mozharness: 2016-09-17 14:27:29.264821Z] Running create-virtualenv step. 07:27:29 INFO - Running pre-action listener: _resource_record_pre_action 07:27:29 INFO - Running main action method: create_virtualenv 07:27:29 INFO - Creating virtualenv /builds/slave/test/build/venv 07:27:29 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 07:27:29 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:27:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:27:29 INFO - Using real prefix '/usr' 07:27:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:27:29 INFO - Installing distribute.............................................................................................................................................................................................done. 07:27:31 INFO - Installing pip.................done. 07:27:31 INFO - Return code: 0 07:27:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:27:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:27:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:27:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:27:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:27:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:31 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 0x2a0a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b6d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ccb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cc93b0>, '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 0x2cca2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cca740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1474121892.991980-143782126', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:27:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:27:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:27:31 INFO - Using env: {'DISPLAY': ':0', 07:27:31 INFO - 'HOME': '/home/cltbld', 07:27:31 INFO - 'LANG': 'en_US.UTF-8', 07:27:31 INFO - 'LANGUAGE': 'en_US:en', 07:27:31 INFO - 'LOGNAME': 'cltbld', 07:27:31 INFO - 'MAIL': '/var/mail/cltbld', 07:27:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:27:31 INFO - 'MOZ_NO_REMOTE': '1', 07:27:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:27:31 INFO - 'NO_EM_RESTART': '1', 07:27:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:27:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:27:31 INFO - 'PWD': '/builds/slave/test', 07:27:31 INFO - 'SHELL': '/bin/bash', 07:27:31 INFO - 'SHLVL': '1', 07:27:31 INFO - 'TERM': 'linux', 07:27:31 INFO - 'TMOUT': '86400', 07:27:31 INFO - 'USER': 'cltbld', 07:27:31 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1474121892.991980-143782126', 07:27:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:27:31 INFO - '_': '/tools/buildbot/bin/python'} 07:27:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:27:32 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 07:27:32 INFO - Cleaning up... 07:27:32 INFO - Return code: 0 07:27:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:27:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:27:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:27:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:27:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:27:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:32 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 0x2a0a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b6d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ccb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cc93b0>, '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 0x2cca2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cca740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1474121892.991980-143782126', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:27:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:27:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:27:32 INFO - Using env: {'DISPLAY': ':0', 07:27:32 INFO - 'HOME': '/home/cltbld', 07:27:32 INFO - 'LANG': 'en_US.UTF-8', 07:27:32 INFO - 'LANGUAGE': 'en_US:en', 07:27:32 INFO - 'LOGNAME': 'cltbld', 07:27:32 INFO - 'MAIL': '/var/mail/cltbld', 07:27:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:27:32 INFO - 'MOZ_NO_REMOTE': '1', 07:27:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:27:32 INFO - 'NO_EM_RESTART': '1', 07:27:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:27:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:27:32 INFO - 'PWD': '/builds/slave/test', 07:27:32 INFO - 'SHELL': '/bin/bash', 07:27:32 INFO - 'SHLVL': '1', 07:27:32 INFO - 'TERM': 'linux', 07:27:32 INFO - 'TMOUT': '86400', 07:27:32 INFO - 'USER': 'cltbld', 07:27:32 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1474121892.991980-143782126', 07:27:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:27:32 INFO - '_': '/tools/buildbot/bin/python'} 07:27:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:27:32 INFO - Downloading/unpacking psutil>=3.1.1 07:27:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:27:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:27:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:27:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:27:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:27:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:27:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:27:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:27:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:27:34 INFO - Installing collected packages: psutil 07:27:34 INFO - Running setup.py install for psutil 07:27:34 INFO - building 'psutil._psutil_linux' extension 07:27:34 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 07:27:34 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 07:27:34 INFO - building 'psutil._psutil_posix' extension 07:27:34 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 07:27:34 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 07:27:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:27:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:27:34 INFO - Successfully installed psutil 07:27:34 INFO - Cleaning up... 07:27:34 INFO - Return code: 0 07:27:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:27:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:27:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:27:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:27:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:27:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a0a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b6d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ccb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cc93b0>, '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 0x2cca2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cca740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1474121892.991980-143782126', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:27:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:27:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:27:34 INFO - Using env: {'DISPLAY': ':0', 07:27:34 INFO - 'HOME': '/home/cltbld', 07:27:34 INFO - 'LANG': 'en_US.UTF-8', 07:27:34 INFO - 'LANGUAGE': 'en_US:en', 07:27:34 INFO - 'LOGNAME': 'cltbld', 07:27:34 INFO - 'MAIL': '/var/mail/cltbld', 07:27:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:27:34 INFO - 'MOZ_NO_REMOTE': '1', 07:27:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:27:34 INFO - 'NO_EM_RESTART': '1', 07:27:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:27:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:27:34 INFO - 'PWD': '/builds/slave/test', 07:27:34 INFO - 'SHELL': '/bin/bash', 07:27:34 INFO - 'SHLVL': '1', 07:27:34 INFO - 'TERM': 'linux', 07:27:34 INFO - 'TMOUT': '86400', 07:27:34 INFO - 'USER': 'cltbld', 07:27:34 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1474121892.991980-143782126', 07:27:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:27:34 INFO - '_': '/tools/buildbot/bin/python'} 07:27:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:27:35 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:27:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:27:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:27:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:27:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:27:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:27:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:27:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:27:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:27:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:27:37 INFO - Installing collected packages: mozsystemmonitor 07:27:37 INFO - Running setup.py install for mozsystemmonitor 07:27:37 INFO - Successfully installed mozsystemmonitor 07:27:37 INFO - Cleaning up... 07:27:37 INFO - Return code: 0 07:27:37 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:27:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:27:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:27:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:27:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:27:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x2a0a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b6d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ccb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cc93b0>, '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 0x2cca2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cca740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1474121892.991980-143782126', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:27:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:27:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:27:37 INFO - Using env: {'DISPLAY': ':0', 07:27:37 INFO - 'HOME': '/home/cltbld', 07:27:37 INFO - 'LANG': 'en_US.UTF-8', 07:27:37 INFO - 'LANGUAGE': 'en_US:en', 07:27:37 INFO - 'LOGNAME': 'cltbld', 07:27:37 INFO - 'MAIL': '/var/mail/cltbld', 07:27:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:27:37 INFO - 'MOZ_NO_REMOTE': '1', 07:27:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:27:37 INFO - 'NO_EM_RESTART': '1', 07:27:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:27:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:27:37 INFO - 'PWD': '/builds/slave/test', 07:27:37 INFO - 'SHELL': '/bin/bash', 07:27:37 INFO - 'SHLVL': '1', 07:27:37 INFO - 'TERM': 'linux', 07:27:37 INFO - 'TMOUT': '86400', 07:27:37 INFO - 'USER': 'cltbld', 07:27:37 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1474121892.991980-143782126', 07:27:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:27:37 INFO - '_': '/tools/buildbot/bin/python'} 07:27:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:27:37 INFO - Downloading/unpacking jsonschema==2.5.1 07:27:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:27:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:27:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:27:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:27:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:27:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:27:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:27:39 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:27:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:27:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:27:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:27:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:27:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:27:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:27:39 INFO - Downloading functools32-3.2.3-2.tar.gz 07:27:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:27:39 INFO - warning: no files found matching '*.txt' 07:27:39 INFO - no previously-included directories found matching 'build' 07:27:39 INFO - no previously-included directories found matching 'dist' 07:27:39 INFO - no previously-included directories found matching '.git*' 07:27:39 INFO - Installing collected packages: jsonschema, functools32 07:27:39 INFO - Running setup.py install for functools32 07:27:39 INFO - warning: no files found matching '*.txt' 07:27:39 INFO - no previously-included directories found matching 'build' 07:27:39 INFO - no previously-included directories found matching 'dist' 07:27:39 INFO - no previously-included directories found matching '.git*' 07:27:39 INFO - Successfully installed jsonschema functools32 07:27:39 INFO - Cleaning up... 07:27:39 INFO - Return code: 0 07:27:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:27:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:27:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:27:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:27:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:27:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x2a0a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b6d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ccb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cc93b0>, '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 0x2cca2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cca740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1474121892.991980-143782126', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:27:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:27:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 07:27:39 INFO - Using env: {'DISPLAY': ':0', 07:27:39 INFO - 'HOME': '/home/cltbld', 07:27:39 INFO - 'LANG': 'en_US.UTF-8', 07:27:39 INFO - 'LANGUAGE': 'en_US:en', 07:27:39 INFO - 'LOGNAME': 'cltbld', 07:27:39 INFO - 'MAIL': '/var/mail/cltbld', 07:27:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:27:39 INFO - 'MOZ_NO_REMOTE': '1', 07:27:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:27:39 INFO - 'NO_EM_RESTART': '1', 07:27:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:27:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:27:39 INFO - 'PWD': '/builds/slave/test', 07:27:39 INFO - 'SHELL': '/bin/bash', 07:27:39 INFO - 'SHLVL': '1', 07:27:39 INFO - 'TERM': 'linux', 07:27:39 INFO - 'TMOUT': '86400', 07:27:39 INFO - 'USER': 'cltbld', 07:27:39 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1474121892.991980-143782126', 07:27:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:27:39 INFO - '_': '/tools/buildbot/bin/python'} 07:27:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:27:39 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:27:39 INFO - Cleaning up... 07:27:39 INFO - Return code: 0 07:27:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:27:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:27:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:27:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:27:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:27:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x2a0a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b6d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ccb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cc93b0>, '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 0x2cca2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cca740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1474121892.991980-143782126', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:27:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:27:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:27:39 INFO - Using env: {'DISPLAY': ':0', 07:27:39 INFO - 'HOME': '/home/cltbld', 07:27:39 INFO - 'LANG': 'en_US.UTF-8', 07:27:39 INFO - 'LANGUAGE': 'en_US:en', 07:27:39 INFO - 'LOGNAME': 'cltbld', 07:27:39 INFO - 'MAIL': '/var/mail/cltbld', 07:27:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:27:39 INFO - 'MOZ_NO_REMOTE': '1', 07:27:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:27:39 INFO - 'NO_EM_RESTART': '1', 07:27:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:27:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:27:39 INFO - 'PWD': '/builds/slave/test', 07:27:39 INFO - 'SHELL': '/bin/bash', 07:27:39 INFO - 'SHLVL': '1', 07:27:39 INFO - 'TERM': 'linux', 07:27:39 INFO - 'TMOUT': '86400', 07:27:39 INFO - 'USER': 'cltbld', 07:27:39 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1474121892.991980-143782126', 07:27:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:27:39 INFO - '_': '/tools/buildbot/bin/python'} 07:27:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:27:40 INFO - Downloading/unpacking blobuploader==1.2.4 07:27:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:27:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:27:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:27:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:27:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:27:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:27:42 INFO - Downloading blobuploader-1.2.4.tar.gz 07:27:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:27:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:27:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:27:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:27:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:27:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:27:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:27:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:27:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:27:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:27:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:27:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:27:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:27:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:27:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:27:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:27:42 INFO - Downloading docopt-0.6.1.tar.gz 07:27:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:27:43 INFO - Installing collected packages: blobuploader, requests, docopt 07:27:43 INFO - Running setup.py install for blobuploader 07:27:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:27:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:27:43 INFO - Running setup.py install for requests 07:27:43 INFO - Running setup.py install for docopt 07:27:43 INFO - Successfully installed blobuploader requests docopt 07:27:43 INFO - Cleaning up... 07:27:43 INFO - Return code: 0 07:27:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:27: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')]} 07:27:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:27:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27: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')]} 07:27:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:27:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:43 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 0x2a0a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b6d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ccb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cc93b0>, '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 0x2cca2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cca740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1474121892.991980-143782126', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:27:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:27:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:27:43 INFO - Using env: {'DISPLAY': ':0', 07:27:43 INFO - 'HOME': '/home/cltbld', 07:27:43 INFO - 'LANG': 'en_US.UTF-8', 07:27:43 INFO - 'LANGUAGE': 'en_US:en', 07:27:43 INFO - 'LOGNAME': 'cltbld', 07:27:43 INFO - 'MAIL': '/var/mail/cltbld', 07:27:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:27:43 INFO - 'MOZ_NO_REMOTE': '1', 07:27:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:27:43 INFO - 'NO_EM_RESTART': '1', 07:27:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:27:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:27:43 INFO - 'PWD': '/builds/slave/test', 07:27:43 INFO - 'SHELL': '/bin/bash', 07:27:43 INFO - 'SHLVL': '1', 07:27:43 INFO - 'TERM': 'linux', 07:27:43 INFO - 'TMOUT': '86400', 07:27:43 INFO - 'USER': 'cltbld', 07:27:43 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1474121892.991980-143782126', 07:27:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:27:43 INFO - '_': '/tools/buildbot/bin/python'} 07:27:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:27:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:27:43 INFO - Running setup.py (path:/tmp/pip-0AtSow-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:27:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:27:43 INFO - Running setup.py (path:/tmp/pip-Nxhxts-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:27:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:27:44 INFO - Running setup.py (path:/tmp/pip-OJR2eR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:27:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:27:44 INFO - Running setup.py (path:/tmp/pip-4mJdh8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:27:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:27:44 INFO - Running setup.py (path:/tmp/pip-cSeSym-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:27:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:27:44 INFO - Running setup.py (path:/tmp/pip-RfYtkw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:27:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:27:44 INFO - Running setup.py (path:/tmp/pip-gjk61V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:27:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:27:44 INFO - Running setup.py (path:/tmp/pip-7kITZM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:27:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:27:44 INFO - Running setup.py (path:/tmp/pip-b7zlEf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:27:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:27:44 INFO - Running setup.py (path:/tmp/pip-bJKIKO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:27:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:27:44 INFO - Running setup.py (path:/tmp/pip-CROeIy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:27:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:27:44 INFO - Running setup.py (path:/tmp/pip-ugpsCy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:27:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:27:44 INFO - Running setup.py (path:/tmp/pip-jBtAmW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:27:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:27:44 INFO - Running setup.py (path:/tmp/pip-YyWYjT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:27:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:27:44 INFO - Running setup.py (path:/tmp/pip-fX2wux-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:27:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:27:44 INFO - Running setup.py (path:/tmp/pip-neOz4g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:27:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:27:45 INFO - Running setup.py (path:/tmp/pip-Twiygc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:27:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:27:45 INFO - Running setup.py install for manifestparser 07:27:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:27:45 INFO - Running setup.py install for mozcrash 07:27:45 INFO - Running setup.py install for mozdebug 07:27:45 INFO - Running setup.py install for mozdevice 07:27:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:27:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:27:45 INFO - Running setup.py install for mozfile 07:27:45 INFO - Running setup.py install for mozhttpd 07:27:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:27:45 INFO - Running setup.py install for mozinfo 07:27:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:27:45 INFO - Running setup.py install for mozInstall 07:27:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:27:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:27:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:27:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:27:45 INFO - Running setup.py install for mozleak 07:27:46 INFO - Running setup.py install for mozlog 07:27:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:27:46 INFO - Running setup.py install for moznetwork 07:27:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:27:46 INFO - Running setup.py install for mozprocess 07:27:46 INFO - Running setup.py install for mozprofile 07:27:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:27:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:27:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:27:46 INFO - Running setup.py install for mozrunner 07:27:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:27:46 INFO - Running setup.py install for mozscreenshot 07:27:46 INFO - Running setup.py install for moztest 07:27:46 INFO - Running setup.py install for mozversion 07:27:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:27:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:27:46 INFO - Cleaning up... 07:27:46 INFO - Return code: 0 07:27:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:27:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:27:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:27:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:27:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:27:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:46 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 0x2a0a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b6d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ccb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cc93b0>, '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 0x2cca2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cca740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1474121892.991980-143782126', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:27:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:27:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:27:46 INFO - Using env: {'DISPLAY': ':0', 07:27:46 INFO - 'HOME': '/home/cltbld', 07:27:46 INFO - 'LANG': 'en_US.UTF-8', 07:27:46 INFO - 'LANGUAGE': 'en_US:en', 07:27:46 INFO - 'LOGNAME': 'cltbld', 07:27:46 INFO - 'MAIL': '/var/mail/cltbld', 07:27:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:27:46 INFO - 'MOZ_NO_REMOTE': '1', 07:27:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:27:46 INFO - 'NO_EM_RESTART': '1', 07:27:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:27:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:27:46 INFO - 'PWD': '/builds/slave/test', 07:27:46 INFO - 'SHELL': '/bin/bash', 07:27:46 INFO - 'SHLVL': '1', 07:27:46 INFO - 'TERM': 'linux', 07:27:46 INFO - 'TMOUT': '86400', 07:27:46 INFO - 'USER': 'cltbld', 07:27:46 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1474121892.991980-143782126', 07:27:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:27:46 INFO - '_': '/tools/buildbot/bin/python'} 07:27:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:27:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:27:47 INFO - Running setup.py (path:/tmp/pip-lOsdCL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:27:47 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 07:27:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:27:47 INFO - Running setup.py (path:/tmp/pip-r6EHWy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:27:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:27:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:27:47 INFO - Running setup.py (path:/tmp/pip-WHv7QA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:27:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:27:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:27:47 INFO - Running setup.py (path:/tmp/pip-YTFMHF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:27:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:27:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:27:47 INFO - Running setup.py (path:/tmp/pip-TKG6YT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:27:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 07:27:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:27:47 INFO - Running setup.py (path:/tmp/pip-n491ZL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:27:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 07:27:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:27:47 INFO - Running setup.py (path:/tmp/pip-7vaMU1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:27:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 07:27:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:27:47 INFO - Running setup.py (path:/tmp/pip-4OIxNz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:27:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 07:27:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:27:47 INFO - Running setup.py (path:/tmp/pip-nSFjNA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:27:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 07:27:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:27:47 INFO - Running setup.py (path:/tmp/pip-pI70rN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:27:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:27:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:27:47 INFO - Running setup.py (path:/tmp/pip-Fu52_I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:27:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 07:27:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:27:47 INFO - Running setup.py (path:/tmp/pip-CslnfN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:27:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 07:27:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:27:48 INFO - Running setup.py (path:/tmp/pip-2Bqb0y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:27:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 07:27:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:27:48 INFO - Running setup.py (path:/tmp/pip-axS_P1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:27:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 07:27:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:27:48 INFO - Running setup.py (path:/tmp/pip-MFxegl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:27:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 07:27:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:27:48 INFO - Running setup.py (path:/tmp/pip-sGiZM6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:27:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 07:27:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:27:48 INFO - Running setup.py (path:/tmp/pip-sPQaIi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:27:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 07:27:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:27:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:27:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:27:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:27:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:27:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:27:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:27:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:27:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:27:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:27:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:27:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:27:51 INFO - Downloading blessings-1.6.tar.gz 07:27:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:27:51 INFO - Installing collected packages: blessings 07:27:51 INFO - Running setup.py install for blessings 07:27:51 INFO - Successfully installed blessings 07:27:51 INFO - Cleaning up... 07:27:51 INFO - Return code: 0 07:27:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:27:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:27:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:27:51 INFO - Reading from file tmpfile_stdout 07:27:51 INFO - Current package versions: 07:27:51 INFO - argparse == 1.2.1 07:27:51 INFO - blessings == 1.6 07:27:51 INFO - blobuploader == 1.2.4 07:27:51 INFO - docopt == 0.6.1 07:27:51 INFO - functools32 == 3.2.3-2 07:27:51 INFO - jsonschema == 2.5.1 07:27:51 INFO - manifestparser == 1.1 07:27:51 INFO - mozInstall == 1.12 07:27:51 INFO - mozcrash == 0.17 07:27:51 INFO - mozdebug == 0.1 07:27:51 INFO - mozdevice == 0.48 07:27:51 INFO - mozfile == 1.2 07:27:51 INFO - mozhttpd == 0.7 07:27:51 INFO - mozinfo == 0.9 07:27:51 INFO - mozleak == 0.1 07:27:51 INFO - mozlog == 3.3 07:27:51 INFO - moznetwork == 0.27 07:27:51 INFO - mozprocess == 0.23 07:27:51 INFO - mozprofile == 0.28 07:27:51 INFO - mozrunner == 6.12 07:27:51 INFO - mozscreenshot == 0.1 07:27:51 INFO - mozsystemmonitor == 0.3 07:27:51 INFO - moztest == 0.7 07:27:51 INFO - mozversion == 1.4 07:27:51 INFO - psutil == 3.1.1 07:27:51 INFO - requests == 1.2.3 07:27:51 INFO - wsgiref == 0.1.2 07:27:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:27:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:27:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:27:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:27:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:27:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:51 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 0x2a0a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b6d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ccb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cc93b0>, '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 0x2cca2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cca740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1474121892.991980-143782126', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:27:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 07:27:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:27:51 INFO - Using env: {'DISPLAY': ':0', 07:27:51 INFO - 'HOME': '/home/cltbld', 07:27:51 INFO - 'LANG': 'en_US.UTF-8', 07:27:51 INFO - 'LANGUAGE': 'en_US:en', 07:27:51 INFO - 'LOGNAME': 'cltbld', 07:27:51 INFO - 'MAIL': '/var/mail/cltbld', 07:27:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:27:51 INFO - 'MOZ_NO_REMOTE': '1', 07:27:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:27:51 INFO - 'NO_EM_RESTART': '1', 07:27:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:27:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:27:51 INFO - 'PWD': '/builds/slave/test', 07:27:51 INFO - 'SHELL': '/bin/bash', 07:27:51 INFO - 'SHLVL': '1', 07:27:51 INFO - 'TERM': 'linux', 07:27:51 INFO - 'TMOUT': '86400', 07:27:51 INFO - 'USER': 'cltbld', 07:27:51 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1474121892.991980-143782126', 07:27:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:27:51 INFO - '_': '/tools/buildbot/bin/python'} 07:27:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:27:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:27:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 07:27:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 07:27:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:27:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 07:27:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 07:27:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 07:27:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 07:27:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 07:27:51 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:27:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:27:51 INFO - Cleaning up... 07:27:51 INFO - Return code: 0 07:27:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:27:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:27:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:27:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:27:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:27:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:51 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 0x2a0a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b6d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ccb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cc93b0>, '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 0x2cca2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cca740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1474121892.991980-143782126', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:27:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:27:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:27:51 INFO - Using env: {'DISPLAY': ':0', 07:27:51 INFO - 'HOME': '/home/cltbld', 07:27:51 INFO - 'LANG': 'en_US.UTF-8', 07:27:51 INFO - 'LANGUAGE': 'en_US:en', 07:27:51 INFO - 'LOGNAME': 'cltbld', 07:27:51 INFO - 'MAIL': '/var/mail/cltbld', 07:27:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:27:51 INFO - 'MOZ_NO_REMOTE': '1', 07:27:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:27:51 INFO - 'NO_EM_RESTART': '1', 07:27:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:27:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:27:51 INFO - 'PWD': '/builds/slave/test', 07:27:51 INFO - 'SHELL': '/bin/bash', 07:27:51 INFO - 'SHLVL': '1', 07:27:51 INFO - 'TERM': 'linux', 07:27:51 INFO - 'TMOUT': '86400', 07:27:51 INFO - 'USER': 'cltbld', 07:27:51 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1474121892.991980-143782126', 07:27:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:27:51 INFO - '_': '/tools/buildbot/bin/python'} 07:27:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:27:52 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:27:52 INFO - Cleaning up... 07:27:52 INFO - Return code: 0 07:27:52 INFO - Running post-action listener: _resource_record_post_action 07:27:52 INFO - Running post-action listener: _start_resource_monitoring 07:27:52 INFO - Starting resource monitoring. 07:27:52 INFO - [mozharness: 2016-09-17 14:27:52.164910Z] Finished create-virtualenv step (success) 07:27:52 INFO - [mozharness: 2016-09-17 14:27:52.165381Z] Running install step. 07:27:52 INFO - Running pre-action listener: _resource_record_pre_action 07:27:52 INFO - Running main action method: install 07:27:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:27:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:27:52 INFO - Reading from file tmpfile_stdout 07:27:52 INFO - Detecting whether we're running mozinstall >=1.0... 07:27:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:27:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:27:52 INFO - Reading from file tmpfile_stdout 07:27:52 INFO - Output received: 07:27:52 INFO - Usage: mozinstall [options] installer 07:27:52 INFO - Options: 07:27:52 INFO - -h, --help show this help message and exit 07:27:52 INFO - -d DEST, --destination=DEST 07:27:52 INFO - Directory to install application into. [default: 07:27:52 INFO - "/builds/slave/test"] 07:27:52 INFO - --app=APP Application being installed. [default: firefox] 07:27:52 INFO - mkdir: /builds/slave/test/build/application 07:27:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 07:27:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 07:28:08 INFO - Reading from file tmpfile_stdout 07:28:08 INFO - Output received: 07:28:08 INFO - /builds/slave/test/build/application/firefox/firefox 07:28:08 INFO - Running post-action listener: _resource_record_post_action 07:28:08 INFO - [mozharness: 2016-09-17 14:28:08.488279Z] Finished install step (success) 07:28:08 INFO - [mozharness: 2016-09-17 14:28:08.488561Z] Running run-tests step. 07:28:08 INFO - Running pre-action listener: _resource_record_pre_action 07:28:08 INFO - Running main action method: run_tests 07:28:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:28:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:28:08 INFO - Python 2.7.3 07:28:08 INFO - Return code: 0 07:28:08 INFO - grabbing minidump binary from tooltool 07:28:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:28:08 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 0x2cc93b0>, '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 0x2cca2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cca740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:28:08 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 07:28:08 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 07:28:08 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 07:28:08 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:28:08 INFO - Return code: 0 07:28:08 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:28:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:28:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:28:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:28:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:28:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-012', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 07:28:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-012 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 07:28:08 INFO - Using env: {'DISPLAY': ':0', 07:28:08 INFO - 'HOME': '/home/cltbld', 07:28:08 INFO - 'LANG': 'en_US.UTF-8', 07:28:08 INFO - 'LANGUAGE': 'en_US:en', 07:28:08 INFO - 'LOGNAME': 'cltbld', 07:28:08 INFO - 'MAIL': '/var/mail/cltbld', 07:28:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:28:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:28:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:28:08 INFO - 'MOZ_NO_REMOTE': '1', 07:28:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:28:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:28:08 INFO - 'NO_EM_RESTART': '1', 07:28:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:28:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:28:08 INFO - 'PWD': '/builds/slave/test', 07:28:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:28:08 INFO - 'SHELL': '/bin/bash', 07:28:08 INFO - 'SHLVL': '1', 07:28:08 INFO - 'TERM': 'linux', 07:28:08 INFO - 'TMOUT': '86400', 07:28:08 INFO - 'USER': 'cltbld', 07:28:08 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1474121892.991980-143782126', 07:28:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:28:08 INFO - '_': '/tools/buildbot/bin/python'} 07:28:08 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-012', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 07:28:08 INFO - mozversion application_buildid: 20160917004009 07:28:08 INFO - mozversion application_changeset: b9499912b32324acd84792bf02b533b40e98e496 07:28:08 INFO - mozversion application_display_name: FirefoxDeveloperEdition 07:28:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:28:08 INFO - mozversion application_name: Firefox 07:28:08 INFO - mozversion application_remotingname: firefox-dev 07:28:08 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:28:08 INFO - mozversion application_vendor: Mozilla 07:28:08 INFO - mozversion application_version: 50.0a2 07:28:08 INFO - mozversion platform_buildid: 20160917004009 07:28:08 INFO - mozversion platform_changeset: b9499912b32324acd84792bf02b533b40e98e496 07:28:08 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:28:08 INFO - mozversion platform_version: 50.0a2 07:28:08 INFO - using testdate: 1474122488 07:28:08 INFO - actual date: 1474122488 07:28:08 INFO - starting webserver on 'localhost:57473' 07:28:08 INFO - SUITE-START | Running 2 tests 07:28:08 INFO - TEST-START | dromaeo_css 07:28:08 INFO - operating with platform_type : linux_ 07:28:08 INFO - Initialising browser for dromaeo_css test... 07:28:08 INFO - TEST-INFO | started process 7298 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9oEmqG/profile http://localhost:57473/getInfo.html) 07:28:12 INFO - PROCESS | 7298 | __metrics Screen width/height:1600/1200 07:28:12 INFO - PROCESS | 7298 | colorDepth:24 07:28:12 INFO - PROCESS | 7298 | Browser inner width/height: 1024/697 07:28:12 INFO - PROCESS | 7298 | __metrics 07:28:12 INFO - PROCESS | 7298 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:28:12 INFO - PROCESS | 7298 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:28:13 INFO - TEST-INFO | 7298: exit 0 07:28:13 INFO - Browser initialized. 07:28:13 INFO - Running cycle 1/1 for dromaeo_css test... 07:28:13 INFO - TEST-INFO | started process 7383 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9oEmqG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:28:25 INFO - PROCESS | 7383 | RSS: Main: 146173952 07:28:25 INFO - PROCESS | 7383 | 07:29:16 INFO - PROCESS | 7383 | 1474122556205 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 07:31:25 INFO - PROCESS | 7383 | Cycle 1(1): loaded http://localhost:57473/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57473/tests/dromaeo/cssquery-ext.html) 07:31:26 INFO - PROCESS | 7383 | RSS: Main: 222736384 07:31:26 INFO - PROCESS | 7383 | 07:34:28 INFO - PROCESS | 7383 | Cycle 1(1): loaded http://localhost:57473/tests/dromaeo/cssquery-ext.html (next: http://localhost:57473/tests/dromaeo/cssquery-jquery.html) 07:34:29 INFO - PROCESS | 7383 | RSS: Main: 229998592 07:34:29 INFO - PROCESS | 7383 | 07:37:31 INFO - PROCESS | 7383 | Cycle 1(1): loaded http://localhost:57473/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57473/tests/dromaeo/cssquery-mootools.html) 07:37:31 INFO - PROCESS | 7383 | RSS: Main: 203292672 07:37:31 INFO - PROCESS | 7383 | 07:40:33 INFO - PROCESS | 7383 | Cycle 1(1): loaded http://localhost:57473/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57473/tests/dromaeo/cssquery-prototype.html) 07:40:34 INFO - PROCESS | 7383 | RSS: Main: 175247360 07:40:34 INFO - PROCESS | 7383 | 07:43:36 INFO - PROCESS | 7383 | Cycle 1(1): loaded http://localhost:57473/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57473/tests/dromaeo/cssquery-yui.html) 07:43:37 INFO - PROCESS | 7383 | RSS: Main: 175419392 07:43:37 INFO - PROCESS | 7383 | 07:46:40 INFO - PROCESS | 7383 | Cycle 1(1): loaded http://localhost:57473/tests/dromaeo/cssquery-yui.html (next: http://localhost:57473/tests/dromaeo/cssquery-dojo.html) 07:46:40 INFO - PROCESS | 7383 | RSS: Main: 266735616 07:46:40 INFO - PROCESS | 7383 | 07:46:40 INFO - PROCESS | 7383 | __start_tp_report 07:46:40 INFO - PROCESS | 7383 | _x_x_mozilla_page_load 07:46:40 INFO - PROCESS | 7383 | _x_x_mozilla_page_load_details 07:46:40 INFO - PROCESS | 7383 | |i|pagename|runs| 07:46:40 INFO - PROCESS | 7383 | |0;dojo.html;3205.182678417004;3238.676132386761;3265.8203798791055;3290.654481279464;3299.7855139415938;5625.240592520008;5704.48659620634;5712.800051998176;5715.857103572413;5719.170720245573;5624.943750562493;5659.745311460983;5683.801066962657;5701.2018317435595;5720.170575266594;5012.598992080643;5079.034983353151;5155.5360017598405;5187.299714538527;5249.737513124348;5163.741812909339;5208.166693329068;5211.088059589576;5213.58291336694;5214.087534681435;5072.365954255718;5107.616928730342;5116.334876466054;5133.666311689728;5205.37535495741;5196.662216955905;5212.791488340462;5225.16397376418;5229.764660590283;5256.316678831728;5041.571466425358;5062.7468626568525;5077.822276220315;5093.134167191549;5102.770375333119;5554.389017208104;5554.944450555524;5563.805266815642;5614.073677843151;5651.40660230674;6074.48366888815;6085.208922840046;6100.877982440371;6101.298350689639;6103.664298463627;5967;5980.102984552352;5999.820005399845;6032.607880487798;6138.60099093562;5662.943370566283;5666.036773748473;5680.233168522279;5688.715564221772;5718.228039214736;6177.2587289524645;6180.505559555225;6185.938140618537;6187.288461826947;6187.907181392283;5855.970720146457;5905.43898329658;5962.314333851663;5968.313643931004;6053.667048312297;6101.434221565316;6116.480099191618;6116.969415152896;6117.296510901214;6122.142899994004;6077.17958075663;6089.604175728652;6095.725692343855;6096.878062438726;6117.021276595723;5902.2622172228475;5969.820905372846;6012.819615411545;6017.488513476316;6114.847128821726;4597.265196127385;4617.976910115428;4621.19129152397;4622.745748983838;4656.231721765871;4157.854475093357;4170.16596680659;4174.165166966619;4178.979105104455;4199.68502362324;5680.346760122557;5702.800401985911;5749.367569567345;5750.942490575041;5854.18041474194;6052.394760523913;6065.787697430657;6076.149339092531;6078.513718902477;6079.787207447718;5758.568107391962;5768.134779783066;5771.422857714279;5788.710564471759;5789.2763404574425;4976.850694479172;5070.695758254443;5097.413797413271;5104.055749686246;5160.948390516047;6303.464205542488;6305.274893387319;6309.053641953652;6312.116303717483;6316.242050953915;6085.543584231289;6112.113743507078;6151.96924015377;6153.907691384543;6174.753009879734;6102.298235702951;6172.105044768394;6183.938160618336;6199.16311297969;6320.241571011508;6019.849503762441;6020.397960203944;6022.307434645072;6033.432525813268;6069.575129740876;6138.723757431016;6151.6924153793025;6151.84620384494;6156.599821011799;6160.199174107516;5834.94165058361;5877.764889404376;5881.235439392852;5894.263217097863;6020.036794112921;5906.084556893688;5937.5249980000635;5947.9702601486715;5956.702164891652;5972.522198224218;5866.67733274674;5909.3795151508475;5942.673152976455;5943.405659434022;5943.732532035982;5805.303363596396;5818.854528636818;5905.616134951214;5928.703564821828;6038.033914573647;5863.442972917566;5869.090291004901;5879.382664820303;5881.852953676193;5887.146363777314 07:46:40 INFO - PROCESS | 7383 | |1;ext.html;603.167628672432;630.9305976342603;656.9146011018566;664.6145235763257;667.9599224046555;34278.65721342786;37333;37492.25015499688;37644.62355376445;37662.8116859416;37983.810080949625;49045;49777;49890.251646225246;53632;9253.074692530743;9388.906110938906;9399.62401503939;9399.95300023499;9401.905980940206;8475.83048339033;9171.63313467464;9273.258139348869;9274;9274.211692006187;11160;11404.087672986183;11404.315741055563;11693.941530292337;11695.064394848392;11260.718482037933;11335.433228338592;11335.433228338632;11347.659570212907;11351.262167959056;11088.778224435466;11179.385133817637;11180.273282236629;11196.832047519294;11205.103591712643;45463.54536454626;45531.08937821258;45609;45831.31253031174;45923.54076459226;3864.671502922227;3985.422113793513;4033.5361433435237;4249.511306199795;4662;34715.65284347149;34979.30041399158;35176.82411587951;35192.296154077034;36784;4501.504834468205;4512.864614061584;4513.729176249435;4598;4620.768961551909;360067;360330;360986;362848;363785;37287.440688389695;63437.36562634315;63833.36166638367;63885.680571597775;63920;39040.80479597641;41166.79416602958;41241.79379103145;41290.587094129274;41368.172636547104;17428.912855435894;17566.736498952527;17591.824081759274;17606.823931760773;17608.471745847644;14942.25288735559;15026.248687565796;15028.098314100971;15029.323680434407;15034.473793417397;8737.956310218535;8891.644334226688;8914.687985920462;9212.124848139416;9293.349465537483;3367.393869103536;3456.6370531094376;3483.1466290758926;3496.7202623790035;3499.2126771476724;1066.2802608239408;1085.4193006741355;1176.9764604707857;1195.4859410453414;1221.5113954418305;34804.65195348065;35127.4730879037;35209.85976030331;35630.64369356325;35765;1169.7601991591703;1182.6156499137815;1187.1986409173946;1189.6966273600349;1233.808759642257;17927.103644817707;18351.265949362143;18362.724559131624;18436.76162787798;18703.438896833115;578314;581528;582253;582765;583257;62745.058824117674;62918.37081629217;65266.347336526975;65988.67005664941;66158;1354.5462270139417;1367.2138520350838;1432.2480697633825;1471.4849802569015;1486.041503230432;34327;36773.63226367702;36840.63159368372;36895.631043689216;36997.075073123386;54013;54456;54528;54537;54977.1753423707;389402;390527;390709;390844;390858;30246.54630180593;31030.379392412477;31103.22241943879;32770.18074548156;32834.17914552155;53053.20420193623;53405.46594534005;53458.73270633622;53483.732581336844;53487.46512534981;43247.56752432435;44580;45160.32259516044;45695.31457028081;47241.29138062863;44136.558634413246;44547.331790023825;45425.772871135436;46190;46867;43212.783936080115;43368.13263734645;43510.56489435192;43642.78178609087;43849;38068;39723.822094231815;40238.59761402466;40470;40735.981600460225;30244.24389390283;30246.481147496466;30617.387652247275;30788.69211307948;30828.69171308348 07:46:40 INFO - PROCESS | 7383 | |2;jquery.html;396.83531334496183;402.3883696780895;422.096713034107;427.9379489973954;428.1437125748503;15743.370265189378;20247.088881000353;20566.48583785406;21090.683639745417;21627.567448651058;20374.490637734063;20747.481312967182;21365.252216172437;21617.135314587398;21633.242836500725;5484.780608775644;5877.6473411595225;5908.822735317947;5912.615679980788;5913.615614985012;4370.2789039808395;5738.110592858114;5811.27359080115;6170.568060235785;6427.5179361547835;6425.132607098052;6457.321981191978;6459.806205813833;6468.191476065492;6469.708863101148;6353.682315884211;6584.967075164642;6602.042703807969;6638.800835974928;6642.800715978528;5358.2498450217;5637.887242255173;5835.44563266489;5849.2103566018895;5854.063349864044;14537.92731036338;14815.111093334435;14818.777718334126;16255.51233462998;24791.25626231216;2516.5721827289403;2632.8946842126293;2781.7913656475644;3039.7720170987263;3143.842807859598;10563.102136318423;10618.203634727426;10841.620543280947;10861.945690271497;11160.665180044612;1882.962340753191;1893.829555340026;1918.2998205654976;1918.5299601597558;1938.5928954919484;81922;84698.15301846965;88686;88752.11247887503;90413;11710.76578468426;11846.940765296118;11914.582989595323;12198.207116537396;12401.13192076564;16979;16990.405335813186;16993.91503042477;16999.91500042492;17014.064226467417;8573.09982451846;8688.174623410765;8724.127587241224;8996.64013439455;9207.355485115977;8688.695895643748;8744;8745.431546949556;8980.236679882153;8998.325125615605;5040.697558146523;5048.722320272347;5127.512886275797;5149.253358263031;5294.867628309323;2009.507670620678;2025.1190732031614;2028.8579799414194;2049.559344740888;2071.1922350283403;960.5053397500293;1021.8262895307815;1030.7938412317567;1039.1219419590584;1050.8791488978713;20957.371278861658;21395.67906481404;21623.89188054081;21678.13287468515;21881;1012.2458768217577;1014.3913651809003;1040.3393844908387;1041.3803786747026;1068.983965240522;11080.944595276973;11312.348281270304;11873.525058997717;11891.227070240408;11945.940270298594;513735;522892;526562;531259;545256;89247;93445.065549345;93666;94500;101893;1035.1460045462522;1035.6064695415694;1048.3709774135457;1055.4564399334195;1055.9251071644103;18360.540986475444;18747.90626046861;19169.137388817257;19290.517737056685;19387.321443749403;43410.131797364505;45529.31706024346;47059.29411058906;47191.292130618756;48028;55158.72420638031;62991.37008629855;63307.36692633199;63647.36352636414;64767.67616161889;8385.454945428528;8455.112213217524;8637.395382323177;8652.956735216283;8715.694950676696;12629.30538820337;13386.598402047954;13559.72880542364;14140.575782726535;14166.504172353918;11864.288142711464;12066.75866482683;12083.093767967262;12187.634370968884;12307.507699691912;11715.707107322385;11828.778998596237;11966.282023078644;12088.818667719815;12242.938785306016;12217.816732749194;12437.253764774143;12611.93694031524;12648.43082061291;12991.220526768424;8864.468131912106;9108.726738197865;9214.078592140731;9301.302402319718;9399.201067909304;12434.316112613893;12612.87387126117;12721.363931803187;12793.61619151427;12800.61598152057 07:46:40 INFO - PROCESS | 7383 | |3;mootools.html;1404.6207523968521;1554.696834117347;1575.9763603545948;1769;1893.5266183454146;8044.678212871486;8917.420367676104;9173.220276276525;9233.49215793132;9445.527723613808;9447.81104377912;9528;9530.094641009093;9535.380200286976;9541.713748587536;2487.2040946896955;2508.134693530729;2511.5855883779154;2512.522620702061;2517.509085728284;2477.479729256849;2545.8217924745277;2546.2488565873114;2546.796256299501;2547.783438407738;2491.19036313198;2502.9737807498927;2543.275166577526;2547.681539807524;2556.833805802626;2434.7443518430578;2440.1947357372023;2454.3741345956755;2479.0951302774356;2482.6276058591175;2431.9270421887372;2442.6091825308044;2453.251758213744;2455.3616059824394;2467.7655622715815;9281.071892810733;9418.717438476857;9435.952820235854;9437.952810235905;9453.574589143505;5669.659820410789;5841.357450680422;5846.766129354821;5850.3564607893095;5853.443922827367;3644.2529281497227;3690.852365905361;3696.5194524711883;3730.235301763132;3742.3825068863875;1117.7205698575356;1124.128800179859;1145.5303325636448;1161.5760247509597;1163.895249427547;13397.12918660284;13436.193828370231;13465.259410732504;13487.190768553919;13500.729985400238;4615.469221039556;4658.557437043475;4810.735409552508;4811.230203167546;4930.161872481615;10481.842772358421;10520.26358154937;10528.894711052944;10530.63142790014;10538.262321637412;5682.403347648437;5685.630434021776;5693.373728889819;5710.885782284331;5711;4578.839740609063;4719.292106184032;5154.819581314636;5203.505666961632;5305.575553955674;3959.5050618672667;3976.4631774710624;4039.0508230565792;4065.918681626351;4078.1843631273864;1172.7068232941765;1178.9174757766873;1181.1082632612322;1188.1267268557604;1208.5347141350644;921.9216366608779;962.8074385122864;1023.2325755683238;1059.3008614314515;1075.7740874416304;6320.5575609706875;6418.711157997901;6424.1327420798525;6543.705533251015;6649.202095748445;499.9300097986285;510.98978020440126;511.86947328431756;1070.71626019105;1086.8261078227445;4057.69567282455;4241.5970482804705;4250.192463431938;4250.404943307939;4252.808623611945;11518.424078796193;11816.940915295541;11822.113341499422;11846.585369512026;11909.642710718692;11645.6506304811;11674.124440666814;11743.412829358498;11758.05935525156;11770.234934729215;8440.40917135807;8774.166454186841;8795.428297160664;8905.88676415448;9032.23226025782;6617.503687223381;6683.465322774069;6698.531102822755;6886.51794374409;6965.233824279224;10552.84170737455;10633.308834925705;10667.733306667395;10674;10762.515686793957;9855.852162217428;10602.310849794738;10766.838497422701;11209.607663732057;11226.045786108272;8930.285577153942;9159;9181.173694367539;9316.394434361744;9417.152456278967;9257.35198536096;9378.765530861783;9386.95306523463;9389.154976052188;9393.48335841508;8285.668573257002;8286.4199506034;8287.834243315221;8293.668253269801;8432.704855330034;8193.180681931759;8244.711435099744;8283;8381.245687888118;8489.193526614961;7472.738454154079;7545.320921117126;7614.6954121836725;7619.123800762984;7643.273888980538;5824.825255242349;5880.14737863016;5968.671723055274;5984.970075149597;6024.156618073561;5630.74661640219;5838.620489668158;5840.123981402823;5949.375315591974;6104.023356262976 07:46:40 INFO - PROCESS | 7383 | |4;prototype.html;329.15243248634755;334.45149954075305;334.9866005359786;339.5178846038625;343.6014223500739;5484.396716361197;5484.424135465779;5533.584981126412;5566.638168519044;5572.498475137237;5362.785488580462;5370;5431.266778984836;5461.290032295796;5463.972680136594;4045.898852528688;4154.792260386984;4262.5950534699305;4278.9786051069705;4286.0999190170105;4230.9153816923645;4232.936505952413;4258.723182993111;4272.359146128075;4272.807723652428;4211.557786432427;4234.745915245096;4245;4250.362445633149;4266.765327906979;4008.9398659019994;4034.6570541504007;4224.640905523003;4258.680598955059;4265.893352666209;3724.7020238380865;3730.925381492355;3740.2706472237924;3747.1194269346684;3760.4547340635454;5286.947130528684;5328.6944698549105;5345.30511033567;5362.142057270857;5371.221172929942;4716.716996980158;4737.644676649231;4752.619790416758;4768.2847572864;4852.902941941176;5545.77816887324;5554.222408862763;5557.5831812614215;5580.581456390746;5631.8028868989795;4067.227226826923;4083.734557253769;4093.85671501496;4096.283150448659;4108.4453598764085;5750.769969201268;5795.82612521625;5832.24180856486;5846.50304724099;5905.1732757413565;4164.7917604120275;4176.955761059735;4181.707280490397;4191.455110835572;4196.349565817303;4228.302330115558;4229.483457342876;4233.343831706091;4239.194553034913;4242.66058715302;4635.791389387486;4763.666543341933;4806.447258565309;4847.563719265283;4847.878803029882;5625.606207565431;5628.339639806267;5636.718164091861;5647.604667673306;5661.745221465044;1457.9781303280458;1482.303317440801;1484.7253258147061;1487.8660920517204;1488.672492051747;896.7623579751371;951.538503825644;952.6903756279237;972.6790159247431;1007.8538611901233;5356.517913387814;5542.362628297717;5556.638818476786;5592.189132575753;5594.944050559524;5854.765809367663;5869.471747542656;5879.265091863517;5882.323532793698;5891.204687367151;5291.206319052096;5511.311086114236;5550.4449555044985;5560.443955604407;5574.275344205227;3046.192758918888;3077.3691393264776;3079.784415090967;3087.814731116095;3095.8142511449387;5880.26496687914;6027.914975304399;6032.698365081728;6039.909401358983;6055.6677530943125;5863.736131874076;5880.353161152269;5913.615614985012;5951.642901425928;5956.910646340309;5999.970000149971;6008.279006519158;6013.6692481913915;6021.668808215502;6023.909641355383;5564.916526252028;5837.416258374129;5838.737256823538;5858.209141765978;5868.354481007171;5810.448007439287;5812.796552120655;5820.592558520862;5822.534197264124;5828.358880523224;5775.873704627557;6037.18498002764;6090;6090.9390906090375;6129.233845769279;4121.505419349697;4140.875773726793;4166.66666666672;4199.139176468755;4212.789360532022;5946.821595352147;5968.612040217372;5991.3409524953095;5992.820215393545;5993.760249589967;5706.4864162225595;5716.885662286814;5784.248047753765;5855.94144058554;5863.618864773776;5698.800541981011;5709.8287051388525;5712;5732.627379220337;5736.68448235351;5704.201411802268;5737.168110624019;5746.482816546531;5748.281464816898;5755.798547050833;5230.7384630769075;5395.595330350318;5732.570057245806;5739.9139012914;5740.540756739534;5616.915746263728;5621.437856214346;5637.633553818989;5648;5657.236273103243 07:46:40 INFO - PROCESS | 7383 | |5;yui.html;837.2757564706527;856.2080075929763;867.3711559119639;907.7549061753323;919.6965001549487;16457.341706331747;17287.222075006623;17507.47477575675;17531.73702394465;17537.64924701505;14742.262886855668;14748.33632486538;15023.098614083136;15164.924175379108;15201;870.6082262981652;887.219247062584;889.4174315823113;891.4473026723441;893.0132203914678;726.93457588817;842.086336325088;851.3444647621328;852.3947996922162;873.6112429968666;917.4220241248038;918.0727465260102;918.5499105438321;918.6876462002917;921.0375157959915;906.3519583497831;907.8819753432011;910.3809409601436;911.9589618467185;912.589334799343;550.411060165623;647.4884840975624;647.9287278399372;652.3118110393533;670.6512613441032;16100.75848862268;16146.919265403598;16170.75743863843;16171.110588917605;16171.83828161715;15430.614234644112;15873.761893571605;15994.84005159945;16156.515304540882;16313;14331.38518407517;14464.868408636059;14561.417543298256;14682.55952321432;14996.775048374284;4576.130535198302;4666.859994200179;4721.386219791405;4829.251466022772;4836.540528649773;85691.57154214189;85739.7139042921;85908;86072;88795;12650.304233267074;12740.36298185087;12751.171173873669;12825;13048.804267935988;23956.40108997254;24053.639195412085;24079.759202407753;24328.1485148019;24343;9224.215941645036;9323.766905827273;9430.10414010668;9455.810883782286;9596.040395960488;5053.646244762905;5336.493033161921;5350.91973620396;5400.432954539717;5507.008738427122;3642.2715456908722;3788.3938569829247;3803.1633040730994;3806.0865392305645;3807.4669546263544;637.8947473666885;648.9643069631121;654.430645338549;659.7163219815526;660.3660485933461;694.885343918258;705.8623568404215;737.9741709040158;783.0564170174925;801.72741267968;9795.118439340493;9995.550200241007;10295.893418492062;10534.132708883051;10697.625583104555;760.1296515489844;763.2863272839912;768.6541056524586;784.3254800871246;786.8898354230412;5077.543021128116;5274.4198138204765;5296.285001524822;5301.231321458367;5314.707691077027;123857;125278;125670.371648143;125810;127396.36301818432;106588;108726;110346;111922.44038779754;112161.75676486587;97400;97791;98261.50869245607;98372.50813745885;101025.49487252516;9761.121499065119;9923.503824808586;10017.248706346907;10060.49697515136;10108.84836727435;45251.54748452473;50531.49468505415;50535.49464505308;52751.47248527466;55477;68892;71540.64229679026;79225;80398.5980070096;82607;14259.07316024455;14951.327190276246;15007.399704011717;15029.774553381927;15102.169380683728;16311.755323670392;16541;17558.473245802645;17602.03188824627;17784;15697.607559811097;15840.60398490047;16456.917715411346;16600.584985375463;16878.83121168822;15458.072515649097;15572;15793.526194214191;16769.916150419656;16801.159942003094;15398.384064637288;15688.372465101726;15739.606509837346;15907.840921590636;16820.57948551296;12527.310997895203;12940.547080852126;12999.09006369545;13380.531681391105;13725.245111518961;11711.238769479956;12034.63896083119;12496;12507.437165327765;12604.243745375306 07:46:40 INFO - PROCESS | 7383 | __end_tp_report 07:46:40 INFO - PROCESS | 7383 | __start_cc_report 07:46:40 INFO - PROCESS | 7383 | _x_x_mozilla_cycle_collect,1067 07:46:40 INFO - PROCESS | 7383 | __end_cc_report 07:46:40 INFO - PROCESS | 7383 | __startTimestamp1474123600730__endTimestamp 07:46:40 INFO - PROCESS | 7383 | 07:46:40 INFO - PROCESS | 7383 | ------- Summary: start ------- 07:46:40 INFO - PROCESS | 7383 | Number of tests: 6 07:46:40 INFO - PROCESS | 7383 | 07:46:40 INFO - PROCESS | 7383 | [#0] dojo.html Cycles:165 Average:5635.63 Median:5877.76 stddev:646.80 (11.0%) stddev-sans-first:620.05 07:46:40 INFO - PROCESS | 7383 | Values: 3205.2 3238.7 3265.8 3290.7 3299.8 5625.2 5704.5 5712.8 5715.9 5719.2 5624.9 5659.7 5683.8 5701.2 5720.2 5012.6 5079.0 5155.5 5187.3 5249.7 5163.7 5208.2 5211.1 5213.6 5214.1 5072.4 5107.6 5116.3 5133.7 5205.4 5196.7 5212.8 5225.2 5229.8 5256.3 5041.6 5062.7 5077.8 5093.1 5102.8 5554.4 5554.9 5563.8 5614.1 5651.4 6074.5 6085.2 6100.9 6101.3 6103.7 5967.0 5980.1 5999.8 6032.6 6138.6 5662.9 5666.0 5680.2 5688.7 5718.2 6177.3 6180.5 6185.9 6187.3 6187.9 5856.0 5905.4 5962.3 5968.3 6053.7 6101.4 6116.5 6117.0 6117.3 6122.1 6077.2 6089.6 6095.7 6096.9 6117.0 5902.3 5969.8 6012.8 6017.5 6114.8 4597.3 4618.0 4621.2 4622.7 4656.2 4157.9 4170.2 4174.2 4179.0 4199.7 5680.3 5702.8 5749.4 5750.9 5854.2 6052.4 6065.8 6076.1 6078.5 6079.8 5758.6 5768.1 5771.4 5788.7 5789.3 4976.9 5070.7 5097.4 5104.1 5160.9 6303.5 6305.3 6309.1 6312.1 6316.2 6085.5 6112.1 6152.0 6153.9 6174.8 6102.3 6172.1 6183.9 6199.2 6320.2 6019.8 6020.4 6022.3 6033.4 6069.6 6138.7 6151.7 6151.8 6156.6 6160.2 5834.9 5877.8 5881.2 5894.3 6020.0 5906.1 5937.5 5948.0 5956.7 5972.5 5866.7 5909.4 5942.7 5943.4 5943.7 5805.3 5818.9 5905.6 5928.7 6038.0 5863.4 5869.1 5879.4 5881.9 5887.1 07:46:40 INFO - PROCESS | 7383 | 07:46:40 INFO - PROCESS | 7383 | [#1] ext.html Cycles:180 Average:61339.41 Median:214783.90 stddev:120746.32 (56.2%) stddev-sans-first:120998.94 07:46:40 INFO - PROCESS | 7383 | Values: 603.2 630.9 656.9 664.6 668.0 34278.7 37333.0 37492.3 37644.6 37662.8 37983.8 49045.0 49777.0 49890.3 53632.0 9253.1 9388.9 9399.6 9400.0 9401.9 8475.8 9171.6 9273.3 9274.0 9274.2 11160.0 11404.1 11404.3 11693.9 11695.1 11260.7 11335.4 11335.4 11347.7 11351.3 11088.8 11179.4 11180.3 11196.8 11205.1 45463.5 45531.1 45609.0 45831.3 45923.5 3864.7 3985.4 4033.5 4249.5 4662.0 34715.7 34979.3 35176.8 35192.3 36784.0 4501.5 4512.9 4513.7 4598.0 4620.8 360067.0 360330.0 360986.0 362848.0 363785.0 37287.4 63437.4 63833.4 63885.7 63920.0 39040.8 41166.8 41241.8 41290.6 41368.2 17428.9 17566.7 17591.8 17606.8 17608.5 14942.3 15026.2 15028.1 15029.3 15034.5 8738.0 8891.6 8914.7 9212.1 9293.3 3367.4 3456.6 3483.1 3496.7 3499.2 1066.3 1085.4 1177.0 1195.5 1221.5 34804.7 35127.5 35209.9 35630.6 35765.0 1169.8 1182.6 1187.2 1189.7 1233.8 17927.1 18351.3 18362.7 18436.8 18703.4 578314.0 581528.0 582253.0 582765.0 583257.0 62745.1 62918.4 65266.3 65988.7 66158.0 1354.5 1367.2 1432.2 1471.5 1486.0 34327.0 36773.6 36840.6 36895.6 36997.1 54013.0 54456.0 54528.0 54537.0 54977.2 389402.0 390527.0 390709.0 390844.0 390858.0 30246.5 31030.4 31103.2 32770.2 32834.2 53053.2 53405.5 53458.7 53483.7 53487.5 43247.6 44580.0 45160.3 45695.3 47241.3 44136.6 44547.3 45425.8 46190.0 46867.0 43212.8 43368.1 43510.6 43642.8 43849.0 38068.0 39723.8 40238.6 40470.0 40736.0 30244.2 30246.5 30617.4 30788.7 30828.7 07:46:40 INFO - PROCESS | 7383 | 07:46:40 INFO - PROCESS | 7383 | [#2] jquery.html Cycles:180 Average:30877.09 Median:21630.41 stddev:87007.35 (402.2%) stddev-sans-first:87221.33 07:46:40 INFO - PROCESS | 7383 | Values: 396.8 402.4 422.1 427.9 428.1 15743.4 20247.1 20566.5 21090.7 21627.6 20374.5 20747.5 21365.3 21617.1 21633.2 5484.8 5877.6 5908.8 5912.6 5913.6 4370.3 5738.1 5811.3 6170.6 6427.5 6425.1 6457.3 6459.8 6468.2 6469.7 6353.7 6585.0 6602.0 6638.8 6642.8 5358.2 5637.9 5835.4 5849.2 5854.1 14537.9 14815.1 14818.8 16255.5 24791.3 2516.6 2632.9 2781.8 3039.8 3143.8 10563.1 10618.2 10841.6 10861.9 11160.7 1883.0 1893.8 1918.3 1918.5 1938.6 81922.0 84698.2 88686.0 88752.1 90413.0 11710.8 11846.9 11914.6 12198.2 12401.1 16979.0 16990.4 16993.9 16999.9 17014.1 8573.1 8688.2 8724.1 8996.6 9207.4 8688.7 8744.0 8745.4 8980.2 8998.3 5040.7 5048.7 5127.5 5149.3 5294.9 2009.5 2025.1 2028.9 2049.6 2071.2 960.5 1021.8 1030.8 1039.1 1050.9 20957.4 21395.7 21623.9 21678.1 21881.0 1012.2 1014.4 1040.3 1041.4 1069.0 11080.9 11312.3 11873.5 11891.2 11945.9 513735.0 522892.0 526562.0 531259.0 545256.0 89247.0 93445.1 93666.0 94500.0 101893.0 1035.1 1035.6 1048.4 1055.5 1055.9 18360.5 18747.9 19169.1 19290.5 19387.3 43410.1 45529.3 47059.3 47191.3 48028.0 55158.7 62991.4 63307.4 63647.4 64767.7 8385.5 8455.1 8637.4 8653.0 8715.7 12629.3 13386.6 13559.7 14140.6 14166.5 11864.3 12066.8 12083.1 12187.6 12307.5 11715.7 11828.8 11966.3 12088.8 12242.9 12217.8 12437.3 12611.9 12648.4 12991.2 8864.5 9108.7 9214.1 9301.3 9399.2 12434.3 12612.9 12721.4 12793.6 12800.6 07:46:40 INFO - PROCESS | 7383 | 07:46:40 INFO - PROCESS | 7383 | [#3] mootools.html Cycles:180 Average:6243.38 Median:4637.01 stddev:3569.18 (77.0%) stddev-sans-first:3560.66 07:46:40 INFO - PROCESS | 7383 | Values: 1404.6 1554.7 1576.0 1769.0 1893.5 8044.7 8917.4 9173.2 9233.5 9445.5 9447.8 9528.0 9530.1 9535.4 9541.7 2487.2 2508.1 2511.6 2512.5 2517.5 2477.5 2545.8 2546.2 2546.8 2547.8 2491.2 2503.0 2543.3 2547.7 2556.8 2434.7 2440.2 2454.4 2479.1 2482.6 2431.9 2442.6 2453.3 2455.4 2467.8 9281.1 9418.7 9436.0 9438.0 9453.6 5669.7 5841.4 5846.8 5850.4 5853.4 3644.3 3690.9 3696.5 3730.2 3742.4 1117.7 1124.1 1145.5 1161.6 1163.9 13397.1 13436.2 13465.3 13487.2 13500.7 4615.5 4658.6 4810.7 4811.2 4930.2 10481.8 10520.3 10528.9 10530.6 10538.3 5682.4 5685.6 5693.4 5710.9 5711.0 4578.8 4719.3 5154.8 5203.5 5305.6 3959.5 3976.5 4039.1 4065.9 4078.2 1172.7 1178.9 1181.1 1188.1 1208.5 921.9 962.8 1023.2 1059.3 1075.8 6320.6 6418.7 6424.1 6543.7 6649.2 499.9 511.0 511.9 1070.7 1086.8 4057.7 4241.6 4250.2 4250.4 4252.8 11518.4 11816.9 11822.1 11846.6 11909.6 11645.7 11674.1 11743.4 11758.1 11770.2 8440.4 8774.2 8795.4 8905.9 9032.2 6617.5 6683.5 6698.5 6886.5 6965.2 10552.8 10633.3 10667.7 10674.0 10762.5 9855.9 10602.3 10766.8 11209.6 11226.0 8930.3 9159.0 9181.2 9316.4 9417.2 9257.4 9378.8 9387.0 9389.2 9393.5 8285.7 8286.4 8287.8 8293.7 8432.7 8193.2 8244.7 8283.0 8381.2 8489.2 7472.7 7545.3 7614.7 7619.1 7643.3 5824.8 5880.1 5968.7 5985.0 6024.2 5630.7 5838.6 5840.1 5949.4 6104.0 07:46:40 INFO - PROCESS | 7383 | 07:46:40 INFO - PROCESS | 7383 | [#4] prototype.html Cycles:180 Average:4802.60 Median:5548.11 stddev:1415.57 (25.5%) stddev-sans-first:1379.15 07:46:40 INFO - PROCESS | 7383 | Values: 329.2 334.5 335.0 339.5 343.6 5484.4 5484.4 5533.6 5566.6 5572.5 5362.8 5370.0 5431.3 5461.3 5464.0 4045.9 4154.8 4262.6 4279.0 4286.1 4230.9 4232.9 4258.7 4272.4 4272.8 4211.6 4234.7 4245.0 4250.4 4266.8 4008.9 4034.7 4224.6 4258.7 4265.9 3724.7 3730.9 3740.3 3747.1 3760.5 5286.9 5328.7 5345.3 5362.1 5371.2 4716.7 4737.6 4752.6 4768.3 4852.9 5545.8 5554.2 5557.6 5580.6 5631.8 4067.2 4083.7 4093.9 4096.3 4108.4 5750.8 5795.8 5832.2 5846.5 5905.2 4164.8 4177.0 4181.7 4191.5 4196.3 4228.3 4229.5 4233.3 4239.2 4242.7 4635.8 4763.7 4806.4 4847.6 4847.9 5625.6 5628.3 5636.7 5647.6 5661.7 1458.0 1482.3 1484.7 1487.9 1488.7 896.8 951.5 952.7 972.7 1007.9 5356.5 5542.4 5556.6 5592.2 5594.9 5854.8 5869.5 5879.3 5882.3 5891.2 5291.2 5511.3 5550.4 5560.4 5574.3 3046.2 3077.4 3079.8 3087.8 3095.8 5880.3 6027.9 6032.7 6039.9 6055.7 5863.7 5880.4 5913.6 5951.6 5956.9 6000.0 6008.3 6013.7 6021.7 6023.9 5564.9 5837.4 5838.7 5858.2 5868.4 5810.4 5812.8 5820.6 5822.5 5828.4 5775.9 6037.2 6090.0 6090.9 6129.2 4121.5 4140.9 4166.7 4199.1 4212.8 5946.8 5968.6 5991.3 5992.8 5993.8 5706.5 5716.9 5784.2 5855.9 5863.6 5698.8 5709.8 5712.0 5732.6 5736.7 5704.2 5737.2 5746.5 5748.3 5755.8 5230.7 5395.6 5732.6 5739.9 5740.5 5616.9 5621.4 5637.6 5648.0 5657.2 07:46:40 INFO - PROCESS | 7383 | 07:46:40 INFO - PROCESS | 7383 | [#5] yui.html Cycles:180 Average:22932.23 Median:3806.78 stddev:32847.18 (862.9%) stddev-sans-first:32897.43 07:46:40 INFO - PROCESS | 7383 | Values: 837.3 856.2 867.4 907.8 919.7 16457.3 17287.2 17507.5 17531.7 17537.6 14742.3 14748.3 15023.1 15164.9 15201.0 870.6 887.2 889.4 891.4 893.0 726.9 842.1 851.3 852.4 873.6 917.4 918.1 918.5 918.7 921.0 906.4 907.9 910.4 912.0 912.6 550.4 647.5 647.9 652.3 670.7 16100.8 16146.9 16170.8 16171.1 16171.8 15430.6 15873.8 15994.8 16156.5 16313.0 14331.4 14464.9 14561.4 14682.6 14996.8 4576.1 4666.9 4721.4 4829.3 4836.5 85691.6 85739.7 85908.0 86072.0 88795.0 12650.3 12740.4 12751.2 12825.0 13048.8 23956.4 24053.6 24079.8 24328.1 24343.0 9224.2 9323.8 9430.1 9455.8 9596.0 5053.6 5336.5 5350.9 5400.4 5507.0 3642.3 3788.4 3803.2 3806.1 3807.5 637.9 649.0 654.4 659.7 660.4 694.9 705.9 738.0 783.1 801.7 9795.1 9995.6 10295.9 10534.1 10697.6 760.1 763.3 768.7 784.3 786.9 5077.5 5274.4 5296.3 5301.2 5314.7 123857.0 125278.0 125670.4 125810.0 127396.4 106588.0 108726.0 110346.0 111922.4 112161.8 97400.0 97791.0 98261.5 98372.5 101025.5 9761.1 9923.5 10017.2 10060.5 10108.8 45251.5 50531.5 50535.5 52751.5 55477.0 68892.0 71540.6 79225.0 80398.6 82607.0 14259.1 14951.3 15007.4 15029.8 15102.2 16311.8 16541.0 17558.5 17602.0 17784.0 15697.6 15840.6 16456.9 16600.6 16878.8 15458.1 15572.0 15793.5 16769.9 16801.2 15398.4 15688.4 15739.6 15907.8 16820.6 12527.3 12940.5 12999.1 13380.5 13725.2 11711.2 12034.6 12496.0 12507.4 12604.2 07:46:40 INFO - PROCESS | 7383 | -------- Summary: end -------- 07:46:40 INFO - PROCESS | 7383 | 07:46:41 INFO - TEST-INFO | 7383: exit 0 07:46:41 INFO - TEST-OK | dromaeo_css | took 1112704ms 07:46:41 INFO - TEST-START | kraken 07:46:41 INFO - operating with platform_type : linux_ 07:46:41 INFO - Initialising browser for kraken test... 07:46:41 INFO - TEST-INFO | started process 7610 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9Y5CPT/profile http://localhost:57473/getInfo.html) 07:46:44 INFO - PROCESS | 7610 | __metrics Screen width/height:1600/1200 07:46:44 INFO - PROCESS | 7610 | colorDepth:24 07:46:44 INFO - PROCESS | 7610 | Browser inner width/height: 1024/697 07:46:44 INFO - PROCESS | 7610 | __metrics 07:46:45 INFO - PROCESS | 7610 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:46:45 INFO - PROCESS | 7610 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:46:46 INFO - TEST-INFO | 7610: exit 0 07:46:46 INFO - Browser initialized. 07:46:46 INFO - Running cycle 1/1 for kraken test... 07:46:46 INFO - TEST-INFO | started process 7696 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9Y5CPT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:46:58 INFO - PROCESS | 7696 | RSS: Main: 146313216 07:46:58 INFO - PROCESS | 7696 | 07:47:00 INFO - PROCESS | 7696 | Cycle 1(1): loaded http://localhost:57473/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57473/tests/kraken/driver.html?testName=audio-beat-detection) 07:47:01 INFO - PROCESS | 7696 | RSS: Main: 201179136 07:47:01 INFO - PROCESS | 7696 | 07:47:03 INFO - PROCESS | 7696 | Cycle 1(1): loaded http://localhost:57473/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57473/tests/kraken/driver.html?testName=audio-dft) 07:47:04 INFO - PROCESS | 7696 | RSS: Main: 185704448 07:47:04 INFO - PROCESS | 7696 | 07:47:07 INFO - PROCESS | 7696 | Cycle 1(1): loaded http://localhost:57473/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57473/tests/kraken/driver.html?testName=audio-fft) 07:47:07 INFO - PROCESS | 7696 | RSS: Main: 276013056 07:47:07 INFO - PROCESS | 7696 | 07:47:10 INFO - PROCESS | 7696 | Cycle 1(1): loaded http://localhost:57473/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57473/tests/kraken/driver.html?testName=audio-oscillator) 07:47:10 INFO - PROCESS | 7696 | RSS: Main: 181903360 07:47:10 INFO - PROCESS | 7696 | 07:47:12 INFO - PROCESS | 7696 | Cycle 1(1): loaded http://localhost:57473/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57473/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:47:13 INFO - PROCESS | 7696 | RSS: Main: 181202944 07:47:13 INFO - PROCESS | 7696 | 07:47:16 INFO - PROCESS | 7696 | Cycle 1(1): loaded http://localhost:57473/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57473/tests/kraken/driver.html?testName=imaging-darkroom) 07:47:16 INFO - PROCESS | 7696 | RSS: Main: 217202688 07:47:16 INFO - PROCESS | 7696 | 07:47:20 INFO - PROCESS | 7696 | Cycle 1(1): loaded http://localhost:57473/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57473/tests/kraken/driver.html?testName=imaging-desaturate) 07:47:20 INFO - PROCESS | 7696 | RSS: Main: 215384064 07:47:20 INFO - PROCESS | 7696 | 07:47:23 INFO - PROCESS | 7696 | Cycle 1(1): loaded http://localhost:57473/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57473/tests/kraken/driver.html?testName=json-parse-financial) 07:47:24 INFO - PROCESS | 7696 | RSS: Main: 215183360 07:47:24 INFO - PROCESS | 7696 | 07:47:25 INFO - PROCESS | 7696 | Cycle 1(1): loaded http://localhost:57473/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57473/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:47:26 INFO - PROCESS | 7696 | RSS: Main: 178020352 07:47:26 INFO - PROCESS | 7696 | 07:47:28 INFO - PROCESS | 7696 | Cycle 1(1): loaded http://localhost:57473/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57473/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:47:28 INFO - PROCESS | 7696 | RSS: Main: 184512512 07:47:28 INFO - PROCESS | 7696 | 07:47:30 INFO - PROCESS | 7696 | Cycle 1(1): loaded http://localhost:57473/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57473/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:47:31 INFO - PROCESS | 7696 | RSS: Main: 187363328 07:47:31 INFO - PROCESS | 7696 | 07:47:33 INFO - PROCESS | 7696 | Cycle 1(1): loaded http://localhost:57473/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57473/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:47:34 INFO - PROCESS | 7696 | RSS: Main: 180584448 07:47:34 INFO - PROCESS | 7696 | 07:47:37 INFO - PROCESS | 7696 | Cycle 1(1): loaded http://localhost:57473/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57473/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:47:37 INFO - PROCESS | 7696 | RSS: Main: 185348096 07:47:37 INFO - PROCESS | 7696 | 07:47:39 INFO - PROCESS | 7696 | Cycle 1(1): loaded http://localhost:57473/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57473/tests/kraken/driver.html?testName=ai-astar) 07:47:39 INFO - PROCESS | 7696 | RSS: Main: 236187648 07:47:39 INFO - PROCESS | 7696 | 07:47:39 INFO - PROCESS | 7696 | __start_tp_report 07:47:39 INFO - PROCESS | 7696 | _x_x_mozilla_page_load 07:47:39 INFO - PROCESS | 7696 | _x_x_mozilla_page_load_details 07:47:39 INFO - PROCESS | 7696 | |i|pagename|runs| 07:47:39 INFO - PROCESS | 7696 | |0;ai-astar;111;112;121;110;108;109;111;110;110;110 07:47:39 INFO - PROCESS | 7696 | |1;audio-beat-detection;146;122;143;172;121;139;146;122;175;116 07:47:39 INFO - PROCESS | 7696 | |2;audio-dft;160;161;161;160;160;160;160;159;161;159 07:47:39 INFO - PROCESS | 7696 | |3;audio-fft;75;74;74;172;76;76;75;75;174;76 07:47:39 INFO - PROCESS | 7696 | |4;audio-oscillator;84;83;147;84;84;84;151;85;84;156 07:47:39 INFO - PROCESS | 7696 | |5;imaging-gaussian-blur;117;117;117;117;116;117;117;117;116;116 07:47:39 INFO - PROCESS | 7696 | |6;imaging-darkroom;146;146;145;146;147;146;146;145;146;146 07:47:39 INFO - PROCESS | 7696 | |7;imaging-desaturate;96;95;95;95;95;95;95;95;95;95 07:47:39 INFO - PROCESS | 7696 | |8;json-parse-financial;50;49;52;49;50;50;51;50;49;49 07:47:39 INFO - PROCESS | 7696 | |9;json-stringify-tinderbox;61;61;61;60;61;61;61;60;61;60 07:47:39 INFO - PROCESS | 7696 | |10;stanford-crypto-aes;73;73;75;73;80;72;73;72;82;72 07:47:39 INFO - PROCESS | 7696 | |11;stanford-crypto-ccm;135;120;129;117;116;117;120;119;194;120 07:47:39 INFO - PROCESS | 7696 | |12;stanford-crypto-pbkdf2;153;152;157;155;153;157;150;152;152;151 07:47:39 INFO - PROCESS | 7696 | |13;stanford-crypto-sha256-iterative;61;62;63;62;71;63;61;66;59;62 07:47:39 INFO - PROCESS | 7696 | __end_tp_report 07:47:39 INFO - PROCESS | 7696 | __start_cc_report 07:47:39 INFO - PROCESS | 7696 | _x_x_mozilla_cycle_collect,1688 07:47:39 INFO - PROCESS | 7696 | __end_cc_report 07:47:39 INFO - PROCESS | 7696 | __startTimestamp1474123659571__endTimestamp 07:47:39 INFO - PROCESS | 7696 | 07:47:39 INFO - PROCESS | 7696 | ------- Summary: start ------- 07:47:39 INFO - PROCESS | 7696 | Number of tests: 14 07:47:39 INFO - PROCESS | 7696 | 07:47:39 INFO - PROCESS | 7696 | [#0] ai-astar Cycles:10 Average:111.20 Median:110.50 stddev:3.61 (3.3%) stddev-sans-first:3.83 07:47:39 INFO - PROCESS | 7696 | Values: 111.0 112.0 121.0 110.0 108.0 109.0 111.0 110.0 110.0 110.0 07:47:39 INFO - PROCESS | 7696 | 07:47:39 INFO - PROCESS | 7696 | [#1] audio-beat-detection Cycles:10 Average:140.20 Median:144.50 stddev:20.86 (14.4%) stddev-sans-first:22.02 07:47:39 INFO - PROCESS | 7696 | Values: 146.0 122.0 143.0 172.0 121.0 139.0 146.0 122.0 175.0 116.0 07:47:39 INFO - PROCESS | 7696 | 07:47:39 INFO - PROCESS | 7696 | [#2] audio-dft Cycles:10 Average:160.10 Median:160.00 stddev:0.74 (0.5%) stddev-sans-first:0.78 07:47:39 INFO - PROCESS | 7696 | Values: 160.0 161.0 161.0 160.0 160.0 160.0 160.0 159.0 161.0 159.0 07:47:39 INFO - PROCESS | 7696 | 07:47:39 INFO - PROCESS | 7696 | [#3] audio-fft Cycles:10 Average:94.70 Median:75.00 stddev:41.28 (55.0%) stddev-sans-first:43.16 07:47:39 INFO - PROCESS | 7696 | Values: 75.0 74.0 74.0 172.0 76.0 76.0 75.0 75.0 174.0 76.0 07:47:39 INFO - PROCESS | 7696 | 07:47:39 INFO - PROCESS | 7696 | [#4] audio-oscillator Cycles:10 Average:104.20 Median:84.00 stddev:32.60 (38.8%) stddev-sans-first:33.75 07:47:39 INFO - PROCESS | 7696 | Values: 84.0 83.0 147.0 84.0 84.0 84.0 151.0 85.0 84.0 156.0 07:47:39 INFO - PROCESS | 7696 | 07:47:39 INFO - PROCESS | 7696 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 07:47:39 INFO - PROCESS | 7696 | Values: 117.0 117.0 117.0 117.0 116.0 117.0 117.0 117.0 116.0 116.0 07:47:39 INFO - PROCESS | 7696 | 07:47:39 INFO - PROCESS | 7696 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 07:47:39 INFO - PROCESS | 7696 | Values: 146.0 146.0 145.0 146.0 147.0 146.0 146.0 145.0 146.0 146.0 07:47:39 INFO - PROCESS | 7696 | 07:47:39 INFO - PROCESS | 7696 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.00 07:47:39 INFO - PROCESS | 7696 | Values: 96.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 07:47:39 INFO - PROCESS | 7696 | 07:47:39 INFO - PROCESS | 7696 | [#8] json-parse-financial Cycles:10 Average:49.90 Median:50.00 stddev:0.99 (2.0%) stddev-sans-first:1.05 07:47:39 INFO - PROCESS | 7696 | Values: 50.0 49.0 52.0 49.0 50.0 50.0 51.0 50.0 49.0 49.0 07:47:39 INFO - PROCESS | 7696 | 07:47:39 INFO - PROCESS | 7696 | [#9] json-stringify-tinderbox Cycles:10 Average:60.70 Median:61.00 stddev:0.48 (0.8%) stddev-sans-first:0.50 07:47:39 INFO - PROCESS | 7696 | Values: 61.0 61.0 61.0 60.0 61.0 61.0 61.0 60.0 61.0 60.0 07:47:39 INFO - PROCESS | 7696 | 07:47:39 INFO - PROCESS | 7696 | [#10] stanford-crypto-aes Cycles:10 Average:74.50 Median:73.00 stddev:3.57 (4.9%) stddev-sans-first:3.74 07:47:39 INFO - PROCESS | 7696 | Values: 73.0 73.0 75.0 73.0 80.0 72.0 73.0 72.0 82.0 72.0 07:47:39 INFO - PROCESS | 7696 | 07:47:39 INFO - PROCESS | 7696 | [#11] stanford-crypto-ccm Cycles:10 Average:128.70 Median:120.00 stddev:23.71 (19.8%) stddev-sans-first:25.04 07:47:39 INFO - PROCESS | 7696 | Values: 135.0 120.0 129.0 117.0 116.0 117.0 120.0 119.0 194.0 120.0 07:47:39 INFO - PROCESS | 7696 | 07:47:39 INFO - PROCESS | 7696 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.20 Median:153.00 stddev:2.39 (1.6%) stddev-sans-first:2.54 07:47:39 INFO - PROCESS | 7696 | Values: 153.0 152.0 157.0 155.0 153.0 157.0 150.0 152.0 152.0 151.0 07:47:39 INFO - PROCESS | 7696 | 07:47:39 INFO - PROCESS | 7696 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.00 Median:62.50 stddev:3.33 (5.3%) stddev-sans-first:3.46 07:47:39 INFO - PROCESS | 7696 | Values: 61.0 62.0 63.0 62.0 71.0 63.0 61.0 66.0 59.0 62.0 07:47:39 INFO - PROCESS | 7696 | -------- Summary: end -------- 07:47:39 INFO - PROCESS | 7696 | 07:47:40 INFO - TEST-INFO | 7696: exit 0 07:47:40 INFO - TEST-OK | kraken | took 59095ms 07:47:40 INFO - SUITE-END | took 1171s 07:47:40 INFO - Completed test suite (00:19:31) 07:47:40 INFO - javascript benchmark 07:47:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3205.182678417004, 3238.676132386761, 3265.8203798791055, 3290.654481279464, 3299.7855139415938, 5625.240592520008, 5704.48659620634, 5712.800051998176, 5715.857103572413, 5719.170720245573, 5624.943750562493, 5659.745311460983, 5683.801066962657, 5701.2018317435595, 5720.170575266594, 5012.598992080643, 5079.034983353151, 5155.5360017598405, 5187.299714538527, 5249.737513124348, 5163.741812909339, 5208.166693329068, 5211.088059589576, 5213.58291336694, 5214.087534681435, 5072.365954255718, 5107.616928730342, 5116.334876466054, 5133.666311689728, 5205.37535495741, 5196.662216955905, 5212.791488340462, 5225.16397376418, 5229.764660590283, 5256.316678831728, 5041.571466425358, 5062.7468626568525, 5077.822276220315, 5093.134167191549, 5102.770375333119, 5554.389017208104, 5554.944450555524, 5563.805266815642, 5614.073677843151, 5651.40660230674, 6074.48366888815, 6085.208922840046, 6100.877982440371, 6101.298350689639, 6103.664298463627, 5967.0, 5980.102984552352, 5999.820005399845, 6032.607880487798, 6138.60099093562, 5662.943370566283, 5666.036773748473, 5680.233168522279, 5688.715564221772, 5718.228039214736, 6177.2587289524645, 6180.505559555225, 6185.938140618537, 6187.288461826947, 6187.907181392283, 5855.970720146457, 5905.43898329658, 5962.314333851663, 5968.313643931004, 6053.667048312297, 6101.434221565316, 6116.480099191618, 6116.969415152896, 6117.296510901214, 6122.142899994004, 6077.17958075663, 6089.604175728652, 6095.725692343855, 6096.878062438726, 6117.021276595723, 5902.2622172228475, 5969.820905372846, 6012.819615411545, 6017.488513476316, 6114.847128821726, 4597.265196127385, 4617.976910115428, 4621.19129152397, 4622.745748983838, 4656.231721765871, 4157.854475093357, 4170.16596680659, 4174.165166966619, 4178.979105104455, 4199.68502362324, 5680.346760122557, 5702.800401985911, 5749.367569567345, 5750.942490575041, 5854.18041474194, 6052.394760523913, 6065.787697430657, 6076.149339092531, 6078.513718902477, 6079.787207447718, 5758.568107391962, 5768.134779783066, 5771.422857714279, 5788.710564471759, 5789.2763404574425, 4976.850694479172, 5070.695758254443, 5097.413797413271, 5104.055749686246, 5160.948390516047, 6303.464205542488, 6305.274893387319, 6309.053641953652, 6312.116303717483, 6316.242050953915, 6085.543584231289, 6112.113743507078, 6151.96924015377, 6153.907691384543, 6174.753009879734, 6102.298235702951, 6172.105044768394, 6183.938160618336, 6199.16311297969, 6320.241571011508, 6019.849503762441, 6020.397960203944, 6022.307434645072, 6033.432525813268, 6069.575129740876, 6138.723757431016, 6151.6924153793025, 6151.84620384494, 6156.599821011799, 6160.199174107516, 5834.94165058361, 5877.764889404376, 5881.235439392852, 5894.263217097863, 6020.036794112921, 5906.084556893688, 5937.5249980000635, 5947.9702601486715, 5956.702164891652, 5972.522198224218, 5866.67733274674, 5909.3795151508475, 5942.673152976455, 5943.405659434022, 5943.732532035982, 5805.303363596396, 5818.854528636818, 5905.616134951214, 5928.703564821828, 6038.033914573647, 5863.442972917566, 5869.090291004901, 5879.382664820303, 5881.852953676193, 5887.146363777314], "value": 5591.364649644535, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [603.167628672432, 630.9305976342603, 656.9146011018566, 664.6145235763257, 667.9599224046555, 34278.65721342786, 37333.0, 37492.25015499688, 37644.62355376445, 37662.8116859416, 37983.810080949625, 49045.0, 49777.0, 49890.251646225246, 53632.0, 9253.074692530743, 9388.906110938906, 9399.62401503939, 9399.95300023499, 9401.905980940206, 8475.83048339033, 9171.63313467464, 9273.258139348869, 9274.0, 9274.211692006187, 11160.0, 11404.087672986183, 11404.315741055563, 11693.941530292337, 11695.064394848392, 11260.718482037933, 11335.433228338592, 11335.433228338632, 11347.659570212907, 11351.262167959056, 11088.778224435466, 11179.385133817637, 11180.273282236629, 11196.832047519294, 11205.103591712643, 45463.54536454626, 45531.08937821258, 45609.0, 45831.31253031174, 45923.54076459226, 3864.671502922227, 3985.422113793513, 4033.5361433435237, 4249.511306199795, 4662.0, 34715.65284347149, 34979.30041399158, 35176.82411587951, 35192.296154077034, 36784.0, 4501.504834468205, 4512.864614061584, 4513.729176249435, 4598.0, 4620.768961551909, 360067.0, 360330.0, 360986.0, 362848.0, 363785.0, 37287.440688389695, 63437.36562634315, 63833.36166638367, 63885.680571597775, 63920.0, 39040.80479597641, 41166.79416602958, 41241.79379103145, 41290.587094129274, 41368.172636547104, 17428.912855435894, 17566.736498952527, 17591.824081759274, 17606.823931760773, 17608.471745847644, 14942.25288735559, 15026.248687565796, 15028.098314100971, 15029.323680434407, 15034.473793417397, 8737.956310218535, 8891.644334226688, 8914.687985920462, 9212.124848139416, 9293.349465537483, 3367.393869103536, 3456.6370531094376, 3483.1466290758926, 3496.7202623790035, 3499.2126771476724, 1066.2802608239408, 1085.4193006741355, 1176.9764604707857, 1195.4859410453414, 1221.5113954418305, 34804.65195348065, 35127.4730879037, 35209.85976030331, 35630.64369356325, 35765.0, 1169.7601991591703, 1182.6156499137815, 1187.1986409173946, 1189.6966273600349, 1233.808759642257, 17927.103644817707, 18351.265949362143, 18362.724559131624, 18436.76162787798, 18703.438896833115, 578314.0, 581528.0, 582253.0, 582765.0, 583257.0, 62745.058824117674, 62918.37081629217, 65266.347336526975, 65988.67005664941, 66158.0, 1354.5462270139417, 1367.2138520350838, 1432.2480697633825, 1471.4849802569015, 1486.041503230432, 34327.0, 36773.63226367702, 36840.63159368372, 36895.631043689216, 36997.075073123386, 54013.0, 54456.0, 54528.0, 54537.0, 54977.1753423707, 389402.0, 390527.0, 390709.0, 390844.0, 390858.0, 30246.54630180593, 31030.379392412477, 31103.22241943879, 32770.18074548156, 32834.17914552155, 53053.20420193623, 53405.46594534005, 53458.73270633622, 53483.732581336844, 53487.46512534981, 43247.56752432435, 44580.0, 45160.32259516044, 45695.31457028081, 47241.29138062863, 44136.558634413246, 44547.331790023825, 45425.772871135436, 46190.0, 46867.0, 43212.783936080115, 43368.13263734645, 43510.56489435192, 43642.78178609087, 43849.0, 38068.0, 39723.822094231815, 40238.59761402466, 40470.0, 40735.981600460225, 30244.24389390283, 30246.481147496466, 30617.387652247275, 30788.69211307948, 30828.69171308348], "value": 20623.072893473047, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [396.83531334496183, 402.3883696780895, 422.096713034107, 427.9379489973954, 428.1437125748503, 15743.370265189378, 20247.088881000353, 20566.48583785406, 21090.683639745417, 21627.567448651058, 20374.490637734063, 20747.481312967182, 21365.252216172437, 21617.135314587398, 21633.242836500725, 5484.780608775644, 5877.6473411595225, 5908.822735317947, 5912.615679980788, 5913.615614985012, 4370.2789039808395, 5738.110592858114, 5811.27359080115, 6170.568060235785, 6427.5179361547835, 6425.132607098052, 6457.321981191978, 6459.806205813833, 6468.191476065492, 6469.708863101148, 6353.682315884211, 6584.967075164642, 6602.042703807969, 6638.800835974928, 6642.800715978528, 5358.2498450217, 5637.887242255173, 5835.44563266489, 5849.2103566018895, 5854.063349864044, 14537.92731036338, 14815.111093334435, 14818.777718334126, 16255.51233462998, 24791.25626231216, 2516.5721827289403, 2632.8946842126293, 2781.7913656475644, 3039.7720170987263, 3143.842807859598, 10563.102136318423, 10618.203634727426, 10841.620543280947, 10861.945690271497, 11160.665180044612, 1882.962340753191, 1893.829555340026, 1918.2998205654976, 1918.5299601597558, 1938.5928954919484, 81922.0, 84698.15301846965, 88686.0, 88752.11247887503, 90413.0, 11710.76578468426, 11846.940765296118, 11914.582989595323, 12198.207116537396, 12401.13192076564, 16979.0, 16990.405335813186, 16993.91503042477, 16999.91500042492, 17014.064226467417, 8573.09982451846, 8688.174623410765, 8724.127587241224, 8996.64013439455, 9207.355485115977, 8688.695895643748, 8744.0, 8745.431546949556, 8980.236679882153, 8998.325125615605, 5040.697558146523, 5048.722320272347, 5127.512886275797, 5149.253358263031, 5294.867628309323, 2009.507670620678, 2025.1190732031614, 2028.8579799414194, 2049.559344740888, 2071.1922350283403, 960.5053397500293, 1021.8262895307815, 1030.7938412317567, 1039.1219419590584, 1050.8791488978713, 20957.371278861658, 21395.67906481404, 21623.89188054081, 21678.13287468515, 21881.0, 1012.2458768217577, 1014.3913651809003, 1040.3393844908387, 1041.3803786747026, 1068.983965240522, 11080.944595276973, 11312.348281270304, 11873.525058997717, 11891.227070240408, 11945.940270298594, 513735.0, 522892.0, 526562.0, 531259.0, 545256.0, 89247.0, 93445.065549345, 93666.0, 94500.0, 101893.0, 1035.1460045462522, 1035.6064695415694, 1048.3709774135457, 1055.4564399334195, 1055.9251071644103, 18360.540986475444, 18747.90626046861, 19169.137388817257, 19290.517737056685, 19387.321443749403, 43410.131797364505, 45529.31706024346, 47059.29411058906, 47191.292130618756, 48028.0, 55158.72420638031, 62991.37008629855, 63307.36692633199, 63647.36352636414, 64767.67616161889, 8385.454945428528, 8455.112213217524, 8637.395382323177, 8652.956735216283, 8715.694950676696, 12629.30538820337, 13386.598402047954, 13559.72880542364, 14140.575782726535, 14166.504172353918, 11864.288142711464, 12066.75866482683, 12083.093767967262, 12187.634370968884, 12307.507699691912, 11715.707107322385, 11828.778998596237, 11966.282023078644, 12088.818667719815, 12242.938785306016, 12217.816732749194, 12437.253764774143, 12611.93694031524, 12648.43082061291, 12991.220526768424, 8864.468131912106, 9108.726738197865, 9214.078592140731, 9301.302402319718, 9399.201067909304, 12434.316112613893, 12612.87387126117, 12721.363931803187, 12793.61619151427, 12800.61598152057], "value": 9731.159113672107, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1404.6207523968521, 1554.696834117347, 1575.9763603545948, 1769.0, 1893.5266183454146, 8044.678212871486, 8917.420367676104, 9173.220276276525, 9233.49215793132, 9445.527723613808, 9447.81104377912, 9528.0, 9530.094641009093, 9535.380200286976, 9541.713748587536, 2487.2040946896955, 2508.134693530729, 2511.5855883779154, 2512.522620702061, 2517.509085728284, 2477.479729256849, 2545.8217924745277, 2546.2488565873114, 2546.796256299501, 2547.783438407738, 2491.19036313198, 2502.9737807498927, 2543.275166577526, 2547.681539807524, 2556.833805802626, 2434.7443518430578, 2440.1947357372023, 2454.3741345956755, 2479.0951302774356, 2482.6276058591175, 2431.9270421887372, 2442.6091825308044, 2453.251758213744, 2455.3616059824394, 2467.7655622715815, 9281.071892810733, 9418.717438476857, 9435.952820235854, 9437.952810235905, 9453.574589143505, 5669.659820410789, 5841.357450680422, 5846.766129354821, 5850.3564607893095, 5853.443922827367, 3644.2529281497227, 3690.852365905361, 3696.5194524711883, 3730.235301763132, 3742.3825068863875, 1117.7205698575356, 1124.128800179859, 1145.5303325636448, 1161.5760247509597, 1163.895249427547, 13397.12918660284, 13436.193828370231, 13465.259410732504, 13487.190768553919, 13500.729985400238, 4615.469221039556, 4658.557437043475, 4810.735409552508, 4811.230203167546, 4930.161872481615, 10481.842772358421, 10520.26358154937, 10528.894711052944, 10530.63142790014, 10538.262321637412, 5682.403347648437, 5685.630434021776, 5693.373728889819, 5710.885782284331, 5711.0, 4578.839740609063, 4719.292106184032, 5154.819581314636, 5203.505666961632, 5305.575553955674, 3959.5050618672667, 3976.4631774710624, 4039.0508230565792, 4065.918681626351, 4078.1843631273864, 1172.7068232941765, 1178.9174757766873, 1181.1082632612322, 1188.1267268557604, 1208.5347141350644, 921.9216366608779, 962.8074385122864, 1023.2325755683238, 1059.3008614314515, 1075.7740874416304, 6320.5575609706875, 6418.711157997901, 6424.1327420798525, 6543.705533251015, 6649.202095748445, 499.9300097986285, 510.98978020440126, 511.86947328431756, 1070.71626019105, 1086.8261078227445, 4057.69567282455, 4241.5970482804705, 4250.192463431938, 4250.404943307939, 4252.808623611945, 11518.424078796193, 11816.940915295541, 11822.113341499422, 11846.585369512026, 11909.642710718692, 11645.6506304811, 11674.124440666814, 11743.412829358498, 11758.05935525156, 11770.234934729215, 8440.40917135807, 8774.166454186841, 8795.428297160664, 8905.88676415448, 9032.23226025782, 6617.503687223381, 6683.465322774069, 6698.531102822755, 6886.51794374409, 6965.233824279224, 10552.84170737455, 10633.308834925705, 10667.733306667395, 10674.0, 10762.515686793957, 9855.852162217428, 10602.310849794738, 10766.838497422701, 11209.607663732057, 11226.045786108272, 8930.285577153942, 9159.0, 9181.173694367539, 9316.394434361744, 9417.152456278967, 9257.35198536096, 9378.765530861783, 9386.95306523463, 9389.154976052188, 9393.48335841508, 8285.668573257002, 8286.4199506034, 8287.834243315221, 8293.668253269801, 8432.704855330034, 8193.180681931759, 8244.711435099744, 8283.0, 8381.245687888118, 8489.193526614961, 7472.738454154079, 7545.320921117126, 7614.6954121836725, 7619.123800762984, 7643.273888980538, 5824.825255242349, 5880.14737863016, 5968.671723055274, 5984.970075149597, 6024.156618073561, 5630.74661640219, 5838.620489668158, 5840.123981402823, 5949.375315591974, 6104.023356262976], "value": 4927.394534972736, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [329.15243248634755, 334.45149954075305, 334.9866005359786, 339.5178846038625, 343.6014223500739, 5484.396716361197, 5484.424135465779, 5533.584981126412, 5566.638168519044, 5572.498475137237, 5362.785488580462, 5370.0, 5431.266778984836, 5461.290032295796, 5463.972680136594, 4045.898852528688, 4154.792260386984, 4262.5950534699305, 4278.9786051069705, 4286.0999190170105, 4230.9153816923645, 4232.936505952413, 4258.723182993111, 4272.359146128075, 4272.807723652428, 4211.557786432427, 4234.745915245096, 4245.0, 4250.362445633149, 4266.765327906979, 4008.9398659019994, 4034.6570541504007, 4224.640905523003, 4258.680598955059, 4265.893352666209, 3724.7020238380865, 3730.925381492355, 3740.2706472237924, 3747.1194269346684, 3760.4547340635454, 5286.947130528684, 5328.6944698549105, 5345.30511033567, 5362.142057270857, 5371.221172929942, 4716.716996980158, 4737.644676649231, 4752.619790416758, 4768.2847572864, 4852.902941941176, 5545.77816887324, 5554.222408862763, 5557.5831812614215, 5580.581456390746, 5631.8028868989795, 4067.227226826923, 4083.734557253769, 4093.85671501496, 4096.283150448659, 4108.4453598764085, 5750.769969201268, 5795.82612521625, 5832.24180856486, 5846.50304724099, 5905.1732757413565, 4164.7917604120275, 4176.955761059735, 4181.707280490397, 4191.455110835572, 4196.349565817303, 4228.302330115558, 4229.483457342876, 4233.343831706091, 4239.194553034913, 4242.66058715302, 4635.791389387486, 4763.666543341933, 4806.447258565309, 4847.563719265283, 4847.878803029882, 5625.606207565431, 5628.339639806267, 5636.718164091861, 5647.604667673306, 5661.745221465044, 1457.9781303280458, 1482.303317440801, 1484.7253258147061, 1487.8660920517204, 1488.672492051747, 896.7623579751371, 951.538503825644, 952.6903756279237, 972.6790159247431, 1007.8538611901233, 5356.517913387814, 5542.362628297717, 5556.638818476786, 5592.189132575753, 5594.944050559524, 5854.765809367663, 5869.471747542656, 5879.265091863517, 5882.323532793698, 5891.204687367151, 5291.206319052096, 5511.311086114236, 5550.4449555044985, 5560.443955604407, 5574.275344205227, 3046.192758918888, 3077.3691393264776, 3079.784415090967, 3087.814731116095, 3095.8142511449387, 5880.26496687914, 6027.914975304399, 6032.698365081728, 6039.909401358983, 6055.6677530943125, 5863.736131874076, 5880.353161152269, 5913.615614985012, 5951.642901425928, 5956.910646340309, 5999.970000149971, 6008.279006519158, 6013.6692481913915, 6021.668808215502, 6023.909641355383, 5564.916526252028, 5837.416258374129, 5838.737256823538, 5858.209141765978, 5868.354481007171, 5810.448007439287, 5812.796552120655, 5820.592558520862, 5822.534197264124, 5828.358880523224, 5775.873704627557, 6037.18498002764, 6090.0, 6090.9390906090375, 6129.233845769279, 4121.505419349697, 4140.875773726793, 4166.66666666672, 4199.139176468755, 4212.789360532022, 5946.821595352147, 5968.612040217372, 5991.3409524953095, 5992.820215393545, 5993.760249589967, 5706.4864162225595, 5716.885662286814, 5784.248047753765, 5855.94144058554, 5863.618864773776, 5698.800541981011, 5709.8287051388525, 5712.0, 5732.627379220337, 5736.68448235351, 5704.201411802268, 5737.168110624019, 5746.482816546531, 5748.281464816898, 5755.798547050833, 5230.7384630769075, 5395.595330350318, 5732.570057245806, 5739.9139012914, 5740.540756739534, 5616.915746263728, 5621.437856214346, 5637.633553818989, 5648.0, 5657.236273103243], "value": 4349.609325123016, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [837.2757564706527, 856.2080075929763, 867.3711559119639, 907.7549061753323, 919.6965001549487, 16457.341706331747, 17287.222075006623, 17507.47477575675, 17531.73702394465, 17537.64924701505, 14742.262886855668, 14748.33632486538, 15023.098614083136, 15164.924175379108, 15201.0, 870.6082262981652, 887.219247062584, 889.4174315823113, 891.4473026723441, 893.0132203914678, 726.93457588817, 842.086336325088, 851.3444647621328, 852.3947996922162, 873.6112429968666, 917.4220241248038, 918.0727465260102, 918.5499105438321, 918.6876462002917, 921.0375157959915, 906.3519583497831, 907.8819753432011, 910.3809409601436, 911.9589618467185, 912.589334799343, 550.411060165623, 647.4884840975624, 647.9287278399372, 652.3118110393533, 670.6512613441032, 16100.75848862268, 16146.919265403598, 16170.75743863843, 16171.110588917605, 16171.83828161715, 15430.614234644112, 15873.761893571605, 15994.84005159945, 16156.515304540882, 16313.0, 14331.38518407517, 14464.868408636059, 14561.417543298256, 14682.55952321432, 14996.775048374284, 4576.130535198302, 4666.859994200179, 4721.386219791405, 4829.251466022772, 4836.540528649773, 85691.57154214189, 85739.7139042921, 85908.0, 86072.0, 88795.0, 12650.304233267074, 12740.36298185087, 12751.171173873669, 12825.0, 13048.804267935988, 23956.40108997254, 24053.639195412085, 24079.759202407753, 24328.1485148019, 24343.0, 9224.215941645036, 9323.766905827273, 9430.10414010668, 9455.810883782286, 9596.040395960488, 5053.646244762905, 5336.493033161921, 5350.91973620396, 5400.432954539717, 5507.008738427122, 3642.2715456908722, 3788.3938569829247, 3803.1633040730994, 3806.0865392305645, 3807.4669546263544, 637.8947473666885, 648.9643069631121, 654.430645338549, 659.7163219815526, 660.3660485933461, 694.885343918258, 705.8623568404215, 737.9741709040158, 783.0564170174925, 801.72741267968, 9795.118439340493, 9995.550200241007, 10295.893418492062, 10534.132708883051, 10697.625583104555, 760.1296515489844, 763.2863272839912, 768.6541056524586, 784.3254800871246, 786.8898354230412, 5077.543021128116, 5274.4198138204765, 5296.285001524822, 5301.231321458367, 5314.707691077027, 123857.0, 125278.0, 125670.371648143, 125810.0, 127396.36301818432, 106588.0, 108726.0, 110346.0, 111922.44038779754, 112161.75676486587, 97400.0, 97791.0, 98261.50869245607, 98372.50813745885, 101025.49487252516, 9761.121499065119, 9923.503824808586, 10017.248706346907, 10060.49697515136, 10108.84836727435, 45251.54748452473, 50531.49468505415, 50535.49464505308, 52751.47248527466, 55477.0, 68892.0, 71540.64229679026, 79225.0, 80398.5980070096, 82607.0, 14259.07316024455, 14951.327190276246, 15007.399704011717, 15029.774553381927, 15102.169380683728, 16311.755323670392, 16541.0, 17558.473245802645, 17602.03188824627, 17784.0, 15697.607559811097, 15840.60398490047, 16456.917715411346, 16600.584985375463, 16878.83121168822, 15458.072515649097, 15572.0, 15793.526194214191, 16769.916150419656, 16801.159942003094, 15398.384064637288, 15688.372465101726, 15739.606509837346, 15907.840921590636, 16820.57948551296, 12527.310997895203, 12940.547080852126, 12999.09006369545, 13380.531681391105, 13725.245111518961, 11711.238769479956, 12034.63896083119, 12496.0, 12507.437165327765, 12604.243745375306], "value": 8342.573703430713, "unit": "score"}], "name": "dromaeo_css", "value": 7651.4023483123165, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 112.0, 121.0, 110.0, 108.0, 109.0, 111.0, 110.0, 110.0, 110.0], "value": 111.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 122.0, 143.0, 172.0, 121.0, 139.0, 146.0, 122.0, 175.0, 116.0], "value": 140.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 161.0, 160.0, 160.0, 160.0, 160.0, 159.0, 161.0, 159.0], "value": 160.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 74.0, 172.0, 76.0, 76.0, 75.0, 75.0, 174.0, 76.0], "value": 94.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 147.0, 84.0, 84.0, 84.0, 151.0, 85.0, 84.0, 156.0], "value": 104.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 116.0, 116.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 146.0, 147.0, 146.0, 146.0, 145.0, 146.0, 146.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 49.0, 52.0, 49.0, 50.0, 50.0, 51.0, 50.0, 49.0, 49.0], "value": 49.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 61.0, 60.0, 61.0, 61.0, 61.0, 60.0, 61.0, 60.0], "value": 60.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 75.0, 73.0, 80.0, 72.0, 73.0, 72.0, 82.0, 72.0], "value": 74.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 120.0, 129.0, 117.0, 116.0, 117.0, 120.0, 119.0, 194.0, 120.0], "value": 128.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 152.0, 157.0, 155.0, 153.0, 157.0, 150.0, 152.0, 152.0, 151.0], "value": 153.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 63.0, 62.0, 71.0, 63.0, 61.0, 66.0, 59.0, 62.0], "value": 63.0, "unit": "score"}], "name": "kraken", "value": 1498.1000000000001, "alertThreshold": 2.0}]} 07:47:40 INFO - Return code: 0 07:47:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:47:40 INFO - # TBPL SUCCESS # 07:47:40 INFO - Running post-action listener: _resource_record_post_action 07:47:40 INFO - [mozharness: 2016-09-17 14:47:40.874356Z] Finished run-tests step (success) 07:47:40 INFO - Running post-run listener: _resource_record_post_run 07:47:40 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 421888; Write bytes: 319664128; Read time: 724; Write time: 210264 07:47:40 INFO - TinderboxPrint: CPU usage
12.9% 07:47:40 INFO - TinderboxPrint: I/O read bytes / time
421,888 / 724 07:47:40 INFO - TinderboxPrint: I/O write bytes / time
319,664,128 / 210,264 07:47:40 INFO - TinderboxPrint: CPU idle
8,258.5 (87.1%) 07:47:40 INFO - TinderboxPrint: CPU user
1,183.4 (12.5%) 07:47:40 INFO - TinderboxPrint: Swap in / out
0 / 0 07:47:40 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 79622144; Read time: 0; Write time: 79604 07:47:40 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 417792; Write bytes: 240041984; Read time: 716; Write time: 130660 07:47:41 INFO - Running post-run listener: _upload_blobber_files 07:47:41 INFO - Blob upload gear active. 07:47:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:47:41 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 07:47:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:47:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:47:41 INFO - (blobuploader) - INFO - Open directory for files ... 07:47:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:47:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:47:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:47:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:47:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:47:42 INFO - (blobuploader) - INFO - Done attempting. 07:47:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:47:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:47:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:47:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:47:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:47:45 INFO - (blobuploader) - INFO - Done attempting. 07:47:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:47:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:47:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:47:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:47:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:47:46 INFO - (blobuploader) - INFO - Done attempting. 07:47:46 INFO - (blobuploader) - INFO - Iteration through files over. 07:47:46 INFO - Return code: 0 07:47:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:47:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:47:46 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/55c098cf8a16d74a39100392b90b5598c610e3742ba5b351fb05d5fb2939bc1d402a652e1e164f21554ca7fa365392e3287f356ec615b8700d126ebbf2460633", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ddfc61deeacaeda8587cd5650722bc9e63bb74fd31574b33370b0d65097f4f126ccafe8ef12bd97727eeb63a2bce632ca12e45efc281c88bb571ae9fb0be44a8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/45609d8200a5e0f817aed4419e83d44bce03a5f25b2b6c0ba2b28898eda9a2dfdac23557bca2da644b6b5d2bc4200efc8d4b2de92a0b8a5ae631859d414a74df"} 07:47:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:47:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:47:46 INFO - Contents: 07:47:46 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/55c098cf8a16d74a39100392b90b5598c610e3742ba5b351fb05d5fb2939bc1d402a652e1e164f21554ca7fa365392e3287f356ec615b8700d126ebbf2460633", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ddfc61deeacaeda8587cd5650722bc9e63bb74fd31574b33370b0d65097f4f126ccafe8ef12bd97727eeb63a2bce632ca12e45efc281c88bb571ae9fb0be44a8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/45609d8200a5e0f817aed4419e83d44bce03a5f25b2b6c0ba2b28898eda9a2dfdac23557bca2da644b6b5d2bc4200efc8d4b2de92a0b8a5ae631859d414a74df"} 07:47:46 INFO - Running post-run listener: copy_logs_to_upload_dir 07:47:46 INFO - Copying logs to upload dir... 07:47:46 INFO - mkdir: /builds/slave/test/build/upload/logs 07:47:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1677.553018 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 57 secs) (at 2016-09-17 07:47:45.329332) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-17 07:47:45.333312) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1474121892.991980-143782126 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/55c098cf8a16d74a39100392b90b5598c610e3742ba5b351fb05d5fb2939bc1d402a652e1e164f21554ca7fa365392e3287f356ec615b8700d126ebbf2460633", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ddfc61deeacaeda8587cd5650722bc9e63bb74fd31574b33370b0d65097f4f126ccafe8ef12bd97727eeb63a2bce632ca12e45efc281c88bb571ae9fb0be44a8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/45609d8200a5e0f817aed4419e83d44bce03a5f25b2b6c0ba2b28898eda9a2dfdac23557bca2da644b6b5d2bc4200efc8d4b2de92a0b8a5ae631859d414a74df"} build_url:https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011179 build_url: 'https://queue.taskcluster.net/v1/task/dEe2JI-8Qv2bXOdj8oNC1w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/55c098cf8a16d74a39100392b90b5598c610e3742ba5b351fb05d5fb2939bc1d402a652e1e164f21554ca7fa365392e3287f356ec615b8700d126ebbf2460633", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ddfc61deeacaeda8587cd5650722bc9e63bb74fd31574b33370b0d65097f4f126ccafe8ef12bd97727eeb63a2bce632ca12e45efc281c88bb571ae9fb0be44a8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/45609d8200a5e0f817aed4419e83d44bce03a5f25b2b6c0ba2b28898eda9a2dfdac23557bca2da644b6b5d2bc4200efc8d4b2de92a0b8a5ae631859d414a74df"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-17 07:47:45.359019) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:47:45.359362) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1474121892.991980-143782126 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004421 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:47:45.441643) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-17 07:47:45.441980) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-17 07:47:45.442307) ========= ========= Total master_lag: 0.17 =========